builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-068 starttime: 1477354138.72 results: success (0) buildid: 20161024141422 builduid: 0dc95967e4484812925e484cbb0f9e06 revision: d025e0641d9eefea99530188fa1eeaf4d780d6af ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:58.724849) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:58.725316) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:58.725595) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:58.759148) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:58.759422) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-24 17:08:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-10-24 17:09:00 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.150038 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:58.924858) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:58.925159) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017836 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:58.975922) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:58.976253) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d025e0641d9eefea99530188fa1eeaf4d780d6af --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d025e0641d9eefea99530188fa1eeaf4d780d6af --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=97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-24 17:09:00,232 truncating revision to first 12 chars 2016-10-24 17:09:00,232 Setting DEBUG logging. 2016-10-24 17:09:00,232 attempt 1/10 2016-10-24 17:09:00,232 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d025e0641d9e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-24 17:09:00,540 unpacking tar archive at: mozilla-beta-d025e0641d9e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.645209 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:59.636949) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:59.637240) ========= script_repo_revision: d025e0641d9eefea99530188fa1eeaf4d780d6af ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:59.637629) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:59.637909) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 17:08:59.652184) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-10-24 17:08:59.652493) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:09:00 INFO - MultiFileLogger online at 20161024 17:09:00 in /builds/slave/test 17:09:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:09:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:09:01 INFO - {'append_to_log': False, 17:09:01 INFO - 'base_work_dir': '/builds/slave/test', 17:09:01 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:09:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:09:01 INFO - 'branch': 'Mozilla-Beta', 17:09:01 INFO - 'buildbot_json_path': 'buildprops.json', 17:09:01 INFO - 'config_files': ('talos/linux_config.py',), 17:09:01 INFO - 'default_actions': ('clobber', 17:09:01 INFO - 'read-buildbot-config', 17:09:01 INFO - 'download-and-extract', 17:09:01 INFO - 'populate-webroot', 17:09:01 INFO - 'create-virtualenv', 17:09:01 INFO - 'install', 17:09:01 INFO - 'run-tests'), 17:09:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:09:01 INFO - 'download_minidump_stackwalk': True, 17:09:01 INFO - 'download_symbols': 'ondemand', 17:09:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:09:01 INFO - 'tooltool.py': '/tools/tooltool.py', 17:09:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:09:01 INFO - '/tools/misc-python/virtualenv.py')}, 17:09:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:09:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:09:01 INFO - 'installer_path': 'installer.exe', 17:09:01 INFO - 'log_level': 'info', 17:09:01 INFO - 'log_name': 'talos', 17:09:01 INFO - 'log_to_console': True, 17:09:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:09:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:09:01 INFO - 'opt_config_files': (), 17:09:01 INFO - 'pip_index': False, 17:09:01 INFO - 'sps_profile': False, 17:09:01 INFO - 'sps_profile_interval': 0, 17:09:01 INFO - 'suite': 'dromaeojs', 17:09:01 INFO - 'system_bits': '32', 17:09:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:09:01 INFO - 'title': 'talos-linux64-ix-068', 17:09:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:09:01 INFO - 'use_talos_json': True, 17:09:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:09:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:09:01 INFO - 'work_dir': 'build'} 17:09:01 INFO - [mozharness: 2016-10-25 00:09:01.011078Z] Running clobber step. 17:09:01 INFO - Running pre-action listener: _resource_record_pre_action 17:09:01 INFO - Running main action method: clobber 17:09:01 INFO - rmtree: /builds/slave/test/build 17:09:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:09:01 INFO - Running post-action listener: _resource_record_post_action 17:09:01 INFO - [mozharness: 2016-10-25 00:09:01.990648Z] Finished clobber step (success) 17:09:01 INFO - [mozharness: 2016-10-25 00:09:01.990750Z] Running read-buildbot-config step. 17:09:01 INFO - Running pre-action listener: _resource_record_pre_action 17:09:01 INFO - Running main action method: read_buildbot_config 17:09:01 INFO - Using buildbot properties: 17:09:01 INFO - { 17:09:01 INFO - "project": "", 17:09:01 INFO - "product": "firefox", 17:09:01 INFO - "script_repo_revision": "production", 17:09:01 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 17:09:01 INFO - "repository": "", 17:09:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 17:09:01 INFO - "buildid": "20161024141422", 17:09:01 INFO - "pgo_build": "True", 17:09:01 INFO - "basedir": "/builds/slave/test", 17:09:01 INFO - "buildnumber": 202, 17:09:01 INFO - "slavename": "talos-linux64-ix-068", 17:09:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:09:01 INFO - "platform": "ubuntu64_hw", 17:09:01 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 17:09:01 INFO - "branch": "mozilla-beta", 17:09:01 INFO - "repo_path": "releases/mozilla-beta", 17:09:01 INFO - "revision": "d025e0641d9eefea99530188fa1eeaf4d780d6af", 17:09:01 INFO - "stage_platform": "linux64-pgo", 17:09:01 INFO - "builduid": "0dc95967e4484812925e484cbb0f9e06", 17:09:01 INFO - "slavebuilddir": "test" 17:09:01 INFO - } 17:09:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 17:09:01 INFO - Running post-action listener: _resource_record_post_action 17:09:01 INFO - [mozharness: 2016-10-25 00:09:01.993207Z] Finished read-buildbot-config step (success) 17:09:01 INFO - [mozharness: 2016-10-25 00:09:01.993297Z] Running download-and-extract step. 17:09:01 INFO - Running pre-action listener: _resource_record_pre_action 17:09:01 INFO - Running main action method: download_and_extract 17:09:01 INFO - mkdir: /builds/slave/test/build/tests 17:09:01 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')]} 17:09:01 INFO - https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:09:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:09:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:09:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:09:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:09:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:09:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:09:02 INFO - retry: Failed, sleeping 30 seconds before retrying 17:09:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 17:09:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:09:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:09:32 INFO - retry: Failed, sleeping 60 seconds before retrying 17:10:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 17:10:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:10:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:10:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 17:10:32 INFO - Caught exception: HTTP Error 404: Not Found 17:10:32 INFO - Caught exception: HTTP Error 404: Not Found 17:10:32 INFO - Caught exception: HTTP Error 404: Not Found 17:10:32 INFO - trying https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:10:32 INFO - Downloading https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:10:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:10:32 INFO - Downloaded 1327 bytes. 17:10:32 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:10:32 INFO - Using the following test package requirements: 17:10:32 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:10:32 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:10:32 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 17:10:32 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:10:32 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 17:10:32 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:10:32 INFO - u'jsshell-linux-x86_64.zip'], 17:10:32 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:10:32 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 17:10:32 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:10:32 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:10:32 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 17:10:32 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:10:32 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 17:10:32 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:10:32 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 17:10:32 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:10:32 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 17:10:32 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:10:32 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')]} 17:10:32 INFO - https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:10:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:10:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:10:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:10:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:10:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:10:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:10:32 INFO - retry: Failed, sleeping 30 seconds before retrying 17:11:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 17:11:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:11:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:11:02 INFO - retry: Failed, sleeping 60 seconds before retrying 17:12:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 17:12:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:12:02 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:12:02 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 17:12:02 INFO - Caught exception: HTTP Error 404: Not Found 17:12:02 INFO - Caught exception: HTTP Error 404: Not Found 17:12:02 INFO - Caught exception: HTTP Error 404: Not Found 17:12:02 INFO - trying https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:12:02 INFO - Downloading https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:12:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:12:04 INFO - Downloaded 21774831 bytes. 17:12:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:12:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:12:05 INFO - Return code: 0 17:12:05 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:12:05 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')]} 17:12:05 INFO - https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:12:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:12:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:12:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:12:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:12:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:12:05 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:12:05 INFO - retry: Failed, sleeping 30 seconds before retrying 17:12:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 17:12:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:12:35 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:12:35 INFO - retry: Failed, sleeping 60 seconds before retrying 17:13:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 17:13:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:13:35 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:13:35 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 17:13:35 INFO - Caught exception: HTTP Error 404: Not Found 17:13:35 INFO - Caught exception: HTTP Error 404: Not Found 17:13:35 INFO - Caught exception: HTTP Error 404: Not Found 17:13:35 INFO - trying https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:13:35 INFO - Downloading https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:13:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:13:36 INFO - Downloaded 21774831 bytes. 17:13:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:13:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:13:37 INFO - Return code: 0 17:13:37 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')]} 17:13:37 INFO - https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:13:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:13:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:13:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:13:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:13:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:13:37 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:13:37 INFO - retry: Failed, sleeping 30 seconds before retrying 17:14:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 17:14:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:14:07 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:14:07 INFO - retry: Failed, sleeping 60 seconds before retrying 17:15:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 17:15:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:15:07 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:15:07 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 17:15:07 INFO - Caught exception: HTTP Error 404: Not Found 17:15:07 INFO - Caught exception: HTTP Error 404: Not Found 17:15:07 INFO - Caught exception: HTTP Error 404: Not Found 17:15:07 INFO - trying https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:15:07 INFO - Downloading https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:15:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:15:08 INFO - Downloaded 14413762 bytes. 17:15:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:15:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:15:08 INFO - Return code: 0 17:15: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')]} 17:15:08 INFO - https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:15:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:15:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:15:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:15:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:15:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:15:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:15:08 INFO - retry: Failed, sleeping 30 seconds before retrying 17:15:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 17:15:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:15:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:15:38 INFO - retry: Failed, sleeping 60 seconds before retrying 17:16:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 17:16:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:16:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:16:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 17:16:38 INFO - Caught exception: HTTP Error 404: Not Found 17:16:38 INFO - Caught exception: HTTP Error 404: Not Found 17:16:38 INFO - Caught exception: HTTP Error 404: Not Found 17:16:38 INFO - trying https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:16:38 INFO - Downloading https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:16:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:16:39 INFO - Downloaded 56805857 bytes. 17:16:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:16:39 INFO - mkdir: /builds/slave/test/properties 17:16:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:16:39 INFO - Writing to file /builds/slave/test/properties/build_url 17:16:39 INFO - Contents: 17:16:39 INFO - build_url:https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:16:40 INFO - Running post-action listener: _resource_record_post_action 17:16:40 INFO - Running post-action listener: set_extra_try_arguments 17:16:40 INFO - [mozharness: 2016-10-25 00:16:40.720246Z] Finished download-and-extract step (success) 17:16:40 INFO - [mozharness: 2016-10-25 00:16:40.720479Z] Running populate-webroot step. 17:16:40 INFO - Running pre-action listener: _resource_record_pre_action 17:16:40 INFO - Running main action method: populate_webroot 17:16:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:16:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:16:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:16:40 INFO - '--tppagecycles', 17:16:40 INFO - '7'], 17:16:40 INFO - 'tests': ['tsvgm']}}, 17:16:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:16:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:16:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:16:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:16:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:16:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:16:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:40 INFO - 'talos_options': ['--disable-e10s'], 17:16:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:40 INFO - 'talos_options': ['--disable-e10s'], 17:16:40 INFO - 'tests': ['damp', 'tps']}, 17:16:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:40 INFO - 'tests': ['damp', 'tps']}, 17:16:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:16:40 INFO - 'tests': ['dromaeo_dom']}, 17:16:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:16:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:16:40 INFO - 'tests': ['basic_compositor_video']}, 17:16:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:16:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:16:40 INFO - 'tests': ['a11yr', 17:16:40 INFO - 'ts_paint', 17:16:40 INFO - 'tpaint', 17:16:40 INFO - 'sessionrestore', 17:16:40 INFO - 'sessionrestore_no_auto_restore', 17:16:40 INFO - 'tabpaint']}, 17:16:40 INFO - 'other-e10s': {'tests': ['a11yr', 17:16:40 INFO - 'ts_paint', 17:16:40 INFO - 'tpaint', 17:16:40 INFO - 'sessionrestore', 17:16:40 INFO - 'sessionrestore_no_auto_restore', 17:16:40 INFO - 'tabpaint']}, 17:16:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:16:40 INFO - 'ts_paint', 17:16:40 INFO - 'tpaint', 17:16:40 INFO - 'sessionrestore', 17:16:40 INFO - 'sessionrestore_no_auto_restore', 17:16:40 INFO - 'tabpaint']}, 17:16:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:16:40 INFO - 'ts_paint', 17:16:40 INFO - 'tpaint', 17:16:40 INFO - 'sessionrestore', 17:16:40 INFO - 'sessionrestore_no_auto_restore', 17:16:40 INFO - 'tabpaint']}, 17:16:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:16:40 INFO - 'tests': ['a11yr', 17:16:40 INFO - 'ts_paint', 17:16:40 INFO - 'tpaint', 17:16:40 INFO - 'sessionrestore', 17:16:40 INFO - 'sessionrestore_no_auto_restore', 17:16:40 INFO - 'tabpaint']}, 17:16:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:16:40 INFO - 'tests': ['a11yr', 17:16:40 INFO - 'ts_paint', 17:16:40 INFO - 'tpaint', 17:16:40 INFO - 'sessionrestore', 17:16:40 INFO - 'sessionrestore_no_auto_restore', 17:16:40 INFO - 'tabpaint']}, 17:16:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:16:40 INFO - 'tests': ['tsvgx', 17:16:40 INFO - 'tsvgr_opacity', 17:16:40 INFO - 'tart', 17:16:40 INFO - 'tscrollx', 17:16:40 INFO - 'cart']}, 17:16:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:16:40 INFO - 'tsvgr_opacity', 17:16:40 INFO - 'tart', 17:16:40 INFO - 'tscrollx', 17:16:40 INFO - 'cart']}, 17:16:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:40 INFO - 'talos_options': ['--disable-e10s'], 17:16:40 INFO - 'tests': ['tp5o']}, 17:16:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:40 INFO - 'tests': ['tp5o']}, 17:16:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:40 INFO - 'talos_options': ['--disable-e10s', 17:16:40 INFO - '--xperf_path', 17:16:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:40 INFO - 'tests': ['tp5n']}, 17:16:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:16:40 INFO - 'talos_options': ['--xperf_path', 17:16:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:40 INFO - 'tests': ['tp5n']}}, 17:16:40 INFO - 'talos.zip': {'path': '', 17:16:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:16:40 INFO - Running post-action listener: _resource_record_post_action 17:16:40 INFO - [mozharness: 2016-10-25 00:16:40.740202Z] Finished populate-webroot step (success) 17:16:40 INFO - [mozharness: 2016-10-25 00:16:40.740292Z] Running create-virtualenv step. 17:16:40 INFO - Running pre-action listener: _resource_record_pre_action 17:16:40 INFO - Running main action method: create_virtualenv 17:16:40 INFO - Creating virtualenv /builds/slave/test/build/venv 17:16:40 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 17:16:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:16:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:16:40 INFO - Using real prefix '/usr' 17:16:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:16:41 INFO - Installing distribute.............................................................................................................................................................................................done. 17:16:43 INFO - Installing pip.................done. 17:16:43 INFO - Return code: 0 17:16:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:16:43 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')]} 17:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:43 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')]} 17:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:43 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 0x1108030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x126b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c73b0>, '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 0x13c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c8740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', '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 17:16:43 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 17:16:43 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 17:16:43 INFO - Using env: {'DISPLAY': ':0', 17:16:43 INFO - 'HOME': '/home/cltbld', 17:16:43 INFO - 'LANG': 'en_US.UTF-8', 17:16:43 INFO - 'LANGUAGE': 'en_US:en', 17:16:43 INFO - 'LOGNAME': 'cltbld', 17:16:43 INFO - 'MAIL': '/var/mail/cltbld', 17:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:43 INFO - 'MOZ_NO_REMOTE': '1', 17:16:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:43 INFO - 'NO_EM_RESTART': '1', 17:16:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:43 INFO - 'PWD': '/builds/slave/test', 17:16:43 INFO - 'SHELL': '/bin/bash', 17:16:43 INFO - 'SHLVL': '1', 17:16:43 INFO - 'TERM': 'linux', 17:16:43 INFO - 'TMOUT': '86400', 17:16:43 INFO - 'USER': 'cltbld', 17:16:43 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', 17:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:43 INFO - '_': '/tools/buildbot/bin/python'} 17:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:43 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 17:16:43 INFO - Cleaning up... 17:16:43 INFO - Return code: 0 17:16:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:16:43 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')]} 17:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:43 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')]} 17:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:43 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 0x1108030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x126b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c73b0>, '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 0x13c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c8740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', '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 17:16:43 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 17:16:43 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 17:16:43 INFO - Using env: {'DISPLAY': ':0', 17:16:43 INFO - 'HOME': '/home/cltbld', 17:16:43 INFO - 'LANG': 'en_US.UTF-8', 17:16:43 INFO - 'LANGUAGE': 'en_US:en', 17:16:43 INFO - 'LOGNAME': 'cltbld', 17:16:43 INFO - 'MAIL': '/var/mail/cltbld', 17:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:43 INFO - 'MOZ_NO_REMOTE': '1', 17:16:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:43 INFO - 'NO_EM_RESTART': '1', 17:16:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:43 INFO - 'PWD': '/builds/slave/test', 17:16:43 INFO - 'SHELL': '/bin/bash', 17:16:43 INFO - 'SHLVL': '1', 17:16:43 INFO - 'TERM': 'linux', 17:16:43 INFO - 'TMOUT': '86400', 17:16:43 INFO - 'USER': 'cltbld', 17:16:43 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', 17:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:43 INFO - '_': '/tools/buildbot/bin/python'} 17:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:43 INFO - Downloading/unpacking psutil>=3.1.1 17:16:43 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 17:16:43 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 17:16:43 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 17:16:43 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 17:16:43 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 17:16:43 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 17:16:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:16:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:45 INFO - Installing collected packages: psutil 17:16:45 INFO - Running setup.py install for psutil 17:16:45 INFO - building 'psutil._psutil_linux' extension 17:16:45 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 17:16:46 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 17:16:46 INFO - building 'psutil._psutil_posix' extension 17:16:46 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 17:16:46 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 17:16:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:46 INFO - Successfully installed psutil 17:16:46 INFO - Cleaning up... 17:16:46 INFO - Return code: 0 17:16:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:16:46 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')]} 17:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:46 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')]} 17:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:46 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 0x1108030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x126b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c73b0>, '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 0x13c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c8740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', '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 17:16:46 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 17:16:46 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 17:16:46 INFO - Using env: {'DISPLAY': ':0', 17:16:46 INFO - 'HOME': '/home/cltbld', 17:16:46 INFO - 'LANG': 'en_US.UTF-8', 17:16:46 INFO - 'LANGUAGE': 'en_US:en', 17:16:46 INFO - 'LOGNAME': 'cltbld', 17:16:46 INFO - 'MAIL': '/var/mail/cltbld', 17:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:46 INFO - 'MOZ_NO_REMOTE': '1', 17:16:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:46 INFO - 'NO_EM_RESTART': '1', 17:16:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:46 INFO - 'PWD': '/builds/slave/test', 17:16:46 INFO - 'SHELL': '/bin/bash', 17:16:46 INFO - 'SHLVL': '1', 17:16:46 INFO - 'TERM': 'linux', 17:16:46 INFO - 'TMOUT': '86400', 17:16:46 INFO - 'USER': 'cltbld', 17:16:46 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', 17:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:46 INFO - '_': '/tools/buildbot/bin/python'} 17:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:16:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:16:48 INFO - Installing collected packages: mozsystemmonitor 17:16:48 INFO - Running setup.py install for mozsystemmonitor 17:16:48 INFO - Successfully installed mozsystemmonitor 17:16:48 INFO - Cleaning up... 17:16:48 INFO - Return code: 0 17:16:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:16:48 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')]} 17:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:48 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')]} 17:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:48 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 0x1108030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x126b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c73b0>, '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 0x13c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c8740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', '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 17:16:48 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 17:16:48 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 17:16:48 INFO - Using env: {'DISPLAY': ':0', 17:16:48 INFO - 'HOME': '/home/cltbld', 17:16:48 INFO - 'LANG': 'en_US.UTF-8', 17:16:48 INFO - 'LANGUAGE': 'en_US:en', 17:16:48 INFO - 'LOGNAME': 'cltbld', 17:16:48 INFO - 'MAIL': '/var/mail/cltbld', 17:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:48 INFO - 'MOZ_NO_REMOTE': '1', 17:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:48 INFO - 'NO_EM_RESTART': '1', 17:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:48 INFO - 'PWD': '/builds/slave/test', 17:16:48 INFO - 'SHELL': '/bin/bash', 17:16:48 INFO - 'SHLVL': '1', 17:16:48 INFO - 'TERM': 'linux', 17:16:48 INFO - 'TMOUT': '86400', 17:16:48 INFO - 'USER': 'cltbld', 17:16:48 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', 17:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:48 INFO - '_': '/tools/buildbot/bin/python'} 17:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:48 INFO - Downloading/unpacking jsonschema==2.5.1 17:16:48 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 17:16:48 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 17:16:48 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 17:16:48 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 17:16:48 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 17:16:48 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 17:16:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:16:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:16:50 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 17:16:50 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 17:16:50 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 17:16:50 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 17:16:50 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 17:16:50 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 17:16:51 INFO - Downloading functools32-3.2.3-2.tar.gz 17:16:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:16:51 INFO - warning: no files found matching '*.txt' 17:16:51 INFO - no previously-included directories found matching 'build' 17:16:51 INFO - no previously-included directories found matching 'dist' 17:16:51 INFO - no previously-included directories found matching '.git*' 17:16:51 INFO - Installing collected packages: jsonschema, functools32 17:16:51 INFO - Running setup.py install for functools32 17:16:51 INFO - warning: no files found matching '*.txt' 17:16:51 INFO - no previously-included directories found matching 'build' 17:16:51 INFO - no previously-included directories found matching 'dist' 17:16:51 INFO - no previously-included directories found matching '.git*' 17:16:51 INFO - Successfully installed jsonschema functools32 17:16:51 INFO - Cleaning up... 17:16:51 INFO - Return code: 0 17:16:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:16:51 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')]} 17:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:51 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')]} 17:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:51 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1108030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x126b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c73b0>, '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 0x13c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c8740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', '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 17:16:51 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:16:51 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 functools32==3.2.3-2 17:16:51 INFO - Using env: {'DISPLAY': ':0', 17:16:51 INFO - 'HOME': '/home/cltbld', 17:16:51 INFO - 'LANG': 'en_US.UTF-8', 17:16:51 INFO - 'LANGUAGE': 'en_US:en', 17:16:51 INFO - 'LOGNAME': 'cltbld', 17:16:51 INFO - 'MAIL': '/var/mail/cltbld', 17:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:51 INFO - 'MOZ_NO_REMOTE': '1', 17:16:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:51 INFO - 'NO_EM_RESTART': '1', 17:16:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:51 INFO - 'PWD': '/builds/slave/test', 17:16:51 INFO - 'SHELL': '/bin/bash', 17:16:51 INFO - 'SHLVL': '1', 17:16:51 INFO - 'TERM': 'linux', 17:16:51 INFO - 'TMOUT': '86400', 17:16:51 INFO - 'USER': 'cltbld', 17:16:51 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', 17:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:51 INFO - '_': '/tools/buildbot/bin/python'} 17:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:16:51 INFO - Cleaning up... 17:16:51 INFO - Return code: 0 17:16:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:16:51 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')]} 17:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:51 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')]} 17:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:51 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 0x1108030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x126b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c73b0>, '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 0x13c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c8740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', '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 17:16:51 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 17:16:51 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 17:16:51 INFO - Using env: {'DISPLAY': ':0', 17:16:51 INFO - 'HOME': '/home/cltbld', 17:16:51 INFO - 'LANG': 'en_US.UTF-8', 17:16:51 INFO - 'LANGUAGE': 'en_US:en', 17:16:51 INFO - 'LOGNAME': 'cltbld', 17:16:51 INFO - 'MAIL': '/var/mail/cltbld', 17:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:51 INFO - 'MOZ_NO_REMOTE': '1', 17:16:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:51 INFO - 'NO_EM_RESTART': '1', 17:16:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:51 INFO - 'PWD': '/builds/slave/test', 17:16:51 INFO - 'SHELL': '/bin/bash', 17:16:51 INFO - 'SHLVL': '1', 17:16:51 INFO - 'TERM': 'linux', 17:16:51 INFO - 'TMOUT': '86400', 17:16:51 INFO - 'USER': 'cltbld', 17:16:51 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', 17:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:51 INFO - '_': '/tools/buildbot/bin/python'} 17:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:51 INFO - Downloading/unpacking blobuploader==1.2.4 17:16:51 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 17:16:51 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 17:16:51 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 17:16:51 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 17:16:51 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 17:16:51 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 17:16:53 INFO - Downloading blobuploader-1.2.4.tar.gz 17:16:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:16:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:16:53 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 17:16:53 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 17:16:53 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 17:16:53 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 17:16:53 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 17:16:53 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 17:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:16:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:16:54 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 17:16:54 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 17:16:54 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 17:16:54 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 17:16:54 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 17:16:54 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 17:16:54 INFO - Downloading docopt-0.6.1.tar.gz 17:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:16:54 INFO - Installing collected packages: blobuploader, requests, docopt 17:16:54 INFO - Running setup.py install for blobuploader 17:16:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:16:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:16:54 INFO - Running setup.py install for requests 17:16:54 INFO - Running setup.py install for docopt 17:16:55 INFO - Successfully installed blobuploader requests docopt 17:16:55 INFO - Cleaning up... 17:16:55 INFO - Return code: 0 17:16:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:55 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')]} 17:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:55 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')]} 17:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:55 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 0x1108030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x126b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c73b0>, '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 0x13c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c8740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', '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 17:16:55 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 17:16:55 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 17:16:55 INFO - Using env: {'DISPLAY': ':0', 17:16:55 INFO - 'HOME': '/home/cltbld', 17:16:55 INFO - 'LANG': 'en_US.UTF-8', 17:16:55 INFO - 'LANGUAGE': 'en_US:en', 17:16:55 INFO - 'LOGNAME': 'cltbld', 17:16:55 INFO - 'MAIL': '/var/mail/cltbld', 17:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:55 INFO - 'MOZ_NO_REMOTE': '1', 17:16:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:55 INFO - 'NO_EM_RESTART': '1', 17:16:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:55 INFO - 'PWD': '/builds/slave/test', 17:16:55 INFO - 'SHELL': '/bin/bash', 17:16:55 INFO - 'SHLVL': '1', 17:16:55 INFO - 'TERM': 'linux', 17:16:55 INFO - 'TMOUT': '86400', 17:16:55 INFO - 'USER': 'cltbld', 17:16:55 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', 17:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:55 INFO - '_': '/tools/buildbot/bin/python'} 17:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:55 INFO - Running setup.py (path:/tmp/pip-5nMmwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:55 INFO - Running setup.py (path:/tmp/pip-53WRJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:55 INFO - Running setup.py (path:/tmp/pip-3y4qbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:55 INFO - Running setup.py (path:/tmp/pip-Pq1yYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:55 INFO - Running setup.py (path:/tmp/pip-_RqBdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:55 INFO - Running setup.py (path:/tmp/pip-cq7bg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:55 INFO - Running setup.py (path:/tmp/pip-4YF1SV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:55 INFO - Running setup.py (path:/tmp/pip-oHiEWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:55 INFO - Running setup.py (path:/tmp/pip-mKk1aE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:56 INFO - Running setup.py (path:/tmp/pip-Sac2z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:56 INFO - Running setup.py (path:/tmp/pip-s1qg_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:56 INFO - Running setup.py (path:/tmp/pip-h69elt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:56 INFO - Running setup.py (path:/tmp/pip-R1Moha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:56 INFO - Running setup.py (path:/tmp/pip-Vv9lFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:56 INFO - Running setup.py (path:/tmp/pip-CUIXlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:56 INFO - Running setup.py (path:/tmp/pip-ICfUKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:56 INFO - Running setup.py (path:/tmp/pip-LKkkLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:16:56 INFO - Running setup.py install for manifestparser 17:16:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:16:56 INFO - Running setup.py install for mozcrash 17:16:56 INFO - Running setup.py install for mozdebug 17:16:56 INFO - Running setup.py install for mozdevice 17:16:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:16:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:16:57 INFO - Running setup.py install for mozfile 17:16:57 INFO - Running setup.py install for mozhttpd 17:16:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:16:57 INFO - Running setup.py install for mozinfo 17:16:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:16:57 INFO - Running setup.py install for mozInstall 17:16:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:16:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:16:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:16:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:16:57 INFO - Running setup.py install for mozleak 17:16:57 INFO - Running setup.py install for mozlog 17:16:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:16:57 INFO - Running setup.py install for moznetwork 17:16:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:16:57 INFO - Running setup.py install for mozprocess 17:16:57 INFO - Running setup.py install for mozprofile 17:16:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:16:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:16:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:16:57 INFO - Running setup.py install for mozrunner 17:16:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:16:58 INFO - Running setup.py install for mozscreenshot 17:16:58 INFO - Running setup.py install for moztest 17:16:58 INFO - Running setup.py install for mozversion 17:16:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:16:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:16:58 INFO - Cleaning up... 17:16:58 INFO - Return code: 0 17:16:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:58 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')]} 17:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:58 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')]} 17:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:58 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 0x1108030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x126b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c73b0>, '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 0x13c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c8740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', '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 17:16:58 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 17:16:58 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 17:16:58 INFO - Using env: {'DISPLAY': ':0', 17:16:58 INFO - 'HOME': '/home/cltbld', 17:16:58 INFO - 'LANG': 'en_US.UTF-8', 17:16:58 INFO - 'LANGUAGE': 'en_US:en', 17:16:58 INFO - 'LOGNAME': 'cltbld', 17:16:58 INFO - 'MAIL': '/var/mail/cltbld', 17:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:58 INFO - 'MOZ_NO_REMOTE': '1', 17:16:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:58 INFO - 'NO_EM_RESTART': '1', 17:16:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:58 INFO - 'PWD': '/builds/slave/test', 17:16:58 INFO - 'SHELL': '/bin/bash', 17:16:58 INFO - 'SHLVL': '1', 17:16:58 INFO - 'TERM': 'linux', 17:16:58 INFO - 'TMOUT': '86400', 17:16:58 INFO - 'USER': 'cltbld', 17:16:58 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', 17:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:58 INFO - '_': '/tools/buildbot/bin/python'} 17:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:58 INFO - Running setup.py (path:/tmp/pip-81oScB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:58 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)) 17:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:58 INFO - Running setup.py (path:/tmp/pip-B3BATP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:58 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)) 17:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:58 INFO - Running setup.py (path:/tmp/pip-JRDLgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:58 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)) 17:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:58 INFO - Running setup.py (path:/tmp/pip-QgX8RR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:58 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)) 17:16:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:58 INFO - Running setup.py (path:/tmp/pip-8dKZoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:59 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)) 17:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:59 INFO - Running setup.py (path:/tmp/pip-6RdFSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:59 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)) 17:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:59 INFO - Running setup.py (path:/tmp/pip-mHkyrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:59 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)) 17:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:59 INFO - Running setup.py (path:/tmp/pip-6Skde4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:59 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)) 17:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:59 INFO - Running setup.py (path:/tmp/pip-JGSL6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:59 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)) 17:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:59 INFO - Running setup.py (path:/tmp/pip-ciHV3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 17:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:59 INFO - Running setup.py (path:/tmp/pip-2EQj5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:59 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)) 17:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:59 INFO - Running setup.py (path:/tmp/pip-gWATIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:59 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)) 17:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:59 INFO - Running setup.py (path:/tmp/pip-PmGpm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:59 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)) 17:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:59 INFO - Running setup.py (path:/tmp/pip-otT2_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:59 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)) 17:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:59 INFO - Running setup.py (path:/tmp/pip-30Izsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:59 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)) 17:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:59 INFO - Running setup.py (path:/tmp/pip-yHlfoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:59 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)) 17:16:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:59 INFO - Running setup.py (path:/tmp/pip-xUTrjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:59 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)) 17:16:59 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)) 17:16:59 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)) 17:16:59 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)) 17:16:59 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)) 17:16:59 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)) 17:16:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:16:59 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 17:16:59 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 17:16:59 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 17:16:59 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 17:16:59 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 17:16:59 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 17:17:01 INFO - Downloading blessings-1.6.tar.gz 17:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:17:01 INFO - Installing collected packages: blessings 17:17:01 INFO - Running setup.py install for blessings 17:17:01 INFO - Successfully installed blessings 17:17:01 INFO - Cleaning up... 17:17:02 INFO - Return code: 0 17:17:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:17:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:02 INFO - Reading from file tmpfile_stdout 17:17:02 INFO - Current package versions: 17:17:02 INFO - argparse == 1.2.1 17:17:02 INFO - blessings == 1.6 17:17:02 INFO - blobuploader == 1.2.4 17:17:02 INFO - docopt == 0.6.1 17:17:02 INFO - functools32 == 3.2.3-2 17:17:02 INFO - jsonschema == 2.5.1 17:17:02 INFO - manifestparser == 1.1 17:17:02 INFO - mozInstall == 1.12 17:17:02 INFO - mozcrash == 0.17 17:17:02 INFO - mozdebug == 0.1 17:17:02 INFO - mozdevice == 0.48 17:17:02 INFO - mozfile == 1.2 17:17:02 INFO - mozhttpd == 0.7 17:17:02 INFO - mozinfo == 0.9 17:17:02 INFO - mozleak == 0.1 17:17:02 INFO - mozlog == 3.3 17:17:02 INFO - moznetwork == 0.27 17:17:02 INFO - mozprocess == 0.23 17:17:02 INFO - mozprofile == 0.28 17:17:02 INFO - mozrunner == 6.12 17:17:02 INFO - mozscreenshot == 0.1 17:17:02 INFO - mozsystemmonitor == 0.3 17:17:02 INFO - moztest == 0.7 17:17:02 INFO - mozversion == 1.4 17:17:02 INFO - psutil == 3.1.1 17:17:02 INFO - requests == 1.2.3 17:17:02 INFO - wsgiref == 0.1.2 17:17:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:02 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')]} 17:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:02 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')]} 17:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:02 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 0x1108030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x126b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c73b0>, '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 0x13c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c8740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', '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 17:17:02 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 17:17:02 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 17:17:02 INFO - Using env: {'DISPLAY': ':0', 17:17:02 INFO - 'HOME': '/home/cltbld', 17:17:02 INFO - 'LANG': 'en_US.UTF-8', 17:17:02 INFO - 'LANGUAGE': 'en_US:en', 17:17:02 INFO - 'LOGNAME': 'cltbld', 17:17:02 INFO - 'MAIL': '/var/mail/cltbld', 17:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:02 INFO - 'MOZ_NO_REMOTE': '1', 17:17:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:02 INFO - 'NO_EM_RESTART': '1', 17:17:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:02 INFO - 'PWD': '/builds/slave/test', 17:17:02 INFO - 'SHELL': '/bin/bash', 17:17:02 INFO - 'SHLVL': '1', 17:17:02 INFO - 'TERM': 'linux', 17:17:02 INFO - 'TMOUT': '86400', 17:17:02 INFO - 'USER': 'cltbld', 17:17:02 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', 17:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:02 INFO - '_': '/tools/buildbot/bin/python'} 17:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:02 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)) 17:17:02 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)) 17:17:02 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)) 17:17:02 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)) 17:17:02 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)) 17:17:02 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)) 17:17:02 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)) 17:17:02 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)) 17:17:02 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)) 17:17:02 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)) 17:17:02 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)) 17:17:02 INFO - Cleaning up... 17:17:02 INFO - Return code: 0 17:17:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:17:02 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')]} 17:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:02 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')]} 17:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:02 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 0x1108030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x126b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c73b0>, '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 0x13c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c8740>, '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': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', '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 17:17:02 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 17:17:02 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 17:17:02 INFO - Using env: {'DISPLAY': ':0', 17:17:02 INFO - 'HOME': '/home/cltbld', 17:17:02 INFO - 'LANG': 'en_US.UTF-8', 17:17:02 INFO - 'LANGUAGE': 'en_US:en', 17:17:02 INFO - 'LOGNAME': 'cltbld', 17:17:02 INFO - 'MAIL': '/var/mail/cltbld', 17:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:02 INFO - 'MOZ_NO_REMOTE': '1', 17:17:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:02 INFO - 'NO_EM_RESTART': '1', 17:17:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:02 INFO - 'PWD': '/builds/slave/test', 17:17:02 INFO - 'SHELL': '/bin/bash', 17:17:02 INFO - 'SHLVL': '1', 17:17:02 INFO - 'TERM': 'linux', 17:17:02 INFO - 'TMOUT': '86400', 17:17:02 INFO - 'USER': 'cltbld', 17:17:02 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', 17:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:02 INFO - '_': '/tools/buildbot/bin/python'} 17:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:17:02 INFO - Cleaning up... 17:17:02 INFO - Return code: 0 17:17:02 INFO - Running post-action listener: _resource_record_post_action 17:17:02 INFO - Running post-action listener: _start_resource_monitoring 17:17:02 INFO - Starting resource monitoring. 17:17:02 INFO - [mozharness: 2016-10-25 00:17:02.776123Z] Finished create-virtualenv step (success) 17:17:02 INFO - [mozharness: 2016-10-25 00:17:02.776555Z] Running install step. 17:17:02 INFO - Running pre-action listener: _resource_record_pre_action 17:17:02 INFO - Running main action method: install 17:17:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:02 INFO - Reading from file tmpfile_stdout 17:17:02 INFO - Detecting whether we're running mozinstall >=1.0... 17:17:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:17:03 INFO - Reading from file tmpfile_stdout 17:17:03 INFO - Output received: 17:17:03 INFO - Usage: mozinstall [options] installer 17:17:03 INFO - Options: 17:17:03 INFO - -h, --help show this help message and exit 17:17:03 INFO - -d DEST, --destination=DEST 17:17:03 INFO - Directory to install application into. [default: 17:17:03 INFO - "/builds/slave/test"] 17:17:03 INFO - --app=APP Application being installed. [default: firefox] 17:17:03 INFO - mkdir: /builds/slave/test/build/application 17:17:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:17:16 INFO - Reading from file tmpfile_stdout 17:17:16 INFO - Output received: 17:17:16 INFO - /builds/slave/test/build/application/firefox/firefox 17:17:16 INFO - Running post-action listener: _resource_record_post_action 17:17:16 INFO - [mozharness: 2016-10-25 00:17:16.594716Z] Finished install step (success) 17:17:16 INFO - [mozharness: 2016-10-25 00:17:16.594997Z] Running run-tests step. 17:17:16 INFO - Running pre-action listener: _resource_record_pre_action 17:17:16 INFO - Running main action method: run_tests 17:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:17:16 INFO - Python 2.7.3 17:17:16 INFO - Return code: 0 17:17:16 INFO - grabbing minidump binary from tooltool 17:17:16 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')]} 17:17:16 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 0x13c73b0>, '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 0x13c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:17:16 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 17:17:16 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 17:17:16 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 17:17:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:17:16 INFO - Return code: 0 17:17:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:17:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:17:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:17:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:17:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 17:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 17:17:16 INFO - Using env: {'DISPLAY': ':0', 17:17:16 INFO - 'HOME': '/home/cltbld', 17:17:16 INFO - 'LANG': 'en_US.UTF-8', 17:17:16 INFO - 'LANGUAGE': 'en_US:en', 17:17:16 INFO - 'LOGNAME': 'cltbld', 17:17:16 INFO - 'MAIL': '/var/mail/cltbld', 17:17:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:17:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:16 INFO - 'MOZ_NO_REMOTE': '1', 17:17:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:17:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:17:16 INFO - 'NO_EM_RESTART': '1', 17:17:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:16 INFO - 'PWD': '/builds/slave/test', 17:17:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:17:16 INFO - 'SHELL': '/bin/bash', 17:17:16 INFO - 'SHLVL': '1', 17:17:16 INFO - 'TERM': 'linux', 17:17:16 INFO - 'TMOUT': '86400', 17:17:16 INFO - 'USER': 'cltbld', 17:17:16 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199', 17:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:16 INFO - '_': '/tools/buildbot/bin/python'} 17:17:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 17:17:16 INFO - mozversion application_buildid: 20161024141422 17:17:16 INFO - mozversion application_changeset: d025e0641d9eefea99530188fa1eeaf4d780d6af 17:17:16 INFO - mozversion application_display_name: Firefox 17:17:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:17:16 INFO - mozversion application_name: Firefox 17:17:16 INFO - mozversion application_remotingname: firefox 17:17:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:17:16 INFO - mozversion application_vendor: Mozilla 17:17:16 INFO - mozversion application_version: 50.0 17:17:16 INFO - mozversion platform_buildid: 20161024141422 17:17:16 INFO - mozversion platform_changeset: d025e0641d9eefea99530188fa1eeaf4d780d6af 17:17:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:17:16 INFO - mozversion platform_version: 50.0 17:17:16 INFO - using testdate: 1477354636 17:17:16 INFO - actual date: 1477354636 17:17:16 INFO - starting webserver on 'localhost:46279' 17:17:16 INFO - SUITE-START | Running 2 tests 17:17:16 INFO - TEST-START | dromaeo_css 17:17:16 INFO - operating with platform_type : linux_ 17:17:16 INFO - Initialising browser for dromaeo_css test... 17:17:16 INFO - TEST-INFO | started process 15148 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH4BcAf/profile http://localhost:46279/getInfo.html) 17:17:21 INFO - PROCESS | 15148 | __metrics Screen width/height:1600/1200 17:17:21 INFO - PROCESS | 15148 | colorDepth:24 17:17:21 INFO - PROCESS | 15148 | Browser inner width/height: 1024/697 17:17:21 INFO - PROCESS | 15148 | __metrics 17:17:22 INFO - PROCESS | 15148 | 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" 17:17:22 INFO - PROCESS | 15148 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:17:23 INFO - TEST-INFO | 15148: exit 0 17:17:23 INFO - Browser initialized. 17:17:23 INFO - Running cycle 1/1 for dromaeo_css test... 17:17:23 INFO - TEST-INFO | started process 15237 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH4BcAf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:17:35 INFO - PROCESS | 15237 | RSS: Main: 144203776 17:17:35 INFO - PROCESS | 15237 | 17:18:26 INFO - PROCESS | 15237 | 1477354706657 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] 17:20:34 INFO - PROCESS | 15237 | Cycle 1(1): loaded http://localhost:46279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46279/tests/dromaeo/cssquery-ext.html) 17:20:35 INFO - PROCESS | 15237 | RSS: Main: 269627392 17:20:35 INFO - PROCESS | 15237 | 17:23:37 INFO - PROCESS | 15237 | Cycle 1(1): loaded http://localhost:46279/tests/dromaeo/cssquery-ext.html (next: http://localhost:46279/tests/dromaeo/cssquery-jquery.html) 17:23:38 INFO - PROCESS | 15237 | RSS: Main: 244195328 17:23:38 INFO - PROCESS | 15237 | 17:26:40 INFO - PROCESS | 15237 | Cycle 1(1): loaded http://localhost:46279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46279/tests/dromaeo/cssquery-mootools.html) 17:26:40 INFO - PROCESS | 15237 | RSS: Main: 204611584 17:26:40 INFO - PROCESS | 15237 | 17:29:42 INFO - PROCESS | 15237 | Cycle 1(1): loaded http://localhost:46279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46279/tests/dromaeo/cssquery-prototype.html) 17:29:43 INFO - PROCESS | 15237 | RSS: Main: 175882240 17:29:43 INFO - PROCESS | 15237 | 17:32:45 INFO - PROCESS | 15237 | Cycle 1(1): loaded http://localhost:46279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46279/tests/dromaeo/cssquery-yui.html) 17:32:46 INFO - PROCESS | 15237 | RSS: Main: 176865280 17:32:46 INFO - PROCESS | 15237 | 17:35:49 INFO - PROCESS | 15237 | Cycle 1(1): loaded http://localhost:46279/tests/dromaeo/cssquery-yui.html (next: http://localhost:46279/tests/dromaeo/cssquery-dojo.html) 17:35:49 INFO - PROCESS | 15237 | RSS: Main: 263368704 17:35:49 INFO - PROCESS | 15237 | 17:35:49 INFO - PROCESS | 15237 | __start_tp_report 17:35:49 INFO - PROCESS | 15237 | _x_x_mozilla_page_load 17:35:49 INFO - PROCESS | 15237 | _x_x_mozilla_page_load_details 17:35:49 INFO - PROCESS | 15237 | |i|pagename|runs| 17:35:49 INFO - PROCESS | 15237 | |0;dojo.html;3194.9201269968257;3210.5184222366643;3233.1593785615746;3249.983750081249;3274.459714147166;5488.094464413367;5579.107342825148;5630.352509461413;5636.379998200195;5649.039663257241;5512.972435137819;5553.7778488860395;5563.332400111982;5576.219329293902;5592.217089607448;5014.4734802845815;5033.094043072246;5074.407661066741;5076.6700164489375;5100.387953445592;4987;5102.744862756847;5128.794848206067;5131.435542090367;5135.332406787113;4933.580645645106;4996.725180115092;5031.823886163985;5047.192449208109;5114.258432527281;5047.747612619372;5065.670731402447;5160.200168973815;5173.896522069575;5206.479352064801;4881;5067.290579318898;5092;5131.486851314876;5155.587552995751;5452.700101494416;5458.5087342139395;5468.507834294933;5490.121580547093;5493.038718224335;5923.763049478016;5964.493018093467;5968.104784282393;5982.700864956735;5988.041913293852;5743.569232307552;6007.909881351783;6018.307894592134;6031.396860313977;6035.517158627299;5626.324841019063;5655.830325090295;5684.147377893349;5700.059490184115;5715.1713001614535;6205.53912556947;6227.813165605039;6234.844128896814;6236.688165591702;6243.625382477066;5659.717014149276;5736.770529178786;5963.314218864886;5971.940280597225;5974.193483879708;5662.85842853932;5899.764009439575;5913.201717768046;5923.881522369615;5956.16613674086;5889.352171261158;5889.5877288590245;5902.1441890926435;5902.527797776168;5903.557233207441;5758.452946970031;5835.153902684087;5854.385289544622;5910.172575839386;5914.704264786744;4491.708038977456;4550.954490455119;4551.8634440966825;4571.017231295221;4577.404937358188;4033.153037862023;4068.0440096577872;4071.8371265149062;4075.3683179107284;4091.509018917749;5507.339119305709;5646.181303710939;5703.486686198179;5718.79984200551;5722.37053924068;5980.55145864062;6076.513878889767;6077.787277445268;6079.544034197364;6092.847678808065;5735.4551317624755;5744.655320680772;5745.396733343023;5747.453991870849;5755.194272801811;5007.924881126786;5090.08378491875;5152.845414637567;5165.044466773659;5172.379314482211;6072.453479186895;6254;6260.186175797117;6261.499080073583;6278.591891527036;6041.75832966694;6052.709091544458;6058.212432383762;6063.242094738158;6079.544034197541;5932.614380065282;6107.206063211754;6191.721372538156;6208.802466729728;6260.342664020394;5793.9420605795085;5916.674582897982;5925.585209035326;5926.525877929842;5944.524438045032;5952.910706339495;6005.159277701038;6014.789482368096;6016.368281330398;6020.51835853122;5663.9716801415725;5757.9424205757405;5896.056630939029;5903.822885313448;5906.911396328973;5745.080787074216;5836.270466191726;5844.12338149264;5848.0350742127075;5870.677112758839;5768.971155144198;5788.305403351625;5798.884022319615;5803.593748437569;5808.07070868659;5604.327480702342;5661.46216109469;5832;5841.269841269841;5885.3820348862755;5588.6646801192055;5701.144828275792;5710.65736055838;5733.369329373682;5740.971295143498 17:35:49 INFO - PROCESS | 15237 | |1;ext.html;578.5920925747348;634.3561285295426;655.6361219523164;681.274442718505;686.7699320727556;33869.322613547716;37563.812180939094;37786.62213377862;44473.55526444734;45805;45942.310865337044;46375.768121159344;46568.53431465684;46571.767141164244;46590.301145482845;9157.175854173107;9287.117723816227;9316.114969077928;9317.068293170696;9317.394369366004;8492.872606910902;11014.889851101507;11393.088552915788;11463.426828658534;11471.082313414949;11301.49143288554;11345.659630211065;11376.715582110472;11420.486078126503;11432;11175.385353805537;11224.102071834233;11236.10111191111;11249.55001799927;11256.943715281453;11014.339139651567;11065.723356916062;11070.446477676083;11087.611933582286;11099.44502774858;45480.77259613714;45724;45788.77105614484;45970.77014614906;45987.31019034717;3653.342398368293;3704.129529560551;3812.80935953204;3954.0555502910206;4280.657547396201;34720.9911511436;34856.30287394263;35284.11789705252;35985.10037249063;36570.634293656985;4590.472095709003;4635.327877457749;4654.201998901049;4686.53134686532;4780.402449693788;368586.1570692156;368644;368886;369210;370192;37356;38524.6147538521;60625.69687151536;60987;61054;40127.59872401297;40394;40505.1898962025;40550.79724601417;40554.39168412476;17415.3033878646;17564.824351756575;17582.384616538617;17600.29598816033;17601.29594816193;13115.27865967381;14435.783463248059;14897.106173629616;15001.474948376754;15004.924975375054;9074.909250907407;9257.722268331961;9390.389624674373;9392.624295028123;9412.482313472825;3514.3674138655288;3572.8392222350058;3577.874774382884;3616.674499295076;3634.254977729559;1068.7274744939991;1081.2269227502375;1141.8401423800674;1150.5915400033034;1153.9671993565712;34705.82647086748;35065;35114.29771404558;35229;35318.117047074025;1170.3387586014046;1173.9589114381126;1202.0924202227263;1219.6950762309423;1220.8473940757406;18136.274549017893;18193.909030454764;18255.361062362754;18279.451616451526;18308.450746477625;159094;159576;159774;160137.199314005;160143;62891.37108628855;63653.681731591045;63667.68166159139;63697.044544331875;63753.681231593546;1360.3606305036617;1404.0592802822134;1427.9928600356932;1460.5399299220712;1488.694817562397;34399.656003439646;35763;35899.28201436009;35926.101847454025;36182.819085904404;53477.4652253488;54202.45797541974;54239.72880135574;54245;54269.18596221139;435130;436989;437505;437506;438167;29766.851165744032;30843.845780770953;30916;31128.221794454414;31337;52134.47865521448;52789;52860;53421;53976.730116349165;44548.77725611351;44861;45372.95108343125;45434.77282613566;45698.77150614359;44494.55505444904;45191.32213016873;45304.320435192836;45809.54190458186;45904;43113.78443107764;43176.35235471533;44289;44500.777496112314;44639.330410044524;35867.28265434729;38738.6126138735;39280;39371.2125757489;39528.80235598804;29565.408691826473;29625.85187074051;29703.851480742458;29704.66998692544;29823.850880745456 17:35:49 INFO - PROCESS | 15237 | |2;jquery.html;395.8206863195094;401.80914065818723;411.47331415787795;413.2520138549225;427.09029766597143;19732.506687332807;20113.899430502865;20598.279060232893;20628.793712062892;20749.273775417863;12151;12678.175918565286;19345.806541934577;20553.4861628459;20853.791462085373;5139.434662187156;5269.473052694719;5414.566834653218;5447.972760136194;5463.153211252262;4110.1163249901265;5282.815101471449;5391.838244852661;5415.404305526389;7192.460565457612;7635.618219089051;7668.309852113309;7798.376129909594;7805.687772489122;7810.49231799934;7727.420443466706;7793.805154871116;7865.370770338388;7895.289423951843;7923.445358824884;7619.276168763959;7645.235476452366;7654.043244594426;7681.155072941972;7696.114946781136;26774.33064173392;27316.31709207266;27564.173074807586;27591.724082759116;27854.72145278562;2678.317029157572;2881.423715256957;2966.3622321201046;2981.6571094324213;3461.6365281645567;16458.42395516151;16672.66654666912;16702.749458758128;16832;17126.657466850593;2148.5380643161793;2158.4064382294837;2202.7797220277844;2219.212179676208;2225.63277059285;97182.51408742982;97366.5131674337;99597;101012;101030;17764.733528997076;17964.730529042074;18294.451166465027;18746.156422961;19816.108275127655;33013;33078;33345.66654333426;33431.164220894185;33532.83233583817;10868.130549556017;10956.90430956898;11041.83437248442;11149.721256968478;11275.590551181102;9462.858057129148;9480.383775054599;9510.763600732;9558.330916835894;9714.125728684452;8806.383553151218;9295.628174873065;9359.859602105973;9522.95238523803;9613;2885.624868767047;3511.034465522002;3621.5011641966958;3683.318586061587;3774.9811250944117;973.5035132082688;1044.4046893270915;1055.9736006599742;1059.5602824827765;1074.7823839090822;19649.803501964798;20170.39488815338;20241.797582024286;20601.158727698978;20853.37439876806;1000.5947591225565;1033.178622994706;1036.6889933020063;1055.3826011783192;1061.3260579532052;9404.576794044147;11279.266847654877;11381.04924357907;11433;11609.419529023515;489472;506122;510876;520144;530728;93078;93329.53335233417;96297.51851240698;96390.51804741072;99527.50236248918;1036.2487196782424;1090.9727256818485;1122.36586328724;1123.6292023632182;1126.2510430563748;17394.652106958045;17983.820161798216;18451;18540.351087711864;18788.530286742392;42537.574624253364;44547.55452445434;44619.776901115285;45529.31706024346;46086.539134608225;58115.418845811;61063;61112;61812.38187618246;62195.6890215546;9399.436033837992;9508.144267016001;9552.283578731483;9667.806643867223;9701.320907536405;12983.156094853804;13421.731565368833;13652.453901843815;13676.589702308947;14014.789778153132;11597.478113485082;11729.35488548138;12190.2076365036;12341.07441941876;12414.379281035732;11630.94184529072;11831.585894493652;11854.703632409259;11901.10741694359;12411.813822792847;8817.603207855791;12112.394380280775;12292.262464252173;12351.629451116482;12489.625311240678;9240.907590924273;9426.245900328095;9472.479013654316;9615.2307815376;9620.182284505616;11731.178817483036;12026.037916966447;12053;12294.631161065183;12387.318697471726 17:35:49 INFO - PROCESS | 15237 | |3;mootools.html;1565.6242501799563;1568.835272296409;1726.7927848658155;1830.670479313723;1836.4766041678124;9186.724398268047;9961.402315861053;9962.153216976565;9967.551460184291;9978.30151889368;8456.06983231844;9261.722148335544;9541.570629321679;9576.473293968846;9766;2416.3355077353735;2432.3311089450367;2435.4641978764644;2436.415260337524;2444.828861979662;2382.5830479666074;2412.7828495435406;2463.5688754467988;2467.037855236459;2467.037855236459;2426.9514609707794;2445.205308274818;2446.7430919753438;2453.865037422941;2454.5459090068307;2386.7851893329685;2395.1138078910826;2404.6753688252124;2406.434487895352;2414.3239892830034;2291.175176936302;2377.393764590036;2379.3694670912223;2382.2257766226044;2384.8092152627746;9916.405015699009;9955.153811925995;9958.950205249;9987.450690212036;10000.649977250834;5503.091989821674;5646.745896434671;5649.0114230009585;5651.2935883014625;5666.886662266773;3470.4967779671806;3549.893503194908;3565.73257005723;3610.873619423334;3617.385044542434;1163.9010684091863;1182.9585964491203;1215.6778453709774;1226.1002728547012;1239.0335538280156;13843.861561384358;14005.789913151311;14046.999999999898;14066.437342506288;14088.2955852208;4546.431696037996;4761.666683332133;4838.782254798472;4843.491433399513;4844.660873738804;10798.244122911477;10853.403062831461;10854.403007834644;10868.728281793019;10895.237333386745;5589.916151257735;5603.859903502445;5609.439056094439;5610.663360198319;5610.803621873297;4529.864104076817;4601.654875884322;4752.976235118802;5141.38303403594;5169.508896654887;4059.2490389278078;4061.1877624475223;4114;4152.190322887069;4218.78906054696;1103.7957977774138;1143.1140865828884;1161.7269941563725;1164.889335513125;1165.854268216473;1038.6364772329775;1066.8719753629614;1079.6599071292517;1080.5623722392443;1089.967300980956;6389.840253993687;6594.769183078569;6644.468442524587;6792.864142717217;6949.89575156373;483.54546726077376;488.060483569128;491.7123482762552;492.68468180364596;495.9107360675113;4405.118976204772;4644;4645.86062418128;4649.558291962258;4710.62315014798;11773.17587768865;12147.210431321935;12240.510379584717;12250.509979600894;12263.325517096468;11838.171328007129;12006.399680015964;12121.878781212075;12123.272603643809;12195.329256890956;9097.90902090984;9151.496667683208;9158.404703694372;9180.6786762463;9196;6439.581427207217;6643.634600096848;6753.628550429774;6756.121704178426;6760.290169532325;11434.656960291204;11435.542578296774;11487.770244595229;11624.128190385585;11744.471498782746;11372.43137843121;11493;11722.062235021349;11729.35488548138;11770.823437648613;7892.960535197287;8378.49729016261;8560.614772335135;8737.78155546091;8975.685850995184;9530.285228608021;9589.90410095918;9591.42451452915;9596.520173991134;9637.084476974676;7845.725399610987;7964.482308649919;8083.959580202061;8089.3528517719615;8373.874391884248;8187;8195.344372450309;8321.25108740192;8335.291500222549;8471.788205294917;7369.742059027908;7526.24737526243;7595.164531901597;7774.416918731004;7880.408969327438;5720.685362305113;5798.536117110705;5827.300723912988;5857.648541087548;5984.431479009487;5805.767769289181;5917.556183286185;5945.167676525203;5948.4943779779205;5974.820825151313 17:35:49 INFO - PROCESS | 15237 | |4;prototype.html;340.91306716787216;341.41788251031994;345.30765814541854;350.4900369961706;353.3145697347146;5535.446455354462;5539.534906966402;5591.30108736408;5606.719664016803;5619;5483.643563168392;5502.284702988616;5506.843562851806;5525.917111243334;5532.446755324466;3940.9162480318;4140.420341152234;4291.785410729467;4348.152110338485;4410.933835992462;4379.386885835986;4387.056782791699;4400.723790100867;4409.228385032622;4456.6880318377725;4350.260455722519;4372.759498227596;4382.408374869384;4395.934060989088;4401.207782599131;4325.891852703708;4377.715448495837;4380.846670366538;4389.4513185851765;4416.58042485965;3902.941455878164;3917.688563747403;3922.3920292354733;3932.488776459042;3945.842166313344;5402.459754024605;5406.91889621656;5420.214068959979;5485.807996720136;5486.972565137189;4657.906841863178;4924.852254432372;5042.949570504285;5086.872828179288;5087.440381558063;5798.768049278024;5806.44838740319;5806.477417032445;5812.389699081577;5829.154772557956;4178.12259425521;4192.916141677179;4197.803625966585;4205.894852628709;4207.410962465257;5818.5926985111055;5994.771071930244;6030.547708921848;6071.423214794588;6105.603135796159;4326.221280169537;4328.480582330078;4344.218040752632;4345.804438800262;4371.803268852909;4419.248727716296;4423.181711383404;4433.645308375322;4437.622802061861;4452.4211852458975;4892.996935628188;5003.624728145403;5004.224345226496;5053.317802096743;5062.670926389773;5748.31020277561;5815.94184058154;5821.505172060338;5822.359540450547;5829.417058294137;1505.2172870107484;1520.657851983295;1521;1522.5383631713678;1526.404702166156;936.7939053408239;976.9071938165863;982.8034393121404;982.931194816356;993.3642468819961;4945.2938736135975;5014.448410674823;5044.772985215674;5075.213341932006;5078.238264260391;5150.690958542425;5227.477252274818;5269;5303.920441193308;5345.278387417727;4990.226514890198;5022.7990880365105;5029.899402011939;5310.92033619496;5736.365135936321;3207.6150861896253;3215.389076075538;3224.742020638343;3244.7566432517656;3245.192908134494;6112.388761123941;6344.555881088279;6356.2054743157105;6358.904616430757;6360.491160707133;5869.5010924071075;6124.591343990818;6139.938600614026;6163.921313770073;6172.993777022486;6151.477124444473;6213.1922850029205;6219.253689557192;6234.410225392587;6234.750609975641;5934.495567876779;5937.881242375215;6001.699915004319;6037.426444487766;6060.1212824139375;5734.455226753452;5894.469497745224;5906.911396329145;5940.7920722776225;5954.821355359346;6196.1635179250225;6208.379162083756;6236.75052997875;6239.688015599111;6268.1851359323;4212.64192543625;4314.676399270004;4329.6969212154845;4344.45694288214;4384.254676704904;5872.236609240771;5912.408759124053;5967.492763115184;5987.790427335022;6001.279846418283;5864.736086876186;5904.232449781673;5906.26171728534;5970.104484327386;5975.43233392827;5575.358833734173;5844.211031510692;5866.7066646668345;5883.882322353444;5899.970500147472;5815.185873977562;5818.770582326849;5822.82531524055;5838.357780644211;5840.795572154954;5669.6314739541795;5871.383504732113;5886.764529418775;5902.940970590239;5909.940900590939;5725.08398656213;5731.8567035824435;5737.192784272941;5749.396313387034;5752.252207213036 17:35:49 INFO - PROCESS | 15237 | |5;yui.html;778.711876605656;822.4777266435811;866.605694409044;888.0409158109243;902.8600566912129;16789.328426862925;18236.90881545592;18242.36151734686;18263.269469221214;18276;16598.58503537409;16742.330306787713;16742.41401550946;16746.330146794146;16806;720.8486217894249;852.4799872078028;869.76516340588;872.9869051964193;878.2052242720348;869.2828416556334;872.9869051964225;873.2402809555664;873.9213470787628;873.9956300218522;934.0985948559638;936.4100616611526;937.3157594955686;937.4141161773891;938.1134827587932;926.7451450851003;927.5640448988964;928.9256859451261;929.5677509957868;929.8930622978377;679.0866284846873;682.1234713393316;682.7064362324198;683.5761827666829;683.8461346197066;16247.431339903165;16358.427455039135;16383.918080409521;16405.17974101302;16410.343586256535;16051;16254.83745162545;16262.674746505121;16575.585610359718;16847.41034063814;12463.439145238486;12584.74830503394;13195.670108247275;13382.598522044354;13464.93267533656;4779.522047795227;4845.709257444564;4890.608751299887;4900.313956046157;4902.803887844482;79215.6039219806;81035.5948220261;82487;84412;85012.57493712491;12177.878221217852;12282.201656892452;12285.815712764315;12365.443555040045;12694.746105077847;22870.771292286863;23095;23104.4223894404;23168.65247021296;23253.67463253661;9445.858312125323;9457.76355591102;9567.90432095684;9675.322727409013;9731.9026809731;4873.439554451213;4878.341423907798;4925.13810083234;5181.5854731621375;5301.4168441472175;4079.184163167319;4242.618164365222;4248.02295472037;4252.128313695685;4259.297215959394;723.2369849808503;723.4321057969574;748.8352562436255;753.3671715758788;762.4053238473999;776.8951191589176;827.8178800663844;856.2550580994574;859.617470225756;867.4665080975154;10592.417417042137;10768.569257229623;10853.891461085446;11146.888531114586;11290.717732056764;664.9335066493312;710.4529512275519;752.0862152484757;788.0897563314381;820.7660816667221;4875.293082503088;4889.804407823647;5135.845924622267;5164.431912489623;5168.302279192261;134248;134811.32594336965;136205;137163;140629;117469;118901;120037.39981300037;122379;123783;102368;105343;106810;107405.4629726862;110943;9464.858027129461;9603.663871764455;9787.559559819967;9901.405915645084;9997.100260976547;58413.70793146007;58900.70549647224;59206.40793592009;61470.385296146465;61940.69029654823;80692.19307806846;87168.56415717881;90168;91759;94864.52567737117;12985.415656295301;13172.868271317164;13367.799483007568;13597.598315235888;13686.52097176594;14283.071600345944;14495.130292182503;14977.251137442867;15520.301586428865;16155.515334539983;14931.17878516692;15175.468858590339;15274.541763747106;16438.260278287267;16520.75218871742;15016.174110424032;15025.624359391102;15546.222688865737;15630.374785008473;16650;14694.191819450032;14729.26353682333;14946.850531494545;15641.765373519635;16087;12562.246265224116;12951.611451656465;13140.080194386683;13304.401301941241;13553.457861685421;13424.865751342362;13441.932790335986;13477.393517291941;13614.38735256896;13620.455181792617 17:35:49 INFO - PROCESS | 15237 | __end_tp_report 17:35:49 INFO - PROCESS | 15237 | __start_cc_report 17:35:49 INFO - PROCESS | 15237 | _x_x_mozilla_cycle_collect,1043 17:35:49 INFO - PROCESS | 15237 | __end_cc_report 17:35:49 INFO - PROCESS | 15237 | __startTimestamp1477355749427__endTimestamp 17:35:49 INFO - PROCESS | 15237 | 17:35:49 INFO - PROCESS | 15237 | ------- Summary: start ------- 17:35:49 INFO - PROCESS | 15237 | Number of tests: 6 17:35:49 INFO - PROCESS | 15237 | 17:35:49 INFO - PROCESS | 15237 | [#0] dojo.html Cycles:165 Average:5545.44 Median:5743.57 stddev:635.68 (11.1%) stddev-sans-first:610.30 17:35:49 INFO - PROCESS | 15237 | Values: 3194.9 3210.5 3233.2 3250.0 3274.5 5488.1 5579.1 5630.4 5636.4 5649.0 5513.0 5553.8 5563.3 5576.2 5592.2 5014.5 5033.1 5074.4 5076.7 5100.4 4987.0 5102.7 5128.8 5131.4 5135.3 4933.6 4996.7 5031.8 5047.2 5114.3 5047.7 5065.7 5160.2 5173.9 5206.5 4881.0 5067.3 5092.0 5131.5 5155.6 5452.7 5458.5 5468.5 5490.1 5493.0 5923.8 5964.5 5968.1 5982.7 5988.0 5743.6 6007.9 6018.3 6031.4 6035.5 5626.3 5655.8 5684.1 5700.1 5715.2 6205.5 6227.8 6234.8 6236.7 6243.6 5659.7 5736.8 5963.3 5971.9 5974.2 5662.9 5899.8 5913.2 5923.9 5956.2 5889.4 5889.6 5902.1 5902.5 5903.6 5758.5 5835.2 5854.4 5910.2 5914.7 4491.7 4551.0 4551.9 4571.0 4577.4 4033.2 4068.0 4071.8 4075.4 4091.5 5507.3 5646.2 5703.5 5718.8 5722.4 5980.6 6076.5 6077.8 6079.5 6092.8 5735.5 5744.7 5745.4 5747.5 5755.2 5007.9 5090.1 5152.8 5165.0 5172.4 6072.5 6254.0 6260.2 6261.5 6278.6 6041.8 6052.7 6058.2 6063.2 6079.5 5932.6 6107.2 6191.7 6208.8 6260.3 5793.9 5916.7 5925.6 5926.5 5944.5 5952.9 6005.2 6014.8 6016.4 6020.5 5664.0 5757.9 5896.1 5903.8 5906.9 5745.1 5836.3 5844.1 5848.0 5870.7 5769.0 5788.3 5798.9 5803.6 5808.1 5604.3 5661.5 5832.0 5841.3 5885.4 5588.7 5701.1 5710.7 5733.4 5741.0 17:35:49 INFO - PROCESS | 15237 | 17:35:49 INFO - PROCESS | 15237 | [#1] ext.html Cycles:180 Average:50842.74 Median:369701.00 stddev:90694.81 (24.5%) stddev-sans-first:90870.72 17:35:49 INFO - PROCESS | 15237 | Values: 578.6 634.4 655.6 681.3 686.8 33869.3 37563.8 37786.6 44473.6 45805.0 45942.3 46375.8 46568.5 46571.8 46590.3 9157.2 9287.1 9316.1 9317.1 9317.4 8492.9 11014.9 11393.1 11463.4 11471.1 11301.5 11345.7 11376.7 11420.5 11432.0 11175.4 11224.1 11236.1 11249.6 11256.9 11014.3 11065.7 11070.4 11087.6 11099.4 45480.8 45724.0 45788.8 45970.8 45987.3 3653.3 3704.1 3812.8 3954.1 4280.7 34721.0 34856.3 35284.1 35985.1 36570.6 4590.5 4635.3 4654.2 4686.5 4780.4 368586.2 368644.0 368886.0 369210.0 370192.0 37356.0 38524.6 60625.7 60987.0 61054.0 40127.6 40394.0 40505.2 40550.8 40554.4 17415.3 17564.8 17582.4 17600.3 17601.3 13115.3 14435.8 14897.1 15001.5 15004.9 9074.9 9257.7 9390.4 9392.6 9412.5 3514.4 3572.8 3577.9 3616.7 3634.3 1068.7 1081.2 1141.8 1150.6 1154.0 34705.8 35065.0 35114.3 35229.0 35318.1 1170.3 1174.0 1202.1 1219.7 1220.8 18136.3 18193.9 18255.4 18279.5 18308.5 159094.0 159576.0 159774.0 160137.2 160143.0 62891.4 63653.7 63667.7 63697.0 63753.7 1360.4 1404.1 1428.0 1460.5 1488.7 34399.7 35763.0 35899.3 35926.1 36182.8 53477.5 54202.5 54239.7 54245.0 54269.2 435130.0 436989.0 437505.0 437506.0 438167.0 29766.9 30843.8 30916.0 31128.2 31337.0 52134.5 52789.0 52860.0 53421.0 53976.7 44548.8 44861.0 45373.0 45434.8 45698.8 44494.6 45191.3 45304.3 45809.5 45904.0 43113.8 43176.4 44289.0 44500.8 44639.3 35867.3 38738.6 39280.0 39371.2 39528.8 29565.4 29625.9 29703.9 29704.7 29823.9 17:35:49 INFO - PROCESS | 15237 | 17:35:49 INFO - PROCESS | 15237 | [#2] jquery.html Cycles:180 Average:31986.44 Median:2883.52 stddev:84440.44 (2928.4%) stddev-sans-first:84644.00 17:35:49 INFO - PROCESS | 15237 | Values: 395.8 401.8 411.5 413.3 427.1 19732.5 20113.9 20598.3 20628.8 20749.3 12151.0 12678.2 19345.8 20553.5 20853.8 5139.4 5269.5 5414.6 5448.0 5463.2 4110.1 5282.8 5391.8 5415.4 7192.5 7635.6 7668.3 7798.4 7805.7 7810.5 7727.4 7793.8 7865.4 7895.3 7923.4 7619.3 7645.2 7654.0 7681.2 7696.1 26774.3 27316.3 27564.2 27591.7 27854.7 2678.3 2881.4 2966.4 2981.7 3461.6 16458.4 16672.7 16702.7 16832.0 17126.7 2148.5 2158.4 2202.8 2219.2 2225.6 97182.5 97366.5 99597.0 101012.0 101030.0 17764.7 17964.7 18294.5 18746.2 19816.1 33013.0 33078.0 33345.7 33431.2 33532.8 10868.1 10956.9 11041.8 11149.7 11275.6 9462.9 9480.4 9510.8 9558.3 9714.1 8806.4 9295.6 9359.9 9523.0 9613.0 2885.6 3511.0 3621.5 3683.3 3775.0 973.5 1044.4 1056.0 1059.6 1074.8 19649.8 20170.4 20241.8 20601.2 20853.4 1000.6 1033.2 1036.7 1055.4 1061.3 9404.6 11279.3 11381.0 11433.0 11609.4 489472.0 506122.0 510876.0 520144.0 530728.0 93078.0 93329.5 96297.5 96390.5 99527.5 1036.2 1091.0 1122.4 1123.6 1126.3 17394.7 17983.8 18451.0 18540.4 18788.5 42537.6 44547.6 44619.8 45529.3 46086.5 58115.4 61063.0 61112.0 61812.4 62195.7 9399.4 9508.1 9552.3 9667.8 9701.3 12983.2 13421.7 13652.5 13676.6 14014.8 11597.5 11729.4 12190.2 12341.1 12414.4 11630.9 11831.6 11854.7 11901.1 12411.8 8817.6 12112.4 12292.3 12351.6 12489.6 9240.9 9426.2 9472.5 9615.2 9620.2 11731.2 12026.0 12053.0 12294.6 12387.3 17:35:49 INFO - PROCESS | 15237 | 17:35:49 INFO - PROCESS | 15237 | [#3] mootools.html Cycles:180 Average:6347.36 Median:4644.93 stddev:3747.67 (80.7%) stddev-sans-first:3740.96 17:35:49 INFO - PROCESS | 15237 | Values: 1565.6 1568.8 1726.8 1830.7 1836.5 9186.7 9961.4 9962.2 9967.6 9978.3 8456.1 9261.7 9541.6 9576.5 9766.0 2416.3 2432.3 2435.5 2436.4 2444.8 2382.6 2412.8 2463.6 2467.0 2467.0 2427.0 2445.2 2446.7 2453.9 2454.5 2386.8 2395.1 2404.7 2406.4 2414.3 2291.2 2377.4 2379.4 2382.2 2384.8 9916.4 9955.2 9959.0 9987.5 10000.6 5503.1 5646.7 5649.0 5651.3 5666.9 3470.5 3549.9 3565.7 3610.9 3617.4 1163.9 1183.0 1215.7 1226.1 1239.0 13843.9 14005.8 14047.0 14066.4 14088.3 4546.4 4761.7 4838.8 4843.5 4844.7 10798.2 10853.4 10854.4 10868.7 10895.2 5589.9 5603.9 5609.4 5610.7 5610.8 4529.9 4601.7 4753.0 5141.4 5169.5 4059.2 4061.2 4114.0 4152.2 4218.8 1103.8 1143.1 1161.7 1164.9 1165.9 1038.6 1066.9 1079.7 1080.6 1090.0 6389.8 6594.8 6644.5 6792.9 6949.9 483.5 488.1 491.7 492.7 495.9 4405.1 4644.0 4645.9 4649.6 4710.6 11773.2 12147.2 12240.5 12250.5 12263.3 11838.2 12006.4 12121.9 12123.3 12195.3 9097.9 9151.5 9158.4 9180.7 9196.0 6439.6 6643.6 6753.6 6756.1 6760.3 11434.7 11435.5 11487.8 11624.1 11744.5 11372.4 11493.0 11722.1 11729.4 11770.8 7893.0 8378.5 8560.6 8737.8 8975.7 9530.3 9589.9 9591.4 9596.5 9637.1 7845.7 7964.5 8084.0 8089.4 8373.9 8187.0 8195.3 8321.3 8335.3 8471.8 7369.7 7526.2 7595.2 7774.4 7880.4 5720.7 5798.5 5827.3 5857.6 5984.4 5805.8 5917.6 5945.2 5948.5 5974.8 17:35:49 INFO - PROCESS | 15237 | 17:35:49 INFO - PROCESS | 15237 | [#4] prototype.html Cycles:180 Average:4892.22 Median:5504.56 stddev:1427.15 (25.9%) stddev-sans-first:1389.67 17:35:49 INFO - PROCESS | 15237 | Values: 340.9 341.4 345.3 350.5 353.3 5535.4 5539.5 5591.3 5606.7 5619.0 5483.6 5502.3 5506.8 5525.9 5532.4 3940.9 4140.4 4291.8 4348.2 4410.9 4379.4 4387.1 4400.7 4409.2 4456.7 4350.3 4372.8 4382.4 4395.9 4401.2 4325.9 4377.7 4380.8 4389.5 4416.6 3902.9 3917.7 3922.4 3932.5 3945.8 5402.5 5406.9 5420.2 5485.8 5487.0 4657.9 4924.9 5042.9 5086.9 5087.4 5798.8 5806.4 5806.5 5812.4 5829.2 4178.1 4192.9 4197.8 4205.9 4207.4 5818.6 5994.8 6030.5 6071.4 6105.6 4326.2 4328.5 4344.2 4345.8 4371.8 4419.2 4423.2 4433.6 4437.6 4452.4 4893.0 5003.6 5004.2 5053.3 5062.7 5748.3 5815.9 5821.5 5822.4 5829.4 1505.2 1520.7 1521.0 1522.5 1526.4 936.8 976.9 982.8 982.9 993.4 4945.3 5014.4 5044.8 5075.2 5078.2 5150.7 5227.5 5269.0 5303.9 5345.3 4990.2 5022.8 5029.9 5310.9 5736.4 3207.6 3215.4 3224.7 3244.8 3245.2 6112.4 6344.6 6356.2 6358.9 6360.5 5869.5 6124.6 6139.9 6163.9 6173.0 6151.5 6213.2 6219.3 6234.4 6234.8 5934.5 5937.9 6001.7 6037.4 6060.1 5734.5 5894.5 5906.9 5940.8 5954.8 6196.2 6208.4 6236.8 6239.7 6268.2 4212.6 4314.7 4329.7 4344.5 4384.3 5872.2 5912.4 5967.5 5987.8 6001.3 5864.7 5904.2 5906.3 5970.1 5975.4 5575.4 5844.2 5866.7 5883.9 5900.0 5815.2 5818.8 5822.8 5838.4 5840.8 5669.6 5871.4 5886.8 5902.9 5909.9 5725.1 5731.9 5737.2 5749.4 5752.3 17:35:49 INFO - PROCESS | 15237 | 17:35:49 INFO - PROCESS | 15237 | [#5] yui.html Cycles:180 Average:24148.79 Median:4160.90 stddev:35760.93 (859.5%) stddev-sans-first:35818.20 17:35:49 INFO - PROCESS | 15237 | Values: 778.7 822.5 866.6 888.0 902.9 16789.3 18236.9 18242.4 18263.3 18276.0 16598.6 16742.3 16742.4 16746.3 16806.0 720.8 852.5 869.8 873.0 878.2 869.3 873.0 873.2 873.9 874.0 934.1 936.4 937.3 937.4 938.1 926.7 927.6 928.9 929.6 929.9 679.1 682.1 682.7 683.6 683.8 16247.4 16358.4 16383.9 16405.2 16410.3 16051.0 16254.8 16262.7 16575.6 16847.4 12463.4 12584.7 13195.7 13382.6 13464.9 4779.5 4845.7 4890.6 4900.3 4902.8 79215.6 81035.6 82487.0 84412.0 85012.6 12177.9 12282.2 12285.8 12365.4 12694.7 22870.8 23095.0 23104.4 23168.7 23253.7 9445.9 9457.8 9567.9 9675.3 9731.9 4873.4 4878.3 4925.1 5181.6 5301.4 4079.2 4242.6 4248.0 4252.1 4259.3 723.2 723.4 748.8 753.4 762.4 776.9 827.8 856.3 859.6 867.5 10592.4 10768.6 10853.9 11146.9 11290.7 664.9 710.5 752.1 788.1 820.8 4875.3 4889.8 5135.8 5164.4 5168.3 134248.0 134811.3 136205.0 137163.0 140629.0 117469.0 118901.0 120037.4 122379.0 123783.0 102368.0 105343.0 106810.0 107405.5 110943.0 9464.9 9603.7 9787.6 9901.4 9997.1 58413.7 58900.7 59206.4 61470.4 61940.7 80692.2 87168.6 90168.0 91759.0 94864.5 12985.4 13172.9 13367.8 13597.6 13686.5 14283.1 14495.1 14977.3 15520.3 16155.5 14931.2 15175.5 15274.5 16438.3 16520.8 15016.2 15025.6 15546.2 15630.4 16650.0 14694.2 14729.3 14946.9 15641.8 16087.0 12562.2 12951.6 13140.1 13304.4 13553.5 13424.9 13441.9 13477.4 13614.4 13620.5 17:35:49 INFO - PROCESS | 15237 | -------- Summary: end -------- 17:35:49 INFO - PROCESS | 15237 | 17:35:50 INFO - TEST-INFO | 15237: exit 0 17:35:50 INFO - TEST-OK | dromaeo_css | took 1113318ms 17:35:50 INFO - TEST-START | kraken 17:35:50 INFO - operating with platform_type : linux_ 17:35:50 INFO - Initialising browser for kraken test... 17:35:50 INFO - TEST-INFO | started process 15429 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzqBY0i/profile http://localhost:46279/getInfo.html) 17:35:54 INFO - PROCESS | 15429 | __metrics Screen width/height:1600/1200 17:35:54 INFO - PROCESS | 15429 | colorDepth:24 17:35:54 INFO - PROCESS | 15429 | Browser inner width/height: 1024/697 17:35:54 INFO - PROCESS | 15429 | __metrics 17:35:55 INFO - PROCESS | 15429 | 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" 17:35:55 INFO - PROCESS | 15429 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:35:55 INFO - PROCESS | 15429 | console.error: PushService: 17:35:55 INFO - PROCESS | 15429 | stateChangeProcessEnqueue: Error transitioning state 17:35:55 INFO - PROCESS | 15429 | UnknownError 17:35:56 INFO - TEST-INFO | 15429: exit 0 17:35:56 INFO - Browser initialized. 17:35:56 INFO - Running cycle 1/1 for kraken test... 17:35:56 INFO - TEST-INFO | started process 15520 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzqBY0i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:36:08 INFO - PROCESS | 15520 | RSS: Main: 144158720 17:36:08 INFO - PROCESS | 15520 | 17:36:10 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46279/tests/kraken/driver.html?testName=audio-beat-detection) 17:36:11 INFO - PROCESS | 15520 | RSS: Main: 198873088 17:36:11 INFO - PROCESS | 15520 | 17:36:13 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46279/tests/kraken/driver.html?testName=audio-dft) 17:36:14 INFO - PROCESS | 15520 | RSS: Main: 183492608 17:36:14 INFO - PROCESS | 15520 | 17:36:17 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46279/tests/kraken/driver.html?testName=audio-fft) 17:36:18 INFO - PROCESS | 15520 | RSS: Main: 274915328 17:36:18 INFO - PROCESS | 15520 | 17:36:20 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46279/tests/kraken/driver.html?testName=audio-oscillator) 17:36:20 INFO - PROCESS | 15520 | RSS: Main: 180289536 17:36:20 INFO - PROCESS | 15520 | 17:36:22 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:36:23 INFO - PROCESS | 15520 | RSS: Main: 179531776 17:36:23 INFO - PROCESS | 15520 | 17:36:26 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46279/tests/kraken/driver.html?testName=imaging-darkroom) 17:36:26 INFO - PROCESS | 15520 | RSS: Main: 214953984 17:36:26 INFO - PROCESS | 15520 | 17:36:30 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46279/tests/kraken/driver.html?testName=imaging-desaturate) 17:36:31 INFO - PROCESS | 15520 | RSS: Main: 214323200 17:36:31 INFO - PROCESS | 15520 | 17:36:33 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46279/tests/kraken/driver.html?testName=json-parse-financial) 17:36:34 INFO - PROCESS | 15520 | RSS: Main: 214069248 17:36:34 INFO - PROCESS | 15520 | 17:36:35 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:36:36 INFO - PROCESS | 15520 | RSS: Main: 176087040 17:36:36 INFO - PROCESS | 15520 | 17:36:38 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46279/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:36:38 INFO - PROCESS | 15520 | RSS: Main: 183103488 17:36:38 INFO - PROCESS | 15520 | 17:36:40 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:36:41 INFO - PROCESS | 15520 | RSS: Main: 189579264 17:36:41 INFO - PROCESS | 15520 | 17:36:43 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:36:44 INFO - PROCESS | 15520 | RSS: Main: 198696960 17:36:44 INFO - PROCESS | 15520 | 17:36:47 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:36:47 INFO - PROCESS | 15520 | RSS: Main: 194523136 17:36:47 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | Cycle 1(1): loaded http://localhost:46279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46279/tests/kraken/driver.html?testName=ai-astar) 17:36:49 INFO - PROCESS | 15520 | RSS: Main: 246431744 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | __start_tp_report 17:36:49 INFO - PROCESS | 15520 | _x_x_mozilla_page_load 17:36:49 INFO - PROCESS | 15520 | _x_x_mozilla_page_load_details 17:36:49 INFO - PROCESS | 15520 | |i|pagename|runs| 17:36:49 INFO - PROCESS | 15520 | |0;ai-astar;110;110;119;111;111;109;109;110;109;111 17:36:49 INFO - PROCESS | 15520 | |1;audio-beat-detection;149;116;144;173;118;146;147;117;178;127 17:36:49 INFO - PROCESS | 15520 | |2;audio-dft;160;163;161;160;162;160;161;162;161;161 17:36:49 INFO - PROCESS | 15520 | |3;audio-fft;75;77;75;169;76;75;75;75;158;75 17:36:49 INFO - PROCESS | 15520 | |4;audio-oscillator;85;85;154;84;84;83;148;84;84;154 17:36:49 INFO - PROCESS | 15520 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;117;116;117 17:36:49 INFO - PROCESS | 15520 | |6;imaging-darkroom;146;147;146;147;146;146;148;147;146;145 17:36:49 INFO - PROCESS | 15520 | |7;imaging-desaturate;95;94;95;95;95;95;94;95;94;95 17:36:49 INFO - PROCESS | 15520 | |8;json-parse-financial;49;50;50;50;49;50;51;50;49;50 17:36:49 INFO - PROCESS | 15520 | |9;json-stringify-tinderbox;63;62;62;62;62;62;62;62;65;61 17:36:49 INFO - PROCESS | 15520 | |10;stanford-crypto-aes;74;73;76;73;73;81;73;73;73;74 17:36:49 INFO - PROCESS | 15520 | |11;stanford-crypto-ccm;123;126;124;120;118;131;138;118;151;118 17:36:49 INFO - PROCESS | 15520 | |12;stanford-crypto-pbkdf2;158;157;158;158;160;154;157;158;159;160 17:36:49 INFO - PROCESS | 15520 | |13;stanford-crypto-sha256-iterative;67;63;64;63;64;63;62;64;62;62 17:36:49 INFO - PROCESS | 15520 | __end_tp_report 17:36:49 INFO - PROCESS | 15520 | __start_cc_report 17:36:49 INFO - PROCESS | 15520 | _x_x_mozilla_cycle_collect,1676 17:36:49 INFO - PROCESS | 15520 | __end_cc_report 17:36:49 INFO - PROCESS | 15520 | __startTimestamp1477355809744__endTimestamp 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | ------- Summary: start ------- 17:36:49 INFO - PROCESS | 15520 | Number of tests: 14 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#0] ai-astar Cycles:10 Average:110.90 Median:110.50 stddev:2.96 (2.7%) stddev-sans-first:3.12 17:36:49 INFO - PROCESS | 15520 | Values: 110.0 110.0 119.0 111.0 111.0 109.0 109.0 110.0 109.0 111.0 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:146.50 stddev:22.24 (15.2%) stddev-sans-first:23.42 17:36:49 INFO - PROCESS | 15520 | Values: 149.0 116.0 144.0 173.0 118.0 146.0 147.0 117.0 178.0 127.0 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:0.97 17:36:49 INFO - PROCESS | 15520 | Values: 160.0 163.0 161.0 160.0 162.0 160.0 161.0 162.0 161.0 161.0 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#3] audio-fft Cycles:10 Average:93.00 Median:75.00 stddev:37.25 (49.7%) stddev-sans-first:38.94 17:36:49 INFO - PROCESS | 15520 | Values: 75.0 77.0 75.0 169.0 76.0 75.0 75.0 75.0 158.0 75.0 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#4] audio-oscillator Cycles:10 Average:104.50 Median:84.00 stddev:32.82 (39.1%) stddev-sans-first:34.05 17:36:49 INFO - PROCESS | 15520 | Values: 85.0 85.0 154.0 84.0 84.0 83.0 148.0 84.0 84.0 154.0 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:36:49 INFO - PROCESS | 15520 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.84 (0.6%) stddev-sans-first:0.88 17:36:49 INFO - PROCESS | 15520 | Values: 146.0 147.0 146.0 147.0 146.0 146.0 148.0 147.0 146.0 145.0 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 17:36:49 INFO - PROCESS | 15520 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#8] json-parse-financial Cycles:10 Average:49.80 Median:50.00 stddev:0.63 (1.3%) stddev-sans-first:0.60 17:36:49 INFO - PROCESS | 15520 | Values: 49.0 50.0 50.0 50.0 49.0 50.0 51.0 50.0 49.0 50.0 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#9] json-stringify-tinderbox Cycles:10 Average:62.30 Median:62.00 stddev:1.06 (1.7%) stddev-sans-first:1.09 17:36:49 INFO - PROCESS | 15520 | Values: 63.0 62.0 62.0 62.0 62.0 62.0 62.0 62.0 65.0 61.0 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:73.50 stddev:2.54 (3.5%) stddev-sans-first:2.69 17:36:49 INFO - PROCESS | 15520 | Values: 74.0 73.0 76.0 73.0 73.0 81.0 73.0 73.0 73.0 74.0 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:125.00 stddev:10.70 (8.6%) stddev-sans-first:11.26 17:36:49 INFO - PROCESS | 15520 | Values: 123.0 126.0 124.0 120.0 118.0 131.0 138.0 118.0 151.0 118.0 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.90 Median:158.00 stddev:1.73 (1.1%) stddev-sans-first:1.83 17:36:49 INFO - PROCESS | 15520 | Values: 158.0 157.0 158.0 158.0 160.0 154.0 157.0 158.0 159.0 160.0 17:36:49 INFO - PROCESS | 15520 | 17:36:49 INFO - PROCESS | 15520 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:63.50 stddev:1.51 (2.4%) stddev-sans-first:0.87 17:36:49 INFO - PROCESS | 15520 | Values: 67.0 63.0 64.0 63.0 64.0 63.0 62.0 64.0 62.0 62.0 17:36:49 INFO - PROCESS | 15520 | -------- Summary: end -------- 17:36:49 INFO - PROCESS | 15520 | 17:36:50 INFO - TEST-INFO | 15520: exit 0 17:36:50 INFO - TEST-OK | kraken | took 60610ms 17:36:50 INFO - SUITE-END | took 1173s 17:36:50 INFO - Completed test suite (00:19:34) 17:36:50 INFO - javascript benchmark 17:36:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3194.9201269968257, 3210.5184222366643, 3233.1593785615746, 3249.983750081249, 3274.459714147166, 5488.094464413367, 5579.107342825148, 5630.352509461413, 5636.379998200195, 5649.039663257241, 5512.972435137819, 5553.7778488860395, 5563.332400111982, 5576.219329293902, 5592.217089607448, 5014.4734802845815, 5033.094043072246, 5074.407661066741, 5076.6700164489375, 5100.387953445592, 4987.0, 5102.744862756847, 5128.794848206067, 5131.435542090367, 5135.332406787113, 4933.580645645106, 4996.725180115092, 5031.823886163985, 5047.192449208109, 5114.258432527281, 5047.747612619372, 5065.670731402447, 5160.200168973815, 5173.896522069575, 5206.479352064801, 4881.0, 5067.290579318898, 5092.0, 5131.486851314876, 5155.587552995751, 5452.700101494416, 5458.5087342139395, 5468.507834294933, 5490.121580547093, 5493.038718224335, 5923.763049478016, 5964.493018093467, 5968.104784282393, 5982.700864956735, 5988.041913293852, 5743.569232307552, 6007.909881351783, 6018.307894592134, 6031.396860313977, 6035.517158627299, 5626.324841019063, 5655.830325090295, 5684.147377893349, 5700.059490184115, 5715.1713001614535, 6205.53912556947, 6227.813165605039, 6234.844128896814, 6236.688165591702, 6243.625382477066, 5659.717014149276, 5736.770529178786, 5963.314218864886, 5971.940280597225, 5974.193483879708, 5662.85842853932, 5899.764009439575, 5913.201717768046, 5923.881522369615, 5956.16613674086, 5889.352171261158, 5889.5877288590245, 5902.1441890926435, 5902.527797776168, 5903.557233207441, 5758.452946970031, 5835.153902684087, 5854.385289544622, 5910.172575839386, 5914.704264786744, 4491.708038977456, 4550.954490455119, 4551.8634440966825, 4571.017231295221, 4577.404937358188, 4033.153037862023, 4068.0440096577872, 4071.8371265149062, 4075.3683179107284, 4091.509018917749, 5507.339119305709, 5646.181303710939, 5703.486686198179, 5718.79984200551, 5722.37053924068, 5980.55145864062, 6076.513878889767, 6077.787277445268, 6079.544034197364, 6092.847678808065, 5735.4551317624755, 5744.655320680772, 5745.396733343023, 5747.453991870849, 5755.194272801811, 5007.924881126786, 5090.08378491875, 5152.845414637567, 5165.044466773659, 5172.379314482211, 6072.453479186895, 6254.0, 6260.186175797117, 6261.499080073583, 6278.591891527036, 6041.75832966694, 6052.709091544458, 6058.212432383762, 6063.242094738158, 6079.544034197541, 5932.614380065282, 6107.206063211754, 6191.721372538156, 6208.802466729728, 6260.342664020394, 5793.9420605795085, 5916.674582897982, 5925.585209035326, 5926.525877929842, 5944.524438045032, 5952.910706339495, 6005.159277701038, 6014.789482368096, 6016.368281330398, 6020.51835853122, 5663.9716801415725, 5757.9424205757405, 5896.056630939029, 5903.822885313448, 5906.911396328973, 5745.080787074216, 5836.270466191726, 5844.12338149264, 5848.0350742127075, 5870.677112758839, 5768.971155144198, 5788.305403351625, 5798.884022319615, 5803.593748437569, 5808.07070868659, 5604.327480702342, 5661.46216109469, 5832.0, 5841.269841269841, 5885.3820348862755, 5588.6646801192055, 5701.144828275792, 5710.65736055838, 5733.369329373682, 5740.971295143498], "value": 5502.365054440536, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.5920925747348, 634.3561285295426, 655.6361219523164, 681.274442718505, 686.7699320727556, 33869.322613547716, 37563.812180939094, 37786.62213377862, 44473.55526444734, 45805.0, 45942.310865337044, 46375.768121159344, 46568.53431465684, 46571.767141164244, 46590.301145482845, 9157.175854173107, 9287.117723816227, 9316.114969077928, 9317.068293170696, 9317.394369366004, 8492.872606910902, 11014.889851101507, 11393.088552915788, 11463.426828658534, 11471.082313414949, 11301.49143288554, 11345.659630211065, 11376.715582110472, 11420.486078126503, 11432.0, 11175.385353805537, 11224.102071834233, 11236.10111191111, 11249.55001799927, 11256.943715281453, 11014.339139651567, 11065.723356916062, 11070.446477676083, 11087.611933582286, 11099.44502774858, 45480.77259613714, 45724.0, 45788.77105614484, 45970.77014614906, 45987.31019034717, 3653.342398368293, 3704.129529560551, 3812.80935953204, 3954.0555502910206, 4280.657547396201, 34720.9911511436, 34856.30287394263, 35284.11789705252, 35985.10037249063, 36570.634293656985, 4590.472095709003, 4635.327877457749, 4654.201998901049, 4686.53134686532, 4780.402449693788, 368586.1570692156, 368644.0, 368886.0, 369210.0, 370192.0, 37356.0, 38524.6147538521, 60625.69687151536, 60987.0, 61054.0, 40127.59872401297, 40394.0, 40505.1898962025, 40550.79724601417, 40554.39168412476, 17415.3033878646, 17564.824351756575, 17582.384616538617, 17600.29598816033, 17601.29594816193, 13115.27865967381, 14435.783463248059, 14897.106173629616, 15001.474948376754, 15004.924975375054, 9074.909250907407, 9257.722268331961, 9390.389624674373, 9392.624295028123, 9412.482313472825, 3514.3674138655288, 3572.8392222350058, 3577.874774382884, 3616.674499295076, 3634.254977729559, 1068.7274744939991, 1081.2269227502375, 1141.8401423800674, 1150.5915400033034, 1153.9671993565712, 34705.82647086748, 35065.0, 35114.29771404558, 35229.0, 35318.117047074025, 1170.3387586014046, 1173.9589114381126, 1202.0924202227263, 1219.6950762309423, 1220.8473940757406, 18136.274549017893, 18193.909030454764, 18255.361062362754, 18279.451616451526, 18308.450746477625, 159094.0, 159576.0, 159774.0, 160137.199314005, 160143.0, 62891.37108628855, 63653.681731591045, 63667.68166159139, 63697.044544331875, 63753.681231593546, 1360.3606305036617, 1404.0592802822134, 1427.9928600356932, 1460.5399299220712, 1488.694817562397, 34399.656003439646, 35763.0, 35899.28201436009, 35926.101847454025, 36182.819085904404, 53477.4652253488, 54202.45797541974, 54239.72880135574, 54245.0, 54269.18596221139, 435130.0, 436989.0, 437505.0, 437506.0, 438167.0, 29766.851165744032, 30843.845780770953, 30916.0, 31128.221794454414, 31337.0, 52134.47865521448, 52789.0, 52860.0, 53421.0, 53976.730116349165, 44548.77725611351, 44861.0, 45372.95108343125, 45434.77282613566, 45698.77150614359, 44494.55505444904, 45191.32213016873, 45304.320435192836, 45809.54190458186, 45904.0, 43113.78443107764, 43176.35235471533, 44289.0, 44500.777496112314, 44639.330410044524, 35867.28265434729, 38738.6126138735, 39280.0, 39371.2125757489, 39528.80235598804, 29565.408691826473, 29625.85187074051, 29703.851480742458, 29704.66998692544, 29823.850880745456], "value": 19903.311629511853, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.8206863195094, 401.80914065818723, 411.47331415787795, 413.2520138549225, 427.09029766597143, 19732.506687332807, 20113.899430502865, 20598.279060232893, 20628.793712062892, 20749.273775417863, 12151.0, 12678.175918565286, 19345.806541934577, 20553.4861628459, 20853.791462085373, 5139.434662187156, 5269.473052694719, 5414.566834653218, 5447.972760136194, 5463.153211252262, 4110.1163249901265, 5282.815101471449, 5391.838244852661, 5415.404305526389, 7192.460565457612, 7635.618219089051, 7668.309852113309, 7798.376129909594, 7805.687772489122, 7810.49231799934, 7727.420443466706, 7793.805154871116, 7865.370770338388, 7895.289423951843, 7923.445358824884, 7619.276168763959, 7645.235476452366, 7654.043244594426, 7681.155072941972, 7696.114946781136, 26774.33064173392, 27316.31709207266, 27564.173074807586, 27591.724082759116, 27854.72145278562, 2678.317029157572, 2881.423715256957, 2966.3622321201046, 2981.6571094324213, 3461.6365281645567, 16458.42395516151, 16672.66654666912, 16702.749458758128, 16832.0, 17126.657466850593, 2148.5380643161793, 2158.4064382294837, 2202.7797220277844, 2219.212179676208, 2225.63277059285, 97182.51408742982, 97366.5131674337, 99597.0, 101012.0, 101030.0, 17764.733528997076, 17964.730529042074, 18294.451166465027, 18746.156422961, 19816.108275127655, 33013.0, 33078.0, 33345.66654333426, 33431.164220894185, 33532.83233583817, 10868.130549556017, 10956.90430956898, 11041.83437248442, 11149.721256968478, 11275.590551181102, 9462.858057129148, 9480.383775054599, 9510.763600732, 9558.330916835894, 9714.125728684452, 8806.383553151218, 9295.628174873065, 9359.859602105973, 9522.95238523803, 9613.0, 2885.624868767047, 3511.034465522002, 3621.5011641966958, 3683.318586061587, 3774.9811250944117, 973.5035132082688, 1044.4046893270915, 1055.9736006599742, 1059.5602824827765, 1074.7823839090822, 19649.803501964798, 20170.39488815338, 20241.797582024286, 20601.158727698978, 20853.37439876806, 1000.5947591225565, 1033.178622994706, 1036.6889933020063, 1055.3826011783192, 1061.3260579532052, 9404.576794044147, 11279.266847654877, 11381.04924357907, 11433.0, 11609.419529023515, 489472.0, 506122.0, 510876.0, 520144.0, 530728.0, 93078.0, 93329.53335233417, 96297.51851240698, 96390.51804741072, 99527.50236248918, 1036.2487196782424, 1090.9727256818485, 1122.36586328724, 1123.6292023632182, 1126.2510430563748, 17394.652106958045, 17983.820161798216, 18451.0, 18540.351087711864, 18788.530286742392, 42537.574624253364, 44547.55452445434, 44619.776901115285, 45529.31706024346, 46086.539134608225, 58115.418845811, 61063.0, 61112.0, 61812.38187618246, 62195.6890215546, 9399.436033837992, 9508.144267016001, 9552.283578731483, 9667.806643867223, 9701.320907536405, 12983.156094853804, 13421.731565368833, 13652.453901843815, 13676.589702308947, 14014.789778153132, 11597.478113485082, 11729.35488548138, 12190.2076365036, 12341.07441941876, 12414.379281035732, 11630.94184529072, 11831.585894493652, 11854.703632409259, 11901.10741694359, 12411.813822792847, 8817.603207855791, 12112.394380280775, 12292.262464252173, 12351.629451116482, 12489.625311240678, 9240.907590924273, 9426.245900328095, 9472.479013654316, 9615.2307815376, 9620.182284505616, 11731.178817483036, 12026.037916966447, 12053.0, 12294.631161065183, 12387.318697471726], "value": 10865.51340458261, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1565.6242501799563, 1568.835272296409, 1726.7927848658155, 1830.670479313723, 1836.4766041678124, 9186.724398268047, 9961.402315861053, 9962.153216976565, 9967.551460184291, 9978.30151889368, 8456.06983231844, 9261.722148335544, 9541.570629321679, 9576.473293968846, 9766.0, 2416.3355077353735, 2432.3311089450367, 2435.4641978764644, 2436.415260337524, 2444.828861979662, 2382.5830479666074, 2412.7828495435406, 2463.5688754467988, 2467.037855236459, 2467.037855236459, 2426.9514609707794, 2445.205308274818, 2446.7430919753438, 2453.865037422941, 2454.5459090068307, 2386.7851893329685, 2395.1138078910826, 2404.6753688252124, 2406.434487895352, 2414.3239892830034, 2291.175176936302, 2377.393764590036, 2379.3694670912223, 2382.2257766226044, 2384.8092152627746, 9916.405015699009, 9955.153811925995, 9958.950205249, 9987.450690212036, 10000.649977250834, 5503.091989821674, 5646.745896434671, 5649.0114230009585, 5651.2935883014625, 5666.886662266773, 3470.4967779671806, 3549.893503194908, 3565.73257005723, 3610.873619423334, 3617.385044542434, 1163.9010684091863, 1182.9585964491203, 1215.6778453709774, 1226.1002728547012, 1239.0335538280156, 13843.861561384358, 14005.789913151311, 14046.999999999898, 14066.437342506288, 14088.2955852208, 4546.431696037996, 4761.666683332133, 4838.782254798472, 4843.491433399513, 4844.660873738804, 10798.244122911477, 10853.403062831461, 10854.403007834644, 10868.728281793019, 10895.237333386745, 5589.916151257735, 5603.859903502445, 5609.439056094439, 5610.663360198319, 5610.803621873297, 4529.864104076817, 4601.654875884322, 4752.976235118802, 5141.38303403594, 5169.508896654887, 4059.2490389278078, 4061.1877624475223, 4114.0, 4152.190322887069, 4218.78906054696, 1103.7957977774138, 1143.1140865828884, 1161.7269941563725, 1164.889335513125, 1165.854268216473, 1038.6364772329775, 1066.8719753629614, 1079.6599071292517, 1080.5623722392443, 1089.967300980956, 6389.840253993687, 6594.769183078569, 6644.468442524587, 6792.864142717217, 6949.89575156373, 483.54546726077376, 488.060483569128, 491.7123482762552, 492.68468180364596, 495.9107360675113, 4405.118976204772, 4644.0, 4645.86062418128, 4649.558291962258, 4710.62315014798, 11773.17587768865, 12147.210431321935, 12240.510379584717, 12250.509979600894, 12263.325517096468, 11838.171328007129, 12006.399680015964, 12121.878781212075, 12123.272603643809, 12195.329256890956, 9097.90902090984, 9151.496667683208, 9158.404703694372, 9180.6786762463, 9196.0, 6439.581427207217, 6643.634600096848, 6753.628550429774, 6756.121704178426, 6760.290169532325, 11434.656960291204, 11435.542578296774, 11487.770244595229, 11624.128190385585, 11744.471498782746, 11372.43137843121, 11493.0, 11722.062235021349, 11729.35488548138, 11770.823437648613, 7892.960535197287, 8378.49729016261, 8560.614772335135, 8737.78155546091, 8975.685850995184, 9530.285228608021, 9589.90410095918, 9591.42451452915, 9596.520173991134, 9637.084476974676, 7845.725399610987, 7964.482308649919, 8083.959580202061, 8089.3528517719615, 8373.874391884248, 8187.0, 8195.344372450309, 8321.25108740192, 8335.291500222549, 8471.788205294917, 7369.742059027908, 7526.24737526243, 7595.164531901597, 7774.416918731004, 7880.408969327438, 5720.685362305113, 5798.536117110705, 5827.300723912988, 5857.648541087548, 5984.431479009487, 5805.767769289181, 5917.556183286185, 5945.167676525203, 5948.4943779779205, 5974.820825151313], "value": 4918.570385353633, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.91306716787216, 341.41788251031994, 345.30765814541854, 350.4900369961706, 353.3145697347146, 5535.446455354462, 5539.534906966402, 5591.30108736408, 5606.719664016803, 5619.0, 5483.643563168392, 5502.284702988616, 5506.843562851806, 5525.917111243334, 5532.446755324466, 3940.9162480318, 4140.420341152234, 4291.785410729467, 4348.152110338485, 4410.933835992462, 4379.386885835986, 4387.056782791699, 4400.723790100867, 4409.228385032622, 4456.6880318377725, 4350.260455722519, 4372.759498227596, 4382.408374869384, 4395.934060989088, 4401.207782599131, 4325.891852703708, 4377.715448495837, 4380.846670366538, 4389.4513185851765, 4416.58042485965, 3902.941455878164, 3917.688563747403, 3922.3920292354733, 3932.488776459042, 3945.842166313344, 5402.459754024605, 5406.91889621656, 5420.214068959979, 5485.807996720136, 5486.972565137189, 4657.906841863178, 4924.852254432372, 5042.949570504285, 5086.872828179288, 5087.440381558063, 5798.768049278024, 5806.44838740319, 5806.477417032445, 5812.389699081577, 5829.154772557956, 4178.12259425521, 4192.916141677179, 4197.803625966585, 4205.894852628709, 4207.410962465257, 5818.5926985111055, 5994.771071930244, 6030.547708921848, 6071.423214794588, 6105.603135796159, 4326.221280169537, 4328.480582330078, 4344.218040752632, 4345.804438800262, 4371.803268852909, 4419.248727716296, 4423.181711383404, 4433.645308375322, 4437.622802061861, 4452.4211852458975, 4892.996935628188, 5003.624728145403, 5004.224345226496, 5053.317802096743, 5062.670926389773, 5748.31020277561, 5815.94184058154, 5821.505172060338, 5822.359540450547, 5829.417058294137, 1505.2172870107484, 1520.657851983295, 1521.0, 1522.5383631713678, 1526.404702166156, 936.7939053408239, 976.9071938165863, 982.8034393121404, 982.931194816356, 993.3642468819961, 4945.2938736135975, 5014.448410674823, 5044.772985215674, 5075.213341932006, 5078.238264260391, 5150.690958542425, 5227.477252274818, 5269.0, 5303.920441193308, 5345.278387417727, 4990.226514890198, 5022.7990880365105, 5029.899402011939, 5310.92033619496, 5736.365135936321, 3207.6150861896253, 3215.389076075538, 3224.742020638343, 3244.7566432517656, 3245.192908134494, 6112.388761123941, 6344.555881088279, 6356.2054743157105, 6358.904616430757, 6360.491160707133, 5869.5010924071075, 6124.591343990818, 6139.938600614026, 6163.921313770073, 6172.993777022486, 6151.477124444473, 6213.1922850029205, 6219.253689557192, 6234.410225392587, 6234.750609975641, 5934.495567876779, 5937.881242375215, 6001.699915004319, 6037.426444487766, 6060.1212824139375, 5734.455226753452, 5894.469497745224, 5906.911396329145, 5940.7920722776225, 5954.821355359346, 6196.1635179250225, 6208.379162083756, 6236.75052997875, 6239.688015599111, 6268.1851359323, 4212.64192543625, 4314.676399270004, 4329.6969212154845, 4344.45694288214, 4384.254676704904, 5872.236609240771, 5912.408759124053, 5967.492763115184, 5987.790427335022, 6001.279846418283, 5864.736086876186, 5904.232449781673, 5906.26171728534, 5970.104484327386, 5975.43233392827, 5575.358833734173, 5844.211031510692, 5866.7066646668345, 5883.882322353444, 5899.970500147472, 5815.185873977562, 5818.770582326849, 5822.82531524055, 5838.357780644211, 5840.795572154954, 5669.6314739541795, 5871.383504732113, 5886.764529418775, 5902.940970590239, 5909.940900590939, 5725.08398656213, 5731.8567035824435, 5737.192784272941, 5749.396313387034, 5752.252207213036], "value": 4437.458391817709, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.711876605656, 822.4777266435811, 866.605694409044, 888.0409158109243, 902.8600566912129, 16789.328426862925, 18236.90881545592, 18242.36151734686, 18263.269469221214, 18276.0, 16598.58503537409, 16742.330306787713, 16742.41401550946, 16746.330146794146, 16806.0, 720.8486217894249, 852.4799872078028, 869.76516340588, 872.9869051964193, 878.2052242720348, 869.2828416556334, 872.9869051964225, 873.2402809555664, 873.9213470787628, 873.9956300218522, 934.0985948559638, 936.4100616611526, 937.3157594955686, 937.4141161773891, 938.1134827587932, 926.7451450851003, 927.5640448988964, 928.9256859451261, 929.5677509957868, 929.8930622978377, 679.0866284846873, 682.1234713393316, 682.7064362324198, 683.5761827666829, 683.8461346197066, 16247.431339903165, 16358.427455039135, 16383.918080409521, 16405.17974101302, 16410.343586256535, 16051.0, 16254.83745162545, 16262.674746505121, 16575.585610359718, 16847.41034063814, 12463.439145238486, 12584.74830503394, 13195.670108247275, 13382.598522044354, 13464.93267533656, 4779.522047795227, 4845.709257444564, 4890.608751299887, 4900.313956046157, 4902.803887844482, 79215.6039219806, 81035.5948220261, 82487.0, 84412.0, 85012.57493712491, 12177.878221217852, 12282.201656892452, 12285.815712764315, 12365.443555040045, 12694.746105077847, 22870.771292286863, 23095.0, 23104.4223894404, 23168.65247021296, 23253.67463253661, 9445.858312125323, 9457.76355591102, 9567.90432095684, 9675.322727409013, 9731.9026809731, 4873.439554451213, 4878.341423907798, 4925.13810083234, 5181.5854731621375, 5301.4168441472175, 4079.184163167319, 4242.618164365222, 4248.02295472037, 4252.128313695685, 4259.297215959394, 723.2369849808503, 723.4321057969574, 748.8352562436255, 753.3671715758788, 762.4053238473999, 776.8951191589176, 827.8178800663844, 856.2550580994574, 859.617470225756, 867.4665080975154, 10592.417417042137, 10768.569257229623, 10853.891461085446, 11146.888531114586, 11290.717732056764, 664.9335066493312, 710.4529512275519, 752.0862152484757, 788.0897563314381, 820.7660816667221, 4875.293082503088, 4889.804407823647, 5135.845924622267, 5164.431912489623, 5168.302279192261, 134248.0, 134811.32594336965, 136205.0, 137163.0, 140629.0, 117469.0, 118901.0, 120037.39981300037, 122379.0, 123783.0, 102368.0, 105343.0, 106810.0, 107405.4629726862, 110943.0, 9464.858027129461, 9603.663871764455, 9787.559559819967, 9901.405915645084, 9997.100260976547, 58413.70793146007, 58900.70549647224, 59206.40793592009, 61470.385296146465, 61940.69029654823, 80692.19307806846, 87168.56415717881, 90168.0, 91759.0, 94864.52567737117, 12985.415656295301, 13172.868271317164, 13367.799483007568, 13597.598315235888, 13686.52097176594, 14283.071600345944, 14495.130292182503, 14977.251137442867, 15520.301586428865, 16155.515334539983, 14931.17878516692, 15175.468858590339, 15274.541763747106, 16438.260278287267, 16520.75218871742, 15016.174110424032, 15025.624359391102, 15546.222688865737, 15630.374785008473, 16650.0, 14694.191819450032, 14729.26353682333, 14946.850531494545, 15641.765373519635, 16087.0, 12562.246265224116, 12951.611451656465, 13140.080194386683, 13304.401301941241, 13553.457861685421, 13424.865751342362, 13441.932790335986, 13477.393517291941, 13614.38735256896, 13620.455181792617], "value": 8495.631839576576, "unit": "score"}], "name": "dromaeo_css", "value": 7773.6104798915985, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 119.0, 111.0, 111.0, 109.0, 109.0, 110.0, 109.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 144.0, 173.0, 118.0, 146.0, 147.0, 117.0, 178.0, 127.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 161.0, 160.0, 162.0, 160.0, 161.0, 162.0, 161.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 75.0, 169.0, 76.0, 75.0, 75.0, 75.0, 158.0, 75.0], "value": 93.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 154.0, 84.0, 84.0, 83.0, 148.0, 84.0, 84.0, 154.0], "value": 104.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 148.0, 147.0, 146.0, 145.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 50.0, 50.0, 49.0, 50.0, 51.0, 50.0, 49.0, 50.0], "value": 49.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 65.0, 61.0], "value": 62.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 76.0, 73.0, 73.0, 81.0, 73.0, 73.0, 73.0, 74.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 126.0, 124.0, 120.0, 118.0, 131.0, 138.0, 118.0, 151.0, 118.0], "value": 126.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 157.0, 158.0, 158.0, 160.0, 154.0, 157.0, 158.0, 159.0, 160.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 64.0, 63.0, 64.0, 63.0, 62.0, 64.0, 62.0, 62.0], "value": 63.4, "unit": "score"}], "name": "kraken", "value": 1503.3000000000002, "alertThreshold": 2.0}]} 17:36:50 INFO - Return code: 0 17:36:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:36:51 INFO - # TBPL SUCCESS # 17:36:51 INFO - Running post-action listener: _resource_record_post_action 17:36:51 INFO - [mozharness: 2016-10-25 00:36:51.039461Z] Finished run-tests step (success) 17:36:51 INFO - Running post-run listener: _resource_record_post_run 17:36:51 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288337920; Read time: 8; Write time: 183992 17:36:51 INFO - TinderboxPrint: CPU usage
12.9% 17:36:51 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 17:36:51 INFO - TinderboxPrint: I/O write bytes / time
288,337,920 / 183,992 17:36:51 INFO - TinderboxPrint: CPU idle
8,256.1 (87.0%) 17:36:51 INFO - TinderboxPrint: CPU user
1,182.7 (12.5%) 17:36:51 INFO - TinderboxPrint: Swap in / out
0 / 0 17:36:51 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70144000; Read time: 0; Write time: 63488 17:36:51 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 218193920; Read time: 8; Write time: 120504 17:36:51 INFO - Running post-run listener: _upload_blobber_files 17:36:51 INFO - Blob upload gear active. 17:36:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:36:51 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 17:36:51 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:36:51 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:36:52 INFO - (blobuploader) - INFO - Open directory for files ... 17:36:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:36:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:36:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:55 INFO - (blobuploader) - INFO - Done attempting. 17:36:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:36:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:36:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:55 INFO - (blobuploader) - INFO - Done attempting. 17:36:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:36:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:36:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:57 INFO - (blobuploader) - INFO - Done attempting. 17:36:57 INFO - (blobuploader) - INFO - Iteration through files over. 17:36:57 INFO - Return code: 0 17:36:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:36:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:36:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/540a058cf0e07ccfb9c352f90498953bfa895b8432cd941b2afef546726eb82cb82493a6afd915ef167e11dd82d59c2df09880274a7460fad82b218bce91f374", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b89e39a52192cc9bfb5602e31c6c6bfec90a669fbf84c6139d86a93311bd8ace25895c35f68aff7020118ce7c67540ad85d7d2b4295eb5ecdbeeba664119904e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a85d5ab3f5ab24978a4ca401a0a3854bacfa1e3b1ee1f404ca67eeb0ecfacb6566d887542c68ecf1a435ef4a14248e0891b5b5c4938638043c98ff56f6bdf320"} 17:36:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:36:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:36:57 INFO - Contents: 17:36:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/540a058cf0e07ccfb9c352f90498953bfa895b8432cd941b2afef546726eb82cb82493a6afd915ef167e11dd82d59c2df09880274a7460fad82b218bce91f374", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b89e39a52192cc9bfb5602e31c6c6bfec90a669fbf84c6139d86a93311bd8ace25895c35f68aff7020118ce7c67540ad85d7d2b4295eb5ecdbeeba664119904e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a85d5ab3f5ab24978a4ca401a0a3854bacfa1e3b1ee1f404ca67eeb0ecfacb6566d887542c68ecf1a435ef4a14248e0891b5b5c4938638043c98ff56f6bdf320"} 17:36:57 INFO - Running post-run listener: copy_logs_to_upload_dir 17:36:57 INFO - Copying logs to upload dir... 17:36:57 INFO - mkdir: /builds/slave/test/build/upload/logs 17:36:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.324969 ========= master_lag: -0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-10-24 17:36:55.962369) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 17:36:55.965762) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/540a058cf0e07ccfb9c352f90498953bfa895b8432cd941b2afef546726eb82cb82493a6afd915ef167e11dd82d59c2df09880274a7460fad82b218bce91f374", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b89e39a52192cc9bfb5602e31c6c6bfec90a669fbf84c6139d86a93311bd8ace25895c35f68aff7020118ce7c67540ad85d7d2b4295eb5ecdbeeba664119904e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a85d5ab3f5ab24978a4ca401a0a3854bacfa1e3b1ee1f404ca67eeb0ecfacb6566d887542c68ecf1a435ef4a14248e0891b5b5c4938638043c98ff56f6bdf320"} build_url:https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011332 build_url: 'https://queue.taskcluster.net/v1/task/XhWryVS5RXu7E2-1mqUuEQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/540a058cf0e07ccfb9c352f90498953bfa895b8432cd941b2afef546726eb82cb82493a6afd915ef167e11dd82d59c2df09880274a7460fad82b218bce91f374", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b89e39a52192cc9bfb5602e31c6c6bfec90a669fbf84c6139d86a93311bd8ace25895c35f68aff7020118ce7c67540ad85d7d2b4295eb5ecdbeeba664119904e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a85d5ab3f5ab24978a4ca401a0a3854bacfa1e3b1ee1f404ca67eeb0ecfacb6566d887542c68ecf1a435ef4a14248e0891b5b5c4938638043c98ff56f6bdf320"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 17:36:55.991161) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:36:55.991526) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1477353083.569954-1763547199 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004543 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 17:36:56.075436) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 17:36:56.075754) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 17:36:56.076053) ========= ========= Total master_lag: 0.14 =========