builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-020 starttime: 1468276146.69 results: success (0) buildid: 20160711140226 builduid: ad9325d9648e43f7b958257a24d5ec57 revision: 88bebcaca249aeaca9197382e89d35b02be8292e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:06.688732) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:06.689239) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:06.689538) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:06.704313) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:06.704715) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-11 15:29:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-07-11 15:29:07 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107826 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:06.826673) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:06.826996) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020702 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:06.876690) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:06.877055) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 88bebcaca249aeaca9197382e89d35b02be8292e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 88bebcaca249aeaca9197382e89d35b02be8292e --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-11 15:29:07,153 truncating revision to first 12 chars 2016-07-11 15:29:07,153 Setting DEBUG logging. 2016-07-11 15:29:07,153 attempt 1/10 2016-07-11 15:29:07,153 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/88bebcaca249?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 15:29:07,461 unpacking tar archive at: mozilla-central-88bebcaca249/testing/mozharness/ program finished with exit code 0 elapsedTime=0.645677 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:07.536398) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:07.536746) ========= script_repo_revision: 88bebcaca249aeaca9197382e89d35b02be8292e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:07.537190) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:07.537478) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 15:29:07.547344) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-11 15:29:07.547760) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:29:07 INFO - MultiFileLogger online at 20160711 15:29:07 in /builds/slave/test 15:29:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:29:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:29:07 INFO - {'append_to_log': False, 15:29:07 INFO - 'base_work_dir': '/builds/slave/test', 15:29:07 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:29:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:29:07 INFO - 'branch': 'Firefox-Non-PGO', 15:29:07 INFO - 'buildbot_json_path': 'buildprops.json', 15:29:07 INFO - 'config_files': ('talos/linux_config.py',), 15:29:07 INFO - 'default_actions': ('clobber', 15:29:07 INFO - 'read-buildbot-config', 15:29:07 INFO - 'download-and-extract', 15:29:07 INFO - 'populate-webroot', 15:29:07 INFO - 'create-virtualenv', 15:29:07 INFO - 'install', 15:29:07 INFO - 'run-tests'), 15:29:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:29:07 INFO - 'download_minidump_stackwalk': True, 15:29:07 INFO - 'download_symbols': 'ondemand', 15:29:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:29:07 INFO - 'tooltool.py': '/tools/tooltool.py', 15:29:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:29:07 INFO - '/tools/misc-python/virtualenv.py')}, 15:29:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:29:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:29:07 INFO - 'installer_path': 'installer.exe', 15:29:07 INFO - 'log_level': 'info', 15:29:07 INFO - 'log_name': 'talos', 15:29:07 INFO - 'log_to_console': True, 15:29:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:29:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:29:07 INFO - 'opt_config_files': (), 15:29:07 INFO - 'pip_index': False, 15:29:07 INFO - 'sps_profile': False, 15:29:07 INFO - 'sps_profile_interval': 0, 15:29:07 INFO - 'suite': 'dromaeojs', 15:29:07 INFO - 'system_bits': '32', 15:29:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:29:07 INFO - 'title': 'talos-linux64-ix-020', 15:29:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:29:07 INFO - 'use_talos_json': True, 15:29:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:29:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:29:07 INFO - 'work_dir': 'build'} 15:29:07 INFO - ##### 15:29:07 INFO - ##### Running clobber step. 15:29:07 INFO - ##### 15:29:07 INFO - Running pre-action listener: _resource_record_pre_action 15:29:07 INFO - Running main action method: clobber 15:29:07 INFO - rmtree: /builds/slave/test/build 15:29:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:29:08 INFO - Running post-action listener: _resource_record_post_action 15:29:08 INFO - ##### 15:29:08 INFO - ##### Finished clobber step (success) 15:29:08 INFO - ##### 15:29:08 INFO - ##### 15:29:08 INFO - ##### Running read-buildbot-config step. 15:29:08 INFO - ##### 15:29:08 INFO - Running pre-action listener: _resource_record_pre_action 15:29:08 INFO - Running main action method: read_buildbot_config 15:29:08 INFO - Using buildbot properties: 15:29:08 INFO - { 15:29:08 INFO - "project": "", 15:29:08 INFO - "product": "firefox", 15:29:08 INFO - "script_repo_revision": "production", 15:29:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:29:08 INFO - "repository": "", 15:29:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 15:29:08 INFO - "buildid": "20160711140226", 15:29:08 INFO - "pgo_build": "False", 15:29:08 INFO - "basedir": "/builds/slave/test", 15:29:08 INFO - "buildnumber": 144, 15:29:08 INFO - "slavename": "talos-linux64-ix-020", 15:29:08 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:29:08 INFO - "platform": "ubuntu64_hw", 15:29:08 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:29:08 INFO - "branch": "mozilla-central", 15:29:08 INFO - "repo_path": "mozilla-central", 15:29:08 INFO - "revision": "88bebcaca249aeaca9197382e89d35b02be8292e", 15:29:08 INFO - "stage_platform": "linux64", 15:29:08 INFO - "builduid": "ad9325d9648e43f7b958257a24d5ec57", 15:29:08 INFO - "slavebuilddir": "test" 15:29:08 INFO - } 15:29:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 15:29:08 INFO - Running post-action listener: _resource_record_post_action 15:29:08 INFO - ##### 15:29:08 INFO - ##### Finished read-buildbot-config step (success) 15:29:08 INFO - ##### 15:29:08 INFO - ##### 15:29:08 INFO - ##### Running download-and-extract step. 15:29:08 INFO - ##### 15:29:08 INFO - Running pre-action listener: _resource_record_pre_action 15:29:08 INFO - Running main action method: download_and_extract 15:29:08 INFO - mkdir: /builds/slave/test/build/tests 15:29:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:08 INFO - https://queue.taskcluster.net/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:29:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:29:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:29:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:29:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:29:09 INFO - Downloaded 1361 bytes. 15:29:09 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:29:09 INFO - Using the following test package requirements: 15:29:09 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:29:09 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:29:09 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:29:09 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:09 INFO - u'jsshell-linux-x86_64.zip'], 15:29:09 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:29:09 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:29:09 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:29:09 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:29:09 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:29:09 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:29:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:29:09 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:09 INFO - https://queue.taskcluster.net/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:29:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:29:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:29:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:29:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:29:12 INFO - Downloaded 22700289 bytes. 15:29:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:29:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:29:14 INFO - Return code: 0 15:29:14 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:14 INFO - https://queue.taskcluster.net/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:29:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:29:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:29:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:29:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:29:16 INFO - Downloaded 22700289 bytes. 15:29:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:29:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:29:17 INFO - Return code: 0 15:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:17 INFO - https://queue.taskcluster.net/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:29:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:29:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:29:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:29:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:29:20 INFO - Downloaded 14403333 bytes. 15:29:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:29:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:29:21 INFO - Return code: 0 15:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:21 INFO - https://queue.taskcluster.net/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:29:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:29:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:29:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:29:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:29:27 INFO - Downloaded 56649191 bytes. 15:29:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:29:27 INFO - mkdir: /builds/slave/test/properties 15:29:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:29:27 INFO - Writing to file /builds/slave/test/properties/build_url 15:29:27 INFO - Contents: 15:29:27 INFO - build_url:https://queue.taskcluster.net/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:29:27 INFO - Running post-action listener: _resource_record_post_action 15:29:27 INFO - Running post-action listener: set_extra_try_arguments 15:29:27 INFO - ##### 15:29:27 INFO - ##### Finished download-and-extract step (success) 15:29:27 INFO - ##### 15:29:27 INFO - ##### 15:29:27 INFO - ##### Running populate-webroot step. 15:29:27 INFO - ##### 15:29:27 INFO - Running pre-action listener: _resource_record_pre_action 15:29:27 INFO - Running main action method: populate_webroot 15:29:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:29:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:29:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:29:27 INFO - '--tppagecycles', 15:29:27 INFO - '7'], 15:29:27 INFO - 'tests': ['tsvgm']}}, 15:29:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:29:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:29:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:29:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:29:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:29:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:29:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:27 INFO - 'talos_options': ['--disable-e10s'], 15:29:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:27 INFO - 'talos_options': ['--disable-e10s'], 15:29:27 INFO - 'tests': ['damp', 'tps']}, 15:29:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:27 INFO - 'tests': ['damp', 'tps']}, 15:29:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:29:27 INFO - 'tests': ['dromaeo_dom']}, 15:29:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:29:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:29:27 INFO - 'tests': ['basic_compositor_video']}, 15:29:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:29:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:29:27 INFO - 'tests': ['a11yr', 15:29:27 INFO - 'ts_paint', 15:29:27 INFO - 'tpaint', 15:29:27 INFO - 'sessionrestore', 15:29:27 INFO - 'sessionrestore_no_auto_restore', 15:29:27 INFO - 'tabpaint']}, 15:29:27 INFO - 'other-e10s': {'tests': ['a11yr', 15:29:27 INFO - 'ts_paint', 15:29:27 INFO - 'tpaint', 15:29:27 INFO - 'sessionrestore', 15:29:27 INFO - 'sessionrestore_no_auto_restore', 15:29:27 INFO - 'tabpaint']}, 15:29:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:29:27 INFO - 'ts_paint', 15:29:27 INFO - 'tpaint', 15:29:27 INFO - 'sessionrestore', 15:29:27 INFO - 'sessionrestore_no_auto_restore', 15:29:27 INFO - 'tabpaint']}, 15:29:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:29:27 INFO - 'ts_paint', 15:29:27 INFO - 'tpaint', 15:29:27 INFO - 'sessionrestore', 15:29:27 INFO - 'sessionrestore_no_auto_restore', 15:29:27 INFO - 'tabpaint']}, 15:29:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:29:27 INFO - 'tests': ['a11yr', 15:29:27 INFO - 'ts_paint', 15:29:27 INFO - 'tpaint', 15:29:27 INFO - 'sessionrestore', 15:29:27 INFO - 'sessionrestore_no_auto_restore', 15:29:27 INFO - 'tabpaint']}, 15:29:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:29:27 INFO - 'tests': ['a11yr', 15:29:27 INFO - 'ts_paint', 15:29:27 INFO - 'tpaint', 15:29:27 INFO - 'sessionrestore', 15:29:27 INFO - 'sessionrestore_no_auto_restore', 15:29:27 INFO - 'tabpaint']}, 15:29:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:29:27 INFO - 'tests': ['tsvgx', 15:29:27 INFO - 'tsvgr_opacity', 15:29:27 INFO - 'tart', 15:29:27 INFO - 'tscrollx', 15:29:27 INFO - 'cart']}, 15:29:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:29:27 INFO - 'tsvgr_opacity', 15:29:27 INFO - 'tart', 15:29:27 INFO - 'tscrollx', 15:29:27 INFO - 'cart']}, 15:29:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:27 INFO - 'talos_options': ['--disable-e10s'], 15:29:27 INFO - 'tests': ['tp5o']}, 15:29:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:27 INFO - 'tests': ['tp5o']}, 15:29:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:27 INFO - 'talos_options': ['--disable-e10s', 15:29:27 INFO - '--xperf_path', 15:29:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:27 INFO - 'tests': ['tp5n']}, 15:29:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:27 INFO - 'talos_options': ['--xperf_path', 15:29:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:27 INFO - 'tests': ['tp5n']}}, 15:29:27 INFO - 'talos.zip': {'path': '', 15:29:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:29:27 INFO - Running post-action listener: _resource_record_post_action 15:29:27 INFO - ##### 15:29:27 INFO - ##### Finished populate-webroot step (success) 15:29:27 INFO - ##### 15:29:27 INFO - ##### 15:29:27 INFO - ##### Running create-virtualenv step. 15:29:27 INFO - ##### 15:29:27 INFO - Running pre-action listener: _resource_record_pre_action 15:29:27 INFO - Running main action method: create_virtualenv 15:29:27 INFO - Creating virtualenv /builds/slave/test/build/venv 15:29:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:29:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:29:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:29:28 INFO - Using real prefix '/usr' 15:29:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:29:28 INFO - Installing distribute.............................................................................................................................................................................................done. 15:29:30 INFO - Installing pip.................done. 15:29:30 INFO - Return code: 0 15:29:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2917030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd63b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:29:30 INFO - Using env: {'DISPLAY': ':0', 15:29:30 INFO - 'HOME': '/home/cltbld', 15:29:30 INFO - 'LANG': 'en_US.UTF-8', 15:29:30 INFO - 'LANGUAGE': 'en_US:en', 15:29:30 INFO - 'LOGNAME': 'cltbld', 15:29:30 INFO - 'MAIL': '/var/mail/cltbld', 15:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:30 INFO - 'MOZ_NO_REMOTE': '1', 15:29:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:30 INFO - 'NO_EM_RESTART': '1', 15:29:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:30 INFO - 'PWD': '/builds/slave/test', 15:29:30 INFO - 'SHELL': '/bin/bash', 15:29:30 INFO - 'SHLVL': '1', 15:29:30 INFO - 'TERM': 'linux', 15:29:30 INFO - 'TMOUT': '86400', 15:29:30 INFO - 'USER': 'cltbld', 15:29:30 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 15:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:30 INFO - '_': '/tools/buildbot/bin/python'} 15:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:29:30 INFO - Cleaning up... 15:29:30 INFO - Return code: 0 15:29:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2917030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd63b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:29:30 INFO - Using env: {'DISPLAY': ':0', 15:29:30 INFO - 'HOME': '/home/cltbld', 15:29:30 INFO - 'LANG': 'en_US.UTF-8', 15:29:30 INFO - 'LANGUAGE': 'en_US:en', 15:29:30 INFO - 'LOGNAME': 'cltbld', 15:29:30 INFO - 'MAIL': '/var/mail/cltbld', 15:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:30 INFO - 'MOZ_NO_REMOTE': '1', 15:29:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:30 INFO - 'NO_EM_RESTART': '1', 15:29:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:30 INFO - 'PWD': '/builds/slave/test', 15:29:30 INFO - 'SHELL': '/bin/bash', 15:29:30 INFO - 'SHLVL': '1', 15:29:30 INFO - 'TERM': 'linux', 15:29:30 INFO - 'TMOUT': '86400', 15:29:30 INFO - 'USER': 'cltbld', 15:29:30 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 15:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:30 INFO - '_': '/tools/buildbot/bin/python'} 15:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:30 INFO - Downloading/unpacking psutil>=3.1.1 15:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:29:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:32 INFO - Installing collected packages: psutil 15:29:32 INFO - Running setup.py install for psutil 15:29:33 INFO - building 'psutil._psutil_linux' extension 15:29:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:29:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:29:33 INFO - building 'psutil._psutil_posix' extension 15:29:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:29:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:29:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:33 INFO - Successfully installed psutil 15:29:33 INFO - Cleaning up... 15:29:33 INFO - Return code: 0 15:29:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2917030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd63b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:29:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:29:33 INFO - Using env: {'DISPLAY': ':0', 15:29:33 INFO - 'HOME': '/home/cltbld', 15:29:33 INFO - 'LANG': 'en_US.UTF-8', 15:29:33 INFO - 'LANGUAGE': 'en_US:en', 15:29:33 INFO - 'LOGNAME': 'cltbld', 15:29:33 INFO - 'MAIL': '/var/mail/cltbld', 15:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:33 INFO - 'MOZ_NO_REMOTE': '1', 15:29:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:33 INFO - 'NO_EM_RESTART': '1', 15:29:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:33 INFO - 'PWD': '/builds/slave/test', 15:29:33 INFO - 'SHELL': '/bin/bash', 15:29:33 INFO - 'SHLVL': '1', 15:29:33 INFO - 'TERM': 'linux', 15:29:33 INFO - 'TMOUT': '86400', 15:29:33 INFO - 'USER': 'cltbld', 15:29:33 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 15:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:33 INFO - '_': '/tools/buildbot/bin/python'} 15:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:29:35 INFO - Installing collected packages: mozsystemmonitor 15:29:35 INFO - Running setup.py install for mozsystemmonitor 15:29:35 INFO - Successfully installed mozsystemmonitor 15:29:35 INFO - Cleaning up... 15:29:35 INFO - Return code: 0 15:29:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2917030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd63b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:29:35 INFO - Using env: {'DISPLAY': ':0', 15:29:35 INFO - 'HOME': '/home/cltbld', 15:29:35 INFO - 'LANG': 'en_US.UTF-8', 15:29:35 INFO - 'LANGUAGE': 'en_US:en', 15:29:35 INFO - 'LOGNAME': 'cltbld', 15:29:35 INFO - 'MAIL': '/var/mail/cltbld', 15:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:35 INFO - 'MOZ_NO_REMOTE': '1', 15:29:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:35 INFO - 'NO_EM_RESTART': '1', 15:29:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:35 INFO - 'PWD': '/builds/slave/test', 15:29:35 INFO - 'SHELL': '/bin/bash', 15:29:35 INFO - 'SHLVL': '1', 15:29:35 INFO - 'TERM': 'linux', 15:29:35 INFO - 'TMOUT': '86400', 15:29:35 INFO - 'USER': 'cltbld', 15:29:35 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 15:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:35 INFO - '_': '/tools/buildbot/bin/python'} 15:29:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:36 INFO - Downloading/unpacking jsonschema==2.5.1 15:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:29:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:38 INFO - Downloading functools32-3.2.3-2.tar.gz 15:29:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:29:38 INFO - warning: no files found matching '*.txt' 15:29:38 INFO - no previously-included directories found matching 'build' 15:29:38 INFO - no previously-included directories found matching 'dist' 15:29:38 INFO - no previously-included directories found matching '.git*' 15:29:38 INFO - Installing collected packages: jsonschema, functools32 15:29:38 INFO - Running setup.py install for functools32 15:29:38 INFO - warning: no files found matching '*.txt' 15:29:38 INFO - no previously-included directories found matching 'build' 15:29:38 INFO - no previously-included directories found matching 'dist' 15:29:38 INFO - no previously-included directories found matching '.git*' 15:29:38 INFO - Successfully installed jsonschema functools32 15:29:38 INFO - Cleaning up... 15:29:38 INFO - Return code: 0 15:29:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2917030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd63b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:29:38 INFO - Using env: {'DISPLAY': ':0', 15:29:38 INFO - 'HOME': '/home/cltbld', 15:29:38 INFO - 'LANG': 'en_US.UTF-8', 15:29:38 INFO - 'LANGUAGE': 'en_US:en', 15:29:38 INFO - 'LOGNAME': 'cltbld', 15:29:38 INFO - 'MAIL': '/var/mail/cltbld', 15:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:38 INFO - 'MOZ_NO_REMOTE': '1', 15:29:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:38 INFO - 'NO_EM_RESTART': '1', 15:29:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:38 INFO - 'PWD': '/builds/slave/test', 15:29:38 INFO - 'SHELL': '/bin/bash', 15:29:38 INFO - 'SHLVL': '1', 15:29:38 INFO - 'TERM': 'linux', 15:29:38 INFO - 'TMOUT': '86400', 15:29:38 INFO - 'USER': 'cltbld', 15:29:38 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 15:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:38 INFO - '_': '/tools/buildbot/bin/python'} 15:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:38 INFO - Downloading/unpacking blobuploader==1.2.4 15:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:40 INFO - Downloading blobuploader-1.2.4.tar.gz 15:29:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:29:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:29:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:41 INFO - Downloading docopt-0.6.1.tar.gz 15:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:29:41 INFO - Installing collected packages: blobuploader, requests, docopt 15:29:41 INFO - Running setup.py install for blobuploader 15:29:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:29:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:29:41 INFO - Running setup.py install for requests 15:29:41 INFO - Running setup.py install for docopt 15:29:41 INFO - Successfully installed blobuploader requests docopt 15:29:41 INFO - Cleaning up... 15:29:41 INFO - Return code: 0 15:29:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2917030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd63b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:29:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:29:41 INFO - Using env: {'DISPLAY': ':0', 15:29:41 INFO - 'HOME': '/home/cltbld', 15:29:41 INFO - 'LANG': 'en_US.UTF-8', 15:29:41 INFO - 'LANGUAGE': 'en_US:en', 15:29:41 INFO - 'LOGNAME': 'cltbld', 15:29:41 INFO - 'MAIL': '/var/mail/cltbld', 15:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:41 INFO - 'MOZ_NO_REMOTE': '1', 15:29:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:41 INFO - 'NO_EM_RESTART': '1', 15:29:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:41 INFO - 'PWD': '/builds/slave/test', 15:29:41 INFO - 'SHELL': '/bin/bash', 15:29:41 INFO - 'SHLVL': '1', 15:29:41 INFO - 'TERM': 'linux', 15:29:41 INFO - 'TMOUT': '86400', 15:29:41 INFO - 'USER': 'cltbld', 15:29:41 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 15:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:41 INFO - '_': '/tools/buildbot/bin/python'} 15:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:29:42 INFO - Running setup.py (path:/tmp/pip-P5TRkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:29:42 INFO - Running setup.py (path:/tmp/pip-ODzAd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:29:42 INFO - Running setup.py (path:/tmp/pip-WtHQzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:29:42 INFO - Running setup.py (path:/tmp/pip-80sxVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:29:42 INFO - Running setup.py (path:/tmp/pip-i0pOnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:29:42 INFO - Running setup.py (path:/tmp/pip-63nEsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:29:42 INFO - Running setup.py (path:/tmp/pip-sjOl4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:29:42 INFO - Running setup.py (path:/tmp/pip-9wwapu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:29:42 INFO - Running setup.py (path:/tmp/pip-QsPXbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:29:42 INFO - Running setup.py (path:/tmp/pip-p55gQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:29:42 INFO - Running setup.py (path:/tmp/pip-4_Dla4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:29:42 INFO - Running setup.py (path:/tmp/pip-DcnEp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:29:43 INFO - Running setup.py (path:/tmp/pip-Ub72XU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:29:43 INFO - Running setup.py (path:/tmp/pip-mG73lO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:43 INFO - Running setup.py (path:/tmp/pip-SjJVRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:29:43 INFO - Running setup.py (path:/tmp/pip-nBExoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:29:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:29:43 INFO - Running setup.py (path:/tmp/pip-mAYCM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:29:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:29:43 INFO - Running setup.py install for manifestparser 15:29:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:29:43 INFO - Running setup.py install for mozcrash 15:29:43 INFO - Running setup.py install for mozdebug 15:29:43 INFO - Running setup.py install for mozdevice 15:29:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:29:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:29:43 INFO - Running setup.py install for mozfile 15:29:43 INFO - Running setup.py install for mozhttpd 15:29:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:29:44 INFO - Running setup.py install for mozinfo 15:29:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:29:44 INFO - Running setup.py install for mozInstall 15:29:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:29:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:29:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:29:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:29:44 INFO - Running setup.py install for mozleak 15:29:44 INFO - Running setup.py install for mozlog 15:29:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:29:44 INFO - Running setup.py install for moznetwork 15:29:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:29:44 INFO - Running setup.py install for mozprocess 15:29:44 INFO - Running setup.py install for mozprofile 15:29:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:29:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:29:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:29:44 INFO - Running setup.py install for mozrunner 15:29:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:29:44 INFO - Running setup.py install for mozscreenshot 15:29:44 INFO - Running setup.py install for moztest 15:29:45 INFO - Running setup.py install for mozversion 15:29:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:29:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:29:45 INFO - Cleaning up... 15:29:45 INFO - Return code: 0 15:29:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2917030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd63b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:29:45 INFO - Using env: {'DISPLAY': ':0', 15:29:45 INFO - 'HOME': '/home/cltbld', 15:29:45 INFO - 'LANG': 'en_US.UTF-8', 15:29:45 INFO - 'LANGUAGE': 'en_US:en', 15:29:45 INFO - 'LOGNAME': 'cltbld', 15:29:45 INFO - 'MAIL': '/var/mail/cltbld', 15:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:45 INFO - 'MOZ_NO_REMOTE': '1', 15:29:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:45 INFO - 'NO_EM_RESTART': '1', 15:29:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:45 INFO - 'PWD': '/builds/slave/test', 15:29:45 INFO - 'SHELL': '/bin/bash', 15:29:45 INFO - 'SHLVL': '1', 15:29:45 INFO - 'TERM': 'linux', 15:29:45 INFO - 'TMOUT': '86400', 15:29:45 INFO - 'USER': 'cltbld', 15:29:45 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 15:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:45 INFO - '_': '/tools/buildbot/bin/python'} 15:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:29:45 INFO - Running setup.py (path:/tmp/pip-hSwF1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:29:45 INFO - Running setup.py (path:/tmp/pip-rbuCqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:29:45 INFO - Running setup.py (path:/tmp/pip-QmdSjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:29:45 INFO - Running setup.py (path:/tmp/pip-wAWo2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:29:45 INFO - Running setup.py (path:/tmp/pip-AJWkpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:29:45 INFO - Running setup.py (path:/tmp/pip-ZdVcPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:29:45 INFO - Running setup.py (path:/tmp/pip-gmR26X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:29:45 INFO - Running setup.py (path:/tmp/pip-0aZgye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:29:45 INFO - Running setup.py (path:/tmp/pip-gmKMo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:29:46 INFO - Running setup.py (path:/tmp/pip-VmELCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:29:46 INFO - Running setup.py (path:/tmp/pip-Uaf3jK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:29:46 INFO - Running setup.py (path:/tmp/pip-mchh8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:29:46 INFO - Running setup.py (path:/tmp/pip-7J2buU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:29:46 INFO - Running setup.py (path:/tmp/pip-Zp0kSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:46 INFO - Running setup.py (path:/tmp/pip-CHJDdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:29:46 INFO - Running setup.py (path:/tmp/pip-DOM9lT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:29:46 INFO - Running setup.py (path:/tmp/pip-08M084-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:29:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:48 INFO - Downloading blessings-1.6.tar.gz 15:29:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:29:48 INFO - Installing collected packages: blessings 15:29:48 INFO - Running setup.py install for blessings 15:29:48 INFO - Successfully installed blessings 15:29:48 INFO - Cleaning up... 15:29:48 INFO - Return code: 0 15:29:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:29:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:29:49 INFO - Reading from file tmpfile_stdout 15:29:49 INFO - Current package versions: 15:29:49 INFO - argparse == 1.2.1 15:29:49 INFO - blessings == 1.6 15:29:49 INFO - blobuploader == 1.2.4 15:29:49 INFO - docopt == 0.6.1 15:29:49 INFO - functools32 == 3.2.3-2 15:29:49 INFO - jsonschema == 2.5.1 15:29:49 INFO - manifestparser == 1.1 15:29:49 INFO - mozInstall == 1.12 15:29:49 INFO - mozcrash == 0.17 15:29:49 INFO - mozdebug == 0.1 15:29:49 INFO - mozdevice == 0.48 15:29:49 INFO - mozfile == 1.2 15:29:49 INFO - mozhttpd == 0.7 15:29:49 INFO - mozinfo == 0.9 15:29:49 INFO - mozleak == 0.1 15:29:49 INFO - mozlog == 3.2 15:29:49 INFO - moznetwork == 0.27 15:29:49 INFO - mozprocess == 0.23 15:29:49 INFO - mozprofile == 0.28 15:29:49 INFO - mozrunner == 6.12 15:29:49 INFO - mozscreenshot == 0.1 15:29:49 INFO - mozsystemmonitor == 0.3 15:29:49 INFO - moztest == 0.7 15:29:49 INFO - mozversion == 1.4 15:29:49 INFO - psutil == 3.1.1 15:29:49 INFO - requests == 1.2.3 15:29:49 INFO - wsgiref == 0.1.2 15:29:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2917030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd63b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:29:49 INFO - Using env: {'DISPLAY': ':0', 15:29:49 INFO - 'HOME': '/home/cltbld', 15:29:49 INFO - 'LANG': 'en_US.UTF-8', 15:29:49 INFO - 'LANGUAGE': 'en_US:en', 15:29:49 INFO - 'LOGNAME': 'cltbld', 15:29:49 INFO - 'MAIL': '/var/mail/cltbld', 15:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:49 INFO - 'MOZ_NO_REMOTE': '1', 15:29:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:49 INFO - 'NO_EM_RESTART': '1', 15:29:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:49 INFO - 'PWD': '/builds/slave/test', 15:29:49 INFO - 'SHELL': '/bin/bash', 15:29:49 INFO - 'SHLVL': '1', 15:29:49 INFO - 'TERM': 'linux', 15:29:49 INFO - 'TMOUT': '86400', 15:29:49 INFO - 'USER': 'cltbld', 15:29:49 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 15:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:49 INFO - '_': '/tools/buildbot/bin/python'} 15:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:29:49 INFO - Cleaning up... 15:29:49 INFO - Return code: 0 15:29:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2917030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a7a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd63b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:29:49 INFO - Using env: {'DISPLAY': ':0', 15:29:49 INFO - 'HOME': '/home/cltbld', 15:29:49 INFO - 'LANG': 'en_US.UTF-8', 15:29:49 INFO - 'LANGUAGE': 'en_US:en', 15:29:49 INFO - 'LOGNAME': 'cltbld', 15:29:49 INFO - 'MAIL': '/var/mail/cltbld', 15:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:49 INFO - 'MOZ_NO_REMOTE': '1', 15:29:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:49 INFO - 'NO_EM_RESTART': '1', 15:29:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:49 INFO - 'PWD': '/builds/slave/test', 15:29:49 INFO - 'SHELL': '/bin/bash', 15:29:49 INFO - 'SHLVL': '1', 15:29:49 INFO - 'TERM': 'linux', 15:29:49 INFO - 'TMOUT': '86400', 15:29:49 INFO - 'USER': 'cltbld', 15:29:49 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 15:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:49 INFO - '_': '/tools/buildbot/bin/python'} 15:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:29:49 INFO - Cleaning up... 15:29:49 INFO - Return code: 0 15:29:49 INFO - Running post-action listener: _resource_record_post_action 15:29:49 INFO - Running post-action listener: _start_resource_monitoring 15:29:49 INFO - Starting resource monitoring. 15:29:49 INFO - ##### 15:29:49 INFO - ##### Finished create-virtualenv step (success) 15:29:49 INFO - ##### 15:29:49 INFO - ##### 15:29:49 INFO - ##### Running install step. 15:29:49 INFO - ##### 15:29:49 INFO - Running pre-action listener: _resource_record_pre_action 15:29:49 INFO - Running main action method: install 15:29:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:29:49 INFO - Reading from file tmpfile_stdout 15:29:49 INFO - Detecting whether we're running mozinstall >=1.0... 15:29:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:29:49 INFO - Reading from file tmpfile_stdout 15:29:49 INFO - Output received: 15:29:49 INFO - Usage: mozinstall [options] installer 15:29:49 INFO - Options: 15:29:49 INFO - -h, --help show this help message and exit 15:29:49 INFO - -d DEST, --destination=DEST 15:29:49 INFO - Directory to install application into. [default: 15:29:49 INFO - "/builds/slave/test"] 15:29:49 INFO - --app=APP Application being installed. [default: firefox] 15:29:49 INFO - mkdir: /builds/slave/test/build/application 15:29:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:30:06 INFO - Reading from file tmpfile_stdout 15:30:06 INFO - Output received: 15:30:06 INFO - /builds/slave/test/build/application/firefox/firefox 15:30:06 INFO - Running post-action listener: _resource_record_post_action 15:30:06 INFO - ##### 15:30:06 INFO - ##### Finished install step (success) 15:30:06 INFO - ##### 15:30:06 INFO - ##### 15:30:06 INFO - ##### Running run-tests step. 15:30:06 INFO - ##### 15:30:06 INFO - Running pre-action listener: _resource_record_pre_action 15:30:06 INFO - Running main action method: run_tests 15:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:30:06 INFO - Python 2.7.3 15:30:06 INFO - Return code: 0 15:30:06 INFO - grabbing minidump binary from tooltool 15:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:30:06 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd63b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bd7740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:30:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:30:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:30:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:30:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:30:06 INFO - Return code: 0 15:30:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:30:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:30:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:30:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:30:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:30:06 INFO - Using env: {'DISPLAY': ':0', 15:30:06 INFO - 'HOME': '/home/cltbld', 15:30:06 INFO - 'LANG': 'en_US.UTF-8', 15:30:06 INFO - 'LANGUAGE': 'en_US:en', 15:30:06 INFO - 'LOGNAME': 'cltbld', 15:30:06 INFO - 'MAIL': '/var/mail/cltbld', 15:30:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:30:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:06 INFO - 'MOZ_NO_REMOTE': '1', 15:30:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:30:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:30:06 INFO - 'NO_EM_RESTART': '1', 15:30:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:06 INFO - 'PWD': '/builds/slave/test', 15:30:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:30:06 INFO - 'SHELL': '/bin/bash', 15:30:06 INFO - 'SHLVL': '1', 15:30:06 INFO - 'TERM': 'linux', 15:30:06 INFO - 'TMOUT': '86400', 15:30:06 INFO - 'USER': 'cltbld', 15:30:06 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039', 15:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:06 INFO - '_': '/tools/buildbot/bin/python'} 15:30:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:30:06 INFO - mozversion application_buildid: 20160711140226 15:30:06 INFO - mozversion application_changeset: 88bebcaca249aeaca9197382e89d35b02be8292e 15:30:06 INFO - mozversion application_display_name: Nightly 15:30:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:30:06 INFO - mozversion application_name: Firefox 15:30:06 INFO - mozversion application_remotingname: firefox 15:30:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:30:06 INFO - mozversion application_vendor: Mozilla 15:30:06 INFO - mozversion application_version: 50.0a1 15:30:06 INFO - mozversion platform_buildid: 20160711140226 15:30:06 INFO - mozversion platform_changeset: 88bebcaca249aeaca9197382e89d35b02be8292e 15:30:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:30:06 INFO - mozversion platform_version: 50.0a1 15:30:06 INFO - using testdate: 1468276206 15:30:06 INFO - actual date: 1468276206 15:30:06 INFO - starting webserver on 'localhost:54519' 15:30:06 INFO - SUITE-START | Running 2 tests 15:30:06 INFO - TEST-START | dromaeo_css 15:30:06 INFO - operating with platform_type : linux_ 15:30:06 INFO - Initialising browser for dromaeo_css test... 15:30:06 INFO - TEST-INFO | started process 18196 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvl6yVV/profile http://localhost:54519/getInfo.html) 15:30:11 INFO - PROCESS | 18196 | __metrics Screen width/height:1600/1200 15:30:11 INFO - PROCESS | 18196 | colorDepth:24 15:30:11 INFO - PROCESS | 18196 | Browser inner width/height: 1024/697 15:30:11 INFO - PROCESS | 18196 | __metrics 15:30:12 INFO - PROCESS | 18196 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:30:12 INFO - PROCESS | 18196 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:30:12 INFO - PROCESS | 18196 | console.error: PushService: 15:30:12 INFO - PROCESS | 18196 | stateChangeProcessEnqueue: Error transitioning state 15:30:12 INFO - PROCESS | 18196 | UnknownError 15:30:13 INFO - TEST-INFO | 18196: exit 0 15:30:13 INFO - Browser initialized. 15:30:13 INFO - Running cycle 1/1 for dromaeo_css test... 15:30:13 INFO - TEST-INFO | started process 18278 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvl6yVV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:30:25 INFO - PROCESS | 18278 | RSS: Main: 158961664 15:30:25 INFO - PROCESS | 18278 | 15:31:16 INFO - PROCESS | 18278 | 1468276276224 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:33:29 INFO - PROCESS | 18278 | Cycle 1(1): loaded http://localhost:54519/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54519/tests/dromaeo/cssquery-ext.html) 15:33:30 INFO - PROCESS | 18278 | RSS: Main: 279429120 15:33:30 INFO - PROCESS | 18278 | 15:36:33 INFO - PROCESS | 18278 | Cycle 1(1): loaded http://localhost:54519/tests/dromaeo/cssquery-ext.html (next: http://localhost:54519/tests/dromaeo/cssquery-jquery.html) 15:36:34 INFO - PROCESS | 18278 | RSS: Main: 242040832 15:36:34 INFO - PROCESS | 18278 | 15:39:36 INFO - PROCESS | 18278 | Cycle 1(1): loaded http://localhost:54519/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54519/tests/dromaeo/cssquery-mootools.html) 15:39:37 INFO - PROCESS | 18278 | RSS: Main: 239112192 15:39:37 INFO - PROCESS | 18278 | 15:42:39 INFO - PROCESS | 18278 | Cycle 1(1): loaded http://localhost:54519/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54519/tests/dromaeo/cssquery-prototype.html) 15:42:39 INFO - PROCESS | 18278 | RSS: Main: 234508288 15:42:39 INFO - PROCESS | 18278 | 15:45:43 INFO - PROCESS | 18278 | Cycle 1(1): loaded http://localhost:54519/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54519/tests/dromaeo/cssquery-yui.html) 15:45:43 INFO - PROCESS | 18278 | RSS: Main: 221097984 15:45:43 INFO - PROCESS | 18278 | 15:48:46 INFO - PROCESS | 18278 | Cycle 1(1): loaded http://localhost:54519/tests/dromaeo/cssquery-yui.html (next: http://localhost:54519/tests/dromaeo/cssquery-dojo.html) 15:48:46 INFO - PROCESS | 18278 | RSS: Main: 323858432 15:48:46 INFO - PROCESS | 18278 | 15:48:46 INFO - PROCESS | 18278 | __start_tp_report 15:48:46 INFO - PROCESS | 18278 | _x_x_mozilla_page_load 15:48:46 INFO - PROCESS | 18278 | _x_x_mozilla_page_load_details 15:48:46 INFO - PROCESS | 18278 | |i|pagename|runs| 15:48:46 INFO - PROCESS | 18278 | |0;dojo.html;2608.5956676715105;2634.881430335637;2671.826331288467;2681.3698780786513;2685.9865700671494;4708.576228139466;4761.69049011814;4775.570198682127;4844.66087373884;4893.33939918111;4747.715137091777;4752.192127338352;4754.120487709776;4805.951940480594;4835.443923948747;4304.483461984566;4359.193549193409;4390.40729501518;4413.646908247332;4534.13851368239;4472.686911916167;4497.505274419811;4508.77456127194;4538.568835960595;4557.2708366661345;4300.440942677432;4355.825766969318;4358.8910277243;4362.585554372329;4373.868783936487;4405.625521830648;4414.095110502371;4433.889152771174;4438.267685831834;4474.507804141542;4445.377647129404;4459.420275364214;4463.553644635543;4464.6874718769695;4496.123255965088;4619.838305659319;4761.0715910397485;4834.637402194814;4888.217885138396;4935.679180853233;5255.816046438395;5256.632035757499;5262.447443018466;5295.4969277918535;5301.204819277101;5201.453847346012;5293.788248470095;5308.177232528964;5310.256564081032;5355.571554275688;4705.247160454311;4735.976320118378;4779.71321720691;4788.353572267769;4981.701097934172;5283.841484755463;5294.629375943647;5309.867253318621;5311.52196302327;5314.734263286898;5074.416442109131;5084.262781896604;5090.185570308733;5092;5092.414372347227;5016.673916195436;5132.948670513321;5212.843614691566;5216.634835561475;5296.311479507639;5175.353080864892;5199.55803756685;5203.817866374735;5206.635535512553;5207.1668533034535;4947.332110165154;5190.506901844319;5195.584353251731;5199.038177937094;5229.6339256251695;4328.394024836525;4371.78141092944;4446.644268458515;4453.1761624099645;4495.078508905666;3866;3867.7485963412287;3869.903252418712;3904.72666913319;3909.3158697227873;4425.048614547888;4432.468103827561;4432.734035957853;4434.933475997863;4479.955200448019;4951.851444456672;5055.8988820223385;5093.032323858455;5121.667091639031;5158.097332966716;4345.109252603209;4366.978165109154;4368.868933931987;4369.519352871182;4419.845305414295;4510.706804057726;4588.7935042923145;4641.257398816173;4648.140094082605;4678.69588476748;4792.2332426811545;4855.635827312895;4860.6111511079735;4865.659403841839;4870.878228044186;5197.792088316425;5206.97396513015;5209.947900521098;5211.244369566468;5264.605154613343;4662.463816661128;4765.356676848581;4816.229403295456;4835.588974937029;4894.339264199288;5072.9854029193575;5105.33630628016;5126.923096153635;5128.743562821918;5168.198929165986;5147.099257629975;5172.922406163986;5177.223416487557;5183.403908550414;5233.947660523346;4880.951190488192;4902.7058376497525;4963.70217786934;5054.039732450822;5077.8730531737;4978.30303757467;4998.475160108136;5000.274960130833;5005.049040682259;5040.84877453677;4934.925976110433;4943;4987.875303117451;4993.575546078624;4996;4813.470518242921;4854.101991131652;4855.757212139309;4905.068037072945;4906.36217291764;4858.076965376567;4917.434495033117;5006.224035274538;5024.196128619404;5026.220935754964 15:48:46 INFO - PROCESS | 18278 | |1;ext.html;570.4723131103731;573.8049063318472;578.7713853028054;584.5206930317141;591.6124938165503;27175;27710;39175.60824391755;40013.39979900304;41495.37756933648;37283.440748388726;37695.623043769556;37856.43215351765;41227.175456490855;41355.79322103393;7921.049474063106;8067.435279530435;8072.152423995484;8074.232947869943;8078.798030049266;7523.510971786844;10117.544710488046;10365.844512332322;10375.066244038035;10747.62383316584;10412.271141020132;10500.894991050107;10509.054185123337;10517.78964420711;10530.78938421235;10275.229357798158;10384.480775961172;10392.272540922138;10399.116075133623;10399.272050956473;10253.179745620333;10320.01959813824;10334.483275836179;10339.844902326471;10349.172066234683;38488.61511384906;38956.61043389587;38977.220455591014;38993.22013559741;39038.80480597607;3094.04084733731;3129.264622813637;3190.744740420761;3229.5155726640955;3381.746369022333;29467.557986630218;29483.11550653484;29658.258543536584;29910.85044574785;30235.244118896982;3454.9309013819584;3470.410030294856;3476.600190978045;3481.268933523963;3552.8578856845697;190957;192019;192269;192886;192925;52052;52598.47401525936;52607.47392526026;52608.47391526112;52652.736736316074;54531.72734136304;54975.450245497035;54978;55094.44905550893;55102;14043.438262469592;14684.632884177767;14782.926085369505;14804.481843135438;14818.259087045604;9902.455364954852;10771.676849694522;11082.445877706245;11635.592754253561;11704.94147529257;15087.849121508863;15262.389504419922;15323.157226352434;15344.616384590474;15428.762829389676;5623.971880140655;5632.324121105412;5645.266115404971;5655.830325090254;5904.7342869570975;722.5973637654714;747.5477336211513;856.6359297298748;876.0626130040795;969.9515024248759;27751.30621734473;28455.71544284572;28711.13866583964;29119.27201819929;29181;932.0353434195575;967.3180407812475;991.8760154980628;1045.7281106912258;1049.9160067194607;14626.341814618369;14685;15036.248187590796;15250.618734531725;15440.922795385952;379841;380047;381033;382706;382887;50006.74996625067;50124.498755011984;50304;50376.24435633468;50529;1077.5420446310286;1132.5583022621183;1171.5255321594768;1209.0388141427586;1224.406163010939;26175.345616359744;29184.854075729487;29424.13450347856;29467;29915.850420747756;41287.04555453365;41760.58239417567;41844.58155418407;41852.37221441742;41883.371749423175;290836;290883;292852;293255;293498;30231.546526801674;34615;35584.28831423409;35759.82120089383;36626.45060324044;41340.37989430101;41507;41952;42428.787856060524;42786;34038.48942265818;35748.28503429865;36816.22251202059;36998.44502332521;37001.854842338704;36190.45714314235;36834;37013.44479832751;37126.814365927996;37399.25201496009;35642.46536302009;36141.0964725884;36148.63851361558;36732.632673672924;36755.63244367522;30877.38245235038;32945.835270823496;33218.16954576155;33244.1688957778;33414;25039.499210015332;25850.61224081678;25875.61186582165;25986.87006564955;26085.478290434465 15:48:46 INFO - PROCESS | 18278 | |2;jquery.html;343.8916741226515;369.5140889730003;377.8564145624663;379.8518577754676;381.4659476732574;12801.615951521457;16168.27242774075;16371.508854734348;17782.110894455258;17783.46649600511;16760.66478670426;17055.14724263785;17634.911825440886;17649.470515884543;17651.117444127773;5109.540141387265;5163.845084647466;5459.262999495077;5465.535429488498;5544.0575102232715;3557.484164796103;5313.203019547061;5525.060739674245;5613.578981576377;5650.5479561634975;5707.944030354377;5721.656700597978;5732.254806875121;5736.541076713853;5745.454181852739;5843.328017278025;6138.202033735631;6310.274318453367;6310.747570097191;6314.71583778731;3491.720662347006;5152.304438900738;5483.616146869721;5487.5884308677005;5562.638428502134;12634.936825315815;12913.67715807103;13033.674158146028;13149.802752958713;13255.536056237985;1785.6517978994102;1948.6005368899341;2008.829249513793;2130.275706259879;2264.0264686184933;9416.811663766686;9536.2371010319;9568.712938611852;9588.37675551087;9659.90340096604;1635.9591010224722;1636.5908522869283;1681.8990860548283;1735.1671197825115;1779.5462157149973;64853.675731621515;73187.26812731857;73641.2635873645;73912.26087739108;74530.62734686292;8740.169683880022;9295.72112836616;10200.183985281159;10265.178785697126;10296.845547316796;13700.520481783089;14109.082909610843;14159.71680566383;14170.645733856736;14215.36030878613;7095.787126386217;7187.78436646891;7314.96342518284;7448.5158464699625;7672.578008209602;6547.541672082908;6660.167479065117;6795.18457785059;6916.757913473004;7055.506114572034;4434.977825110918;4449.532799056117;4473.888152796206;4494.775261236925;4570.588647021784;1656.0394970916839;1689.3833750680913;1805.3049575913367;1814.7550080739195;1824.771903512061;799.8440304140753;816.1552792859362;851.5146366570995;881.4226681523612;896.8475359188823;15334.23328833554;16573.6685266294;16745;16968;17072.146392680315;830.3274347778319;838.1241602525375;875.2079368171815;892.8705337726124;906.6600024990629;9251.213646840093;9547.904520954702;9736;9823.508824558743;9848.50757462124;358234;359672;360229;364601;366776;46744;47182;73673;81232.18767812365;81241;899.6446403670644;917.0554329041098;922.6032805893531;938.0385105267019;943.1935694980692;15072.472463464164;15177.544673659808;15231.771523426936;15559;15846.524604261891;37104.628953711195;37508;37595.62404375921;37686.81156594199;37911.43132853065;53150.734246328524;53245.733771330895;53251.467485324654;53630;53692.73153634207;7232.783016509513;7239.529430586995;7284.235155308616;7401.555906645618;7494.175640679412;5631.042722737063;9694.175995040501;10804.837927431252;10822.51298691545;10957.671269861918;9446.29152813528;9674.129328360481;9700.563474643517;9865.210783137189;9971.152452041368;9417.952910235404;9605.423674579548;9625.855612165964;9723;9737.318387712792;9526.190273826525;9847.803043938939;9930.702078937644;9936.751581210528;10010.14913732341;7346;7377.188509264084;7521.247875212435;7577.734779282699;7623.123340815655;8877;9172.35793494449;9250.167484926389;9362.251019918527;9434.198093162158 15:48:46 INFO - PROCESS | 18278 | |3;mootools.html;1222.7615614955075;1331.9067665263428;1388.1254809470033;1437.547172640618;1673.196865504557;6375.744970201192;7216.603086830222;7401.62991850408;7413.073365829271;7530.62346882655;7316.707331706739;7414.073240844895;7422.035135432387;7423.331900128987;7454.068241469817;2184.170015394148;2209.5801797658473;2210.9557808843815;2211.0492488230057;2211.4139752965475;2175.9456013599706;2203.6143674856917;2209.0611490116717;2209.569134018867;2216.769692820484;2181.9345419637434;2187.21260346276;2195.7913998170147;2196.121551379445;2223.1774243529917;2093.3092079613843;2093.64408050631;2101.957960840775;2102.0015492640873;2103.7685854555984;2094.675325324577;2145.6352420088624;2146.431195733132;2146.6779983002675;2149.0544160569457;7185.425165986709;7397.445191610597;7398.519096258779;7404.629768511553;7463.328300452931;4383.60547550722;4481.327800829869;4484.663650226246;4486.865394038184;4487.39420178275;2777.1390868830526;2827.137722994486;2891.5662650602367;2898.159533735214;2907.8836846526115;827.8302947895667;840.7855996720858;842.5871323051722;1611.4440518021263;1630.380455426942;9820.459874706889;9965.70102896907;9988.151007164472;9997.350172238854;10027.749306267402;4077.89805254871;4104.117614712792;4233.25917964355;4236.809343579546;4236.978815105905;8516.531590762444;8527.23254907049;8530.530820804915;8537.445066070686;8588.141185881486;4583.747893865802;4594.6783725139585;4605.125026245003;4605.884852878705;4606.516315786861;3623.3115708015393;3995.4606128172904;4178.039051018282;4253.3619957006695;4258.659307255412;3496;3590.9461358079648;3659.249853779969;3672.4674922136674;3691.427828686558;1121.1535290855352;1173.6244401791346;1181.2085902445383;1185.9407029648482;1199.1725709260581;827.759949614605;830.7341650671727;837.2130197614223;847.7329641163137;857.1300130367682;4857.081452826184;4972.254161875748;5057.241413787888;5067.265246539231;5142.61430392722;400.6754528831656;401.49612236642827;410.20625090450073;411.3069477929698;413.6091393633048;3560.2701446203464;3616.27674465108;3645.070507020746;3686.3733165361955;3687.0597997510463;8721.520316382534;9136.771580710536;9147.634094636272;9173.678921237724;9188.40275382098;8855.424397414276;9060.184583387396;9062.81874362509;9080.137386948361;9082.727518174466;6823.761168359084;6955.200151982587;7024.332688394595;7026.683799229047;7043.471739619549;4646.279826626878;4800.711957282574;4831.73425461603;4860.513948605253;4886.609071274361;8480.703175388831;8527.488350698977;8550.914490855012;8583.055863855096;8599.26906212979;8024.117347091932;8355.997280326399;8363;8571.571421428778;8625.39622226438;5716.114002329646;6292.874142517216;6428.839279018062;6507.446867016357;6612.570182938093;7270.854582908418;7277.090363704537;7329.267073292842;7338.486305958531;7345.669444874887;6395.104685343963;6603.141591593254;6621.404073633396;6697.631630260383;6728.966355168192;6616.338366163345;6630.83422914431;6666.100076489612;6692.330766923464;6747.628880411625;6110.236220472441;6125.050617154348;6149.877002460015;6154.876902461836;6161.336439161491;4400.757958312323;4402.229609818205;4467.084247729273;4476.9328460073775;4559.680822342537;4437.467503899424;4464.642828573771;4484.75333856641;4498.797554110139;4534.7052441591195 15:48:46 INFO - PROCESS | 18278 | |4;prototype.html;293.33266817989073;294.76418864908084;299.4355639619317;301.37013641489284;302.06811985026184;4882.292067650193;4944.975275123628;4969.105560999019;4972.875678108049;4976.402831660202;4849.709017458955;4906.632002599801;4906.828261010865;4917.361152746968;4920.655554111205;3635.091227193195;3745.8314375853015;3767.3595488766973;3864.333609809237;3897.5834982310867;3851.5378154621485;3852.8266228019806;3860.1471293779005;3892.8053597320163;3905.6875449964077;3613.602503724588;3827.674647654939;3835.712321575879;3840.0015995841004;3859.8842034739005;3680.3191409589176;3814.5803961564206;3816.2367526494672;3818.3317919364;3864.652181303696;3373.4939759036342;3374.8818791342433;3375.8987230383127;3407.114150320934;3411.30068335993;4748.601956012191;4750.263709125091;4764.285357196413;4783.4499032611;4784.712917224978;4237.385579091046;4321.7406955582765;4332.184080347799;4385.386045953569;4392.780360981938;5057.140286151363;5058.772355244023;5077.923831142536;5098.337216161875;5117.1556693145585;3676.356637588411;3692.1508053147927;3712.2204337089242;3723.4600982857605;3724.7020238380865;5170.224466330081;5275.235090911796;5329.2006199070065;5360.3299587551555;5360.909245394299;3717.75834570752;3727.440883867387;3743.8689645862264;3752.3114598430816;3758.774473531597;3781.829817658157;3786.413105968524;3786.67813235878;3810.237952409529;3811.2187001664593;4318.201132790444;4328.415663885336;4332.891677708019;4340.414044104069;4358.128374325148;4925.975370123198;5040.2691609716385;5057.797688092435;5066.36670416198;5098.311727916684;1300.9544665936646;1311.1936159262175;1311.2198242045968;1315.3686230609362;1315.3883444198493;867.1415298854215;872.8739925496166;915.665781989569;928.9349745517749;941.8351788436996;4370.497392799869;4439.156560253541;4460.174867649431;4461.13007963444;4463.2858742601675;5160.819371321985;5186.585073194211;5195.2986346843445;5196.76614552346;5224.712640804716;4308.784560772012;4408.140412619572;4437.755923424178;4440.644748420119;4589.885252868705;2792.5531914893927;2816.3240822202524;2822.209781261209;2829.571066611389;2839.872205750746;4939.90120197594;4951.9752401237765;4972.801087956514;4989.226669866177;4992.450830408652;5197.674592978842;5444.863878402993;5446.264754258124;5469.644473109315;5477.534409575151;4944.456109827845;4948.901021979612;4951.192814622636;4989.351384320015;4997.625178111656;5284.5995811109315;5291.682499050069;5329.573634109184;5336.17289320156;5338.332708411449;4927.655064145475;5192.299039629753;5210.270562121382;5219.7211683137875;5229.163333866563;4937.5556199942175;4963.553280204799;4972.403311602631;4975.776090076028;4978.975105124451;3698.8150592470806;3728.8322025508373;3730.645588669072;3735.103575142001;3737.5141231639714;5103.5661968733075;5192.766325515437;5228.37259528859;5237.397699264481;5333.973330133324;5069.1635880079375;5095.464976177448;5103.821366252163;5118.181091025567;5123.359580052494;5092.694438333712;5122.820701275437;5143.682324900344;5155.252488389087;5203.765830537711;4813.95186048149;4964.67729597575;4968.975155124202;5015.247712843103;5016.824411145592;4961.231009193581;5060.84817455477;5064.670796398223;5069.958025184859;5070.873228169326;4976.825811096594;4997.150484417585;5020.393474088257;5031.949680503295;5032.169692000925 15:48:46 INFO - PROCESS | 18278 | |5;yui.html;597.9880402391952;628.9119523266744;666.6466772610515;699.7061234281601;734.467511054486;11520.251183673066;12237.93881030596;12241.877581224186;12308.753824923497;12328.4452199651;11317;11348.205625606211;11370.431478426088;11372.431378431065;11378.431078446087;622.6233128956984;768.2010708862776;791.2878409431501;791.2917938445088;797.3939805747644;782.6673663692936;788.2117882117882;790.8022994251437;791.956442395668;791.9643616037292;650.8730797494491;839.1398816213369;841.3605659698643;841.4488510025913;841.8148007438353;660.3363619562333;668.7191379620571;669.0299066353806;801.7193982106274;813.5850716134755;611.6972098811073;611.960222585535;611.9847003824896;612.2224774536359;613.1201725523888;11863.88136118645;11876.16866819323;11901.047916166686;11904.16670833042;11953.701157471132;12502.624921252376;12766.489340426371;12893;12986.675333116747;13057.086003979724;11608.825867611993;11644.126690498246;11658.475368608433;11721.238119522204;12127.575534856238;4101.487314085739;4133.751974881517;4140.958590414088;4190;4287.121140166259;62179.68910155466;62288.688556556925;62292.68853655703;62348.37651623471;62364;9597.520123993772;9687.56511127207;10041.748956276151;10089.596416143271;10169;18074.883783101195;18385;18519.814801852077;18562.350317738816;18572.535686607673;6535.117759102555;6655;6729.899051514231;6841.418479429205;6889.586624802528;3602.171500554887;3817.1411432428035;3821.7898015608853;3845.192509573021;3897.2010737798682;2654.362952891292;2740.5067087923967;2763.281546797847;2763.5440152375036;2764.060219525371;499.5079846351341;503.55183886341183;508.6058304813799;509.6025100421677;517.4024002277375;523.4320761973302;546.8578169675833;588.0003993211473;626.4205609810907;629.4932579273637;7735.226477352332;8017.879731804028;8283.95858020706;8676.08901065379;8950.955245223733;547.9150731636603;630.3435718809296;640.2157357237347;646.890028695123;659.3538332434146;3897.57126716061;4014.578469260744;4061.6953728470485;4082.081531655413;4193.832246710159;53685.731571341894;53770.35411281759;53814.7309263459;54270.45729542654;71351;84259;85133;85334.14665853386;85854.57072714597;86578.56710716532;71821.64089179625;74081.62959185278;74944;75310;76333.61833190799;7744.303012728881;7763.417743669134;7915.1293357729455;8004.679812807422;8046.316063134465;39948;45047.32429013633;45129.548704512534;45165.09669806651;45177;54549.45450545602;59589;61256;61959.38040619536;62114.37885621086;11411.289121085434;11539.538418463168;11837.526498939946;11895.643130706092;11924;12452.439640216393;12553.058520611174;12981.675458113623;13398.531051413544;13689.794653080013;11665.825012624648;11893.167478276437;12370.381480925738;12501.24992500453;12588.685282868002;12705.36473176356;12805.935970320461;12890.35548222604;12904.16122952005;13205.801912971505;12261.203021803554;12594.181378210387;12807.743845122859;13092.279924604238;13245.80131298012;9792.706218813448;10024.448655324028;10077.143442807443;10294.691159265234;10560.260781745512;10082.69751907444;10175.84736228972;10566.41884696349;10739;10775.137988961022 15:48:46 INFO - PROCESS | 18278 | __end_tp_report 15:48:46 INFO - PROCESS | 18278 | __start_cc_report 15:48:46 INFO - PROCESS | 18278 | _x_x_mozilla_cycle_collect,1129 15:48:46 INFO - PROCESS | 18278 | __end_cc_report 15:48:46 INFO - PROCESS | 18278 | __startTimestamp1468277326631__endTimestamp 15:48:46 INFO - PROCESS | 18278 | 15:48:46 INFO - PROCESS | 18278 | ------- Summary: start ------- 15:48:46 INFO - PROCESS | 18278 | Number of tests: 6 15:48:46 INFO - PROCESS | 18278 | 15:48:46 INFO - PROCESS | 18278 | [#0] dojo.html Cycles:165 Average:4764.98 Median:4865.66 stddev:510.06 (10.5%) stddev-sans-first:482.76 15:48:46 INFO - PROCESS | 18278 | Values: 2608.6 2634.9 2671.8 2681.4 2686.0 4708.6 4761.7 4775.6 4844.7 4893.3 4747.7 4752.2 4754.1 4806.0 4835.4 4304.5 4359.2 4390.4 4413.6 4534.1 4472.7 4497.5 4508.8 4538.6 4557.3 4300.4 4355.8 4358.9 4362.6 4373.9 4405.6 4414.1 4433.9 4438.3 4474.5 4445.4 4459.4 4463.6 4464.7 4496.1 4619.8 4761.1 4834.6 4888.2 4935.7 5255.8 5256.6 5262.4 5295.5 5301.2 5201.5 5293.8 5308.2 5310.3 5355.6 4705.2 4736.0 4779.7 4788.4 4981.7 5283.8 5294.6 5309.9 5311.5 5314.7 5074.4 5084.3 5090.2 5092.0 5092.4 5016.7 5132.9 5212.8 5216.6 5296.3 5175.4 5199.6 5203.8 5206.6 5207.2 4947.3 5190.5 5195.6 5199.0 5229.6 4328.4 4371.8 4446.6 4453.2 4495.1 3866.0 3867.7 3869.9 3904.7 3909.3 4425.0 4432.5 4432.7 4434.9 4480.0 4951.9 5055.9 5093.0 5121.7 5158.1 4345.1 4367.0 4368.9 4369.5 4419.8 4510.7 4588.8 4641.3 4648.1 4678.7 4792.2 4855.6 4860.6 4865.7 4870.9 5197.8 5207.0 5209.9 5211.2 5264.6 4662.5 4765.4 4816.2 4835.6 4894.3 5073.0 5105.3 5126.9 5128.7 5168.2 5147.1 5172.9 5177.2 5183.4 5233.9 4881.0 4902.7 4963.7 5054.0 5077.9 4978.3 4998.5 5000.3 5005.0 5040.8 4934.9 4943.0 4987.9 4993.6 4996.0 4813.5 4854.1 4855.8 4905.1 4906.4 4858.1 4917.4 5006.2 5024.2 5026.2 15:48:46 INFO - PROCESS | 18278 | 15:48:46 INFO - PROCESS | 18278 | [#1] ext.html Cycles:180 Average:45302.10 Median:19042.71 stddev:78534.37 (412.4%) stddev-sans-first:78682.86 15:48:46 INFO - PROCESS | 18278 | Values: 570.5 573.8 578.8 584.5 591.6 27175.0 27710.0 39175.6 40013.4 41495.4 37283.4 37695.6 37856.4 41227.2 41355.8 7921.0 8067.4 8072.2 8074.2 8078.8 7523.5 10117.5 10365.8 10375.1 10747.6 10412.3 10500.9 10509.1 10517.8 10530.8 10275.2 10384.5 10392.3 10399.1 10399.3 10253.2 10320.0 10334.5 10339.8 10349.2 38488.6 38956.6 38977.2 38993.2 39038.8 3094.0 3129.3 3190.7 3229.5 3381.7 29467.6 29483.1 29658.3 29910.9 30235.2 3454.9 3470.4 3476.6 3481.3 3552.9 190957.0 192019.0 192269.0 192886.0 192925.0 52052.0 52598.5 52607.5 52608.5 52652.7 54531.7 54975.5 54978.0 55094.4 55102.0 14043.4 14684.6 14782.9 14804.5 14818.3 9902.5 10771.7 11082.4 11635.6 11704.9 15087.8 15262.4 15323.2 15344.6 15428.8 5624.0 5632.3 5645.3 5655.8 5904.7 722.6 747.5 856.6 876.1 970.0 27751.3 28455.7 28711.1 29119.3 29181.0 932.0 967.3 991.9 1045.7 1049.9 14626.3 14685.0 15036.2 15250.6 15440.9 379841.0 380047.0 381033.0 382706.0 382887.0 50006.7 50124.5 50304.0 50376.2 50529.0 1077.5 1132.6 1171.5 1209.0 1224.4 26175.3 29184.9 29424.1 29467.0 29915.9 41287.0 41760.6 41844.6 41852.4 41883.4 290836.0 290883.0 292852.0 293255.0 293498.0 30231.5 34615.0 35584.3 35759.8 36626.5 41340.4 41507.0 41952.0 42428.8 42786.0 34038.5 35748.3 36816.2 36998.4 37001.9 36190.5 36834.0 37013.4 37126.8 37399.3 35642.5 36141.1 36148.6 36732.6 36755.6 30877.4 32945.8 33218.2 33244.2 33414.0 25039.5 25850.6 25875.6 25986.9 26085.5 15:48:46 INFO - PROCESS | 18278 | 15:48:46 INFO - PROCESS | 18278 | [#2] jquery.html Cycles:180 Average:23048.25 Median:5553.35 stddev:59914.83 (1078.9%) stddev-sans-first:60058.65 15:48:46 INFO - PROCESS | 18278 | Values: 343.9 369.5 377.9 379.9 381.5 12801.6 16168.3 16371.5 17782.1 17783.5 16760.7 17055.1 17634.9 17649.5 17651.1 5109.5 5163.8 5459.3 5465.5 5544.1 3557.5 5313.2 5525.1 5613.6 5650.5 5707.9 5721.7 5732.3 5736.5 5745.5 5843.3 6138.2 6310.3 6310.7 6314.7 3491.7 5152.3 5483.6 5487.6 5562.6 12634.9 12913.7 13033.7 13149.8 13255.5 1785.7 1948.6 2008.8 2130.3 2264.0 9416.8 9536.2 9568.7 9588.4 9659.9 1636.0 1636.6 1681.9 1735.2 1779.5 64853.7 73187.3 73641.3 73912.3 74530.6 8740.2 9295.7 10200.2 10265.2 10296.8 13700.5 14109.1 14159.7 14170.6 14215.4 7095.8 7187.8 7315.0 7448.5 7672.6 6547.5 6660.2 6795.2 6916.8 7055.5 4435.0 4449.5 4473.9 4494.8 4570.6 1656.0 1689.4 1805.3 1814.8 1824.8 799.8 816.2 851.5 881.4 896.8 15334.2 16573.7 16745.0 16968.0 17072.1 830.3 838.1 875.2 892.9 906.7 9251.2 9547.9 9736.0 9823.5 9848.5 358234.0 359672.0 360229.0 364601.0 366776.0 46744.0 47182.0 73673.0 81232.2 81241.0 899.6 917.1 922.6 938.0 943.2 15072.5 15177.5 15231.8 15559.0 15846.5 37104.6 37508.0 37595.6 37686.8 37911.4 53150.7 53245.7 53251.5 53630.0 53692.7 7232.8 7239.5 7284.2 7401.6 7494.2 5631.0 9694.2 10804.8 10822.5 10957.7 9446.3 9674.1 9700.6 9865.2 9971.2 9418.0 9605.4 9625.9 9723.0 9737.3 9526.2 9847.8 9930.7 9936.8 10010.1 7346.0 7377.2 7521.2 7577.7 7623.1 8877.0 9172.4 9250.2 9362.3 9434.2 15:48:46 INFO - PROCESS | 18278 | 15:48:46 INFO - PROCESS | 18278 | [#3] mootools.html Cycles:180 Average:4931.58 Median:4844.41 stddev:2695.65 (55.6%) stddev-sans-first:2688.80 15:48:46 INFO - PROCESS | 18278 | Values: 1222.8 1331.9 1388.1 1437.5 1673.2 6375.7 7216.6 7401.6 7413.1 7530.6 7316.7 7414.1 7422.0 7423.3 7454.1 2184.2 2209.6 2211.0 2211.0 2211.4 2175.9 2203.6 2209.1 2209.6 2216.8 2181.9 2187.2 2195.8 2196.1 2223.2 2093.3 2093.6 2102.0 2102.0 2103.8 2094.7 2145.6 2146.4 2146.7 2149.1 7185.4 7397.4 7398.5 7404.6 7463.3 4383.6 4481.3 4484.7 4486.9 4487.4 2777.1 2827.1 2891.6 2898.2 2907.9 827.8 840.8 842.6 1611.4 1630.4 9820.5 9965.7 9988.2 9997.4 10027.7 4077.9 4104.1 4233.3 4236.8 4237.0 8516.5 8527.2 8530.5 8537.4 8588.1 4583.7 4594.7 4605.1 4605.9 4606.5 3623.3 3995.5 4178.0 4253.4 4258.7 3496.0 3590.9 3659.2 3672.5 3691.4 1121.2 1173.6 1181.2 1185.9 1199.2 827.8 830.7 837.2 847.7 857.1 4857.1 4972.3 5057.2 5067.3 5142.6 400.7 401.5 410.2 411.3 413.6 3560.3 3616.3 3645.1 3686.4 3687.1 8721.5 9136.8 9147.6 9173.7 9188.4 8855.4 9060.2 9062.8 9080.1 9082.7 6823.8 6955.2 7024.3 7026.7 7043.5 4646.3 4800.7 4831.7 4860.5 4886.6 8480.7 8527.5 8550.9 8583.1 8599.3 8024.1 8356.0 8363.0 8571.6 8625.4 5716.1 6292.9 6428.8 6507.4 6612.6 7270.9 7277.1 7329.3 7338.5 7345.7 6395.1 6603.1 6621.4 6697.6 6729.0 6616.3 6630.8 6666.1 6692.3 6747.6 6110.2 6125.1 6149.9 6154.9 6161.3 4400.8 4402.2 4467.1 4476.9 4559.7 4437.5 4464.6 4484.8 4498.8 4534.7 15:48:46 INFO - PROCESS | 18278 | 15:48:46 INFO - PROCESS | 18278 | [#4] prototype.html Cycles:180 Average:4249.17 Median:4919.01 stddev:1224.82 (24.9%) stddev-sans-first:1191.73 15:48:46 INFO - PROCESS | 18278 | Values: 293.3 294.8 299.4 301.4 302.1 4882.3 4945.0 4969.1 4972.9 4976.4 4849.7 4906.6 4906.8 4917.4 4920.7 3635.1 3745.8 3767.4 3864.3 3897.6 3851.5 3852.8 3860.1 3892.8 3905.7 3613.6 3827.7 3835.7 3840.0 3859.9 3680.3 3814.6 3816.2 3818.3 3864.7 3373.5 3374.9 3375.9 3407.1 3411.3 4748.6 4750.3 4764.3 4783.4 4784.7 4237.4 4321.7 4332.2 4385.4 4392.8 5057.1 5058.8 5077.9 5098.3 5117.2 3676.4 3692.2 3712.2 3723.5 3724.7 5170.2 5275.2 5329.2 5360.3 5360.9 3717.8 3727.4 3743.9 3752.3 3758.8 3781.8 3786.4 3786.7 3810.2 3811.2 4318.2 4328.4 4332.9 4340.4 4358.1 4926.0 5040.3 5057.8 5066.4 5098.3 1301.0 1311.2 1311.2 1315.4 1315.4 867.1 872.9 915.7 928.9 941.8 4370.5 4439.2 4460.2 4461.1 4463.3 5160.8 5186.6 5195.3 5196.8 5224.7 4308.8 4408.1 4437.8 4440.6 4589.9 2792.6 2816.3 2822.2 2829.6 2839.9 4939.9 4952.0 4972.8 4989.2 4992.5 5197.7 5444.9 5446.3 5469.6 5477.5 4944.5 4948.9 4951.2 4989.4 4997.6 5284.6 5291.7 5329.6 5336.2 5338.3 4927.7 5192.3 5210.3 5219.7 5229.2 4937.6 4963.6 4972.4 4975.8 4979.0 3698.8 3728.8 3730.6 3735.1 3737.5 5103.6 5192.8 5228.4 5237.4 5334.0 5069.2 5095.5 5103.8 5118.2 5123.4 5092.7 5122.8 5143.7 5155.3 5203.8 4814.0 4964.7 4969.0 5015.2 5016.8 4961.2 5060.8 5064.7 5070.0 5070.9 4976.8 4997.2 5020.4 5031.9 5032.2 15:48:46 INFO - PROCESS | 18278 | 15:48:46 INFO - PROCESS | 18278 | [#5] yui.html Cycles:180 Average:16637.31 Median:45171.05 stddev:22452.55 (49.7%) stddev-sans-first:22483.23 15:48:46 INFO - PROCESS | 18278 | Values: 598.0 628.9 666.6 699.7 734.5 11520.3 12237.9 12241.9 12308.8 12328.4 11317.0 11348.2 11370.4 11372.4 11378.4 622.6 768.2 791.3 791.3 797.4 782.7 788.2 790.8 792.0 792.0 650.9 839.1 841.4 841.4 841.8 660.3 668.7 669.0 801.7 813.6 611.7 612.0 612.0 612.2 613.1 11863.9 11876.2 11901.0 11904.2 11953.7 12502.6 12766.5 12893.0 12986.7 13057.1 11608.8 11644.1 11658.5 11721.2 12127.6 4101.5 4133.8 4141.0 4190.0 4287.1 62179.7 62288.7 62292.7 62348.4 62364.0 9597.5 9687.6 10041.7 10089.6 10169.0 18074.9 18385.0 18519.8 18562.4 18572.5 6535.1 6655.0 6729.9 6841.4 6889.6 3602.2 3817.1 3821.8 3845.2 3897.2 2654.4 2740.5 2763.3 2763.5 2764.1 499.5 503.6 508.6 509.6 517.4 523.4 546.9 588.0 626.4 629.5 7735.2 8017.9 8284.0 8676.1 8951.0 547.9 630.3 640.2 646.9 659.4 3897.6 4014.6 4061.7 4082.1 4193.8 53685.7 53770.4 53814.7 54270.5 71351.0 84259.0 85133.0 85334.1 85854.6 86578.6 71821.6 74081.6 74944.0 75310.0 76333.6 7744.3 7763.4 7915.1 8004.7 8046.3 39948.0 45047.3 45129.5 45165.1 45177.0 54549.5 59589.0 61256.0 61959.4 62114.4 11411.3 11539.5 11837.5 11895.6 11924.0 12452.4 12553.1 12981.7 13398.5 13689.8 11665.8 11893.2 12370.4 12501.2 12588.7 12705.4 12805.9 12890.4 12904.2 13205.8 12261.2 12594.2 12807.7 13092.3 13245.8 9792.7 10024.4 10077.1 10294.7 10560.3 10082.7 10175.8 10566.4 10739.0 10775.1 15:48:46 INFO - PROCESS | 18278 | -------- Summary: end -------- 15:48:46 INFO - PROCESS | 18278 | 15:48:47 INFO - TEST-INFO | 18278: exit 0 15:48:47 INFO - TEST-OK | dromaeo_css | took 1120992ms 15:48:47 INFO - TEST-START | kraken 15:48:47 INFO - operating with platform_type : linux_ 15:48:47 INFO - Initialising browser for kraken test... 15:48:47 INFO - TEST-INFO | started process 18469 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAwOgyY/profile http://localhost:54519/getInfo.html) 15:48:52 INFO - PROCESS | 18469 | __metrics Screen width/height:1600/1200 15:48:52 INFO - PROCESS | 18469 | colorDepth:24 15:48:52 INFO - PROCESS | 18469 | Browser inner width/height: 1024/697 15:48:52 INFO - PROCESS | 18469 | __metrics 15:48:53 INFO - PROCESS | 18469 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:48:53 INFO - PROCESS | 18469 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:48:54 INFO - TEST-INFO | 18469: exit 0 15:48:54 INFO - Browser initialized. 15:48:54 INFO - Running cycle 1/1 for kraken test... 15:48:54 INFO - TEST-INFO | started process 18548 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAwOgyY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:49:06 INFO - PROCESS | 18548 | RSS: Main: 158638080 15:49:06 INFO - PROCESS | 18548 | 15:49:09 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54519/tests/kraken/driver.html?testName=audio-beat-detection) 15:49:10 INFO - PROCESS | 18548 | RSS: Main: 217538560 15:49:10 INFO - PROCESS | 18548 | 15:49:12 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54519/tests/kraken/driver.html?testName=audio-dft) 15:49:13 INFO - PROCESS | 18548 | RSS: Main: 203239424 15:49:13 INFO - PROCESS | 18548 | 15:49:16 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54519/tests/kraken/driver.html?testName=audio-fft) 15:49:17 INFO - PROCESS | 18548 | RSS: Main: 270921728 15:49:17 INFO - PROCESS | 18548 | 15:49:19 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54519/tests/kraken/driver.html?testName=audio-oscillator) 15:49:20 INFO - PROCESS | 18548 | RSS: Main: 181235712 15:49:20 INFO - PROCESS | 18548 | 15:49:22 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54519/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:49:22 INFO - PROCESS | 18548 | RSS: Main: 185004032 15:49:22 INFO - PROCESS | 18548 | 15:49:26 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54519/tests/kraken/driver.html?testName=imaging-darkroom) 15:49:26 INFO - PROCESS | 18548 | RSS: Main: 221388800 15:49:26 INFO - PROCESS | 18548 | 15:49:30 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54519/tests/kraken/driver.html?testName=imaging-desaturate) 15:49:30 INFO - PROCESS | 18548 | RSS: Main: 217513984 15:49:30 INFO - PROCESS | 18548 | 15:49:33 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54519/tests/kraken/driver.html?testName=json-parse-financial) 15:49:34 INFO - PROCESS | 18548 | RSS: Main: 220217344 15:49:34 INFO - PROCESS | 18548 | 15:49:35 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54519/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:49:36 INFO - PROCESS | 18548 | RSS: Main: 207364096 15:49:36 INFO - PROCESS | 18548 | 15:49:38 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54519/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:49:39 INFO - PROCESS | 18548 | RSS: Main: 211222528 15:49:39 INFO - PROCESS | 18548 | 15:49:41 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54519/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:49:41 INFO - PROCESS | 18548 | RSS: Main: 211181568 15:49:41 INFO - PROCESS | 18548 | 15:49:44 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54519/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:49:45 INFO - PROCESS | 18548 | RSS: Main: 225959936 15:49:45 INFO - PROCESS | 18548 | 15:49:48 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54519/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:49:48 INFO - PROCESS | 18548 | RSS: Main: 226615296 15:49:48 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | Cycle 1(1): loaded http://localhost:54519/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54519/tests/kraken/driver.html?testName=ai-astar) 15:49:50 INFO - PROCESS | 18548 | RSS: Main: 269455360 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | __start_tp_report 15:49:50 INFO - PROCESS | 18548 | _x_x_mozilla_page_load 15:49:50 INFO - PROCESS | 18548 | _x_x_mozilla_page_load_details 15:49:50 INFO - PROCESS | 18548 | |i|pagename|runs| 15:49:50 INFO - PROCESS | 18548 | |0;ai-astar;106;116;114;114;114;113;114;113;113;114 15:49:50 INFO - PROCESS | 18548 | |1;audio-beat-detection;147;122;125;174;123;123;143;121;186;141 15:49:50 INFO - PROCESS | 18548 | |2;audio-dft;163;161;161;162;161;161;162;160;163;160 15:49:50 INFO - PROCESS | 18548 | |3;audio-fft;77;75;76;109;76;77;76;76;97;77 15:49:50 INFO - PROCESS | 18548 | |4;audio-oscillator;85;87;115;85;85;86;122;85;86;123 15:49:50 INFO - PROCESS | 18548 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;116;117 15:49:50 INFO - PROCESS | 18548 | |6;imaging-darkroom;147;147;147;148;148;146;147;148;147;147 15:49:50 INFO - PROCESS | 18548 | |7;imaging-desaturate;95;95;95;94;95;95;95;94;95;95 15:49:50 INFO - PROCESS | 18548 | |8;json-parse-financial;65;63;63;62;63;64;62;63;64;62 15:49:50 INFO - PROCESS | 18548 | |9;json-stringify-tinderbox;65;65;65;69;64;65;64;65;64;64 15:49:50 INFO - PROCESS | 18548 | |10;stanford-crypto-aes;79;80;79;85;79;78;77;85;79;88 15:49:50 INFO - PROCESS | 18548 | |11;stanford-crypto-ccm;134;129;127;139;138;136;129;132;216;131 15:49:50 INFO - PROCESS | 18548 | |12;stanford-crypto-pbkdf2;169;170;166;172;164;166;164;165;166;167 15:49:50 INFO - PROCESS | 18548 | |13;stanford-crypto-sha256-iterative;67;69;68;67;67;68;67;69;66;71 15:49:50 INFO - PROCESS | 18548 | __end_tp_report 15:49:50 INFO - PROCESS | 18548 | __start_cc_report 15:49:50 INFO - PROCESS | 18548 | _x_x_mozilla_cycle_collect,1924 15:49:50 INFO - PROCESS | 18548 | __end_cc_report 15:49:50 INFO - PROCESS | 18548 | __startTimestamp1468277390867__endTimestamp 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | ------- Summary: start ------- 15:49:50 INFO - PROCESS | 18548 | Number of tests: 14 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:2.64 (2.3%) stddev-sans-first:0.93 15:49:50 INFO - PROCESS | 18548 | Values: 106.0 116.0 114.0 114.0 114.0 113.0 114.0 113.0 113.0 114.0 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:142.00 stddev:23.13 (16.3%) stddev-sans-first:24.42 15:49:50 INFO - PROCESS | 18548 | Values: 147.0 122.0 125.0 174.0 123.0 123.0 143.0 121.0 186.0 141.0 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.07 (0.7%) stddev-sans-first:0.97 15:49:50 INFO - PROCESS | 18548 | Values: 163.0 161.0 161.0 162.0 161.0 161.0 162.0 160.0 163.0 160.0 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#3] audio-fft Cycles:10 Average:81.60 Median:76.50 stddev:11.64 (15.2%) stddev-sans-first:12.23 15:49:50 INFO - PROCESS | 18548 | Values: 77.0 75.0 76.0 109.0 76.0 77.0 76.0 76.0 97.0 77.0 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:85.00 stddev:16.77 (19.7%) stddev-sans-first:17.32 15:49:50 INFO - PROCESS | 18548 | Values: 85.0 87.0 115.0 85.0 85.0 86.0 122.0 85.0 86.0 123.0 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:49:50 INFO - PROCESS | 18548 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 15:49:50 INFO - PROCESS | 18548 | Values: 147.0 147.0 147.0 148.0 148.0 146.0 147.0 148.0 147.0 147.0 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:49:50 INFO - PROCESS | 18548 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:63.00 stddev:0.99 (1.6%) stddev-sans-first:0.78 15:49:50 INFO - PROCESS | 18548 | Values: 65.0 63.0 63.0 62.0 63.0 64.0 62.0 63.0 64.0 62.0 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:1.49 (2.3%) stddev-sans-first:1.58 15:49:50 INFO - PROCESS | 18548 | Values: 65.0 65.0 65.0 69.0 64.0 65.0 64.0 65.0 64.0 64.0 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:79.50 stddev:3.70 (4.6%) stddev-sans-first:3.86 15:49:50 INFO - PROCESS | 18548 | Values: 79.0 80.0 79.0 85.0 79.0 78.0 77.0 85.0 79.0 88.0 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#11] stanford-crypto-ccm Cycles:10 Average:141.10 Median:135.00 stddev:26.62 (19.7%) stddev-sans-first:28.11 15:49:50 INFO - PROCESS | 18548 | Values: 134.0 129.0 127.0 139.0 138.0 136.0 129.0 132.0 216.0 131.0 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:166.50 stddev:2.64 (1.6%) stddev-sans-first:2.69 15:49:50 INFO - PROCESS | 18548 | Values: 169.0 170.0 166.0 172.0 164.0 166.0 164.0 165.0 166.0 167.0 15:49:50 INFO - PROCESS | 18548 | 15:49:50 INFO - PROCESS | 18548 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:1.45 (2.1%) stddev-sans-first:1.50 15:49:50 INFO - PROCESS | 18548 | Values: 67.0 69.0 68.0 67.0 67.0 68.0 67.0 69.0 66.0 71.0 15:49:50 INFO - PROCESS | 18548 | -------- Summary: end -------- 15:49:50 INFO - PROCESS | 18548 | 15:49:52 INFO - TEST-INFO | 18548: exit 0 15:49:52 INFO - TEST-OK | kraken | took 64691ms 15:49:52 INFO - SUITE-END | took 1185s 15:49:52 INFO - Completed test suite (00:19:46) 15:49:52 INFO - javascript benchmark 15:49:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2608.5956676715105, 2634.881430335637, 2671.826331288467, 2681.3698780786513, 2685.9865700671494, 4708.576228139466, 4761.69049011814, 4775.570198682127, 4844.66087373884, 4893.33939918111, 4747.715137091777, 4752.192127338352, 4754.120487709776, 4805.951940480594, 4835.443923948747, 4304.483461984566, 4359.193549193409, 4390.40729501518, 4413.646908247332, 4534.13851368239, 4472.686911916167, 4497.505274419811, 4508.77456127194, 4538.568835960595, 4557.2708366661345, 4300.440942677432, 4355.825766969318, 4358.8910277243, 4362.585554372329, 4373.868783936487, 4405.625521830648, 4414.095110502371, 4433.889152771174, 4438.267685831834, 4474.507804141542, 4445.377647129404, 4459.420275364214, 4463.553644635543, 4464.6874718769695, 4496.123255965088, 4619.838305659319, 4761.0715910397485, 4834.637402194814, 4888.217885138396, 4935.679180853233, 5255.816046438395, 5256.632035757499, 5262.447443018466, 5295.4969277918535, 5301.204819277101, 5201.453847346012, 5293.788248470095, 5308.177232528964, 5310.256564081032, 5355.571554275688, 4705.247160454311, 4735.976320118378, 4779.71321720691, 4788.353572267769, 4981.701097934172, 5283.841484755463, 5294.629375943647, 5309.867253318621, 5311.52196302327, 5314.734263286898, 5074.416442109131, 5084.262781896604, 5090.185570308733, 5092.0, 5092.414372347227, 5016.673916195436, 5132.948670513321, 5212.843614691566, 5216.634835561475, 5296.311479507639, 5175.353080864892, 5199.55803756685, 5203.817866374735, 5206.635535512553, 5207.1668533034535, 4947.332110165154, 5190.506901844319, 5195.584353251731, 5199.038177937094, 5229.6339256251695, 4328.394024836525, 4371.78141092944, 4446.644268458515, 4453.1761624099645, 4495.078508905666, 3866.0, 3867.7485963412287, 3869.903252418712, 3904.72666913319, 3909.3158697227873, 4425.048614547888, 4432.468103827561, 4432.734035957853, 4434.933475997863, 4479.955200448019, 4951.851444456672, 5055.8988820223385, 5093.032323858455, 5121.667091639031, 5158.097332966716, 4345.109252603209, 4366.978165109154, 4368.868933931987, 4369.519352871182, 4419.845305414295, 4510.706804057726, 4588.7935042923145, 4641.257398816173, 4648.140094082605, 4678.69588476748, 4792.2332426811545, 4855.635827312895, 4860.6111511079735, 4865.659403841839, 4870.878228044186, 5197.792088316425, 5206.97396513015, 5209.947900521098, 5211.244369566468, 5264.605154613343, 4662.463816661128, 4765.356676848581, 4816.229403295456, 4835.588974937029, 4894.339264199288, 5072.9854029193575, 5105.33630628016, 5126.923096153635, 5128.743562821918, 5168.198929165986, 5147.099257629975, 5172.922406163986, 5177.223416487557, 5183.403908550414, 5233.947660523346, 4880.951190488192, 4902.7058376497525, 4963.70217786934, 5054.039732450822, 5077.8730531737, 4978.30303757467, 4998.475160108136, 5000.274960130833, 5005.049040682259, 5040.84877453677, 4934.925976110433, 4943.0, 4987.875303117451, 4993.575546078624, 4996.0, 4813.470518242921, 4854.101991131652, 4855.757212139309, 4905.068037072945, 4906.36217291764, 4858.076965376567, 4917.434495033117, 5006.224035274538, 5024.196128619404, 5026.220935754964], "value": 4731.8241196126455, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.4723131103731, 573.8049063318472, 578.7713853028054, 584.5206930317141, 591.6124938165503, 27175.0, 27710.0, 39175.60824391755, 40013.39979900304, 41495.37756933648, 37283.440748388726, 37695.623043769556, 37856.43215351765, 41227.175456490855, 41355.79322103393, 7921.049474063106, 8067.435279530435, 8072.152423995484, 8074.232947869943, 8078.798030049266, 7523.510971786844, 10117.544710488046, 10365.844512332322, 10375.066244038035, 10747.62383316584, 10412.271141020132, 10500.894991050107, 10509.054185123337, 10517.78964420711, 10530.78938421235, 10275.229357798158, 10384.480775961172, 10392.272540922138, 10399.116075133623, 10399.272050956473, 10253.179745620333, 10320.01959813824, 10334.483275836179, 10339.844902326471, 10349.172066234683, 38488.61511384906, 38956.61043389587, 38977.220455591014, 38993.22013559741, 39038.80480597607, 3094.04084733731, 3129.264622813637, 3190.744740420761, 3229.5155726640955, 3381.746369022333, 29467.557986630218, 29483.11550653484, 29658.258543536584, 29910.85044574785, 30235.244118896982, 3454.9309013819584, 3470.410030294856, 3476.600190978045, 3481.268933523963, 3552.8578856845697, 190957.0, 192019.0, 192269.0, 192886.0, 192925.0, 52052.0, 52598.47401525936, 52607.47392526026, 52608.47391526112, 52652.736736316074, 54531.72734136304, 54975.450245497035, 54978.0, 55094.44905550893, 55102.0, 14043.438262469592, 14684.632884177767, 14782.926085369505, 14804.481843135438, 14818.259087045604, 9902.455364954852, 10771.676849694522, 11082.445877706245, 11635.592754253561, 11704.94147529257, 15087.849121508863, 15262.389504419922, 15323.157226352434, 15344.616384590474, 15428.762829389676, 5623.971880140655, 5632.324121105412, 5645.266115404971, 5655.830325090254, 5904.7342869570975, 722.5973637654714, 747.5477336211513, 856.6359297298748, 876.0626130040795, 969.9515024248759, 27751.30621734473, 28455.71544284572, 28711.13866583964, 29119.27201819929, 29181.0, 932.0353434195575, 967.3180407812475, 991.8760154980628, 1045.7281106912258, 1049.9160067194607, 14626.341814618369, 14685.0, 15036.248187590796, 15250.618734531725, 15440.922795385952, 379841.0, 380047.0, 381033.0, 382706.0, 382887.0, 50006.74996625067, 50124.498755011984, 50304.0, 50376.24435633468, 50529.0, 1077.5420446310286, 1132.5583022621183, 1171.5255321594768, 1209.0388141427586, 1224.406163010939, 26175.345616359744, 29184.854075729487, 29424.13450347856, 29467.0, 29915.850420747756, 41287.04555453365, 41760.58239417567, 41844.58155418407, 41852.37221441742, 41883.371749423175, 290836.0, 290883.0, 292852.0, 293255.0, 293498.0, 30231.546526801674, 34615.0, 35584.28831423409, 35759.82120089383, 36626.45060324044, 41340.37989430101, 41507.0, 41952.0, 42428.787856060524, 42786.0, 34038.48942265818, 35748.28503429865, 36816.22251202059, 36998.44502332521, 37001.854842338704, 36190.45714314235, 36834.0, 37013.44479832751, 37126.814365927996, 37399.25201496009, 35642.46536302009, 36141.0964725884, 36148.63851361558, 36732.632673672924, 36755.63244367522, 30877.38245235038, 32945.835270823496, 33218.16954576155, 33244.1688957778, 33414.0, 25039.499210015332, 25850.61224081678, 25875.61186582165, 25986.87006564955, 26085.478290434465], "value": 17867.27834526473, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.8916741226515, 369.5140889730003, 377.8564145624663, 379.8518577754676, 381.4659476732574, 12801.615951521457, 16168.27242774075, 16371.508854734348, 17782.110894455258, 17783.46649600511, 16760.66478670426, 17055.14724263785, 17634.911825440886, 17649.470515884543, 17651.117444127773, 5109.540141387265, 5163.845084647466, 5459.262999495077, 5465.535429488498, 5544.0575102232715, 3557.484164796103, 5313.203019547061, 5525.060739674245, 5613.578981576377, 5650.5479561634975, 5707.944030354377, 5721.656700597978, 5732.254806875121, 5736.541076713853, 5745.454181852739, 5843.328017278025, 6138.202033735631, 6310.274318453367, 6310.747570097191, 6314.71583778731, 3491.720662347006, 5152.304438900738, 5483.616146869721, 5487.5884308677005, 5562.638428502134, 12634.936825315815, 12913.67715807103, 13033.674158146028, 13149.802752958713, 13255.536056237985, 1785.6517978994102, 1948.6005368899341, 2008.829249513793, 2130.275706259879, 2264.0264686184933, 9416.811663766686, 9536.2371010319, 9568.712938611852, 9588.37675551087, 9659.90340096604, 1635.9591010224722, 1636.5908522869283, 1681.8990860548283, 1735.1671197825115, 1779.5462157149973, 64853.675731621515, 73187.26812731857, 73641.2635873645, 73912.26087739108, 74530.62734686292, 8740.169683880022, 9295.72112836616, 10200.183985281159, 10265.178785697126, 10296.845547316796, 13700.520481783089, 14109.082909610843, 14159.71680566383, 14170.645733856736, 14215.36030878613, 7095.787126386217, 7187.78436646891, 7314.96342518284, 7448.5158464699625, 7672.578008209602, 6547.541672082908, 6660.167479065117, 6795.18457785059, 6916.757913473004, 7055.506114572034, 4434.977825110918, 4449.532799056117, 4473.888152796206, 4494.775261236925, 4570.588647021784, 1656.0394970916839, 1689.3833750680913, 1805.3049575913367, 1814.7550080739195, 1824.771903512061, 799.8440304140753, 816.1552792859362, 851.5146366570995, 881.4226681523612, 896.8475359188823, 15334.23328833554, 16573.6685266294, 16745.0, 16968.0, 17072.146392680315, 830.3274347778319, 838.1241602525375, 875.2079368171815, 892.8705337726124, 906.6600024990629, 9251.213646840093, 9547.904520954702, 9736.0, 9823.508824558743, 9848.50757462124, 358234.0, 359672.0, 360229.0, 364601.0, 366776.0, 46744.0, 47182.0, 73673.0, 81232.18767812365, 81241.0, 899.6446403670644, 917.0554329041098, 922.6032805893531, 938.0385105267019, 943.1935694980692, 15072.472463464164, 15177.544673659808, 15231.771523426936, 15559.0, 15846.524604261891, 37104.628953711195, 37508.0, 37595.62404375921, 37686.81156594199, 37911.43132853065, 53150.734246328524, 53245.733771330895, 53251.467485324654, 53630.0, 53692.73153634207, 7232.783016509513, 7239.529430586995, 7284.235155308616, 7401.555906645618, 7494.175640679412, 5631.042722737063, 9694.175995040501, 10804.837927431252, 10822.51298691545, 10957.671269861918, 9446.29152813528, 9674.129328360481, 9700.563474643517, 9865.210783137189, 9971.152452041368, 9417.952910235404, 9605.423674579548, 9625.855612165964, 9723.0, 9737.318387712792, 9526.190273826525, 9847.803043938939, 9930.702078937644, 9936.751581210528, 10010.14913732341, 7346.0, 7377.188509264084, 7521.247875212435, 7577.734779282699, 7623.123340815655, 8877.0, 9172.35793494449, 9250.167484926389, 9362.251019918527, 9434.198093162158], "value": 7985.991301859153, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1222.7615614955075, 1331.9067665263428, 1388.1254809470033, 1437.547172640618, 1673.196865504557, 6375.744970201192, 7216.603086830222, 7401.62991850408, 7413.073365829271, 7530.62346882655, 7316.707331706739, 7414.073240844895, 7422.035135432387, 7423.331900128987, 7454.068241469817, 2184.170015394148, 2209.5801797658473, 2210.9557808843815, 2211.0492488230057, 2211.4139752965475, 2175.9456013599706, 2203.6143674856917, 2209.0611490116717, 2209.569134018867, 2216.769692820484, 2181.9345419637434, 2187.21260346276, 2195.7913998170147, 2196.121551379445, 2223.1774243529917, 2093.3092079613843, 2093.64408050631, 2101.957960840775, 2102.0015492640873, 2103.7685854555984, 2094.675325324577, 2145.6352420088624, 2146.431195733132, 2146.6779983002675, 2149.0544160569457, 7185.425165986709, 7397.445191610597, 7398.519096258779, 7404.629768511553, 7463.328300452931, 4383.60547550722, 4481.327800829869, 4484.663650226246, 4486.865394038184, 4487.39420178275, 2777.1390868830526, 2827.137722994486, 2891.5662650602367, 2898.159533735214, 2907.8836846526115, 827.8302947895667, 840.7855996720858, 842.5871323051722, 1611.4440518021263, 1630.380455426942, 9820.459874706889, 9965.70102896907, 9988.151007164472, 9997.350172238854, 10027.749306267402, 4077.89805254871, 4104.117614712792, 4233.25917964355, 4236.809343579546, 4236.978815105905, 8516.531590762444, 8527.23254907049, 8530.530820804915, 8537.445066070686, 8588.141185881486, 4583.747893865802, 4594.6783725139585, 4605.125026245003, 4605.884852878705, 4606.516315786861, 3623.3115708015393, 3995.4606128172904, 4178.039051018282, 4253.3619957006695, 4258.659307255412, 3496.0, 3590.9461358079648, 3659.249853779969, 3672.4674922136674, 3691.427828686558, 1121.1535290855352, 1173.6244401791346, 1181.2085902445383, 1185.9407029648482, 1199.1725709260581, 827.759949614605, 830.7341650671727, 837.2130197614223, 847.7329641163137, 857.1300130367682, 4857.081452826184, 4972.254161875748, 5057.241413787888, 5067.265246539231, 5142.61430392722, 400.6754528831656, 401.49612236642827, 410.20625090450073, 411.3069477929698, 413.6091393633048, 3560.2701446203464, 3616.27674465108, 3645.070507020746, 3686.3733165361955, 3687.0597997510463, 8721.520316382534, 9136.771580710536, 9147.634094636272, 9173.678921237724, 9188.40275382098, 8855.424397414276, 9060.184583387396, 9062.81874362509, 9080.137386948361, 9082.727518174466, 6823.761168359084, 6955.200151982587, 7024.332688394595, 7026.683799229047, 7043.471739619549, 4646.279826626878, 4800.711957282574, 4831.73425461603, 4860.513948605253, 4886.609071274361, 8480.703175388831, 8527.488350698977, 8550.914490855012, 8583.055863855096, 8599.26906212979, 8024.117347091932, 8355.997280326399, 8363.0, 8571.571421428778, 8625.39622226438, 5716.114002329646, 6292.874142517216, 6428.839279018062, 6507.446867016357, 6612.570182938093, 7270.854582908418, 7277.090363704537, 7329.267073292842, 7338.486305958531, 7345.669444874887, 6395.104685343963, 6603.141591593254, 6621.404073633396, 6697.631630260383, 6728.966355168192, 6616.338366163345, 6630.83422914431, 6666.100076489612, 6692.330766923464, 6747.628880411625, 6110.236220472441, 6125.050617154348, 6149.877002460015, 6154.876902461836, 6161.336439161491, 4400.757958312323, 4402.229609818205, 4467.084247729273, 4476.9328460073775, 4559.680822342537, 4437.467503899424, 4464.642828573771, 4484.75333856641, 4498.797554110139, 4534.7052441591195], "value": 3963.2862905095317, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.33266817989073, 294.76418864908084, 299.4355639619317, 301.37013641489284, 302.06811985026184, 4882.292067650193, 4944.975275123628, 4969.105560999019, 4972.875678108049, 4976.402831660202, 4849.709017458955, 4906.632002599801, 4906.828261010865, 4917.361152746968, 4920.655554111205, 3635.091227193195, 3745.8314375853015, 3767.3595488766973, 3864.333609809237, 3897.5834982310867, 3851.5378154621485, 3852.8266228019806, 3860.1471293779005, 3892.8053597320163, 3905.6875449964077, 3613.602503724588, 3827.674647654939, 3835.712321575879, 3840.0015995841004, 3859.8842034739005, 3680.3191409589176, 3814.5803961564206, 3816.2367526494672, 3818.3317919364, 3864.652181303696, 3373.4939759036342, 3374.8818791342433, 3375.8987230383127, 3407.114150320934, 3411.30068335993, 4748.601956012191, 4750.263709125091, 4764.285357196413, 4783.4499032611, 4784.712917224978, 4237.385579091046, 4321.7406955582765, 4332.184080347799, 4385.386045953569, 4392.780360981938, 5057.140286151363, 5058.772355244023, 5077.923831142536, 5098.337216161875, 5117.1556693145585, 3676.356637588411, 3692.1508053147927, 3712.2204337089242, 3723.4600982857605, 3724.7020238380865, 5170.224466330081, 5275.235090911796, 5329.2006199070065, 5360.3299587551555, 5360.909245394299, 3717.75834570752, 3727.440883867387, 3743.8689645862264, 3752.3114598430816, 3758.774473531597, 3781.829817658157, 3786.413105968524, 3786.67813235878, 3810.237952409529, 3811.2187001664593, 4318.201132790444, 4328.415663885336, 4332.891677708019, 4340.414044104069, 4358.128374325148, 4925.975370123198, 5040.2691609716385, 5057.797688092435, 5066.36670416198, 5098.311727916684, 1300.9544665936646, 1311.1936159262175, 1311.2198242045968, 1315.3686230609362, 1315.3883444198493, 867.1415298854215, 872.8739925496166, 915.665781989569, 928.9349745517749, 941.8351788436996, 4370.497392799869, 4439.156560253541, 4460.174867649431, 4461.13007963444, 4463.2858742601675, 5160.819371321985, 5186.585073194211, 5195.2986346843445, 5196.76614552346, 5224.712640804716, 4308.784560772012, 4408.140412619572, 4437.755923424178, 4440.644748420119, 4589.885252868705, 2792.5531914893927, 2816.3240822202524, 2822.209781261209, 2829.571066611389, 2839.872205750746, 4939.90120197594, 4951.9752401237765, 4972.801087956514, 4989.226669866177, 4992.450830408652, 5197.674592978842, 5444.863878402993, 5446.264754258124, 5469.644473109315, 5477.534409575151, 4944.456109827845, 4948.901021979612, 4951.192814622636, 4989.351384320015, 4997.625178111656, 5284.5995811109315, 5291.682499050069, 5329.573634109184, 5336.17289320156, 5338.332708411449, 4927.655064145475, 5192.299039629753, 5210.270562121382, 5219.7211683137875, 5229.163333866563, 4937.5556199942175, 4963.553280204799, 4972.403311602631, 4975.776090076028, 4978.975105124451, 3698.8150592470806, 3728.8322025508373, 3730.645588669072, 3735.103575142001, 3737.5141231639714, 5103.5661968733075, 5192.766325515437, 5228.37259528859, 5237.397699264481, 5333.973330133324, 5069.1635880079375, 5095.464976177448, 5103.821366252163, 5118.181091025567, 5123.359580052494, 5092.694438333712, 5122.820701275437, 5143.682324900344, 5155.252488389087, 5203.765830537711, 4813.95186048149, 4964.67729597575, 4968.975155124202, 5015.247712843103, 5016.824411145592, 4961.231009193581, 5060.84817455477, 5064.670796398223, 5069.958025184859, 5070.873228169326, 4976.825811096594, 4997.150484417585, 5020.393474088257, 5031.949680503295, 5032.169692000925], "value": 3860.786676316735, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.9880402391952, 628.9119523266744, 666.6466772610515, 699.7061234281601, 734.467511054486, 11520.251183673066, 12237.93881030596, 12241.877581224186, 12308.753824923497, 12328.4452199651, 11317.0, 11348.205625606211, 11370.431478426088, 11372.431378431065, 11378.431078446087, 622.6233128956984, 768.2010708862776, 791.2878409431501, 791.2917938445088, 797.3939805747644, 782.6673663692936, 788.2117882117882, 790.8022994251437, 791.956442395668, 791.9643616037292, 650.8730797494491, 839.1398816213369, 841.3605659698643, 841.4488510025913, 841.8148007438353, 660.3363619562333, 668.7191379620571, 669.0299066353806, 801.7193982106274, 813.5850716134755, 611.6972098811073, 611.960222585535, 611.9847003824896, 612.2224774536359, 613.1201725523888, 11863.88136118645, 11876.16866819323, 11901.047916166686, 11904.16670833042, 11953.701157471132, 12502.624921252376, 12766.489340426371, 12893.0, 12986.675333116747, 13057.086003979724, 11608.825867611993, 11644.126690498246, 11658.475368608433, 11721.238119522204, 12127.575534856238, 4101.487314085739, 4133.751974881517, 4140.958590414088, 4190.0, 4287.121140166259, 62179.68910155466, 62288.688556556925, 62292.68853655703, 62348.37651623471, 62364.0, 9597.520123993772, 9687.56511127207, 10041.748956276151, 10089.596416143271, 10169.0, 18074.883783101195, 18385.0, 18519.814801852077, 18562.350317738816, 18572.535686607673, 6535.117759102555, 6655.0, 6729.899051514231, 6841.418479429205, 6889.586624802528, 3602.171500554887, 3817.1411432428035, 3821.7898015608853, 3845.192509573021, 3897.2010737798682, 2654.362952891292, 2740.5067087923967, 2763.281546797847, 2763.5440152375036, 2764.060219525371, 499.5079846351341, 503.55183886341183, 508.6058304813799, 509.6025100421677, 517.4024002277375, 523.4320761973302, 546.8578169675833, 588.0003993211473, 626.4205609810907, 629.4932579273637, 7735.226477352332, 8017.879731804028, 8283.95858020706, 8676.08901065379, 8950.955245223733, 547.9150731636603, 630.3435718809296, 640.2157357237347, 646.890028695123, 659.3538332434146, 3897.57126716061, 4014.578469260744, 4061.6953728470485, 4082.081531655413, 4193.832246710159, 53685.731571341894, 53770.35411281759, 53814.7309263459, 54270.45729542654, 71351.0, 84259.0, 85133.0, 85334.14665853386, 85854.57072714597, 86578.56710716532, 71821.64089179625, 74081.62959185278, 74944.0, 75310.0, 76333.61833190799, 7744.303012728881, 7763.417743669134, 7915.1293357729455, 8004.679812807422, 8046.316063134465, 39948.0, 45047.32429013633, 45129.548704512534, 45165.09669806651, 45177.0, 54549.45450545602, 59589.0, 61256.0, 61959.38040619536, 62114.37885621086, 11411.289121085434, 11539.538418463168, 11837.526498939946, 11895.643130706092, 11924.0, 12452.439640216393, 12553.058520611174, 12981.675458113623, 13398.531051413544, 13689.794653080013, 11665.825012624648, 11893.167478276437, 12370.381480925738, 12501.24992500453, 12588.685282868002, 12705.36473176356, 12805.935970320461, 12890.35548222604, 12904.16122952005, 13205.801912971505, 12261.203021803554, 12594.181378210387, 12807.743845122859, 13092.279924604238, 13245.80131298012, 9792.706218813448, 10024.448655324028, 10077.143442807443, 10294.691159265234, 10560.260781745512, 10082.69751907444, 10175.84736228972, 10566.41884696349, 10739.0, 10775.137988961022], "value": 6497.331380518849, "unit": "score"}], "name": "dromaeo_css", "value": 6375.1262127189875, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 114.0, 114.0, 114.0, 113.0, 114.0, 113.0, 113.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 122.0, 125.0, 174.0, 123.0, 123.0, 143.0, 121.0, 186.0, 141.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 162.0, 161.0, 161.0, 162.0, 160.0, 163.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 76.0, 109.0, 76.0, 77.0, 76.0, 76.0, 97.0, 77.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 115.0, 85.0, 85.0, 86.0, 122.0, 85.0, 86.0, 123.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 148.0, 148.0, 146.0, 147.0, 148.0, 147.0, 147.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 63.0, 62.0, 63.0, 64.0, 62.0, 63.0, 64.0, 62.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 69.0, 64.0, 65.0, 64.0, 65.0, 64.0, 64.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 79.0, 85.0, 79.0, 78.0, 77.0, 85.0, 79.0, 88.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 129.0, 127.0, 139.0, 138.0, 136.0, 129.0, 132.0, 216.0, 131.0], "value": 141.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 166.0, 172.0, 164.0, 166.0, 164.0, 165.0, 166.0, 167.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 67.0, 67.0, 68.0, 67.0, 69.0, 66.0, 71.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1536.3000000000002, "alertThreshold": 2.0}]} 15:49:52 INFO - Return code: 0 15:49:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:49:52 INFO - # TBPL SUCCESS # 15:49:52 INFO - Running post-action listener: _resource_record_post_action 15:49:52 INFO - ##### 15:49:52 INFO - ##### Finished run-tests step (success) 15:49:52 INFO - ##### 15:49:52 INFO - Running post-run listener: _resource_record_post_run 15:49:52 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 532021248; Read time: 8; Write time: 1067136 15:49:52 INFO - TinderboxPrint: CPU usage
13.0% 15:49:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 15:49:52 INFO - TinderboxPrint: I/O write bytes / time
532,021,248 / 1,067,136 15:49:52 INFO - TinderboxPrint: CPU idle
8,351.7 (87.0%) 15:49:52 INFO - TinderboxPrint: CPU user
1,200.4 (12.5%) 15:49:52 INFO - TinderboxPrint: Swap in / out
0 / 0 15:49:52 INFO - install - Wall time: 17s; CPU: 16.0%; Read bytes: 0; Write bytes: 233947136; Read time: 0; Write time: 558628 15:49:52 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 298074112; Read time: 0; Write time: 508508 15:49:53 INFO - Running post-run listener: _upload_blobber_files 15:49:53 INFO - Blob upload gear active. 15:49:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:49:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:49:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:49:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:49:53 INFO - (blobuploader) - INFO - Open directory for files ... 15:49:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:49:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:49:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:57 INFO - (blobuploader) - INFO - Done attempting. 15:49:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:49:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:49:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:57 INFO - (blobuploader) - INFO - Done attempting. 15:49:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:49:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:49:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:58 INFO - (blobuploader) - INFO - Done attempting. 15:49:58 INFO - (blobuploader) - INFO - Iteration through files over. 15:49:58 INFO - Return code: 0 15:49:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:49:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:49:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d29b11dad5e8d9d53bd7539cf1840e2087e2db6937263e754ce352e4d76dafcf4896f0086e7241c6bb105c18cadc7b9bb68f9ccaea8e5906de6ab7b960eca814", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33aed3bfb7ab586f6a14488d4edec259c048785a4291d66be49a97eed4bc769d50005ff84146ef5be0004095aebb4dfb15e598cdff4533998e028ab249787d1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/336c0d933c7fc985bf2d1361784e3f2764347f5e70f89bc0318069c144314a2b1a8fddd92c2b2b9c671109d3d43d32cade588ad344a0df1ced2f127e1f6848bf"} 15:49:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:49:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:49:58 INFO - Contents: 15:49:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d29b11dad5e8d9d53bd7539cf1840e2087e2db6937263e754ce352e4d76dafcf4896f0086e7241c6bb105c18cadc7b9bb68f9ccaea8e5906de6ab7b960eca814", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33aed3bfb7ab586f6a14488d4edec259c048785a4291d66be49a97eed4bc769d50005ff84146ef5be0004095aebb4dfb15e598cdff4533998e028ab249787d1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/336c0d933c7fc985bf2d1361784e3f2764347f5e70f89bc0318069c144314a2b1a8fddd92c2b2b9c671109d3d43d32cade588ad344a0df1ced2f127e1f6848bf"} 15:49:58 INFO - Running post-run listener: copy_logs_to_upload_dir 15:49:58 INFO - Copying logs to upload dir... 15:49:58 INFO - mkdir: /builds/slave/test/build/upload/logs 15:49:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.936824 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-11 15:49:58.495162) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 15:49:58.498915) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d29b11dad5e8d9d53bd7539cf1840e2087e2db6937263e754ce352e4d76dafcf4896f0086e7241c6bb105c18cadc7b9bb68f9ccaea8e5906de6ab7b960eca814", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33aed3bfb7ab586f6a14488d4edec259c048785a4291d66be49a97eed4bc769d50005ff84146ef5be0004095aebb4dfb15e598cdff4533998e028ab249787d1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/336c0d933c7fc985bf2d1361784e3f2764347f5e70f89bc0318069c144314a2b1a8fddd92c2b2b9c671109d3d43d32cade588ad344a0df1ced2f127e1f6848bf"} build_url:https://queue.taskcluster.net/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011190 build_url: 'https://queue.taskcluster.net/v1/task/K0WRQAmKRa6BNddbSqsfJQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d29b11dad5e8d9d53bd7539cf1840e2087e2db6937263e754ce352e4d76dafcf4896f0086e7241c6bb105c18cadc7b9bb68f9ccaea8e5906de6ab7b960eca814", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33aed3bfb7ab586f6a14488d4edec259c048785a4291d66be49a97eed4bc769d50005ff84146ef5be0004095aebb4dfb15e598cdff4533998e028ab249787d1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/336c0d933c7fc985bf2d1361784e3f2764347f5e70f89bc0318069c144314a2b1a8fddd92c2b2b9c671109d3d43d32cade588ad344a0df1ced2f127e1f6848bf"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 15:49:58.523957) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:49:58.524264) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=af724c981e2a0f424dbabf99000001e7-1468276146.163169-1610576039 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 15:49:58.576766) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 15:49:58.577090) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 15:49:58.577372) ========= ========= Total master_lag: 0.13 =========