builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1476313753.43 results: success (0) buildid: 20161012130846 builduid: a639665ad4fd43218e6018ede79d7dae revision: 1cd295569b2bd7b40cab83c179525fd549c73051 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:13.435231) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:13.435682) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:13.435961) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:13.450952) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:13.451340) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-12 16:09:13-- 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% 42.7M=0s 2016-10-12 16:09:13 (42.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128609 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:13.593573) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:13.593898) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020542 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:13.643217) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:13.643616) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1cd295569b2bd7b40cab83c179525fd549c73051 --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 1cd295569b2bd7b40cab83c179525fd549c73051 --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=5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-12 16:09:13,951 truncating revision to first 12 chars 2016-10-12 16:09:13,951 Setting DEBUG logging. 2016-10-12 16:09:13,951 attempt 1/10 2016-10-12 16:09:13,951 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1cd295569b2b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 16:09:14,298 unpacking tar archive at: mozilla-beta-1cd295569b2b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.671473 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:14.329060) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:14.329472) ========= script_repo_revision: 1cd295569b2bd7b40cab83c179525fd549c73051 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:14.329910) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:14.330209) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 16:09:14.340468) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-12 16:09:14.340803) ========= /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=5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:09:14 INFO - MultiFileLogger online at 20161012 16:09:14 in /builds/slave/test 16:09:14 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 16:09:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:09:14 INFO - {'append_to_log': False, 16:09:14 INFO - 'base_work_dir': '/builds/slave/test', 16:09:14 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:09:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:09:14 INFO - 'branch': 'Mozilla-Beta', 16:09:14 INFO - 'buildbot_json_path': 'buildprops.json', 16:09:14 INFO - 'config_files': ('talos/linux_config.py',), 16:09:14 INFO - 'default_actions': ('clobber', 16:09:14 INFO - 'read-buildbot-config', 16:09:14 INFO - 'download-and-extract', 16:09:14 INFO - 'populate-webroot', 16:09:14 INFO - 'create-virtualenv', 16:09:14 INFO - 'install', 16:09:14 INFO - 'run-tests'), 16:09:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:09:14 INFO - 'download_minidump_stackwalk': True, 16:09:14 INFO - 'download_symbols': 'ondemand', 16:09:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:09:14 INFO - 'tooltool.py': '/tools/tooltool.py', 16:09:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:09:14 INFO - '/tools/misc-python/virtualenv.py')}, 16:09:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:09:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:09:14 INFO - 'installer_path': 'installer.exe', 16:09:14 INFO - 'log_level': 'info', 16:09:14 INFO - 'log_name': 'talos', 16:09:14 INFO - 'log_to_console': True, 16:09:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:09:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:09:14 INFO - 'opt_config_files': (), 16:09:14 INFO - 'pip_index': False, 16:09:14 INFO - 'sps_profile': False, 16:09:14 INFO - 'sps_profile_interval': 0, 16:09:14 INFO - 'suite': 'dromaeojs', 16:09:14 INFO - 'system_bits': '32', 16:09:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:09:14 INFO - 'title': 'talos-linux64-ix-051', 16:09:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:09:14 INFO - 'use_talos_json': True, 16:09:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:09:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:09:14 INFO - 'work_dir': 'build'} 16:09:14 INFO - [mozharness: 2016-10-12 23:09:14.736444Z] Running clobber step. 16:09:14 INFO - Running pre-action listener: _resource_record_pre_action 16:09:14 INFO - Running main action method: clobber 16:09:14 INFO - rmtree: /builds/slave/test/build 16:09:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:09:15 INFO - Running post-action listener: _resource_record_post_action 16:09:15 INFO - [mozharness: 2016-10-12 23:09:15.228105Z] Finished clobber step (success) 16:09:15 INFO - [mozharness: 2016-10-12 23:09:15.228207Z] Running read-buildbot-config step. 16:09:15 INFO - Running pre-action listener: _resource_record_pre_action 16:09:15 INFO - Running main action method: read_buildbot_config 16:09:15 INFO - Using buildbot properties: 16:09:15 INFO - { 16:09:15 INFO - "project": "", 16:09:15 INFO - "product": "firefox", 16:09:15 INFO - "script_repo_revision": "production", 16:09:15 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:09:15 INFO - "repository": "", 16:09:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:09:15 INFO - "buildid": "20161012130846", 16:09:15 INFO - "pgo_build": "True", 16:09:15 INFO - "basedir": "/builds/slave/test", 16:09:15 INFO - "buildnumber": 194, 16:09:15 INFO - "slavename": "talos-linux64-ix-051", 16:09:15 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:09:15 INFO - "platform": "ubuntu64_hw", 16:09:15 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:09:15 INFO - "branch": "mozilla-beta", 16:09:15 INFO - "repo_path": "releases/mozilla-beta", 16:09:15 INFO - "revision": "1cd295569b2bd7b40cab83c179525fd549c73051", 16:09:15 INFO - "stage_platform": "linux64-pgo", 16:09:15 INFO - "builduid": "a639665ad4fd43218e6018ede79d7dae", 16:09:15 INFO - "slavebuilddir": "test" 16:09:15 INFO - } 16:09:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 16:09:15 INFO - Running post-action listener: _resource_record_post_action 16:09:15 INFO - [mozharness: 2016-10-12 23:09:15.230755Z] Finished read-buildbot-config step (success) 16:09:15 INFO - [mozharness: 2016-10-12 23:09:15.230845Z] Running download-and-extract step. 16:09:15 INFO - Running pre-action listener: _resource_record_pre_action 16:09:15 INFO - Running main action method: download_and_extract 16:09:15 INFO - mkdir: /builds/slave/test/build/tests 16:09:15 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')]} 16:09:15 INFO - https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:09:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:09:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:09:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:09:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:09:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:09:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:09:15 INFO - retry: Failed, sleeping 30 seconds before retrying 16:09:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:09:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:09:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:09:45 INFO - retry: Failed, sleeping 60 seconds before retrying 16:10:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:10:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:10:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:10:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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! 16:10:45 INFO - Caught exception: HTTP Error 404: Not Found 16:10:45 INFO - Caught exception: HTTP Error 404: Not Found 16:10:45 INFO - Caught exception: HTTP Error 404: Not Found 16:10:45 INFO - trying https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:10:45 INFO - Downloading https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:10:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:10:45 INFO - Downloaded 1327 bytes. 16:10:45 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:10:45 INFO - Using the following test package requirements: 16:10:45 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:10:45 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:45 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:10:45 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:45 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 16:10:45 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:45 INFO - u'jsshell-linux-x86_64.zip'], 16:10:45 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:45 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:10:45 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:10:45 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:45 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 16:10:45 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:45 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 16:10:45 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:45 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:10:45 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:10:45 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:10:45 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:45 INFO - https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:10:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:10:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:10:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:10:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:10:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:10:45 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:10:45 INFO - retry: Failed, sleeping 30 seconds before retrying 16:11:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:11:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:11:15 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:11:15 INFO - retry: Failed, sleeping 60 seconds before retrying 16:12:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:12:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:12:16 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:12:16 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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! 16:12:16 INFO - Caught exception: HTTP Error 404: Not Found 16:12:16 INFO - Caught exception: HTTP Error 404: Not Found 16:12:16 INFO - Caught exception: HTTP Error 404: Not Found 16:12:16 INFO - trying https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:12:16 INFO - Downloading https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:12:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:12:16 INFO - Downloaded 21774215 bytes. 16:12:16 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'] 16:12:16 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 16:12:18 INFO - Return code: 0 16:12:18 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 16:12:18 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')]} 16:12:18 INFO - https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:12:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:12:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:12:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:12:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:12:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:12:18 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:12:18 INFO - retry: Failed, sleeping 30 seconds before retrying 16:12:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:12:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:12:48 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:12:48 INFO - retry: Failed, sleeping 60 seconds before retrying 16:13:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:13:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:13:48 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:13:48 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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! 16:13:48 INFO - Caught exception: HTTP Error 404: Not Found 16:13:48 INFO - Caught exception: HTTP Error 404: Not Found 16:13:48 INFO - Caught exception: HTTP Error 404: Not Found 16:13:48 INFO - trying https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:13:48 INFO - Downloading https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:13:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:13:49 INFO - Downloaded 21774215 bytes. 16:13:49 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'] 16:13:49 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 16:13:50 INFO - Return code: 0 16:13:50 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')]} 16:13:50 INFO - https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:13:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:13:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:13:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:13:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:13:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:13:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:13:50 INFO - retry: Failed, sleeping 30 seconds before retrying 16:14:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:14:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:14:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:14:20 INFO - retry: Failed, sleeping 60 seconds before retrying 16:15:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:15:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:15:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:15:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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! 16:15:20 INFO - Caught exception: HTTP Error 404: Not Found 16:15:20 INFO - Caught exception: HTTP Error 404: Not Found 16:15:20 INFO - Caught exception: HTTP Error 404: Not Found 16:15:20 INFO - trying https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:15:20 INFO - Downloading https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:15:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:15:21 INFO - Downloaded 14413740 bytes. 16:15:21 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'] 16:15:21 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 16:15:21 INFO - Return code: 0 16:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:15:21 INFO - https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:15:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:15:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:15:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:15:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:15:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:15:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:15:21 INFO - retry: Failed, sleeping 30 seconds before retrying 16:15:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:15:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:15:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:15:51 INFO - retry: Failed, sleeping 60 seconds before retrying 16:16:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:16:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:16:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:16:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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! 16:16:51 INFO - Caught exception: HTTP Error 404: Not Found 16:16:51 INFO - Caught exception: HTTP Error 404: Not Found 16:16:51 INFO - Caught exception: HTTP Error 404: Not Found 16:16:51 INFO - trying https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:16:51 INFO - Downloading https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:16:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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 16:16:52 INFO - Downloaded 56792851 bytes. 16:16:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:16:52 INFO - mkdir: /builds/slave/test/properties 16:16:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:16:52 INFO - Writing to file /builds/slave/test/properties/build_url 16:16:52 INFO - Contents: 16:16:52 INFO - build_url:https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:16:53 INFO - Running post-action listener: _resource_record_post_action 16:16:53 INFO - Running post-action listener: set_extra_try_arguments 16:16:53 INFO - [mozharness: 2016-10-12 23:16:53.481867Z] Finished download-and-extract step (success) 16:16:53 INFO - [mozharness: 2016-10-12 23:16:53.482100Z] Running populate-webroot step. 16:16:53 INFO - Running pre-action listener: _resource_record_pre_action 16:16:53 INFO - Running main action method: populate_webroot 16:16:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:16:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:16:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:16:53 INFO - '--tppagecycles', 16:16:53 INFO - '7'], 16:16:53 INFO - 'tests': ['tsvgm']}}, 16:16:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:16:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:16:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:16:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:16:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:16:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:16:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:53 INFO - 'talos_options': ['--disable-e10s'], 16:16:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:53 INFO - 'talos_options': ['--disable-e10s'], 16:16:53 INFO - 'tests': ['damp', 'tps']}, 16:16:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:53 INFO - 'tests': ['damp', 'tps']}, 16:16:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:16:53 INFO - 'tests': ['dromaeo_dom']}, 16:16:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:16:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:16:53 INFO - 'tests': ['basic_compositor_video']}, 16:16:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:16:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:16:53 INFO - 'tests': ['a11yr', 16:16:53 INFO - 'ts_paint', 16:16:53 INFO - 'tpaint', 16:16:53 INFO - 'sessionrestore', 16:16:53 INFO - 'sessionrestore_no_auto_restore', 16:16:53 INFO - 'tabpaint']}, 16:16:53 INFO - 'other-e10s': {'tests': ['a11yr', 16:16:53 INFO - 'ts_paint', 16:16:53 INFO - 'tpaint', 16:16:53 INFO - 'sessionrestore', 16:16:53 INFO - 'sessionrestore_no_auto_restore', 16:16:53 INFO - 'tabpaint']}, 16:16:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:16:53 INFO - 'ts_paint', 16:16:53 INFO - 'tpaint', 16:16:53 INFO - 'sessionrestore', 16:16:53 INFO - 'sessionrestore_no_auto_restore', 16:16:53 INFO - 'tabpaint']}, 16:16:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:16:53 INFO - 'ts_paint', 16:16:53 INFO - 'tpaint', 16:16:53 INFO - 'sessionrestore', 16:16:53 INFO - 'sessionrestore_no_auto_restore', 16:16:53 INFO - 'tabpaint']}, 16:16:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:16:53 INFO - 'tests': ['a11yr', 16:16:53 INFO - 'ts_paint', 16:16:53 INFO - 'tpaint', 16:16:53 INFO - 'sessionrestore', 16:16:53 INFO - 'sessionrestore_no_auto_restore', 16:16:53 INFO - 'tabpaint']}, 16:16:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:16:53 INFO - 'tests': ['a11yr', 16:16:53 INFO - 'ts_paint', 16:16:53 INFO - 'tpaint', 16:16:53 INFO - 'sessionrestore', 16:16:53 INFO - 'sessionrestore_no_auto_restore', 16:16:53 INFO - 'tabpaint']}, 16:16:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:16:53 INFO - 'tests': ['tsvgx', 16:16:53 INFO - 'tsvgr_opacity', 16:16:53 INFO - 'tart', 16:16:53 INFO - 'tscrollx', 16:16:53 INFO - 'cart']}, 16:16:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:16:53 INFO - 'tsvgr_opacity', 16:16:53 INFO - 'tart', 16:16:53 INFO - 'tscrollx', 16:16:53 INFO - 'cart']}, 16:16:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:53 INFO - 'talos_options': ['--disable-e10s'], 16:16:53 INFO - 'tests': ['tp5o']}, 16:16:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:53 INFO - 'tests': ['tp5o']}, 16:16:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:53 INFO - 'talos_options': ['--disable-e10s', 16:16:53 INFO - '--xperf_path', 16:16:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:53 INFO - 'tests': ['tp5n']}, 16:16:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:53 INFO - 'talos_options': ['--xperf_path', 16:16:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:53 INFO - 'tests': ['tp5n']}}, 16:16:53 INFO - 'talos.zip': {'path': '', 16:16:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:16:53 INFO - Running post-action listener: _resource_record_post_action 16:16:53 INFO - [mozharness: 2016-10-12 23:16:53.504605Z] Finished populate-webroot step (success) 16:16:53 INFO - [mozharness: 2016-10-12 23:16:53.504697Z] Running create-virtualenv step. 16:16:53 INFO - Running pre-action listener: _resource_record_pre_action 16:16:53 INFO - Running main action method: create_virtualenv 16:16:53 INFO - Creating virtualenv /builds/slave/test/build/venv 16:16:53 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 16:16:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:16:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:16:53 INFO - Using real prefix '/usr' 16:16:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:16:54 INFO - Installing distribute.............................................................................................................................................................................................done. 16:16:56 INFO - Installing pip.................done. 16:16:56 INFO - Return code: 0 16:16:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:16:56 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')]} 16:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:56 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')]} 16:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:56 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 0x2049030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x230a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23083b0>, '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 0x23092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2309740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', '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 16:16:56 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 16:16:56 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 16:16:56 INFO - Using env: {'DISPLAY': ':0', 16:16:56 INFO - 'HOME': '/home/cltbld', 16:16:56 INFO - 'LANG': 'en_US.UTF-8', 16:16:56 INFO - 'LANGUAGE': 'en_US:en', 16:16:56 INFO - 'LOGNAME': 'cltbld', 16:16:56 INFO - 'MAIL': '/var/mail/cltbld', 16:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:56 INFO - 'MOZ_NO_REMOTE': '1', 16:16:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:56 INFO - 'NO_EM_RESTART': '1', 16:16:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:56 INFO - 'PWD': '/builds/slave/test', 16:16:56 INFO - 'SHELL': '/bin/bash', 16:16:56 INFO - 'SHLVL': '1', 16:16:56 INFO - 'TERM': 'linux', 16:16:56 INFO - 'TMOUT': '86400', 16:16:56 INFO - 'USER': 'cltbld', 16:16:56 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', 16:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:56 INFO - '_': '/tools/buildbot/bin/python'} 16:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:56 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 16:16:56 INFO - Cleaning up... 16:16:56 INFO - Return code: 0 16:16:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:16:56 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')]} 16:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:56 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')]} 16:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:56 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 0x2049030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x230a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23083b0>, '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 0x23092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2309740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', '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 16:16:56 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 16:16:56 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 16:16:56 INFO - Using env: {'DISPLAY': ':0', 16:16:56 INFO - 'HOME': '/home/cltbld', 16:16:56 INFO - 'LANG': 'en_US.UTF-8', 16:16:56 INFO - 'LANGUAGE': 'en_US:en', 16:16:56 INFO - 'LOGNAME': 'cltbld', 16:16:56 INFO - 'MAIL': '/var/mail/cltbld', 16:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:56 INFO - 'MOZ_NO_REMOTE': '1', 16:16:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:56 INFO - 'NO_EM_RESTART': '1', 16:16:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:56 INFO - 'PWD': '/builds/slave/test', 16:16:56 INFO - 'SHELL': '/bin/bash', 16:16:56 INFO - 'SHLVL': '1', 16:16:56 INFO - 'TERM': 'linux', 16:16:56 INFO - 'TMOUT': '86400', 16:16:56 INFO - 'USER': 'cltbld', 16:16:56 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', 16:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:56 INFO - '_': '/tools/buildbot/bin/python'} 16:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:56 INFO - Downloading/unpacking psutil>=3.1.1 16:16:56 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 16:16:56 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 16:16:56 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 16:16:56 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 16:16:56 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 16:16:56 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 16:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:16:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:58 INFO - Installing collected packages: psutil 16:16:58 INFO - Running setup.py install for psutil 16:16:58 INFO - building 'psutil._psutil_linux' extension 16:16:58 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 16:16:58 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 16:16:58 INFO - building 'psutil._psutil_posix' extension 16:16:58 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 16:16:58 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 16:16:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:59 INFO - Successfully installed psutil 16:16:59 INFO - Cleaning up... 16:16:59 INFO - Return code: 0 16:16:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:16:59 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')]} 16:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:59 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')]} 16:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:59 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 0x2049030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x230a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23083b0>, '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 0x23092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2309740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', '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 16:16:59 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 16:16:59 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 16:16:59 INFO - Using env: {'DISPLAY': ':0', 16:16:59 INFO - 'HOME': '/home/cltbld', 16:16:59 INFO - 'LANG': 'en_US.UTF-8', 16:16:59 INFO - 'LANGUAGE': 'en_US:en', 16:16:59 INFO - 'LOGNAME': 'cltbld', 16:16:59 INFO - 'MAIL': '/var/mail/cltbld', 16:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:59 INFO - 'MOZ_NO_REMOTE': '1', 16:16:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:59 INFO - 'NO_EM_RESTART': '1', 16:16:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:59 INFO - 'PWD': '/builds/slave/test', 16:16:59 INFO - 'SHELL': '/bin/bash', 16:16:59 INFO - 'SHLVL': '1', 16:16:59 INFO - 'TERM': 'linux', 16:16:59 INFO - 'TMOUT': '86400', 16:16:59 INFO - 'USER': 'cltbld', 16:16:59 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', 16:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:59 INFO - '_': '/tools/buildbot/bin/python'} 16:16:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 16: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 16: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 16: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 16: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 16: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 16: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 16:17:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:17:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:17:01 INFO - Installing collected packages: mozsystemmonitor 16:17:01 INFO - Running setup.py install for mozsystemmonitor 16:17:01 INFO - Successfully installed mozsystemmonitor 16:17:01 INFO - Cleaning up... 16:17:01 INFO - Return code: 0 16:17:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:17: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')]} 16:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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')]} 16:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:01 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 0x2049030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x230a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23083b0>, '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 0x23092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2309740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', '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 16:17:01 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 16:17:01 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 16:17:01 INFO - Using env: {'DISPLAY': ':0', 16:17:01 INFO - 'HOME': '/home/cltbld', 16:17:01 INFO - 'LANG': 'en_US.UTF-8', 16:17:01 INFO - 'LANGUAGE': 'en_US:en', 16:17:01 INFO - 'LOGNAME': 'cltbld', 16:17:01 INFO - 'MAIL': '/var/mail/cltbld', 16:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:01 INFO - 'MOZ_NO_REMOTE': '1', 16:17:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:01 INFO - 'NO_EM_RESTART': '1', 16:17:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:01 INFO - 'PWD': '/builds/slave/test', 16:17:01 INFO - 'SHELL': '/bin/bash', 16:17:01 INFO - 'SHLVL': '1', 16:17:01 INFO - 'TERM': 'linux', 16:17:01 INFO - 'TMOUT': '86400', 16:17:01 INFO - 'USER': 'cltbld', 16:17:01 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', 16:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:01 INFO - '_': '/tools/buildbot/bin/python'} 16:17:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:01 INFO - Downloading/unpacking jsonschema==2.5.1 16:17:01 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 16:17:01 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 16:17:01 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 16:17:01 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 16:17:01 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 16:17:01 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 16:17:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:17:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:17:03 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 16:17:03 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 16:17:03 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 16:17:03 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 16:17:03 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 16:17:03 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 16:17:03 INFO - Downloading functools32-3.2.3-2.tar.gz 16:17:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:17:03 INFO - warning: no files found matching '*.txt' 16:17:03 INFO - no previously-included directories found matching 'build' 16:17:03 INFO - no previously-included directories found matching 'dist' 16:17:03 INFO - no previously-included directories found matching '.git*' 16:17:03 INFO - Installing collected packages: jsonschema, functools32 16:17:03 INFO - Running setup.py install for functools32 16:17:03 INFO - warning: no files found matching '*.txt' 16:17:03 INFO - no previously-included directories found matching 'build' 16:17:03 INFO - no previously-included directories found matching 'dist' 16:17:03 INFO - no previously-included directories found matching '.git*' 16:17:03 INFO - Successfully installed jsonschema functools32 16:17:03 INFO - Cleaning up... 16:17:04 INFO - Return code: 0 16:17:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:17:04 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')]} 16:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:04 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')]} 16:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:04 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 0x2049030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x230a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23083b0>, '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 0x23092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2309740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', '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 16:17:04 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 16:17:04 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 16:17:04 INFO - Using env: {'DISPLAY': ':0', 16:17:04 INFO - 'HOME': '/home/cltbld', 16:17:04 INFO - 'LANG': 'en_US.UTF-8', 16:17:04 INFO - 'LANGUAGE': 'en_US:en', 16:17:04 INFO - 'LOGNAME': 'cltbld', 16:17:04 INFO - 'MAIL': '/var/mail/cltbld', 16:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:04 INFO - 'MOZ_NO_REMOTE': '1', 16:17:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:04 INFO - 'NO_EM_RESTART': '1', 16:17:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:04 INFO - 'PWD': '/builds/slave/test', 16:17:04 INFO - 'SHELL': '/bin/bash', 16:17:04 INFO - 'SHLVL': '1', 16:17:04 INFO - 'TERM': 'linux', 16:17:04 INFO - 'TMOUT': '86400', 16:17:04 INFO - 'USER': 'cltbld', 16:17:04 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', 16:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:04 INFO - '_': '/tools/buildbot/bin/python'} 16:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:17:04 INFO - Cleaning up... 16:17:04 INFO - Return code: 0 16:17:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:17:04 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')]} 16:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:04 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')]} 16:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:04 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 0x2049030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x230a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23083b0>, '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 0x23092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2309740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', '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 16:17:04 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 16:17:04 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 16:17:04 INFO - Using env: {'DISPLAY': ':0', 16:17:04 INFO - 'HOME': '/home/cltbld', 16:17:04 INFO - 'LANG': 'en_US.UTF-8', 16:17:04 INFO - 'LANGUAGE': 'en_US:en', 16:17:04 INFO - 'LOGNAME': 'cltbld', 16:17:04 INFO - 'MAIL': '/var/mail/cltbld', 16:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:04 INFO - 'MOZ_NO_REMOTE': '1', 16:17:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:04 INFO - 'NO_EM_RESTART': '1', 16:17:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:04 INFO - 'PWD': '/builds/slave/test', 16:17:04 INFO - 'SHELL': '/bin/bash', 16:17:04 INFO - 'SHLVL': '1', 16:17:04 INFO - 'TERM': 'linux', 16:17:04 INFO - 'TMOUT': '86400', 16:17:04 INFO - 'USER': 'cltbld', 16:17:04 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', 16:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:04 INFO - '_': '/tools/buildbot/bin/python'} 16:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:04 INFO - Downloading/unpacking blobuploader==1.2.4 16:17:04 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 16:17:04 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 16:17:04 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 16:17:04 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 16:17:04 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 16:17:04 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 16:17:06 INFO - Downloading blobuploader-1.2.4.tar.gz 16:17:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:17:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:17:06 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 16:17:06 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 16:17:06 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 16:17:06 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 16:17:06 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 16:17:06 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 16:17:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:17:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:17:06 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 16:17:06 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 16:17:06 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 16:17:06 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 16:17:06 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 16:17:06 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 16:17:07 INFO - Downloading docopt-0.6.1.tar.gz 16:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:17:07 INFO - Installing collected packages: blobuploader, requests, docopt 16:17:07 INFO - Running setup.py install for blobuploader 16:17:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:17:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:17:07 INFO - Running setup.py install for requests 16:17:07 INFO - Running setup.py install for docopt 16:17:07 INFO - Successfully installed blobuploader requests docopt 16:17:07 INFO - Cleaning up... 16:17:07 INFO - Return code: 0 16:17:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:07 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')]} 16:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:07 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')]} 16:17:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:07 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 0x2049030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x230a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23083b0>, '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 0x23092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2309740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', '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 16:17:07 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 16:17:07 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 16:17:07 INFO - Using env: {'DISPLAY': ':0', 16:17:07 INFO - 'HOME': '/home/cltbld', 16:17:07 INFO - 'LANG': 'en_US.UTF-8', 16:17:07 INFO - 'LANGUAGE': 'en_US:en', 16:17:07 INFO - 'LOGNAME': 'cltbld', 16:17:07 INFO - 'MAIL': '/var/mail/cltbld', 16:17:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:07 INFO - 'MOZ_NO_REMOTE': '1', 16:17:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:07 INFO - 'NO_EM_RESTART': '1', 16:17:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:07 INFO - 'PWD': '/builds/slave/test', 16:17:07 INFO - 'SHELL': '/bin/bash', 16:17:07 INFO - 'SHLVL': '1', 16:17:07 INFO - 'TERM': 'linux', 16:17:07 INFO - 'TMOUT': '86400', 16:17:07 INFO - 'USER': 'cltbld', 16:17:07 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', 16:17:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:07 INFO - '_': '/tools/buildbot/bin/python'} 16:17:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:07 INFO - Running setup.py (path:/tmp/pip-bzXvbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:08 INFO - Running setup.py (path:/tmp/pip-OAN4Xo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:08 INFO - Running setup.py (path:/tmp/pip-BbPKqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:08 INFO - Running setup.py (path:/tmp/pip-sHzJga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:08 INFO - Running setup.py (path:/tmp/pip-ia7Izh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:08 INFO - Running setup.py (path:/tmp/pip-zOwJpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:08 INFO - Running setup.py (path:/tmp/pip-jS9KKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:08 INFO - Running setup.py (path:/tmp/pip-0MDHJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:08 INFO - Running setup.py (path:/tmp/pip-pKiXqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:08 INFO - Running setup.py (path:/tmp/pip-LPFt7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:08 INFO - Running setup.py (path:/tmp/pip-sbftx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:08 INFO - Running setup.py (path:/tmp/pip-TdupCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:08 INFO - Running setup.py (path:/tmp/pip-qNv21i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:08 INFO - Running setup.py (path:/tmp/pip-0WMSYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:08 INFO - Running setup.py (path:/tmp/pip-57x34R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:09 INFO - Running setup.py (path:/tmp/pip-DnHQ5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:09 INFO - Running setup.py (path:/tmp/pip-mgU7JR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:17:09 INFO - Running setup.py install for manifestparser 16:17:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:17:09 INFO - Running setup.py install for mozcrash 16:17:09 INFO - Running setup.py install for mozdebug 16:17:09 INFO - Running setup.py install for mozdevice 16:17:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:17:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:17:09 INFO - Running setup.py install for mozfile 16:17:09 INFO - Running setup.py install for mozhttpd 16:17:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:17:09 INFO - Running setup.py install for mozinfo 16:17:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:17:09 INFO - Running setup.py install for mozInstall 16:17:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:17:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:17:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:17:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:17:09 INFO - Running setup.py install for mozleak 16:17:10 INFO - Running setup.py install for mozlog 16:17:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:17:10 INFO - Running setup.py install for moznetwork 16:17:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:17:10 INFO - Running setup.py install for mozprocess 16:17:10 INFO - Running setup.py install for mozprofile 16:17:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:17:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:17:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:17:10 INFO - Running setup.py install for mozrunner 16:17:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:17:10 INFO - Running setup.py install for mozscreenshot 16:17:10 INFO - Running setup.py install for moztest 16:17:10 INFO - Running setup.py install for mozversion 16:17:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:17:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:17:10 INFO - Cleaning up... 16:17:10 INFO - Return code: 0 16:17:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:10 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')]} 16:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:10 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')]} 16:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:10 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 0x2049030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x230a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23083b0>, '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 0x23092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2309740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', '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 16:17:10 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 16:17:10 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 16:17:10 INFO - Using env: {'DISPLAY': ':0', 16:17:10 INFO - 'HOME': '/home/cltbld', 16:17:10 INFO - 'LANG': 'en_US.UTF-8', 16:17:10 INFO - 'LANGUAGE': 'en_US:en', 16:17:10 INFO - 'LOGNAME': 'cltbld', 16:17:10 INFO - 'MAIL': '/var/mail/cltbld', 16:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:10 INFO - 'MOZ_NO_REMOTE': '1', 16:17:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:10 INFO - 'NO_EM_RESTART': '1', 16:17:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:10 INFO - 'PWD': '/builds/slave/test', 16:17:10 INFO - 'SHELL': '/bin/bash', 16:17:10 INFO - 'SHLVL': '1', 16:17:10 INFO - 'TERM': 'linux', 16:17:10 INFO - 'TMOUT': '86400', 16:17:10 INFO - 'USER': 'cltbld', 16:17:10 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', 16:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:10 INFO - '_': '/tools/buildbot/bin/python'} 16:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:11 INFO - Running setup.py (path:/tmp/pip-Al2Bn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:11 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)) 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:11 INFO - Running setup.py (path:/tmp/pip-vWjb5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:11 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)) 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:11 INFO - Running setup.py (path:/tmp/pip-A4K23x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:11 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)) 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:11 INFO - Running setup.py (path:/tmp/pip-RUexAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:11 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)) 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:11 INFO - Running setup.py (path:/tmp/pip-NrGc91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:11 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)) 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:11 INFO - Running setup.py (path:/tmp/pip-gK2MFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:11 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)) 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:11 INFO - Running setup.py (path:/tmp/pip-xokP36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:11 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)) 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:11 INFO - Running setup.py (path:/tmp/pip-pvrM3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:11 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)) 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:11 INFO - Running setup.py (path:/tmp/pip-3VyQt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:11 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)) 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:11 INFO - Running setup.py (path:/tmp/pip-clB7AT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:11 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)) 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:11 INFO - Running setup.py (path:/tmp/pip-cViuL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:11 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)) 16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:12 INFO - Running setup.py (path:/tmp/pip-DcVS5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:12 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)) 16:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:12 INFO - Running setup.py (path:/tmp/pip-xpQXTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:12 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)) 16:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:12 INFO - Running setup.py (path:/tmp/pip-EVcVul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:12 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)) 16:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:12 INFO - Running setup.py (path:/tmp/pip-OPf19g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:12 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)) 16:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:12 INFO - Running setup.py (path:/tmp/pip-Hwllwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:12 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)) 16:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:12 INFO - Running setup.py (path:/tmp/pip-HnERGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:12 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)) 16:17:12 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)) 16:17:12 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)) 16:17:12 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)) 16:17:12 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)) 16:17:12 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)) 16:17:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:17:12 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 16:17:12 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 16:17:12 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 16:17:12 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 16:17:12 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 16:17:12 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 16:17:14 INFO - Downloading blessings-1.6.tar.gz 16:17:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:17:14 INFO - Installing collected packages: blessings 16:17:14 INFO - Running setup.py install for blessings 16:17:14 INFO - Successfully installed blessings 16:17:14 INFO - Cleaning up... 16:17:14 INFO - Return code: 0 16:17:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:17:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:14 INFO - Reading from file tmpfile_stdout 16:17:14 INFO - Current package versions: 16:17:14 INFO - argparse == 1.2.1 16:17:14 INFO - blessings == 1.6 16:17:14 INFO - blobuploader == 1.2.4 16:17:14 INFO - docopt == 0.6.1 16:17:14 INFO - functools32 == 3.2.3-2 16:17:14 INFO - jsonschema == 2.5.1 16:17:14 INFO - manifestparser == 1.1 16:17:14 INFO - mozInstall == 1.12 16:17:14 INFO - mozcrash == 0.17 16:17:14 INFO - mozdebug == 0.1 16:17:14 INFO - mozdevice == 0.48 16:17:14 INFO - mozfile == 1.2 16:17:14 INFO - mozhttpd == 0.7 16:17:14 INFO - mozinfo == 0.9 16:17:14 INFO - mozleak == 0.1 16:17:14 INFO - mozlog == 3.3 16:17:14 INFO - moznetwork == 0.27 16:17:14 INFO - mozprocess == 0.23 16:17:14 INFO - mozprofile == 0.28 16:17:14 INFO - mozrunner == 6.12 16:17:14 INFO - mozscreenshot == 0.1 16:17:14 INFO - mozsystemmonitor == 0.3 16:17:14 INFO - moztest == 0.7 16:17:14 INFO - mozversion == 1.4 16:17:14 INFO - psutil == 3.1.1 16:17:14 INFO - requests == 1.2.3 16:17:14 INFO - wsgiref == 0.1.2 16:17:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:14 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 0x2049030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x230a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23083b0>, '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 0x23092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2309740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', '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 16:17:14 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 16:17:14 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 16:17:14 INFO - Using env: {'DISPLAY': ':0', 16:17:14 INFO - 'HOME': '/home/cltbld', 16:17:14 INFO - 'LANG': 'en_US.UTF-8', 16:17:14 INFO - 'LANGUAGE': 'en_US:en', 16:17:14 INFO - 'LOGNAME': 'cltbld', 16:17:14 INFO - 'MAIL': '/var/mail/cltbld', 16:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:14 INFO - 'MOZ_NO_REMOTE': '1', 16:17:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:14 INFO - 'NO_EM_RESTART': '1', 16:17:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:14 INFO - 'PWD': '/builds/slave/test', 16:17:14 INFO - 'SHELL': '/bin/bash', 16:17:14 INFO - 'SHLVL': '1', 16:17:14 INFO - 'TERM': 'linux', 16:17:14 INFO - 'TMOUT': '86400', 16:17:14 INFO - 'USER': 'cltbld', 16:17:14 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', 16:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:14 INFO - '_': '/tools/buildbot/bin/python'} 16:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:15 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)) 16:17:15 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)) 16:17:15 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)) 16:17:15 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)) 16:17:15 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)) 16:17:15 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)) 16:17:15 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)) 16:17:15 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)) 16:17:15 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)) 16:17:15 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)) 16:17:15 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)) 16:17:15 INFO - Cleaning up... 16:17:15 INFO - Return code: 0 16:17:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:17:15 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')]} 16:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:15 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')]} 16:17:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:15 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 0x2049030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x230a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23083b0>, '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 0x23092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2309740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', '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 16:17:15 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 16:17:15 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 16:17:15 INFO - Using env: {'DISPLAY': ':0', 16:17:15 INFO - 'HOME': '/home/cltbld', 16:17:15 INFO - 'LANG': 'en_US.UTF-8', 16:17:15 INFO - 'LANGUAGE': 'en_US:en', 16:17:15 INFO - 'LOGNAME': 'cltbld', 16:17:15 INFO - 'MAIL': '/var/mail/cltbld', 16:17:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:15 INFO - 'MOZ_NO_REMOTE': '1', 16:17:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:15 INFO - 'NO_EM_RESTART': '1', 16:17:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:15 INFO - 'PWD': '/builds/slave/test', 16:17:15 INFO - 'SHELL': '/bin/bash', 16:17:15 INFO - 'SHLVL': '1', 16:17:15 INFO - 'TERM': 'linux', 16:17:15 INFO - 'TMOUT': '86400', 16:17:15 INFO - 'USER': 'cltbld', 16:17:15 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', 16:17:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:15 INFO - '_': '/tools/buildbot/bin/python'} 16:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:17:15 INFO - Cleaning up... 16:17:15 INFO - Return code: 0 16:17:15 INFO - Running post-action listener: _resource_record_post_action 16:17:15 INFO - Running post-action listener: _start_resource_monitoring 16:17:15 INFO - Starting resource monitoring. 16:17:15 INFO - [mozharness: 2016-10-12 23:17:15.291999Z] Finished create-virtualenv step (success) 16:17:15 INFO - [mozharness: 2016-10-12 23:17:15.292479Z] Running install step. 16:17:15 INFO - Running pre-action listener: _resource_record_pre_action 16:17:15 INFO - Running main action method: install 16:17:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:15 INFO - Reading from file tmpfile_stdout 16:17:15 INFO - Detecting whether we're running mozinstall >=1.0... 16:17:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:17:15 INFO - Reading from file tmpfile_stdout 16:17:15 INFO - Output received: 16:17:15 INFO - Usage: mozinstall [options] installer 16:17:15 INFO - Options: 16:17:15 INFO - -h, --help show this help message and exit 16:17:15 INFO - -d DEST, --destination=DEST 16:17:15 INFO - Directory to install application into. [default: 16:17:15 INFO - "/builds/slave/test"] 16:17:15 INFO - --app=APP Application being installed. [default: firefox] 16:17:15 INFO - mkdir: /builds/slave/test/build/application 16:17:15 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'] 16:17:15 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 16:17:29 INFO - Reading from file tmpfile_stdout 16:17:29 INFO - Output received: 16:17:29 INFO - /builds/slave/test/build/application/firefox/firefox 16:17:29 INFO - Running post-action listener: _resource_record_post_action 16:17:29 INFO - [mozharness: 2016-10-12 23:17:29.735966Z] Finished install step (success) 16:17:29 INFO - [mozharness: 2016-10-12 23:17:29.736247Z] Running run-tests step. 16:17:29 INFO - Running pre-action listener: _resource_record_pre_action 16:17:29 INFO - Running main action method: run_tests 16:17:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:17:29 INFO - Python 2.7.3 16:17:29 INFO - Return code: 0 16:17:29 INFO - grabbing minidump binary from tooltool 16:17:29 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')]} 16:17:29 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 0x23083b0>, '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 0x23092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2309740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:17:29 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 16:17:29 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 16:17:29 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 16:17:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:17:29 INFO - Return code: 0 16:17:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:17:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:17:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:17:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:17:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:17:29 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/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 16:17:29 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/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 16:17:29 INFO - Using env: {'DISPLAY': ':0', 16:17:29 INFO - 'HOME': '/home/cltbld', 16:17:29 INFO - 'LANG': 'en_US.UTF-8', 16:17:29 INFO - 'LANGUAGE': 'en_US:en', 16:17:29 INFO - 'LOGNAME': 'cltbld', 16:17:29 INFO - 'MAIL': '/var/mail/cltbld', 16:17:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:17:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:29 INFO - 'MOZ_NO_REMOTE': '1', 16:17:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:17:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:29 INFO - 'NO_EM_RESTART': '1', 16:17:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:29 INFO - 'PWD': '/builds/slave/test', 16:17:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:17:29 INFO - 'SHELL': '/bin/bash', 16:17:29 INFO - 'SHLVL': '1', 16:17:29 INFO - 'TERM': 'linux', 16:17:29 INFO - 'TMOUT': '86400', 16:17:29 INFO - 'USER': 'cltbld', 16:17:29 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398', 16:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:29 INFO - '_': '/tools/buildbot/bin/python'} 16:17:29 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/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 16:17:30 INFO - mozversion application_buildid: 20161012130846 16:17:30 INFO - mozversion application_changeset: 1cd295569b2bd7b40cab83c179525fd549c73051 16:17:30 INFO - mozversion application_display_name: Firefox 16:17:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:17:30 INFO - mozversion application_name: Firefox 16:17:30 INFO - mozversion application_remotingname: firefox 16:17:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:17:30 INFO - mozversion application_vendor: Mozilla 16:17:30 INFO - mozversion application_version: 50.0 16:17:30 INFO - mozversion platform_buildid: 20161012130846 16:17:30 INFO - mozversion platform_changeset: 1cd295569b2bd7b40cab83c179525fd549c73051 16:17:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:17:30 INFO - mozversion platform_version: 50.0 16:17:30 INFO - using testdate: 1476314250 16:17:30 INFO - actual date: 1476314250 16:17:30 INFO - starting webserver on 'localhost:57301' 16:17:30 INFO - SUITE-START | Running 2 tests 16:17:30 INFO - TEST-START | dromaeo_css 16:17:30 INFO - operating with platform_type : linux_ 16:17:30 INFO - Initialising browser for dromaeo_css test... 16:17:30 INFO - TEST-INFO | started process 5454 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj1NOcW/profile http://localhost:57301/getInfo.html) 16:17:33 INFO - PROCESS | 5454 | __metrics Screen width/height:1600/1200 16:17:33 INFO - PROCESS | 5454 | colorDepth:24 16:17:33 INFO - PROCESS | 5454 | Browser inner width/height: 1024/697 16:17:33 INFO - PROCESS | 5454 | __metrics 16:17:34 INFO - PROCESS | 5454 | 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" 16:17:34 INFO - PROCESS | 5454 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:17:35 INFO - TEST-INFO | 5454: exit 0 16:17:35 INFO - Browser initialized. 16:17:35 INFO - Running cycle 1/1 for dromaeo_css test... 16:17:35 INFO - TEST-INFO | started process 5544 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj1NOcW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:17:47 INFO - PROCESS | 5544 | RSS: Main: 143859712 16:17:47 INFO - PROCESS | 5544 | 16:18:37 INFO - PROCESS | 5544 | 1476314317901 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] 16:20:46 INFO - PROCESS | 5544 | Cycle 1(1): loaded http://localhost:57301/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57301/tests/dromaeo/cssquery-ext.html) 16:20:47 INFO - PROCESS | 5544 | RSS: Main: 237256704 16:20:47 INFO - PROCESS | 5544 | 16:23:50 INFO - PROCESS | 5544 | Cycle 1(1): loaded http://localhost:57301/tests/dromaeo/cssquery-ext.html (next: http://localhost:57301/tests/dromaeo/cssquery-jquery.html) 16:23:50 INFO - PROCESS | 5544 | RSS: Main: 237342720 16:23:50 INFO - PROCESS | 5544 | 16:26:52 INFO - PROCESS | 5544 | Cycle 1(1): loaded http://localhost:57301/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57301/tests/dromaeo/cssquery-mootools.html) 16:26:53 INFO - PROCESS | 5544 | RSS: Main: 213876736 16:26:53 INFO - PROCESS | 5544 | 16:29:55 INFO - PROCESS | 5544 | Cycle 1(1): loaded http://localhost:57301/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57301/tests/dromaeo/cssquery-prototype.html) 16:29:55 INFO - PROCESS | 5544 | RSS: Main: 221425664 16:29:55 INFO - PROCESS | 5544 | 16:32:58 INFO - PROCESS | 5544 | Cycle 1(1): loaded http://localhost:57301/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57301/tests/dromaeo/cssquery-yui.html) 16:32:58 INFO - PROCESS | 5544 | RSS: Main: 176410624 16:32:58 INFO - PROCESS | 5544 | 16:36:01 INFO - PROCESS | 5544 | Cycle 1(1): loaded http://localhost:57301/tests/dromaeo/cssquery-yui.html (next: http://localhost:57301/tests/dromaeo/cssquery-dojo.html) 16:36:01 INFO - PROCESS | 5544 | RSS: Main: 258199552 16:36:01 INFO - PROCESS | 5544 | 16:36:01 INFO - PROCESS | 5544 | __start_tp_report 16:36:01 INFO - PROCESS | 5544 | _x_x_mozilla_page_load 16:36:01 INFO - PROCESS | 5544 | _x_x_mozilla_page_load_details 16:36:01 INFO - PROCESS | 5544 | |i|pagename|runs| 16:36:01 INFO - PROCESS | 5544 | |0;dojo.html;3228.6287076986146;3228.8708451661932;3268.101272150157;3283.09714828422;3304.1409233599256;5623.831285061449;5715.913976344492;5729.598928075036;5730;5739.3399759027725;5666.206731057655;5734.713264336788;5744.310682718069;5750.223719797826;5755.366909639936;5177;5181.792728290873;5184.274201611777;5246.029484545371;5260.710660913648;5067.037262920052;5105.668131571436;5273.8945221095555;5278.498542638463;5313.309269794921;5056.898862022776;5064.2403639454005;5085.313482679846;5108.923366149511;5115.718635475047;5089.872753181163;5140.742962851842;5210.791568337262;5220;5257.185136303879;5000.224965130411;5054.797808087672;5135.075686376451;5175.741212939338;5186.962607478482;5498;5538;5541.5289700375515;5545.390007099216;5551.306086739158;5909.438603332719;5951.345352011275;6007.7596896124105;6008.279006519202;6016.578839481238;5864.06175011996;5985.072313791369;6028.7588496460085;6032.849178770566;6036.667983260919;5592.692401917893;5659.660420374791;5679.2049113123785;5680.630759000693;5684.6020778545935;6204.720787564571;6209;6215.751369945242;6217.036359364306;6221.5333849961435;5791.507721843606;5850.61970971894;5915.319738230072;5960.225170727777;5980.730867110903;6024.036154215304;6063.83243364268;6081.604695694766;6097.3292937776805;6104.236970378703;6028.216331876828;6034.607750496291;6039.607425517415;6040.728167232485;6041.879162416815;5790.71046447676;5899.085641725539;5907.025340818803;6037.698115094227;6038.245219347582;4617.238155704271;4619.838305659286;4621.353010578522;4623.69945953512;4624.930626040612;4088.8568900088353;4096.672266218696;4118.279301122292;4158.293090174678;4175.31107367287;5760;5763.567732420085;5766.3368712598585;5775.711214439261;5777.826665200051;6100.115483254992;6110.633361998294;6117.143599896018;6122.571420000646;6125.142480052797;5498.80754173602;5685;5714.828555143353;5731.197632331477;5816.447437493432;5128.1794912813775;5133.281340612317;5138.743062846843;5140.56305214061;5141.974290128525;6230.345813689588;6262.1546091278005;6310.652914089769;6316.494680425555;6327.557071005078;5865.648061116347;5992.730327135377;6034.39656034393;6063.363346848694;6108.053251745987;6263.09185168139;6297.464715499051;6298.433141017331;6304.05439184126;6321.557490975588;5982.252218472691;5983.640981541122;5987.191729116687;5997.640141591519;6000.838022267359;5895.67573783446;5957.6127551709;5999.640021598718;6026.789062382971;6110.103144028831;5889.970550147222;5901.232839730807;5906.291245050449;5922.348541660328;5933.258342707161;5879.588428809942;5879.970600146972;5882.558808089325;5922.289325280994;5941.643501389931;5662.801801936913;5826.883462330815;5866.6186697864505;5881.264841894763;5901.881962360643;6025.276966764016;6037.366076561898;6057.848553786014;6060.060690592965;6074.3925607438905;5852.795152169653;5864.67744274069;5866.560007999332;5868.67722275279;5874.118882167709 16:36:01 INFO - PROCESS | 5544 | |1;ext.html;595.5860676829602;619.8233503451515;661.3948237362813;669.0165456778537;673.014034439546;35478.645213547854;44759;46475.302870456886;46507.53492465075;46539.53460465394;48393.51606483943;48544;48572;48719.269210961866;48839.51160488394;8456.704015359464;9040.457572545636;9218.078192180761;9219.35464517484;9230.446173229595;10765.407902565395;11043.72390690231;11052.889471105267;11358;11364.829527557093;11213.327200368005;11272.661820145368;11312.264702794333;11314.717132071723;11320;11059.889401106007;11103.111751059896;11112.944435277852;11120.165987550967;11125.610603628875;10977.231593788438;11029.889701103048;11042;11048;11057.225994180411;45269.54730452686;45313.320300195526;45326.32010519845;45333.54666453359;45413.63759087233;3875.7093218008763;3906.160175562239;3921.745086569364;3990.20195960809;4053.2096241232675;34589.481157782655;34598.30803383918;34605.307893841986;34938.825305873565;35276.47085293697;4406.1187762447635;4429.579189976947;4439.511653718088;4487.326900964848;4497.977510112429;383599;386791;389947;390747.0462647697;391250;63828.68085659635;64120.35879641237;64651;64671.67664161649;64737;40331.59668403337;40757.18485630271;40881;68203.31796682069;68328.6583567089;17697.911510442365;18028.36900708469;18035.458936231673;18040.368587099652;18045.548861278312;12551.184173028723;12893.097483176087;13580.796288055688;13812.033157679067;13829;19557.36133849776;20539.178432862816;20774.688379674317;20814.687779683318;20834.68747968782;7379.3358597726465;7454.589997550187;7551.358134558624;7720.683794861519;7792.142864284924;974.7563109222694;1042.827933390982;1106.7288514314046;1156.6992581928591;1196.7008247938015;35139.64860351415;35278.29443411117;35439.29121417557;35472.64527354745;35831.82084089615;1119.9664010079712;1180.557291015869;1206.3847437806783;1225.871283515236;1291.6835375333103;17500;18515.444536663923;18546.165422556016;18548.165332560067;18567.90716046411;618000;624862;625954;627343;629467;64872.35127648663;65462;65462.67268663721;65477;65520.67239663771;1328.7076843094503;1376.2430663135435;1387.1954266525593;1435.727211829749;1468.4493315006873;33548.83225583856;35742.821285893406;36072.45891311685;36311;36412.27175456529;54467.45532544782;55419.16871247015;55495.445045550645;55542.16686749621;55546.166807498725;362676;364111;364501;365178;365642;26114.12580605913;28592.57111143376;28611;29994.4001119972;29999.55000674948;47068.52931470778;52394.476055238956;52546.71639112212;52876.47123528716;53705.73147134239;43683.160594561254;44844.32733508935;45178.54821451743;46367;46437.53562464332;43842.12315753731;45029.32456013228;45046.54953450424;45722.31416528688;46111.07777844492;43737;44736;45853.770731147466;46017.76991115023;48056;39122.608773911896;40643;41028.1794364117;41403.378949315185;41441.79279103585;30182.54726179153;30468;30809.383812324075;30813.22966925845;31140.53289200709 16:36:01 INFO - PROCESS | 5544 | |2;jquery.html;373.2479054704769;373.9925201495968;399.1279055264247;405.9269331520325;416.53348249960044;13699.38352774125;19029.524261893443;19972.800271997276;20661.586768264646;20699.793002069975;20132.496687582818;20135.395938121845;20628.278010269645;20832.895835520838;20861.061252243646;5963.791267305645;6150.661713605772;6155.415235552616;6163;6165.691715414234;6060.606060606046;6100.1154832549255;6101.847453813646;6104.114903339013;6104.969475152618;5502.614816962814;5733.426657334275;5766.250387449647;5845.035569131088;5845.853853653672;5819.650820950736;5820.621659592134;5857.297124345085;5866.853328666774;5883.382244864313;6038.154658347835;6086.2087928569445;6169.568130230886;6173.320934697224;6180.103884936704;15284.617884552976;15409.922950385288;19974.90138042407;24486.14298499544;25281.620775688378;2510.1465501729317;2513.924582262535;2552.770250677448;2795.65054368204;2818.943621127566;10730.678079657622;10774.138068954464;10775.83836242449;10903.236773425784;11145.16411269158;2034.8575599708026;2049.5183631846503;2052.4971382011504;2087.5198704297945;2096.2872623307994;84838;89089.99999999935;89690.55154724185;89837.55081224618;89852.5507372459;10748.94625526883;10762.461876906124;11001.22991390595;11389.829152562717;11735.4132293385;15691;15738.134402607966;15782.605434864221;15872.682546349008;15892.125933073792;7565.940768292444;7739.806504837424;8308.626111824982;8446.66213351459;8537.103604121601;7587.848243035109;7644.120926093459;7654.846903061907;7750.651220695082;7841.960790196013;5021.6735912165595;5051.949480505222;5140.203268493389;5190.273361729361;5232.607554433433;2057.968425961741;2092.7593326767615;2104.663253879372;2128.553003869174;2220.067571619891;1003.6336737091056;1004.467632154959;1019.3119644240256;1044.1646682653848;1050.579768092769;19970.30103946355;20688.689669654967;21059.78940210609;21108.155673773184;21112.894435528033;993.2649839119;1019.2101121630795;1044.3159730376615;1058.8147074261974;1061.8991195836384;10618.309809862334;11641.185117041896;11790.351530665725;11854.525818967144;11881.702957425961;527667;550314;553340;562078;563610;75568;85134;85632;98092.50953745328;99867;996.8436613528272;1001.154024849016;1033.0186322993186;1040.245821779219;1041.6093964763213;17779.46661600154;18523.81476185221;18921.188372186276;19084.522886927938;19242.71135932934;42747.35878961756;43495.13009739724;43535.12929741324;44044.77977610091;44593.55406446024;61247;62410.37589624045;62670;63445.36554634394;63701.85937914021;9224.215941645036;9334.766630834283;9365.859512107187;9368.67209647659;9376.718698439057;11064.612738554111;13398.732025359634;13644.65888352799;14046.929765351108;14101.435942562182;11853.703657408634;12131.393430328271;12198;12417.62747117588;12760.29818359999;11393.715157121138;12173.2696038238;12173.878261217274;12246.387680616112;12349.93825030869;11717.414129293671;12121.636350909486;12372.32684328123;12576.371181440709;12621;8886.976221921937;9091.272698184262;9163.587638556148;9166.770830729285;9276.16514513697;8873.542771195796;11930.582429614922;11936.940315298369;12277.263364198177;12507.312097834343 16:36:01 INFO - PROCESS | 5544 | |3;mootools.html;1558.4233833481615;1572.347475797544;1719.1662043908702;1818.7817461904574;1839.4665546991373;9079.500627465486;9841.655542056029;9846.359986600866;9849.162821160202;9861.901380986188;7953.602319883998;9137.314701397388;9352.111549402796;9393.530323483832;9656.42061476312;2465.8027357811375;2500.7874330681916;2501.6372625969225;2505.624156376549;2515.55977703902;2450.0444826517673;2524.8737563121863;2538.2385284414695;2538.441542860577;2541.136013755323;2413.987930060356;2420.721617014048;2517.962230566543;2518.0934863449143;2520.09276660403;2432.805375569959;2447.4248551590363;2450.840695354814;2452.9754702452924;2457.1891275879098;2422.0553983946097;2428.0651948999584;2437.0130097310616;2438.902443902242;2471.1351027140536;9890.15933645641;9975.451350175737;9976.650817221362;9981.850272245922;9982.401055936667;5529.668219906818;5646.435356464362;5653.745581448845;5663.631863928873;5664.773409063632;3451.516787649731;3516.384632689269;3519.225770330523;3540.6459354064646;3564.9643503565153;1001.9148372388356;1016.1667432705111;1045.6915210013065;1053.3206082077095;1063.9680809575725;13639.045266831326;13640.522581709592;13694.109882857681;13731.45074197031;13747.793783093262;4572.314152877028;4600.976995115003;4731.29030645406;4740.051989602093;4841.346418233494;11350.716232094099;11352;11353.432328383547;11363.318200907972;11369.602063927889;5569.832905012856;5600.747966341443;5619.550435965195;5619.943800561942;5620.803271885465;4480.484744254387;4614.330922016289;5052.469490703496;5104.3619547556555;5156;4186.623203911662;4215.093754842724;4363.890902727394;4393.758343291086;4407;1316.58527563819;1328.5150919914163;1342.7713642017445;1346.7104572516955;1350.4800651748951;948.2745699539857;1088.6026600290836;1093.699232710995;1099.681092483171;1109.3343993603773;6405.231372235362;6529.836754081091;6652.368025037614;6684.565503242371;6827.180738311435;466.8576084294255;474.34066647360197;478.0963978081429;482.48133256749134;484.6776893365872;4447.6212374164115;4547.386102876136;4581.65637577183;4610.561996610317;4685.1800934836265;12142.089343299287;12469.563565275172;12501.874981250254;12548.560800371943;12662.113652044269;11407.600733974272;12453.626391208278;12480.375981200903;12519.874801251872;12563.93718031404;8767.693130740394;9194.954025229965;9229.861552076723;9236.122568355995;9237;6647.867042659216;6702.83242918931;6736.730530778714;6811.795646130624;6893.758718445031;11328.716782080513;11485.310881347146;11500.367479788358;11658.591949281734;11670.066394688234;11313.490892909675;11686.590969316034;11728.70678233051;11745.588904388305;11770.470328835394;7744.303012728881;7989.360851131779;7991.800204994922;8627.654893804178;8861.379703420698;8659.177378149066;9232.907670923205;9257.398269112486;9610;9643;8455.450395724258;8616.483011019109;8623.655053797778;8644.394892357726;8849.026607073089;8189.467684600482;8370.623321950405;8514.914850851412;8537.317014638938;8577.699780507652;7006.229314775473;7119.967604697394;7190.892136618059;7344.742933997284;7348.669309880961;5391.379991301051;5514.448555144453;5563.610547261653;5641.746121424628;5691.601587888808;5713.885722285448;5716.342620598684;5797.565182611405;5808.5353171747;5872 16:36:01 INFO - PROCESS | 5544 | |4;prototype.html;349.9247661752723;355.89678993092;356.8144564826291;364.9397849354853;372.9571099323577;5546.362168350641;5572.58205634577;5591.66450012999;5630;5663.207150998864;5253.474652534745;5341.011912796135;5526.613137080406;5533.833984980457;5547.861303467415;4359.4768627764715;4359.520452750195;4363.0619416825375;4372.737635741865;4375.343698445226;4312.008238105238;4343.652507799368;4357.498887627931;4361.716488428257;4364.847230346939;4110.91778164438;4259.318509038554;4260.147970405916;4327.956720432802;4336.588024137718;4252.277112890816;4294.377315289296;4294.506131794852;4298.871033868973;4311.202427550913;3717.237966216919;3749.8687545936036;3778.848846046155;3782.451544526056;3789.2610940866543;5144.459831717691;5373.274607927919;5390.407055223922;5391.865203369947;5421.21392398104;4958.528939750718;4963.081829861487;4968.378952630921;4971.328870602459;4993.626752643016;5724.5992780505385;5739.196512488255;5743.253377060997;5743.4543718346695;5758.74085666146;3991.5409727881083;4148.191102734968;4217.451731274945;4243.405923170759;4265.97867010663;6002.759889604411;6016.608920420159;6035.275766907956;6036.396360363972;6043.879122417571;4222.261104306734;4252.893677658021;4253.702240843173;4273.102648443862;4283.336082907092;4130.442390277334;4312.482502099768;4361.931326824948;4409.184300904343;4417.469903611587;4969.478204788517;4970.552650261494;4972.453030166679;4977.576905963034;5003.149464591028;5848.824535263949;5850.444207800338;5931.73307201177;5949.524038076944;5976.73104710289;1431.8711315981611;1446.1178681004578;1496.4537943650707;1525.8855585831107;1532.6474910770362;969.9369540979814;993.101243374747;1001.1190152665607;1018.9032041956002;1040.6201736366172;5590.944090559124;5625.8593535161945;5635.943640563624;5667.744951477193;5668.206451096849;5832.88334233313;5991.910121348184;6025.548083893726;6031.969840150771;6054.243219597551;5577.776888924398;5619.595101224694;5626.409227031185;5653.265075540154;5657.547396208293;3141.403133404646;3166.6358368787423;3172.4289627867206;3186.3149422874194;3194.9680503194672;5805.535557155417;6030.246219222598;6062.545309101747;6063.393660633901;6082.300535438393;5951.3751056139345;5980.73086711099;5996.760129594854;6006.489448396936;6011.549133814894;5953.136795164677;6017.044460550426;6089.63462192261;6103.938960610426;6123.663198524124;5835.862006908782;5845;5866.823995280149;5890.2342695449315;5923.200367950271;5839.328477225004;5845.795397161079;5846.129238643593;5902.609443175521;5911.256179427165;5888.882222355443;5981.611195272293;6021.253836387398;6052.546059045501;6060.151578778887;4292.5445016761805;4296.839853239545;4304.806283717177;4331.761753103484;4347.565243475754;5722.2561067061015;5954.035168394506;5997.250343707036;6017.916774980546;6025.457708806229;5736.4550367715;5744.3394009687745;5769.144273584435;5770.596058276048;5772.278465191851;5691.26013618227;5722.798212375281;5765.4522820332;5816.389279125631;5874.472637114466;5210.296609957759;5514.972425137848;5711.029694755302;5792.797252096157;5826.621269617462;5846.965593740306;5887.264091988502;5904.380040095728;5918.881622367442;5924.851878703067;5603.635763675348;5733.741981610754;5779.2775903012125;5788.8552786179;5795.39148389413 16:36:01 INFO - PROCESS | 5544 | |5;yui.html;832.3091833777964;867.7526904832121;869.2871845087029;899.9775005624855;925.4868290345283;13912.16527008379;16777.496675099752;17461.47615571535;17504.474865754015;17510.91244543777;14066.437342506313;14130.717385652306;14154.858451415483;14158.504452344145;14172.220527870963;701.3932947999969;729.0194688144437;849.6856163219586;861.3711990247124;888.103015953888;884.1600479544427;895.6820328783285;896.5696465696469;896.6099746610213;896.6592694776009;928.4846909964966;930.0234753508822;930.0374112793261;930.4045410937006;930.50683137937;926.5645146781002;927.4991504587515;929.1173385283973;929.5909799688184;929.6560272699077;681.7034589953386;682.8839097353462;683.1733602341174;683.2620769568904;683.6581709145428;15588.688226235412;15692.607684807857;15700.842991570053;15703.764443533357;15738.763918541232;13551.39018744149;13584.456621735118;13882;14151.363188656534;15378.231088445533;13088.541901033419;13246.205227686274;13579.52471663487;13611.727765444735;13885.652858678613;4681.6020638245445;4709.929351059737;4711.411073615798;4837.806487740486;4936.062148191832;83400;87694;87710;88878;89655;11508.146856437934;11532.423378831025;11692.415379231004;11737.647870563897;11886.821697674543;20969.161233550487;21413.57172856534;21432.142714291567;21507.354779356643;21546.245881394076;8410.411271210956;8586.570671466401;8608.48349099056;8640.524771137521;8920.107989201028;5022.171341728574;5111.821086261963;5145.639805213599;5249.895002100013;5252.868678283074;3687.391580389259;3823.9235215296094;3826.617338266151;3827.5406951165482;3833.4058220975794;610.703808652803;620.9254889413209;630.0706457974451;631.2897989761518;645.8385403649088;709.2163159708551;714.7033980897974;717.5945590741214;731.0094821516874;758.7609902880575;9542.713718588453;10204.897951020543;10343.172546196287;10459.633912813015;10548.947255263827;728.8214387475102;734.203389322591;738.7340557399332;760.1296515489734;780.1709841738243;4732.384789977281;4756.239001759702;4889.65599808441;4897.804087836518;4909.77905994224;74280.25719742842;92293;119481;137239;141156.29421852826;114823;115700;116397;117451.41274293573;120147.39926300487;98025;100280.49859750655;100947.99052009532;101819.45451363776;102461.48769256106;9260;9448.18936354151;9549.761255968657;9638.590128915217;9693.27300452483;47166.52833471759;48280.517194827604;52977;59617.403825961184;60732.08901866387;78600.60699696693;79314.60342698249;79755.20244797709;80761.59619201867;82032;13997.370118344495;14222.573322800332;14270.357833897291;14320.713585728436;14438.927805360907;13868.237246951514;14185.716285674021;14349.56951291463;15733.292001859714;16015.679686405974;14897.329620167335;14927.925360373129;14988.325525351604;15327.46353877609;15977.121258330902;14036.719265614835;14210.549894720125;14226;14368.640783980483;14414;12557.372131393211;13173.736525269249;14285.07259324323;14518;14598.416063357347;12117;12119.575814846437;12132.696682583006;12395.690107747017;12638.219591689813;11468;11896;12406.689832754253;12659.746805064031;12743.681407964876 16:36:01 INFO - PROCESS | 5544 | __end_tp_report 16:36:01 INFO - PROCESS | 5544 | __start_cc_report 16:36:01 INFO - PROCESS | 5544 | _x_x_mozilla_cycle_collect,858 16:36:01 INFO - PROCESS | 5544 | __end_cc_report 16:36:01 INFO - PROCESS | 5544 | __startTimestamp1476315361787__endTimestamp 16:36:01 INFO - PROCESS | 5544 | 16:36:01 INFO - PROCESS | 5544 | ------- Summary: start ------- 16:36:01 INFO - PROCESS | 5544 | Number of tests: 6 16:36:01 INFO - PROCESS | 5544 | 16:36:01 INFO - PROCESS | 5544 | [#0] dojo.html Cycles:165 Average:5618.85 Median:5864.68 stddev:640.11 (10.9%) stddev-sans-first:613.99 16:36:01 INFO - PROCESS | 5544 | Values: 3228.6 3228.9 3268.1 3283.1 3304.1 5623.8 5715.9 5729.6 5730.0 5739.3 5666.2 5734.7 5744.3 5750.2 5755.4 5177.0 5181.8 5184.3 5246.0 5260.7 5067.0 5105.7 5273.9 5278.5 5313.3 5056.9 5064.2 5085.3 5108.9 5115.7 5089.9 5140.7 5210.8 5220.0 5257.2 5000.2 5054.8 5135.1 5175.7 5187.0 5498.0 5538.0 5541.5 5545.4 5551.3 5909.4 5951.3 6007.8 6008.3 6016.6 5864.1 5985.1 6028.8 6032.8 6036.7 5592.7 5659.7 5679.2 5680.6 5684.6 6204.7 6209.0 6215.8 6217.0 6221.5 5791.5 5850.6 5915.3 5960.2 5980.7 6024.0 6063.8 6081.6 6097.3 6104.2 6028.2 6034.6 6039.6 6040.7 6041.9 5790.7 5899.1 5907.0 6037.7 6038.2 4617.2 4619.8 4621.4 4623.7 4624.9 4088.9 4096.7 4118.3 4158.3 4175.3 5760.0 5763.6 5766.3 5775.7 5777.8 6100.1 6110.6 6117.1 6122.6 6125.1 5498.8 5685.0 5714.8 5731.2 5816.4 5128.2 5133.3 5138.7 5140.6 5142.0 6230.3 6262.2 6310.7 6316.5 6327.6 5865.6 5992.7 6034.4 6063.4 6108.1 6263.1 6297.5 6298.4 6304.1 6321.6 5982.3 5983.6 5987.2 5997.6 6000.8 5895.7 5957.6 5999.6 6026.8 6110.1 5890.0 5901.2 5906.3 5922.3 5933.3 5879.6 5880.0 5882.6 5922.3 5941.6 5662.8 5826.9 5866.6 5881.3 5901.9 6025.3 6037.4 6057.8 6060.1 6074.4 5852.8 5864.7 5866.6 5868.7 5874.1 16:36:01 INFO - PROCESS | 5544 | 16:36:01 INFO - PROCESS | 5544 | [#1] ext.html Cycles:180 Average:63624.44 Median:214934.83 stddev:125898.21 (58.6%) stddev-sans-first:126162.44 16:36:01 INFO - PROCESS | 5544 | Values: 595.6 619.8 661.4 669.0 673.0 35478.6 44759.0 46475.3 46507.5 46539.5 48393.5 48544.0 48572.0 48719.3 48839.5 8456.7 9040.5 9218.1 9219.4 9230.4 10765.4 11043.7 11052.9 11358.0 11364.8 11213.3 11272.7 11312.3 11314.7 11320.0 11059.9 11103.1 11112.9 11120.2 11125.6 10977.2 11029.9 11042.0 11048.0 11057.2 45269.5 45313.3 45326.3 45333.5 45413.6 3875.7 3906.2 3921.7 3990.2 4053.2 34589.5 34598.3 34605.3 34938.8 35276.5 4406.1 4429.6 4439.5 4487.3 4498.0 383599.0 386791.0 389947.0 390747.0 391250.0 63828.7 64120.4 64651.0 64671.7 64737.0 40331.6 40757.2 40881.0 68203.3 68328.7 17697.9 18028.4 18035.5 18040.4 18045.5 12551.2 12893.1 13580.8 13812.0 13829.0 19557.4 20539.2 20774.7 20814.7 20834.7 7379.3 7454.6 7551.4 7720.7 7792.1 974.8 1042.8 1106.7 1156.7 1196.7 35139.6 35278.3 35439.3 35472.6 35831.8 1120.0 1180.6 1206.4 1225.9 1291.7 17500.0 18515.4 18546.2 18548.2 18567.9 618000.0 624862.0 625954.0 627343.0 629467.0 64872.4 65462.0 65462.7 65477.0 65520.7 1328.7 1376.2 1387.2 1435.7 1468.4 33548.8 35742.8 36072.5 36311.0 36412.3 54467.5 55419.2 55495.4 55542.2 55546.2 362676.0 364111.0 364501.0 365178.0 365642.0 26114.1 28592.6 28611.0 29994.4 29999.6 47068.5 52394.5 52546.7 52876.5 53705.7 43683.2 44844.3 45178.5 46367.0 46437.5 43842.1 45029.3 45046.5 45722.3 46111.1 43737.0 44736.0 45853.8 46017.8 48056.0 39122.6 40643.0 41028.2 41403.4 41441.8 30182.5 30468.0 30809.4 30813.2 31140.5 16:36:01 INFO - PROCESS | 5544 | 16:36:01 INFO - PROCESS | 5544 | [#2] jquery.html Cycles:180 Average:31237.93 Median:13498.14 stddev:90722.35 (672.1%) stddev-sans-first:90947.24 16:36:01 INFO - PROCESS | 5544 | Values: 373.2 374.0 399.1 405.9 416.5 13699.4 19029.5 19972.8 20661.6 20699.8 20132.5 20135.4 20628.3 20832.9 20861.1 5963.8 6150.7 6155.4 6163.0 6165.7 6060.6 6100.1 6101.8 6104.1 6105.0 5502.6 5733.4 5766.3 5845.0 5845.9 5819.7 5820.6 5857.3 5866.9 5883.4 6038.2 6086.2 6169.6 6173.3 6180.1 15284.6 15409.9 19974.9 24486.1 25281.6 2510.1 2513.9 2552.8 2795.7 2818.9 10730.7 10774.1 10775.8 10903.2 11145.2 2034.9 2049.5 2052.5 2087.5 2096.3 84838.0 89090.0 89690.6 89837.6 89852.6 10748.9 10762.5 11001.2 11389.8 11735.4 15691.0 15738.1 15782.6 15872.7 15892.1 7565.9 7739.8 8308.6 8446.7 8537.1 7587.8 7644.1 7654.8 7750.7 7842.0 5021.7 5051.9 5140.2 5190.3 5232.6 2058.0 2092.8 2104.7 2128.6 2220.1 1003.6 1004.5 1019.3 1044.2 1050.6 19970.3 20688.7 21059.8 21108.2 21112.9 993.3 1019.2 1044.3 1058.8 1061.9 10618.3 11641.2 11790.4 11854.5 11881.7 527667.0 550314.0 553340.0 562078.0 563610.0 75568.0 85134.0 85632.0 98092.5 99867.0 996.8 1001.2 1033.0 1040.2 1041.6 17779.5 18523.8 18921.2 19084.5 19242.7 42747.4 43495.1 43535.1 44044.8 44593.6 61247.0 62410.4 62670.0 63445.4 63701.9 9224.2 9334.8 9365.9 9368.7 9376.7 11064.6 13398.7 13644.7 14046.9 14101.4 11853.7 12131.4 12198.0 12417.6 12760.3 11393.7 12173.3 12173.9 12246.4 12349.9 11717.4 12121.6 12372.3 12576.4 12621.0 8887.0 9091.3 9163.6 9166.8 9276.2 8873.5 11930.6 11936.9 12277.3 12507.3 16:36:01 INFO - PROCESS | 5544 | 16:36:01 INFO - PROCESS | 5544 | [#3] mootools.html Cycles:180 Average:6350.33 Median:4612.45 stddev:3754.48 (81.4%) stddev-sans-first:3747.74 16:36:01 INFO - PROCESS | 5544 | Values: 1558.4 1572.3 1719.2 1818.8 1839.5 9079.5 9841.7 9846.4 9849.2 9861.9 7953.6 9137.3 9352.1 9393.5 9656.4 2465.8 2500.8 2501.6 2505.6 2515.6 2450.0 2524.9 2538.2 2538.4 2541.1 2414.0 2420.7 2518.0 2518.1 2520.1 2432.8 2447.4 2450.8 2453.0 2457.2 2422.1 2428.1 2437.0 2438.9 2471.1 9890.2 9975.5 9976.7 9981.9 9982.4 5529.7 5646.4 5653.7 5663.6 5664.8 3451.5 3516.4 3519.2 3540.6 3565.0 1001.9 1016.2 1045.7 1053.3 1064.0 13639.0 13640.5 13694.1 13731.5 13747.8 4572.3 4601.0 4731.3 4740.1 4841.3 11350.7 11352.0 11353.4 11363.3 11369.6 5569.8 5600.7 5619.6 5619.9 5620.8 4480.5 4614.3 5052.5 5104.4 5156.0 4186.6 4215.1 4363.9 4393.8 4407.0 1316.6 1328.5 1342.8 1346.7 1350.5 948.3 1088.6 1093.7 1099.7 1109.3 6405.2 6529.8 6652.4 6684.6 6827.2 466.9 474.3 478.1 482.5 484.7 4447.6 4547.4 4581.7 4610.6 4685.2 12142.1 12469.6 12501.9 12548.6 12662.1 11407.6 12453.6 12480.4 12519.9 12563.9 8767.7 9195.0 9229.9 9236.1 9237.0 6647.9 6702.8 6736.7 6811.8 6893.8 11328.7 11485.3 11500.4 11658.6 11670.1 11313.5 11686.6 11728.7 11745.6 11770.5 7744.3 7989.4 7991.8 8627.7 8861.4 8659.2 9232.9 9257.4 9610.0 9643.0 8455.5 8616.5 8623.7 8644.4 8849.0 8189.5 8370.6 8514.9 8537.3 8577.7 7006.2 7120.0 7190.9 7344.7 7348.7 5391.4 5514.4 5563.6 5641.7 5691.6 5713.9 5716.3 5797.6 5808.5 5872.0 16:36:01 INFO - PROCESS | 5544 | 16:36:01 INFO - PROCESS | 5544 | [#4] prototype.html Cycles:180 Average:4886.42 Median:5611.62 stddev:1425.55 (25.4%) stddev-sans-first:1388.28 16:36:01 INFO - PROCESS | 5544 | Values: 349.9 355.9 356.8 364.9 373.0 5546.4 5572.6 5591.7 5630.0 5663.2 5253.5 5341.0 5526.6 5533.8 5547.9 4359.5 4359.5 4363.1 4372.7 4375.3 4312.0 4343.7 4357.5 4361.7 4364.8 4110.9 4259.3 4260.1 4328.0 4336.6 4252.3 4294.4 4294.5 4298.9 4311.2 3717.2 3749.9 3778.8 3782.5 3789.3 5144.5 5373.3 5390.4 5391.9 5421.2 4958.5 4963.1 4968.4 4971.3 4993.6 5724.6 5739.2 5743.3 5743.5 5758.7 3991.5 4148.2 4217.5 4243.4 4266.0 6002.8 6016.6 6035.3 6036.4 6043.9 4222.3 4252.9 4253.7 4273.1 4283.3 4130.4 4312.5 4361.9 4409.2 4417.5 4969.5 4970.6 4972.5 4977.6 5003.1 5848.8 5850.4 5931.7 5949.5 5976.7 1431.9 1446.1 1496.5 1525.9 1532.6 969.9 993.1 1001.1 1018.9 1040.6 5590.9 5625.9 5635.9 5667.7 5668.2 5832.9 5991.9 6025.5 6032.0 6054.2 5577.8 5619.6 5626.4 5653.3 5657.5 3141.4 3166.6 3172.4 3186.3 3195.0 5805.5 6030.2 6062.5 6063.4 6082.3 5951.4 5980.7 5996.8 6006.5 6011.5 5953.1 6017.0 6089.6 6103.9 6123.7 5835.9 5845.0 5866.8 5890.2 5923.2 5839.3 5845.8 5846.1 5902.6 5911.3 5888.9 5981.6 6021.3 6052.5 6060.2 4292.5 4296.8 4304.8 4331.8 4347.6 5722.3 5954.0 5997.3 6017.9 6025.5 5736.5 5744.3 5769.1 5770.6 5772.3 5691.3 5722.8 5765.5 5816.4 5874.5 5210.3 5515.0 5711.0 5792.8 5826.6 5847.0 5887.3 5904.4 5918.9 5924.9 5603.6 5733.7 5779.3 5788.9 5795.4 16:36:01 INFO - PROCESS | 5544 | 16:36:01 INFO - PROCESS | 5544 | [#5] yui.html Cycles:180 Average:22499.08 Median:4257.50 stddev:33179.12 (779.3%) stddev-sans-first:33232.31 16:36:01 INFO - PROCESS | 5544 | Values: 832.3 867.8 869.3 900.0 925.5 13912.2 16777.5 17461.5 17504.5 17510.9 14066.4 14130.7 14154.9 14158.5 14172.2 701.4 729.0 849.7 861.4 888.1 884.2 895.7 896.6 896.6 896.7 928.5 930.0 930.0 930.4 930.5 926.6 927.5 929.1 929.6 929.7 681.7 682.9 683.2 683.3 683.7 15588.7 15692.6 15700.8 15703.8 15738.8 13551.4 13584.5 13882.0 14151.4 15378.2 13088.5 13246.2 13579.5 13611.7 13885.7 4681.6 4709.9 4711.4 4837.8 4936.1 83400.0 87694.0 87710.0 88878.0 89655.0 11508.1 11532.4 11692.4 11737.6 11886.8 20969.2 21413.6 21432.1 21507.4 21546.2 8410.4 8586.6 8608.5 8640.5 8920.1 5022.2 5111.8 5145.6 5249.9 5252.9 3687.4 3823.9 3826.6 3827.5 3833.4 610.7 620.9 630.1 631.3 645.8 709.2 714.7 717.6 731.0 758.8 9542.7 10204.9 10343.2 10459.6 10548.9 728.8 734.2 738.7 760.1 780.2 4732.4 4756.2 4889.7 4897.8 4909.8 74280.3 92293.0 119481.0 137239.0 141156.3 114823.0 115700.0 116397.0 117451.4 120147.4 98025.0 100280.5 100948.0 101819.5 102461.5 9260.0 9448.2 9549.8 9638.6 9693.3 47166.5 48280.5 52977.0 59617.4 60732.1 78600.6 79314.6 79755.2 80761.6 82032.0 13997.4 14222.6 14270.4 14320.7 14438.9 13868.2 14185.7 14349.6 15733.3 16015.7 14897.3 14927.9 14988.3 15327.5 15977.1 14036.7 14210.5 14226.0 14368.6 14414.0 12557.4 13173.7 14285.1 14518.0 14598.4 12117.0 12119.6 12132.7 12395.7 12638.2 11468.0 11896.0 12406.7 12659.7 12743.7 16:36:01 INFO - PROCESS | 5544 | -------- Summary: end -------- 16:36:01 INFO - PROCESS | 5544 | 16:36:02 INFO - TEST-INFO | 5544: exit 0 16:36:02 INFO - TEST-OK | dromaeo_css | took 1112549ms 16:36:02 INFO - TEST-START | kraken 16:36:02 INFO - operating with platform_type : linux_ 16:36:02 INFO - Initialising browser for kraken test... 16:36:02 INFO - TEST-INFO | started process 5738 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZYxZX4/profile http://localhost:57301/getInfo.html) 16:36:06 INFO - PROCESS | 5738 | __metrics Screen width/height:1600/1200 16:36:06 INFO - PROCESS | 5738 | colorDepth:24 16:36:06 INFO - PROCESS | 5738 | Browser inner width/height: 1024/697 16:36:06 INFO - PROCESS | 5738 | __metrics 16:36:07 INFO - PROCESS | 5738 | 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" 16:36:07 INFO - PROCESS | 5738 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:36:07 INFO - PROCESS | 5738 | console.error: PushService: 16:36:07 INFO - PROCESS | 5738 | stateChangeProcessEnqueue: Error transitioning state 16:36:07 INFO - PROCESS | 5738 | UnknownError 16:36:08 INFO - TEST-INFO | 5738: exit 0 16:36:08 INFO - Browser initialized. 16:36:08 INFO - Running cycle 1/1 for kraken test... 16:36:08 INFO - TEST-INFO | started process 5828 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZYxZX4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:36:19 INFO - PROCESS | 5828 | RSS: Main: 143781888 16:36:19 INFO - PROCESS | 5828 | 16:36:22 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57301/tests/kraken/driver.html?testName=audio-beat-detection) 16:36:23 INFO - PROCESS | 5828 | RSS: Main: 199102464 16:36:23 INFO - PROCESS | 5828 | 16:36:25 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57301/tests/kraken/driver.html?testName=audio-dft) 16:36:26 INFO - PROCESS | 5828 | RSS: Main: 183246848 16:36:26 INFO - PROCESS | 5828 | 16:36:29 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57301/tests/kraken/driver.html?testName=audio-fft) 16:36:29 INFO - PROCESS | 5828 | RSS: Main: 274616320 16:36:29 INFO - PROCESS | 5828 | 16:36:31 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57301/tests/kraken/driver.html?testName=audio-oscillator) 16:36:32 INFO - PROCESS | 5828 | RSS: Main: 180887552 16:36:32 INFO - PROCESS | 5828 | 16:36:34 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57301/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:36:35 INFO - PROCESS | 5828 | RSS: Main: 179740672 16:36:35 INFO - PROCESS | 5828 | 16:36:38 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57301/tests/kraken/driver.html?testName=imaging-darkroom) 16:36:38 INFO - PROCESS | 5828 | RSS: Main: 216023040 16:36:38 INFO - PROCESS | 5828 | 16:36:41 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57301/tests/kraken/driver.html?testName=imaging-desaturate) 16:36:42 INFO - PROCESS | 5828 | RSS: Main: 214659072 16:36:42 INFO - PROCESS | 5828 | 16:36:45 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57301/tests/kraken/driver.html?testName=json-parse-financial) 16:36:45 INFO - PROCESS | 5828 | RSS: Main: 213737472 16:36:45 INFO - PROCESS | 5828 | 16:36:47 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57301/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:36:48 INFO - PROCESS | 5828 | RSS: Main: 175734784 16:36:48 INFO - PROCESS | 5828 | 16:36:49 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57301/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:36:50 INFO - PROCESS | 5828 | RSS: Main: 183857152 16:36:50 INFO - PROCESS | 5828 | 16:36:52 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57301/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:36:52 INFO - PROCESS | 5828 | RSS: Main: 189870080 16:36:52 INFO - PROCESS | 5828 | 16:36:55 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57301/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:36:56 INFO - PROCESS | 5828 | RSS: Main: 197300224 16:36:56 INFO - PROCESS | 5828 | 16:36:58 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57301/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:36:59 INFO - PROCESS | 5828 | RSS: Main: 194105344 16:36:59 INFO - PROCESS | 5828 | 16:37:00 INFO - PROCESS | 5828 | Cycle 1(1): loaded http://localhost:57301/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57301/tests/kraken/driver.html?testName=ai-astar) 16:37:01 INFO - PROCESS | 5828 | RSS: Main: 243535872 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | __start_tp_report 16:37:01 INFO - PROCESS | 5828 | _x_x_mozilla_page_load 16:37:01 INFO - PROCESS | 5828 | _x_x_mozilla_page_load_details 16:37:01 INFO - PROCESS | 5828 | |i|pagename|runs| 16:37:01 INFO - PROCESS | 5828 | |0;ai-astar;113;112;120;111;112;110;111;111;110;110 16:37:01 INFO - PROCESS | 5828 | |1;audio-beat-detection;147;116;145;184;118;142;138;118;171;117 16:37:01 INFO - PROCESS | 5828 | |2;audio-dft;160;161;161;160;160;161;161;160;161;160 16:37:01 INFO - PROCESS | 5828 | |3;audio-fft;75;86;74;178;75;75;74;75;173;75 16:37:01 INFO - PROCESS | 5828 | |4;audio-oscillator;83;84;146;84;84;84;135;85;85;143 16:37:01 INFO - PROCESS | 5828 | |5;imaging-gaussian-blur;117;116;116;116;116;117;117;116;116;117 16:37:01 INFO - PROCESS | 5828 | |6;imaging-darkroom;146;146;146;146;146;146;145;147;146;145 16:37:01 INFO - PROCESS | 5828 | |7;imaging-desaturate;94;95;95;94;95;94;94;94;95;95 16:37:01 INFO - PROCESS | 5828 | |8;json-parse-financial;52;51;52;51;51;51;52;51;51;52 16:37:01 INFO - PROCESS | 5828 | |9;json-stringify-tinderbox;59;61;58;59;59;59;60;60;60;61 16:37:01 INFO - PROCESS | 5828 | |10;stanford-crypto-aes;73;74;73;73;84;80;73;76;74;82 16:37:01 INFO - PROCESS | 5828 | |11;stanford-crypto-ccm;122;120;121;120;124;121;120;118;155;119 16:37:01 INFO - PROCESS | 5828 | |12;stanford-crypto-pbkdf2;155;156;156;160;151;152;151;158;154;151 16:37:01 INFO - PROCESS | 5828 | |13;stanford-crypto-sha256-iterative;65;62;62;65;62;62;63;61;60;60 16:37:01 INFO - PROCESS | 5828 | __end_tp_report 16:37:01 INFO - PROCESS | 5828 | __start_cc_report 16:37:01 INFO - PROCESS | 5828 | _x_x_mozilla_cycle_collect,1692 16:37:01 INFO - PROCESS | 5828 | __end_cc_report 16:37:01 INFO - PROCESS | 5828 | __startTimestamp1476315421186__endTimestamp 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | ------- Summary: start ------- 16:37:01 INFO - PROCESS | 5828 | Number of tests: 14 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#0] ai-astar Cycles:10 Average:112.00 Median:111.50 stddev:2.98 (2.7%) stddev-sans-first:3.14 16:37:01 INFO - PROCESS | 5828 | Values: 113.0 112.0 120.0 111.0 112.0 110.0 111.0 111.0 110.0 110.0 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:143.50 stddev:23.69 (16.5%) stddev-sans-first:24.97 16:37:01 INFO - PROCESS | 5828 | Values: 147.0 116.0 145.0 184.0 118.0 142.0 138.0 118.0 171.0 117.0 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.53 (0.3%) stddev-sans-first:0.53 16:37:01 INFO - PROCESS | 5828 | Values: 160.0 161.0 161.0 160.0 160.0 161.0 161.0 160.0 161.0 160.0 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#3] audio-fft Cycles:10 Average:96.00 Median:75.00 stddev:42.07 (56.1%) stddev-sans-first:43.93 16:37:01 INFO - PROCESS | 5828 | Values: 75.0 86.0 74.0 178.0 75.0 75.0 74.0 75.0 173.0 75.0 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#4] audio-oscillator Cycles:10 Average:101.30 Median:84.00 stddev:27.76 (33.0%) stddev-sans-first:28.64 16:37:01 INFO - PROCESS | 5828 | Values: 83.0 84.0 146.0 84.0 84.0 84.0 135.0 85.0 85.0 143.0 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 16:37:01 INFO - PROCESS | 5828 | Values: 117.0 116.0 116.0 116.0 116.0 117.0 117.0 116.0 116.0 117.0 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 16:37:01 INFO - PROCESS | 5828 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 145.0 147.0 146.0 145.0 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 16:37:01 INFO - PROCESS | 5828 | Values: 94.0 95.0 95.0 94.0 95.0 94.0 94.0 94.0 95.0 95.0 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:51.50 stddev:0.52 (1.0%) stddev-sans-first:0.50 16:37:01 INFO - PROCESS | 5828 | Values: 52.0 51.0 52.0 51.0 51.0 51.0 52.0 51.0 51.0 52.0 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:0.97 (1.6%) stddev-sans-first:1.00 16:37:01 INFO - PROCESS | 5828 | Values: 59.0 61.0 58.0 59.0 59.0 59.0 60.0 60.0 60.0 61.0 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:75.00 stddev:4.21 (5.6%) stddev-sans-first:4.30 16:37:01 INFO - PROCESS | 5828 | Values: 73.0 74.0 73.0 73.0 84.0 80.0 73.0 76.0 74.0 82.0 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#11] stanford-crypto-ccm Cycles:10 Average:124.00 Median:121.00 stddev:11.02 (9.1%) stddev-sans-first:11.66 16:37:01 INFO - PROCESS | 5828 | Values: 122.0 120.0 121.0 120.0 124.0 121.0 120.0 118.0 155.0 119.0 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.40 Median:155.50 stddev:3.17 (2.0%) stddev-sans-first:3.35 16:37:01 INFO - PROCESS | 5828 | Values: 155.0 156.0 156.0 160.0 151.0 152.0 151.0 158.0 154.0 151.0 16:37:01 INFO - PROCESS | 5828 | 16:37:01 INFO - PROCESS | 5828 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:1.75 (2.8%) stddev-sans-first:1.54 16:37:01 INFO - PROCESS | 5828 | Values: 65.0 62.0 62.0 65.0 62.0 62.0 63.0 61.0 60.0 60.0 16:37:01 INFO - PROCESS | 5828 | -------- Summary: end -------- 16:37:01 INFO - PROCESS | 5828 | 16:37:02 INFO - TEST-INFO | 5828: exit 0 16:37:02 INFO - TEST-OK | kraken | took 59720ms 16:37:02 INFO - SUITE-END | took 1172s 16:37:02 INFO - Completed test suite (00:19:32) 16:37:02 INFO - javascript benchmark 16:37:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3228.6287076986146, 3228.8708451661932, 3268.101272150157, 3283.09714828422, 3304.1409233599256, 5623.831285061449, 5715.913976344492, 5729.598928075036, 5730.0, 5739.3399759027725, 5666.206731057655, 5734.713264336788, 5744.310682718069, 5750.223719797826, 5755.366909639936, 5177.0, 5181.792728290873, 5184.274201611777, 5246.029484545371, 5260.710660913648, 5067.037262920052, 5105.668131571436, 5273.8945221095555, 5278.498542638463, 5313.309269794921, 5056.898862022776, 5064.2403639454005, 5085.313482679846, 5108.923366149511, 5115.718635475047, 5089.872753181163, 5140.742962851842, 5210.791568337262, 5220.0, 5257.185136303879, 5000.224965130411, 5054.797808087672, 5135.075686376451, 5175.741212939338, 5186.962607478482, 5498.0, 5538.0, 5541.5289700375515, 5545.390007099216, 5551.306086739158, 5909.438603332719, 5951.345352011275, 6007.7596896124105, 6008.279006519202, 6016.578839481238, 5864.06175011996, 5985.072313791369, 6028.7588496460085, 6032.849178770566, 6036.667983260919, 5592.692401917893, 5659.660420374791, 5679.2049113123785, 5680.630759000693, 5684.6020778545935, 6204.720787564571, 6209.0, 6215.751369945242, 6217.036359364306, 6221.5333849961435, 5791.507721843606, 5850.61970971894, 5915.319738230072, 5960.225170727777, 5980.730867110903, 6024.036154215304, 6063.83243364268, 6081.604695694766, 6097.3292937776805, 6104.236970378703, 6028.216331876828, 6034.607750496291, 6039.607425517415, 6040.728167232485, 6041.879162416815, 5790.71046447676, 5899.085641725539, 5907.025340818803, 6037.698115094227, 6038.245219347582, 4617.238155704271, 4619.838305659286, 4621.353010578522, 4623.69945953512, 4624.930626040612, 4088.8568900088353, 4096.672266218696, 4118.279301122292, 4158.293090174678, 4175.31107367287, 5760.0, 5763.567732420085, 5766.3368712598585, 5775.711214439261, 5777.826665200051, 6100.115483254992, 6110.633361998294, 6117.143599896018, 6122.571420000646, 6125.142480052797, 5498.80754173602, 5685.0, 5714.828555143353, 5731.197632331477, 5816.447437493432, 5128.1794912813775, 5133.281340612317, 5138.743062846843, 5140.56305214061, 5141.974290128525, 6230.345813689588, 6262.1546091278005, 6310.652914089769, 6316.494680425555, 6327.557071005078, 5865.648061116347, 5992.730327135377, 6034.39656034393, 6063.363346848694, 6108.053251745987, 6263.09185168139, 6297.464715499051, 6298.433141017331, 6304.05439184126, 6321.557490975588, 5982.252218472691, 5983.640981541122, 5987.191729116687, 5997.640141591519, 6000.838022267359, 5895.67573783446, 5957.6127551709, 5999.640021598718, 6026.789062382971, 6110.103144028831, 5889.970550147222, 5901.232839730807, 5906.291245050449, 5922.348541660328, 5933.258342707161, 5879.588428809942, 5879.970600146972, 5882.558808089325, 5922.289325280994, 5941.643501389931, 5662.801801936913, 5826.883462330815, 5866.6186697864505, 5881.264841894763, 5901.881962360643, 6025.276966764016, 6037.366076561898, 6057.848553786014, 6060.060690592965, 6074.3925607438905, 5852.795152169653, 5864.67744274069, 5866.560007999332, 5868.67722275279, 5874.118882167709], "value": 5575.391551414585, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.5860676829602, 619.8233503451515, 661.3948237362813, 669.0165456778537, 673.014034439546, 35478.645213547854, 44759.0, 46475.302870456886, 46507.53492465075, 46539.53460465394, 48393.51606483943, 48544.0, 48572.0, 48719.269210961866, 48839.51160488394, 8456.704015359464, 9040.457572545636, 9218.078192180761, 9219.35464517484, 9230.446173229595, 10765.407902565395, 11043.72390690231, 11052.889471105267, 11358.0, 11364.829527557093, 11213.327200368005, 11272.661820145368, 11312.264702794333, 11314.717132071723, 11320.0, 11059.889401106007, 11103.111751059896, 11112.944435277852, 11120.165987550967, 11125.610603628875, 10977.231593788438, 11029.889701103048, 11042.0, 11048.0, 11057.225994180411, 45269.54730452686, 45313.320300195526, 45326.32010519845, 45333.54666453359, 45413.63759087233, 3875.7093218008763, 3906.160175562239, 3921.745086569364, 3990.20195960809, 4053.2096241232675, 34589.481157782655, 34598.30803383918, 34605.307893841986, 34938.825305873565, 35276.47085293697, 4406.1187762447635, 4429.579189976947, 4439.511653718088, 4487.326900964848, 4497.977510112429, 383599.0, 386791.0, 389947.0, 390747.0462647697, 391250.0, 63828.68085659635, 64120.35879641237, 64651.0, 64671.67664161649, 64737.0, 40331.59668403337, 40757.18485630271, 40881.0, 68203.31796682069, 68328.6583567089, 17697.911510442365, 18028.36900708469, 18035.458936231673, 18040.368587099652, 18045.548861278312, 12551.184173028723, 12893.097483176087, 13580.796288055688, 13812.033157679067, 13829.0, 19557.36133849776, 20539.178432862816, 20774.688379674317, 20814.687779683318, 20834.68747968782, 7379.3358597726465, 7454.589997550187, 7551.358134558624, 7720.683794861519, 7792.142864284924, 974.7563109222694, 1042.827933390982, 1106.7288514314046, 1156.6992581928591, 1196.7008247938015, 35139.64860351415, 35278.29443411117, 35439.29121417557, 35472.64527354745, 35831.82084089615, 1119.9664010079712, 1180.557291015869, 1206.3847437806783, 1225.871283515236, 1291.6835375333103, 17500.0, 18515.444536663923, 18546.165422556016, 18548.165332560067, 18567.90716046411, 618000.0, 624862.0, 625954.0, 627343.0, 629467.0, 64872.35127648663, 65462.0, 65462.67268663721, 65477.0, 65520.67239663771, 1328.7076843094503, 1376.2430663135435, 1387.1954266525593, 1435.727211829749, 1468.4493315006873, 33548.83225583856, 35742.821285893406, 36072.45891311685, 36311.0, 36412.27175456529, 54467.45532544782, 55419.16871247015, 55495.445045550645, 55542.16686749621, 55546.166807498725, 362676.0, 364111.0, 364501.0, 365178.0, 365642.0, 26114.12580605913, 28592.57111143376, 28611.0, 29994.4001119972, 29999.55000674948, 47068.52931470778, 52394.476055238956, 52546.71639112212, 52876.47123528716, 53705.73147134239, 43683.160594561254, 44844.32733508935, 45178.54821451743, 46367.0, 46437.53562464332, 43842.12315753731, 45029.32456013228, 45046.54953450424, 45722.31416528688, 46111.07777844492, 43737.0, 44736.0, 45853.770731147466, 46017.76991115023, 48056.0, 39122.608773911896, 40643.0, 41028.1794364117, 41403.378949315185, 41441.79279103585, 30182.54726179153, 30468.0, 30809.383812324075, 30813.22966925845, 31140.53289200709], "value": 21812.166063974877, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.2479054704769, 373.9925201495968, 399.1279055264247, 405.9269331520325, 416.53348249960044, 13699.38352774125, 19029.524261893443, 19972.800271997276, 20661.586768264646, 20699.793002069975, 20132.496687582818, 20135.395938121845, 20628.278010269645, 20832.895835520838, 20861.061252243646, 5963.791267305645, 6150.661713605772, 6155.415235552616, 6163.0, 6165.691715414234, 6060.606060606046, 6100.1154832549255, 6101.847453813646, 6104.114903339013, 6104.969475152618, 5502.614816962814, 5733.426657334275, 5766.250387449647, 5845.035569131088, 5845.853853653672, 5819.650820950736, 5820.621659592134, 5857.297124345085, 5866.853328666774, 5883.382244864313, 6038.154658347835, 6086.2087928569445, 6169.568130230886, 6173.320934697224, 6180.103884936704, 15284.617884552976, 15409.922950385288, 19974.90138042407, 24486.14298499544, 25281.620775688378, 2510.1465501729317, 2513.924582262535, 2552.770250677448, 2795.65054368204, 2818.943621127566, 10730.678079657622, 10774.138068954464, 10775.83836242449, 10903.236773425784, 11145.16411269158, 2034.8575599708026, 2049.5183631846503, 2052.4971382011504, 2087.5198704297945, 2096.2872623307994, 84838.0, 89089.99999999935, 89690.55154724185, 89837.55081224618, 89852.5507372459, 10748.94625526883, 10762.461876906124, 11001.22991390595, 11389.829152562717, 11735.4132293385, 15691.0, 15738.134402607966, 15782.605434864221, 15872.682546349008, 15892.125933073792, 7565.940768292444, 7739.806504837424, 8308.626111824982, 8446.66213351459, 8537.103604121601, 7587.848243035109, 7644.120926093459, 7654.846903061907, 7750.651220695082, 7841.960790196013, 5021.6735912165595, 5051.949480505222, 5140.203268493389, 5190.273361729361, 5232.607554433433, 2057.968425961741, 2092.7593326767615, 2104.663253879372, 2128.553003869174, 2220.067571619891, 1003.6336737091056, 1004.467632154959, 1019.3119644240256, 1044.1646682653848, 1050.579768092769, 19970.30103946355, 20688.689669654967, 21059.78940210609, 21108.155673773184, 21112.894435528033, 993.2649839119, 1019.2101121630795, 1044.3159730376615, 1058.8147074261974, 1061.8991195836384, 10618.309809862334, 11641.185117041896, 11790.351530665725, 11854.525818967144, 11881.702957425961, 527667.0, 550314.0, 553340.0, 562078.0, 563610.0, 75568.0, 85134.0, 85632.0, 98092.50953745328, 99867.0, 996.8436613528272, 1001.154024849016, 1033.0186322993186, 1040.245821779219, 1041.6093964763213, 17779.46661600154, 18523.81476185221, 18921.188372186276, 19084.522886927938, 19242.71135932934, 42747.35878961756, 43495.13009739724, 43535.12929741324, 44044.77977610091, 44593.55406446024, 61247.0, 62410.37589624045, 62670.0, 63445.36554634394, 63701.85937914021, 9224.215941645036, 9334.766630834283, 9365.859512107187, 9368.67209647659, 9376.718698439057, 11064.612738554111, 13398.732025359634, 13644.65888352799, 14046.929765351108, 14101.435942562182, 11853.703657408634, 12131.393430328271, 12198.0, 12417.62747117588, 12760.29818359999, 11393.715157121138, 12173.2696038238, 12173.878261217274, 12246.387680616112, 12349.93825030869, 11717.414129293671, 12121.636350909486, 12372.32684328123, 12576.371181440709, 12621.0, 8886.976221921937, 9091.272698184262, 9163.587638556148, 9166.770830729285, 9276.16514513697, 8873.542771195796, 11930.582429614922, 11936.940315298369, 12277.263364198177, 12507.312097834343], "value": 9597.578918199708, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1558.4233833481615, 1572.347475797544, 1719.1662043908702, 1818.7817461904574, 1839.4665546991373, 9079.500627465486, 9841.655542056029, 9846.359986600866, 9849.162821160202, 9861.901380986188, 7953.602319883998, 9137.314701397388, 9352.111549402796, 9393.530323483832, 9656.42061476312, 2465.8027357811375, 2500.7874330681916, 2501.6372625969225, 2505.624156376549, 2515.55977703902, 2450.0444826517673, 2524.8737563121863, 2538.2385284414695, 2538.441542860577, 2541.136013755323, 2413.987930060356, 2420.721617014048, 2517.962230566543, 2518.0934863449143, 2520.09276660403, 2432.805375569959, 2447.4248551590363, 2450.840695354814, 2452.9754702452924, 2457.1891275879098, 2422.0553983946097, 2428.0651948999584, 2437.0130097310616, 2438.902443902242, 2471.1351027140536, 9890.15933645641, 9975.451350175737, 9976.650817221362, 9981.850272245922, 9982.401055936667, 5529.668219906818, 5646.435356464362, 5653.745581448845, 5663.631863928873, 5664.773409063632, 3451.516787649731, 3516.384632689269, 3519.225770330523, 3540.6459354064646, 3564.9643503565153, 1001.9148372388356, 1016.1667432705111, 1045.6915210013065, 1053.3206082077095, 1063.9680809575725, 13639.045266831326, 13640.522581709592, 13694.109882857681, 13731.45074197031, 13747.793783093262, 4572.314152877028, 4600.976995115003, 4731.29030645406, 4740.051989602093, 4841.346418233494, 11350.716232094099, 11352.0, 11353.432328383547, 11363.318200907972, 11369.602063927889, 5569.832905012856, 5600.747966341443, 5619.550435965195, 5619.943800561942, 5620.803271885465, 4480.484744254387, 4614.330922016289, 5052.469490703496, 5104.3619547556555, 5156.0, 4186.623203911662, 4215.093754842724, 4363.890902727394, 4393.758343291086, 4407.0, 1316.58527563819, 1328.5150919914163, 1342.7713642017445, 1346.7104572516955, 1350.4800651748951, 948.2745699539857, 1088.6026600290836, 1093.699232710995, 1099.681092483171, 1109.3343993603773, 6405.231372235362, 6529.836754081091, 6652.368025037614, 6684.565503242371, 6827.180738311435, 466.8576084294255, 474.34066647360197, 478.0963978081429, 482.48133256749134, 484.6776893365872, 4447.6212374164115, 4547.386102876136, 4581.65637577183, 4610.561996610317, 4685.1800934836265, 12142.089343299287, 12469.563565275172, 12501.874981250254, 12548.560800371943, 12662.113652044269, 11407.600733974272, 12453.626391208278, 12480.375981200903, 12519.874801251872, 12563.93718031404, 8767.693130740394, 9194.954025229965, 9229.861552076723, 9236.122568355995, 9237.0, 6647.867042659216, 6702.83242918931, 6736.730530778714, 6811.795646130624, 6893.758718445031, 11328.716782080513, 11485.310881347146, 11500.367479788358, 11658.591949281734, 11670.066394688234, 11313.490892909675, 11686.590969316034, 11728.70678233051, 11745.588904388305, 11770.470328835394, 7744.303012728881, 7989.360851131779, 7991.800204994922, 8627.654893804178, 8861.379703420698, 8659.177378149066, 9232.907670923205, 9257.398269112486, 9610.0, 9643.0, 8455.450395724258, 8616.483011019109, 8623.655053797778, 8644.394892357726, 8849.026607073089, 8189.467684600482, 8370.623321950405, 8514.914850851412, 8537.317014638938, 8577.699780507652, 7006.229314775473, 7119.967604697394, 7190.892136618059, 7344.742933997284, 7348.669309880961, 5391.379991301051, 5514.448555144453, 5563.610547261653, 5641.746121424628, 5691.601587888808, 5713.885722285448, 5716.342620598684, 5797.565182611405, 5808.5353171747, 5872.0], "value": 4923.7191835265685, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.9247661752723, 355.89678993092, 356.8144564826291, 364.9397849354853, 372.9571099323577, 5546.362168350641, 5572.58205634577, 5591.66450012999, 5630.0, 5663.207150998864, 5253.474652534745, 5341.011912796135, 5526.613137080406, 5533.833984980457, 5547.861303467415, 4359.4768627764715, 4359.520452750195, 4363.0619416825375, 4372.737635741865, 4375.343698445226, 4312.008238105238, 4343.652507799368, 4357.498887627931, 4361.716488428257, 4364.847230346939, 4110.91778164438, 4259.318509038554, 4260.147970405916, 4327.956720432802, 4336.588024137718, 4252.277112890816, 4294.377315289296, 4294.506131794852, 4298.871033868973, 4311.202427550913, 3717.237966216919, 3749.8687545936036, 3778.848846046155, 3782.451544526056, 3789.2610940866543, 5144.459831717691, 5373.274607927919, 5390.407055223922, 5391.865203369947, 5421.21392398104, 4958.528939750718, 4963.081829861487, 4968.378952630921, 4971.328870602459, 4993.626752643016, 5724.5992780505385, 5739.196512488255, 5743.253377060997, 5743.4543718346695, 5758.74085666146, 3991.5409727881083, 4148.191102734968, 4217.451731274945, 4243.405923170759, 4265.97867010663, 6002.759889604411, 6016.608920420159, 6035.275766907956, 6036.396360363972, 6043.879122417571, 4222.261104306734, 4252.893677658021, 4253.702240843173, 4273.102648443862, 4283.336082907092, 4130.442390277334, 4312.482502099768, 4361.931326824948, 4409.184300904343, 4417.469903611587, 4969.478204788517, 4970.552650261494, 4972.453030166679, 4977.576905963034, 5003.149464591028, 5848.824535263949, 5850.444207800338, 5931.73307201177, 5949.524038076944, 5976.73104710289, 1431.8711315981611, 1446.1178681004578, 1496.4537943650707, 1525.8855585831107, 1532.6474910770362, 969.9369540979814, 993.101243374747, 1001.1190152665607, 1018.9032041956002, 1040.6201736366172, 5590.944090559124, 5625.8593535161945, 5635.943640563624, 5667.744951477193, 5668.206451096849, 5832.88334233313, 5991.910121348184, 6025.548083893726, 6031.969840150771, 6054.243219597551, 5577.776888924398, 5619.595101224694, 5626.409227031185, 5653.265075540154, 5657.547396208293, 3141.403133404646, 3166.6358368787423, 3172.4289627867206, 3186.3149422874194, 3194.9680503194672, 5805.535557155417, 6030.246219222598, 6062.545309101747, 6063.393660633901, 6082.300535438393, 5951.3751056139345, 5980.73086711099, 5996.760129594854, 6006.489448396936, 6011.549133814894, 5953.136795164677, 6017.044460550426, 6089.63462192261, 6103.938960610426, 6123.663198524124, 5835.862006908782, 5845.0, 5866.823995280149, 5890.2342695449315, 5923.200367950271, 5839.328477225004, 5845.795397161079, 5846.129238643593, 5902.609443175521, 5911.256179427165, 5888.882222355443, 5981.611195272293, 6021.253836387398, 6052.546059045501, 6060.151578778887, 4292.5445016761805, 4296.839853239545, 4304.806283717177, 4331.761753103484, 4347.565243475754, 5722.2561067061015, 5954.035168394506, 5997.250343707036, 6017.916774980546, 6025.457708806229, 5736.4550367715, 5744.3394009687745, 5769.144273584435, 5770.596058276048, 5772.278465191851, 5691.26013618227, 5722.798212375281, 5765.4522820332, 5816.389279125631, 5874.472637114466, 5210.296609957759, 5514.972425137848, 5711.029694755302, 5792.797252096157, 5826.621269617462, 5846.965593740306, 5887.264091988502, 5904.380040095728, 5918.881622367442, 5924.851878703067, 5603.635763675348, 5733.741981610754, 5779.2775903012125, 5788.8552786179, 5795.39148389413], "value": 4436.345913616972, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [832.3091833777964, 867.7526904832121, 869.2871845087029, 899.9775005624855, 925.4868290345283, 13912.16527008379, 16777.496675099752, 17461.47615571535, 17504.474865754015, 17510.91244543777, 14066.437342506313, 14130.717385652306, 14154.858451415483, 14158.504452344145, 14172.220527870963, 701.3932947999969, 729.0194688144437, 849.6856163219586, 861.3711990247124, 888.103015953888, 884.1600479544427, 895.6820328783285, 896.5696465696469, 896.6099746610213, 896.6592694776009, 928.4846909964966, 930.0234753508822, 930.0374112793261, 930.4045410937006, 930.50683137937, 926.5645146781002, 927.4991504587515, 929.1173385283973, 929.5909799688184, 929.6560272699077, 681.7034589953386, 682.8839097353462, 683.1733602341174, 683.2620769568904, 683.6581709145428, 15588.688226235412, 15692.607684807857, 15700.842991570053, 15703.764443533357, 15738.763918541232, 13551.39018744149, 13584.456621735118, 13882.0, 14151.363188656534, 15378.231088445533, 13088.541901033419, 13246.205227686274, 13579.52471663487, 13611.727765444735, 13885.652858678613, 4681.6020638245445, 4709.929351059737, 4711.411073615798, 4837.806487740486, 4936.062148191832, 83400.0, 87694.0, 87710.0, 88878.0, 89655.0, 11508.146856437934, 11532.423378831025, 11692.415379231004, 11737.647870563897, 11886.821697674543, 20969.161233550487, 21413.57172856534, 21432.142714291567, 21507.354779356643, 21546.245881394076, 8410.411271210956, 8586.570671466401, 8608.48349099056, 8640.524771137521, 8920.107989201028, 5022.171341728574, 5111.821086261963, 5145.639805213599, 5249.895002100013, 5252.868678283074, 3687.391580389259, 3823.9235215296094, 3826.617338266151, 3827.5406951165482, 3833.4058220975794, 610.703808652803, 620.9254889413209, 630.0706457974451, 631.2897989761518, 645.8385403649088, 709.2163159708551, 714.7033980897974, 717.5945590741214, 731.0094821516874, 758.7609902880575, 9542.713718588453, 10204.897951020543, 10343.172546196287, 10459.633912813015, 10548.947255263827, 728.8214387475102, 734.203389322591, 738.7340557399332, 760.1296515489734, 780.1709841738243, 4732.384789977281, 4756.239001759702, 4889.65599808441, 4897.804087836518, 4909.77905994224, 74280.25719742842, 92293.0, 119481.0, 137239.0, 141156.29421852826, 114823.0, 115700.0, 116397.0, 117451.41274293573, 120147.39926300487, 98025.0, 100280.49859750655, 100947.99052009532, 101819.45451363776, 102461.48769256106, 9260.0, 9448.18936354151, 9549.761255968657, 9638.590128915217, 9693.27300452483, 47166.52833471759, 48280.517194827604, 52977.0, 59617.403825961184, 60732.08901866387, 78600.60699696693, 79314.60342698249, 79755.20244797709, 80761.59619201867, 82032.0, 13997.370118344495, 14222.573322800332, 14270.357833897291, 14320.713585728436, 14438.927805360907, 13868.237246951514, 14185.716285674021, 14349.56951291463, 15733.292001859714, 16015.679686405974, 14897.329620167335, 14927.925360373129, 14988.325525351604, 15327.46353877609, 15977.121258330902, 14036.719265614835, 14210.549894720125, 14226.0, 14368.640783980483, 14414.0, 12557.372131393211, 13173.736525269249, 14285.07259324323, 14518.0, 14598.416063357347, 12117.0, 12119.575814846437, 12132.696682583006, 12395.690107747017, 12638.219591689813, 11468.0, 11896.0, 12406.689832754253, 12659.746805064031, 12743.681407964876], "value": 8050.8995630312365, "unit": "score"}], "name": "dromaeo_css", "value": 7680.5171053556805, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 120.0, 111.0, 112.0, 110.0, 111.0, 111.0, 110.0, 110.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 145.0, 184.0, 118.0, 142.0, 138.0, 118.0, 171.0, 117.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 160.0, 160.0, 161.0, 161.0, 160.0, 161.0, 160.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 86.0, 74.0, 178.0, 75.0, 75.0, 74.0, 75.0, 173.0, 75.0], "value": 96.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 146.0, 84.0, 84.0, 84.0, 135.0, 85.0, 85.0, 143.0], "value": 101.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 147.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 51.0, 51.0, 51.0, 52.0, 51.0, 51.0, 52.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 58.0, 59.0, 59.0, 59.0, 60.0, 60.0, 60.0, 61.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 73.0, 84.0, 80.0, 73.0, 76.0, 74.0, 82.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 120.0, 121.0, 120.0, 124.0, 121.0, 120.0, 118.0, 155.0, 119.0], "value": 124.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 156.0, 156.0, 160.0, 151.0, 152.0, 151.0, 158.0, 154.0, 151.0], "value": 154.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 62.0, 65.0, 62.0, 62.0, 63.0, 61.0, 60.0, 60.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1494.0, "alertThreshold": 2.0}]} 16:37:02 INFO - Return code: 0 16:37:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:37:02 INFO - # TBPL SUCCESS # 16:37:02 INFO - Running post-action listener: _resource_record_post_action 16:37:02 INFO - [mozharness: 2016-10-12 23:37:02.476373Z] Finished run-tests step (success) 16:37:02 INFO - Running post-run listener: _resource_record_post_run 16:37:02 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290570240; Read time: 4; Write time: 268488 16:37:02 INFO - TinderboxPrint: CPU usage
12.9% 16:37:02 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 16:37:02 INFO - TinderboxPrint: I/O write bytes / time
290,570,240 / 268,488 16:37:02 INFO - TinderboxPrint: CPU idle
8,252.0 (87.1%) 16:37:02 INFO - TinderboxPrint: CPU user
1,179.2 (12.4%) 16:37:02 INFO - TinderboxPrint: Swap in / out
0 / 0 16:37:02 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71290880; Read time: 0; Write time: 101928 16:37:02 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 219279360; Read time: 0; Write time: 166560 16:37:03 INFO - Running post-run listener: _upload_blobber_files 16:37:03 INFO - Blob upload gear active. 16:37:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:37:03 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 16:37:03 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'] 16:37:03 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 16:37:03 INFO - (blobuploader) - INFO - Open directory for files ... 16:37:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:37:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:37:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:04 INFO - (blobuploader) - INFO - Done attempting. 16:37:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:37:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:37:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:05 INFO - (blobuploader) - INFO - Done attempting. 16:37:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:37:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:37:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:08 INFO - (blobuploader) - INFO - Done attempting. 16:37:08 INFO - (blobuploader) - INFO - Iteration through files over. 16:37:08 INFO - Return code: 0 16:37:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:37:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:37:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/903b8bc02b9c72e38f33ea20bbce0ffefcafbeb2dd8060a58a1ed56f57d83941a19a4a9211e17e7cfa8a1bf442a3209b556cfb444158ab6b758df8614cdf0ae6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7622e456aa67fe3ee784a11f8600eaa18480e1a6478214b167b542fc2b2c60fcab38eae2c60dcf71d7760be51ccfb8567c71937f941ed2cab0793f9de063854e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/df9c097432d484e38ed9e0fc3f169ee537be90ee3371f5ba7be07a48d39639ec5b85f6f16f5c1fe784e6475b11a287e3517035c40b5f92e4e435862f72d3fb6b"} 16:37:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:37:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:37:08 INFO - Contents: 16:37:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/903b8bc02b9c72e38f33ea20bbce0ffefcafbeb2dd8060a58a1ed56f57d83941a19a4a9211e17e7cfa8a1bf442a3209b556cfb444158ab6b758df8614cdf0ae6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7622e456aa67fe3ee784a11f8600eaa18480e1a6478214b167b542fc2b2c60fcab38eae2c60dcf71d7760be51ccfb8567c71937f941ed2cab0793f9de063854e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/df9c097432d484e38ed9e0fc3f169ee537be90ee3371f5ba7be07a48d39639ec5b85f6f16f5c1fe784e6475b11a287e3517035c40b5f92e4e435862f72d3fb6b"} 16:37:08 INFO - Running post-run listener: copy_logs_to_upload_dir 16:37:08 INFO - Copying logs to upload dir... 16:37:08 INFO - mkdir: /builds/slave/test/build/upload/logs 16:37:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.403164 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-12 16:37:08.744821) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 16:37:08.748516) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398 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/903b8bc02b9c72e38f33ea20bbce0ffefcafbeb2dd8060a58a1ed56f57d83941a19a4a9211e17e7cfa8a1bf442a3209b556cfb444158ab6b758df8614cdf0ae6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7622e456aa67fe3ee784a11f8600eaa18480e1a6478214b167b542fc2b2c60fcab38eae2c60dcf71d7760be51ccfb8567c71937f941ed2cab0793f9de063854e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/df9c097432d484e38ed9e0fc3f169ee537be90ee3371f5ba7be07a48d39639ec5b85f6f16f5c1fe784e6475b11a287e3517035c40b5f92e4e435862f72d3fb6b"} build_url:https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011207 build_url: 'https://queue.taskcluster.net/v1/task/ChXUPgeGQlSJAse02Tnf4Q/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/903b8bc02b9c72e38f33ea20bbce0ffefcafbeb2dd8060a58a1ed56f57d83941a19a4a9211e17e7cfa8a1bf442a3209b556cfb444158ab6b758df8614cdf0ae6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7622e456aa67fe3ee784a11f8600eaa18480e1a6478214b167b542fc2b2c60fcab38eae2c60dcf71d7760be51ccfb8567c71937f941ed2cab0793f9de063854e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/df9c097432d484e38ed9e0fc3f169ee537be90ee3371f5ba7be07a48d39639ec5b85f6f16f5c1fe784e6475b11a287e3517035c40b5f92e4e435862f72d3fb6b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 16:37:08.772648) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:37:08.772928) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1476313752.837364-472897398 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004518 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:37:08.855827) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 16:37:08.856138) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 16:37:08.856427) ========= ========= Total master_lag: 0.15 =========