builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-010 starttime: 1473454652.57 results: success (0) buildid: 20160909110920 builduid: 2e201c485d0c40e498d20353f655b5a6 revision: 28878b37a89e309b3311bc9cc6aa864a74a96d3e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:32.573763) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:32.574247) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:32.574618) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:32.594655) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:32.595139) ========= 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=eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-09 13:57:33-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.6M=0s 2016-09-09 13:57:33 (40.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.076331 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:32.688319) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:32.688694) ========= 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=eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023252 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:32.756543) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:32.756908) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 28878b37a89e309b3311bc9cc6aa864a74a96d3e --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-aurora --rev 28878b37a89e309b3311bc9cc6aa864a74a96d3e --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=eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-09 13:57:33,766 truncating revision to first 12 chars 2016-09-09 13:57:33,766 Setting DEBUG logging. 2016-09-09 13:57:33,766 attempt 1/10 2016-09-09 13:57:33,766 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/28878b37a89e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-09 13:57:34,066 unpacking tar archive at: mozilla-aurora-28878b37a89e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.638595 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:33.410599) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:33.410994) ========= script_repo_revision: 28878b37a89e309b3311bc9cc6aa864a74a96d3e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:33.411445) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:33.412119) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 13:57:33.421942) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 8 secs) (at 2016-09-09 13:57:33.422264) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:57:34 INFO - MultiFileLogger online at 20160909 13:57:34 in /builds/slave/test 13:57:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:57:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:57:34 INFO - {'append_to_log': False, 13:57:34 INFO - 'base_work_dir': '/builds/slave/test', 13:57:34 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:57:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:57:34 INFO - 'branch': 'Mozilla-Aurora', 13:57:34 INFO - 'buildbot_json_path': 'buildprops.json', 13:57:34 INFO - 'config_files': ('talos/linux_config.py',), 13:57:34 INFO - 'default_actions': ('clobber', 13:57:34 INFO - 'read-buildbot-config', 13:57:34 INFO - 'download-and-extract', 13:57:34 INFO - 'populate-webroot', 13:57:34 INFO - 'create-virtualenv', 13:57:34 INFO - 'install', 13:57:34 INFO - 'run-tests'), 13:57:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:57:34 INFO - 'download_minidump_stackwalk': True, 13:57:34 INFO - 'download_symbols': 'ondemand', 13:57:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:57:34 INFO - 'tooltool.py': '/tools/tooltool.py', 13:57:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:57:34 INFO - '/tools/misc-python/virtualenv.py')}, 13:57:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:57:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:57:34 INFO - 'installer_path': 'installer.exe', 13:57:34 INFO - 'log_level': 'info', 13:57:34 INFO - 'log_name': 'talos', 13:57:34 INFO - 'log_to_console': True, 13:57:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:57:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:57:34 INFO - 'opt_config_files': (), 13:57:34 INFO - 'pip_index': False, 13:57:34 INFO - 'sps_profile': False, 13:57:34 INFO - 'sps_profile_interval': 0, 13:57:34 INFO - 'suite': 'dromaeojs', 13:57:34 INFO - 'system_bits': '32', 13:57:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:57:34 INFO - 'title': 'talos-linux64-ix-010', 13:57:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:57:34 INFO - 'use_talos_json': True, 13:57:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:57:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:57:34 INFO - 'work_dir': 'build'} 13:57:34 INFO - [mozharness: 2016-09-09 20:57:34.525781Z] Running clobber step. 13:57:34 INFO - Running pre-action listener: _resource_record_pre_action 13:57:34 INFO - Running main action method: clobber 13:57:34 INFO - rmtree: /builds/slave/test/build 13:57:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:57:34 INFO - Running post-action listener: _resource_record_post_action 13:57:34 INFO - [mozharness: 2016-09-09 20:57:34.987225Z] Finished clobber step (success) 13:57:34 INFO - [mozharness: 2016-09-09 20:57:34.987327Z] Running read-buildbot-config step. 13:57:34 INFO - Running pre-action listener: _resource_record_pre_action 13:57:34 INFO - Running main action method: read_buildbot_config 13:57:34 INFO - Using buildbot properties: 13:57:34 INFO - { 13:57:34 INFO - "project": "", 13:57:34 INFO - "product": "firefox", 13:57:34 INFO - "script_repo_revision": "production", 13:57:34 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 13:57:34 INFO - "repository": "", 13:57:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 13:57:34 INFO - "buildid": "20160909110920", 13:57:34 INFO - "pgo_build": "True", 13:57:34 INFO - "basedir": "/builds/slave/test", 13:57:34 INFO - "buildnumber": 458, 13:57:34 INFO - "slavename": "talos-linux64-ix-010", 13:57:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:57:34 INFO - "platform": "ubuntu64_hw", 13:57:34 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 13:57:34 INFO - "branch": "mozilla-aurora", 13:57:34 INFO - "repo_path": "releases/mozilla-aurora", 13:57:34 INFO - "revision": "28878b37a89e309b3311bc9cc6aa864a74a96d3e", 13:57:34 INFO - "stage_platform": "linux64-pgo", 13:57:34 INFO - "builduid": "2e201c485d0c40e498d20353f655b5a6", 13:57:34 INFO - "slavebuilddir": "test" 13:57:34 INFO - } 13:57:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 13:57:34 INFO - Running post-action listener: _resource_record_post_action 13:57:34 INFO - [mozharness: 2016-09-09 20:57:34.990483Z] Finished read-buildbot-config step (success) 13:57:34 INFO - [mozharness: 2016-09-09 20:57:34.990573Z] Running download-and-extract step. 13:57:34 INFO - Running pre-action listener: _resource_record_pre_action 13:57:34 INFO - Running main action method: download_and_extract 13:57:34 INFO - mkdir: /builds/slave/test/build/tests 13:57:34 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')]} 13:57:34 INFO - https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:57:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:57:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:57:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:57:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 13:57:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:57:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:57:34 INFO - retry: Failed, sleeping 30 seconds before retrying 13:58:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 13:58:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:58:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:58:05 INFO - retry: Failed, sleeping 60 seconds before retrying 13:59:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 13:59:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:59:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:59:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 13:59:05 INFO - Caught exception: HTTP Error 404: Not Found 13:59:05 INFO - Caught exception: HTTP Error 404: Not Found 13:59:05 INFO - Caught exception: HTTP Error 404: Not Found 13:59:05 INFO - trying https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:59:05 INFO - Downloading https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:59:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 13:59:05 INFO - Downloaded 1361 bytes. 13:59:05 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:59:05 INFO - Using the following test package requirements: 13:59:05 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:59:05 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:59:05 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 13:59:05 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:59:05 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 13:59:05 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:59:05 INFO - u'jsshell-linux-x86_64.zip'], 13:59:05 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:59:05 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 13:59:05 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:59:05 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:59:05 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 13:59:05 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:59:05 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 13:59:05 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:59:05 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 13:59:05 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:59:05 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 13:59:05 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:59:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:59:05 INFO - https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:59:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:59:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:59:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:59:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:59:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:59:05 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:59:05 INFO - retry: Failed, sleeping 30 seconds before retrying 13:59:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 13:59:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:59:35 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:59:35 INFO - retry: Failed, sleeping 60 seconds before retrying 14:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:00:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:00:35 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:00:35 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 14:00:35 INFO - Caught exception: HTTP Error 404: Not Found 14:00:35 INFO - Caught exception: HTTP Error 404: Not Found 14:00:35 INFO - Caught exception: HTTP Error 404: Not Found 14:00:35 INFO - trying https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:00:35 INFO - Downloading https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:00:36 INFO - Downloaded 21770094 bytes. 14:00:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:00:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:00:37 INFO - Return code: 0 14:00:37 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:00:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:00:37 INFO - https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:00:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:00:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:00:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:00:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:00:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:00:37 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:00:37 INFO - retry: Failed, sleeping 30 seconds before retrying 14:01:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 14:01:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:01:07 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:01:07 INFO - retry: Failed, sleeping 60 seconds before retrying 14:02:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:02:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:02:07 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:02:07 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 14:02:07 INFO - Caught exception: HTTP Error 404: Not Found 14:02:07 INFO - Caught exception: HTTP Error 404: Not Found 14:02:07 INFO - Caught exception: HTTP Error 404: Not Found 14:02:07 INFO - trying https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:02:07 INFO - Downloading https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:02:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:02:08 INFO - Downloaded 21770094 bytes. 14:02:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:02:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:02:10 INFO - Return code: 0 14:02: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')]} 14:02:10 INFO - https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:02:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:02:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:02:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:02:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:02:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:02:10 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:02:10 INFO - retry: Failed, sleeping 30 seconds before retrying 14:02:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 14:02:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:02:40 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:02:40 INFO - retry: Failed, sleeping 60 seconds before retrying 14:03:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 14:03:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:03:40 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:03:40 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 14:03:40 INFO - Caught exception: HTTP Error 404: Not Found 14:03:40 INFO - Caught exception: HTTP Error 404: Not Found 14:03:40 INFO - Caught exception: HTTP Error 404: Not Found 14:03:40 INFO - trying https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:03:40 INFO - Downloading https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:03:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:03:41 INFO - Downloaded 14413740 bytes. 14:03:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:03:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:03:41 INFO - Return code: 0 14:03:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:03:41 INFO - https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:03:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:03:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:03:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:03:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:03:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:03:41 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:03:41 INFO - retry: Failed, sleeping 30 seconds before retrying 14:04:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 14:04:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:04:11 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:04:11 INFO - retry: Failed, sleeping 60 seconds before retrying 14:05:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 14:05:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:05:11 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:05:11 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 14:05:11 INFO - Caught exception: HTTP Error 404: Not Found 14:05:11 INFO - Caught exception: HTTP Error 404: Not Found 14:05:11 INFO - Caught exception: HTTP Error 404: Not Found 14:05:11 INFO - trying https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:05:11 INFO - Downloading https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:05:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:05:12 INFO - Downloaded 63895868 bytes. 14:05:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:05:12 INFO - mkdir: /builds/slave/test/properties 14:05:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:05:12 INFO - Writing to file /builds/slave/test/properties/build_url 14:05:12 INFO - Contents: 14:05:12 INFO - build_url:https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:05:13 INFO - Running post-action listener: _resource_record_post_action 14:05:13 INFO - Running post-action listener: set_extra_try_arguments 14:05:13 INFO - [mozharness: 2016-09-09 21:05:13.277662Z] Finished download-and-extract step (success) 14:05:13 INFO - [mozharness: 2016-09-09 21:05:13.277896Z] Running populate-webroot step. 14:05:13 INFO - Running pre-action listener: _resource_record_pre_action 14:05:13 INFO - Running main action method: populate_webroot 14:05:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:05:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:05:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:05:13 INFO - '--tppagecycles', 14:05:13 INFO - '7'], 14:05:13 INFO - 'tests': ['tsvgm']}}, 14:05:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:05:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:05:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:05:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:05:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:05:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:05:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:13 INFO - 'talos_options': ['--disable-e10s'], 14:05:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:05:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:05:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:13 INFO - 'talos_options': ['--disable-e10s'], 14:05:13 INFO - 'tests': ['damp', 'tps']}, 14:05:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:13 INFO - 'tests': ['damp', 'tps']}, 14:05:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:05:13 INFO - 'tests': ['dromaeo_dom']}, 14:05:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:05:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:05:13 INFO - 'tests': ['basic_compositor_video']}, 14:05:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:05:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:05:13 INFO - 'tests': ['a11yr', 14:05:13 INFO - 'ts_paint', 14:05:13 INFO - 'tpaint', 14:05:13 INFO - 'sessionrestore', 14:05:13 INFO - 'sessionrestore_no_auto_restore', 14:05:13 INFO - 'tabpaint']}, 14:05:13 INFO - 'other-e10s': {'tests': ['a11yr', 14:05:13 INFO - 'ts_paint', 14:05:13 INFO - 'tpaint', 14:05:13 INFO - 'sessionrestore', 14:05:13 INFO - 'sessionrestore_no_auto_restore', 14:05:13 INFO - 'tabpaint']}, 14:05:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:05:13 INFO - 'ts_paint', 14:05:13 INFO - 'tpaint', 14:05:13 INFO - 'sessionrestore', 14:05:13 INFO - 'sessionrestore_no_auto_restore', 14:05:13 INFO - 'tabpaint']}, 14:05:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:05:13 INFO - 'ts_paint', 14:05:13 INFO - 'tpaint', 14:05:13 INFO - 'sessionrestore', 14:05:13 INFO - 'sessionrestore_no_auto_restore', 14:05:13 INFO - 'tabpaint']}, 14:05:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:05:13 INFO - 'tests': ['a11yr', 14:05:13 INFO - 'ts_paint', 14:05:13 INFO - 'tpaint', 14:05:13 INFO - 'sessionrestore', 14:05:13 INFO - 'sessionrestore_no_auto_restore', 14:05:13 INFO - 'tabpaint']}, 14:05:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:05:13 INFO - 'tests': ['a11yr', 14:05:13 INFO - 'ts_paint', 14:05:13 INFO - 'tpaint', 14:05:13 INFO - 'sessionrestore', 14:05:13 INFO - 'sessionrestore_no_auto_restore', 14:05:13 INFO - 'tabpaint']}, 14:05:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:05:13 INFO - 'tests': ['tsvgx', 14:05:13 INFO - 'tsvgr_opacity', 14:05:13 INFO - 'tart', 14:05:13 INFO - 'tscrollx', 14:05:13 INFO - 'cart']}, 14:05:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:05:13 INFO - 'tsvgr_opacity', 14:05:13 INFO - 'tart', 14:05:13 INFO - 'tscrollx', 14:05:13 INFO - 'cart']}, 14:05:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:13 INFO - 'talos_options': ['--disable-e10s'], 14:05:13 INFO - 'tests': ['tp5o']}, 14:05:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:13 INFO - 'tests': ['tp5o']}, 14:05:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:13 INFO - 'talos_options': ['--disable-e10s', 14:05:13 INFO - '--xperf_path', 14:05:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:05:13 INFO - 'tests': ['tp5n']}, 14:05:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:13 INFO - 'talos_options': ['--xperf_path', 14:05:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:05:13 INFO - 'tests': ['tp5n']}}, 14:05:13 INFO - 'talos.zip': {'path': '', 14:05:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:05:13 INFO - Running post-action listener: _resource_record_post_action 14:05:13 INFO - [mozharness: 2016-09-09 21:05:13.293376Z] Finished populate-webroot step (success) 14:05:13 INFO - [mozharness: 2016-09-09 21:05:13.293467Z] Running create-virtualenv step. 14:05:13 INFO - Running pre-action listener: _resource_record_pre_action 14:05:13 INFO - Running main action method: create_virtualenv 14:05:13 INFO - Creating virtualenv /builds/slave/test/build/venv 14:05:13 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 14:05:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:05:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:05:13 INFO - Using real prefix '/usr' 14:05:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:05:13 INFO - Installing distribute.............................................................................................................................................................................................done. 14:05:15 INFO - Installing pip.................done. 14:05:15 INFO - Return code: 0 14:05:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:05: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')]} 14:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05: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')]} 14:05:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c19030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eda180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed83b0>, '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 0x1ed92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9740>, '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': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', '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 14:05: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', 'pip>=1.5'] in /builds/slave/test/build 14:05: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 pip>=1.5 14:05:15 INFO - Using env: {'DISPLAY': ':0', 14:05:15 INFO - 'HOME': '/home/cltbld', 14:05:15 INFO - 'LANG': 'en_US.UTF-8', 14:05:15 INFO - 'LANGUAGE': 'en_US:en', 14:05:15 INFO - 'LOGNAME': 'cltbld', 14:05:15 INFO - 'MAIL': '/var/mail/cltbld', 14:05:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:15 INFO - 'MOZ_NO_REMOTE': '1', 14:05:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:15 INFO - 'NO_EM_RESTART': '1', 14:05:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:15 INFO - 'PWD': '/builds/slave/test', 14:05:15 INFO - 'SHELL': '/bin/bash', 14:05:15 INFO - 'SHLVL': '1', 14:05:15 INFO - 'TERM': 'linux', 14:05:15 INFO - 'TMOUT': '86400', 14:05:15 INFO - 'USER': 'cltbld', 14:05:15 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', 14:05:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:15 INFO - '_': '/tools/buildbot/bin/python'} 14:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:16 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 14:05:16 INFO - Cleaning up... 14:05:16 INFO - Return code: 0 14:05:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:16 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 0x1c19030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eda180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed83b0>, '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 0x1ed92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9740>, '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': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', '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 14:05:16 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 14:05:16 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 14:05:16 INFO - Using env: {'DISPLAY': ':0', 14:05:16 INFO - 'HOME': '/home/cltbld', 14:05:16 INFO - 'LANG': 'en_US.UTF-8', 14:05:16 INFO - 'LANGUAGE': 'en_US:en', 14:05:16 INFO - 'LOGNAME': 'cltbld', 14:05:16 INFO - 'MAIL': '/var/mail/cltbld', 14:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:16 INFO - 'MOZ_NO_REMOTE': '1', 14:05:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:16 INFO - 'NO_EM_RESTART': '1', 14:05:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:16 INFO - 'PWD': '/builds/slave/test', 14:05:16 INFO - 'SHELL': '/bin/bash', 14:05:16 INFO - 'SHLVL': '1', 14:05:16 INFO - 'TERM': 'linux', 14:05:16 INFO - 'TMOUT': '86400', 14:05:16 INFO - 'USER': 'cltbld', 14:05:16 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', 14:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:16 INFO - '_': '/tools/buildbot/bin/python'} 14:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:16 INFO - Downloading/unpacking psutil>=3.1.1 14:05:16 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 14:05:16 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 14:05:16 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 14:05:16 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 14:05:16 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 14:05:16 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 14:05:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:05:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:05:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:05:18 INFO - Installing collected packages: psutil 14:05:18 INFO - Running setup.py install for psutil 14:05:18 INFO - building 'psutil._psutil_linux' extension 14:05:18 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 14:05:18 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 14:05:18 INFO - building 'psutil._psutil_posix' extension 14:05:18 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 14:05:18 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 14:05:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:05:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:05:18 INFO - Successfully installed psutil 14:05:18 INFO - Cleaning up... 14:05:18 INFO - Return code: 0 14:05:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:05: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')]} 14:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05: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')]} 14:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:18 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 0x1c19030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eda180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed83b0>, '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 0x1ed92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9740>, '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': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', '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 14:05:18 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 14:05:18 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 14:05:18 INFO - Using env: {'DISPLAY': ':0', 14:05:18 INFO - 'HOME': '/home/cltbld', 14:05:18 INFO - 'LANG': 'en_US.UTF-8', 14:05:18 INFO - 'LANGUAGE': 'en_US:en', 14:05:18 INFO - 'LOGNAME': 'cltbld', 14:05:18 INFO - 'MAIL': '/var/mail/cltbld', 14:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:18 INFO - 'MOZ_NO_REMOTE': '1', 14:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:18 INFO - 'NO_EM_RESTART': '1', 14:05:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:18 INFO - 'PWD': '/builds/slave/test', 14:05:18 INFO - 'SHELL': '/bin/bash', 14:05:18 INFO - 'SHLVL': '1', 14:05:18 INFO - 'TERM': 'linux', 14:05:18 INFO - 'TMOUT': '86400', 14:05:18 INFO - 'USER': 'cltbld', 14:05:18 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', 14:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:18 INFO - '_': '/tools/buildbot/bin/python'} 14:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:05:19 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 14:05:19 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 14:05:19 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 14:05:19 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 14:05:19 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 14:05:19 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 14:05:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:05:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:05:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:05:20 INFO - Installing collected packages: mozsystemmonitor 14:05:20 INFO - Running setup.py install for mozsystemmonitor 14:05:21 INFO - Successfully installed mozsystemmonitor 14:05:21 INFO - Cleaning up... 14:05:21 INFO - Return code: 0 14:05:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:05: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')]} 14:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05: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')]} 14:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:21 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 0x1c19030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eda180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed83b0>, '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 0x1ed92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9740>, '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': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', '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 14:05:21 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 14:05:21 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 14:05:21 INFO - Using env: {'DISPLAY': ':0', 14:05:21 INFO - 'HOME': '/home/cltbld', 14:05:21 INFO - 'LANG': 'en_US.UTF-8', 14:05:21 INFO - 'LANGUAGE': 'en_US:en', 14:05:21 INFO - 'LOGNAME': 'cltbld', 14:05:21 INFO - 'MAIL': '/var/mail/cltbld', 14:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:21 INFO - 'MOZ_NO_REMOTE': '1', 14:05:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:21 INFO - 'NO_EM_RESTART': '1', 14:05:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:21 INFO - 'PWD': '/builds/slave/test', 14:05:21 INFO - 'SHELL': '/bin/bash', 14:05:21 INFO - 'SHLVL': '1', 14:05:21 INFO - 'TERM': 'linux', 14:05:21 INFO - 'TMOUT': '86400', 14:05:21 INFO - 'USER': 'cltbld', 14:05:21 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', 14:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:21 INFO - '_': '/tools/buildbot/bin/python'} 14:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:21 INFO - Downloading/unpacking jsonschema==2.5.1 14:05:21 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 14:05:21 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 14:05:21 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 14:05:21 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 14:05:21 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 14:05:21 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 14:05:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:05:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:05:23 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 14:05:23 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 14:05:23 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 14:05:23 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 14:05:23 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 14:05:23 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 14:05:23 INFO - Downloading functools32-3.2.3-2.tar.gz 14:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:05:23 INFO - warning: no files found matching '*.txt' 14:05:23 INFO - no previously-included directories found matching 'build' 14:05:23 INFO - no previously-included directories found matching 'dist' 14:05:23 INFO - no previously-included directories found matching '.git*' 14:05:23 INFO - Installing collected packages: jsonschema, functools32 14:05:23 INFO - Running setup.py install for functools32 14:05:23 INFO - warning: no files found matching '*.txt' 14:05:23 INFO - no previously-included directories found matching 'build' 14:05:23 INFO - no previously-included directories found matching 'dist' 14:05:23 INFO - no previously-included directories found matching '.git*' 14:05:23 INFO - Successfully installed jsonschema functools32 14:05:23 INFO - Cleaning up... 14:05:23 INFO - Return code: 0 14:05:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:05:23 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')]} 14:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:23 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')]} 14:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:23 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 0x1c19030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eda180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed83b0>, '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 0x1ed92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9740>, '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': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', '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 14:05:23 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 14:05:23 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 14:05:23 INFO - Using env: {'DISPLAY': ':0', 14:05:23 INFO - 'HOME': '/home/cltbld', 14:05:23 INFO - 'LANG': 'en_US.UTF-8', 14:05:23 INFO - 'LANGUAGE': 'en_US:en', 14:05:23 INFO - 'LOGNAME': 'cltbld', 14:05:23 INFO - 'MAIL': '/var/mail/cltbld', 14:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:23 INFO - 'MOZ_NO_REMOTE': '1', 14:05:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:23 INFO - 'NO_EM_RESTART': '1', 14:05:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:23 INFO - 'PWD': '/builds/slave/test', 14:05:23 INFO - 'SHELL': '/bin/bash', 14:05:23 INFO - 'SHLVL': '1', 14:05:23 INFO - 'TERM': 'linux', 14:05:23 INFO - 'TMOUT': '86400', 14:05:23 INFO - 'USER': 'cltbld', 14:05:23 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', 14:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:23 INFO - '_': '/tools/buildbot/bin/python'} 14:05:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:05:23 INFO - Cleaning up... 14:05:23 INFO - Return code: 0 14:05:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:05:23 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')]} 14:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:23 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')]} 14:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:23 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 0x1c19030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eda180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed83b0>, '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 0x1ed92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9740>, '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': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', '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 14:05:23 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 14:05:23 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 14:05:23 INFO - Using env: {'DISPLAY': ':0', 14:05:23 INFO - 'HOME': '/home/cltbld', 14:05:23 INFO - 'LANG': 'en_US.UTF-8', 14:05:23 INFO - 'LANGUAGE': 'en_US:en', 14:05:23 INFO - 'LOGNAME': 'cltbld', 14:05:23 INFO - 'MAIL': '/var/mail/cltbld', 14:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:23 INFO - 'MOZ_NO_REMOTE': '1', 14:05:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:23 INFO - 'NO_EM_RESTART': '1', 14:05:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:23 INFO - 'PWD': '/builds/slave/test', 14:05:23 INFO - 'SHELL': '/bin/bash', 14:05:23 INFO - 'SHLVL': '1', 14:05:23 INFO - 'TERM': 'linux', 14:05:23 INFO - 'TMOUT': '86400', 14:05:23 INFO - 'USER': 'cltbld', 14:05:23 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', 14:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:23 INFO - '_': '/tools/buildbot/bin/python'} 14:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:24 INFO - Downloading/unpacking blobuploader==1.2.4 14:05:24 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 14:05:24 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 14:05:24 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 14:05:24 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 14:05:24 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 14:05:24 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 14:05:25 INFO - Downloading blobuploader-1.2.4.tar.gz 14:05:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:05:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:05:26 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 14:05:26 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 14:05:26 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 14:05:26 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 14:05:26 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 14:05:26 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 14:05:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:05:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:05:26 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 14:05:26 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 14:05:26 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 14:05:26 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 14:05:26 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 14:05:26 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 14:05:26 INFO - Downloading docopt-0.6.1.tar.gz 14:05:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:05:26 INFO - Installing collected packages: blobuploader, requests, docopt 14:05:26 INFO - Running setup.py install for blobuploader 14:05:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:05:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:05:26 INFO - Running setup.py install for requests 14:05:27 INFO - Running setup.py install for docopt 14:05:27 INFO - Successfully installed blobuploader requests docopt 14:05:27 INFO - Cleaning up... 14:05:27 INFO - Return code: 0 14:05:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:05:27 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')]} 14:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:27 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')]} 14:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:27 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 0x1c19030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eda180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed83b0>, '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 0x1ed92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9740>, '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': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', '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 14:05:27 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 14:05:27 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 14:05:27 INFO - Using env: {'DISPLAY': ':0', 14:05:27 INFO - 'HOME': '/home/cltbld', 14:05:27 INFO - 'LANG': 'en_US.UTF-8', 14:05:27 INFO - 'LANGUAGE': 'en_US:en', 14:05:27 INFO - 'LOGNAME': 'cltbld', 14:05:27 INFO - 'MAIL': '/var/mail/cltbld', 14:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:27 INFO - 'MOZ_NO_REMOTE': '1', 14:05:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:27 INFO - 'NO_EM_RESTART': '1', 14:05:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:27 INFO - 'PWD': '/builds/slave/test', 14:05:27 INFO - 'SHELL': '/bin/bash', 14:05:27 INFO - 'SHLVL': '1', 14:05:27 INFO - 'TERM': 'linux', 14:05:27 INFO - 'TMOUT': '86400', 14:05:27 INFO - 'USER': 'cltbld', 14:05:27 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', 14:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:27 INFO - '_': '/tools/buildbot/bin/python'} 14:05:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:05:27 INFO - Running setup.py (path:/tmp/pip-gmMHgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:05:27 INFO - Running setup.py (path:/tmp/pip-f368yz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:05:27 INFO - Running setup.py (path:/tmp/pip-2rtVLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:05:27 INFO - Running setup.py (path:/tmp/pip-aWuxPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:05:27 INFO - Running setup.py (path:/tmp/pip-lhIoU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:05:27 INFO - Running setup.py (path:/tmp/pip-0EoSDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:05:28 INFO - Running setup.py (path:/tmp/pip-tS_Ncp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:05:28 INFO - Running setup.py (path:/tmp/pip-b7kQFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:05:28 INFO - Running setup.py (path:/tmp/pip-Ej9q2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:05:28 INFO - Running setup.py (path:/tmp/pip-5zIOun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:05:28 INFO - Running setup.py (path:/tmp/pip-TFoKXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:05:28 INFO - Running setup.py (path:/tmp/pip-3DgUQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:05:28 INFO - Running setup.py (path:/tmp/pip-AdukS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:05:28 INFO - Running setup.py (path:/tmp/pip-y4D_BQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:05:28 INFO - Running setup.py (path:/tmp/pip-jwc29B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:05:28 INFO - Running setup.py (path:/tmp/pip-PVHeap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:05:28 INFO - Running setup.py (path:/tmp/pip-6DpWON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:05:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:05:28 INFO - Running setup.py install for manifestparser 14:05:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:05:28 INFO - Running setup.py install for mozcrash 14:05:29 INFO - Running setup.py install for mozdebug 14:05:29 INFO - Running setup.py install for mozdevice 14:05:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:05:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:05:29 INFO - Running setup.py install for mozfile 14:05:29 INFO - Running setup.py install for mozhttpd 14:05:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:05:29 INFO - Running setup.py install for mozinfo 14:05:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:05:29 INFO - Running setup.py install for mozInstall 14:05:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:05:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:05:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:05:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:05:29 INFO - Running setup.py install for mozleak 14:05:29 INFO - Running setup.py install for mozlog 14:05:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:05:29 INFO - Running setup.py install for moznetwork 14:05:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:05:29 INFO - Running setup.py install for mozprocess 14:05:30 INFO - Running setup.py install for mozprofile 14:05:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:05:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:05:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:05:30 INFO - Running setup.py install for mozrunner 14:05:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:05:30 INFO - Running setup.py install for mozscreenshot 14:05:30 INFO - Running setup.py install for moztest 14:05:30 INFO - Running setup.py install for mozversion 14:05:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:05:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:05:30 INFO - Cleaning up... 14:05:30 INFO - Return code: 0 14:05:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:30 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 0x1c19030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eda180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed83b0>, '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 0x1ed92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9740>, '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': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', '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 14:05:30 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 14:05:30 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 14:05:30 INFO - Using env: {'DISPLAY': ':0', 14:05:30 INFO - 'HOME': '/home/cltbld', 14:05:30 INFO - 'LANG': 'en_US.UTF-8', 14:05:30 INFO - 'LANGUAGE': 'en_US:en', 14:05:30 INFO - 'LOGNAME': 'cltbld', 14:05:30 INFO - 'MAIL': '/var/mail/cltbld', 14:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:30 INFO - 'MOZ_NO_REMOTE': '1', 14:05:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:30 INFO - 'NO_EM_RESTART': '1', 14:05:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:30 INFO - 'PWD': '/builds/slave/test', 14:05:30 INFO - 'SHELL': '/bin/bash', 14:05:30 INFO - 'SHLVL': '1', 14:05:30 INFO - 'TERM': 'linux', 14:05:30 INFO - 'TMOUT': '86400', 14:05:30 INFO - 'USER': 'cltbld', 14:05:30 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', 14:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:30 INFO - '_': '/tools/buildbot/bin/python'} 14:05:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:05:30 INFO - Running setup.py (path:/tmp/pip-9OqxOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:05:30 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)) 14:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:05:30 INFO - Running setup.py (path:/tmp/pip-4dWmgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:05:31 INFO - Running setup.py (path:/tmp/pip-7ylrmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:05:31 INFO - Running setup.py (path:/tmp/pip-PgNAK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:05:31 INFO - Running setup.py (path:/tmp/pip-ntL5MZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:05:31 INFO - Running setup.py (path:/tmp/pip-6ON0IM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:05:31 INFO - Running setup.py (path:/tmp/pip-qjBHwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:05:31 INFO - Running setup.py (path:/tmp/pip-Edo4ja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:05:31 INFO - Running setup.py (path:/tmp/pip-Hu4ZTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:05:31 INFO - Running setup.py (path:/tmp/pip-heDE5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:05:31 INFO - Running setup.py (path:/tmp/pip-3Q6lee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:05:31 INFO - Running setup.py (path:/tmp/pip-L2nIA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:05:31 INFO - Running setup.py (path:/tmp/pip-kGaPHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:05:31 INFO - Running setup.py (path:/tmp/pip-Oe58Z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:05:31 INFO - Running setup.py (path:/tmp/pip-nsbIUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:05:31 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)) 14:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:05:31 INFO - Running setup.py (path:/tmp/pip-l3VIdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:05:32 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)) 14:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:05:32 INFO - Running setup.py (path:/tmp/pip-DkyAhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:05:32 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)) 14:05:32 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)) 14:05:32 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)) 14:05:32 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)) 14:05:32 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)) 14:05:32 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)) 14:05:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:05:32 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 14:05:32 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 14:05:32 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 14:05:32 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 14:05:32 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 14:05:32 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 14:05:34 INFO - Downloading blessings-1.6.tar.gz 14:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:05:34 INFO - Installing collected packages: blessings 14:05:34 INFO - Running setup.py install for blessings 14:05:34 INFO - Successfully installed blessings 14:05:34 INFO - Cleaning up... 14:05:34 INFO - Return code: 0 14:05:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:05:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:05:34 INFO - Reading from file tmpfile_stdout 14:05:34 INFO - Current package versions: 14:05:34 INFO - argparse == 1.2.1 14:05:34 INFO - blessings == 1.6 14:05:34 INFO - blobuploader == 1.2.4 14:05:34 INFO - docopt == 0.6.1 14:05:34 INFO - functools32 == 3.2.3-2 14:05:34 INFO - jsonschema == 2.5.1 14:05:34 INFO - manifestparser == 1.1 14:05:34 INFO - mozInstall == 1.12 14:05:34 INFO - mozcrash == 0.17 14:05:34 INFO - mozdebug == 0.1 14:05:34 INFO - mozdevice == 0.48 14:05:34 INFO - mozfile == 1.2 14:05:34 INFO - mozhttpd == 0.7 14:05:34 INFO - mozinfo == 0.9 14:05:34 INFO - mozleak == 0.1 14:05:34 INFO - mozlog == 3.3 14:05:34 INFO - moznetwork == 0.27 14:05:34 INFO - mozprocess == 0.23 14:05:34 INFO - mozprofile == 0.28 14:05:34 INFO - mozrunner == 6.12 14:05:34 INFO - mozscreenshot == 0.1 14:05:34 INFO - mozsystemmonitor == 0.3 14:05:34 INFO - moztest == 0.7 14:05:34 INFO - mozversion == 1.4 14:05:34 INFO - psutil == 3.1.1 14:05:34 INFO - requests == 1.2.3 14:05:34 INFO - wsgiref == 0.1.2 14:05:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:05:34 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')]} 14:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:34 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')]} 14:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:34 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 0x1c19030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eda180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed83b0>, '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 0x1ed92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9740>, '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': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', '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 14:05:34 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 14:05:34 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 14:05:34 INFO - Using env: {'DISPLAY': ':0', 14:05:34 INFO - 'HOME': '/home/cltbld', 14:05:34 INFO - 'LANG': 'en_US.UTF-8', 14:05:34 INFO - 'LANGUAGE': 'en_US:en', 14:05:34 INFO - 'LOGNAME': 'cltbld', 14:05:34 INFO - 'MAIL': '/var/mail/cltbld', 14:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:34 INFO - 'MOZ_NO_REMOTE': '1', 14:05:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:34 INFO - 'NO_EM_RESTART': '1', 14:05:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:34 INFO - 'PWD': '/builds/slave/test', 14:05:34 INFO - 'SHELL': '/bin/bash', 14:05:34 INFO - 'SHLVL': '1', 14:05:34 INFO - 'TERM': 'linux', 14:05:34 INFO - 'TMOUT': '86400', 14:05:34 INFO - 'USER': 'cltbld', 14:05:34 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', 14:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:34 INFO - '_': '/tools/buildbot/bin/python'} 14:05:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:34 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)) 14:05:34 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)) 14:05:34 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)) 14:05:34 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)) 14:05:34 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)) 14:05:34 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)) 14:05:34 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)) 14:05:34 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)) 14:05:34 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)) 14:05:34 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)) 14:05:34 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)) 14:05:34 INFO - Cleaning up... 14:05:34 INFO - Return code: 0 14:05:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:05:34 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')]} 14:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:34 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')]} 14:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:34 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 0x1c19030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eda180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ed83b0>, '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 0x1ed92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9740>, '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': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', '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 14:05:34 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 14:05:34 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 14:05:34 INFO - Using env: {'DISPLAY': ':0', 14:05:34 INFO - 'HOME': '/home/cltbld', 14:05:34 INFO - 'LANG': 'en_US.UTF-8', 14:05:34 INFO - 'LANGUAGE': 'en_US:en', 14:05:34 INFO - 'LOGNAME': 'cltbld', 14:05:34 INFO - 'MAIL': '/var/mail/cltbld', 14:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:34 INFO - 'MOZ_NO_REMOTE': '1', 14:05:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:34 INFO - 'NO_EM_RESTART': '1', 14:05:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:34 INFO - 'PWD': '/builds/slave/test', 14:05:34 INFO - 'SHELL': '/bin/bash', 14:05:34 INFO - 'SHLVL': '1', 14:05:34 INFO - 'TERM': 'linux', 14:05:34 INFO - 'TMOUT': '86400', 14:05:34 INFO - 'USER': 'cltbld', 14:05:34 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', 14:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:34 INFO - '_': '/tools/buildbot/bin/python'} 14:05:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:05:34 INFO - Cleaning up... 14:05:34 INFO - Return code: 0 14:05:34 INFO - Running post-action listener: _resource_record_post_action 14:05:34 INFO - Running post-action listener: _start_resource_monitoring 14:05:34 INFO - Starting resource monitoring. 14:05:34 INFO - [mozharness: 2016-09-09 21:05:34.946127Z] Finished create-virtualenv step (success) 14:05:34 INFO - [mozharness: 2016-09-09 21:05:34.946560Z] Running install step. 14:05:34 INFO - Running pre-action listener: _resource_record_pre_action 14:05:34 INFO - Running main action method: install 14:05:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:05:35 INFO - Reading from file tmpfile_stdout 14:05:35 INFO - Detecting whether we're running mozinstall >=1.0... 14:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:05:35 INFO - Reading from file tmpfile_stdout 14:05:35 INFO - Output received: 14:05:35 INFO - Usage: mozinstall [options] installer 14:05:35 INFO - Options: 14:05:35 INFO - -h, --help show this help message and exit 14:05:35 INFO - -d DEST, --destination=DEST 14:05:35 INFO - Directory to install application into. [default: 14:05:35 INFO - "/builds/slave/test"] 14:05:35 INFO - --app=APP Application being installed. [default: firefox] 14:05:35 INFO - mkdir: /builds/slave/test/build/application 14:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:05:52 INFO - Reading from file tmpfile_stdout 14:05:52 INFO - Output received: 14:05:52 INFO - /builds/slave/test/build/application/firefox/firefox 14:05:52 INFO - Running post-action listener: _resource_record_post_action 14:05:52 INFO - [mozharness: 2016-09-09 21:05:52.103888Z] Finished install step (success) 14:05:52 INFO - [mozharness: 2016-09-09 21:05:52.104167Z] Running run-tests step. 14:05:52 INFO - Running pre-action listener: _resource_record_pre_action 14:05:52 INFO - Running main action method: run_tests 14:05:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:05:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:05:52 INFO - Python 2.7.3 14:05:52 INFO - Return code: 0 14:05:52 INFO - grabbing minidump binary from tooltool 14:05:52 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')]} 14:05:52 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 0x1ed83b0>, '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 0x1ed92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ed9740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:05:52 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 14:05:52 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 14:05:52 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 14:05:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:05:52 INFO - Return code: 0 14:05:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:05:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:05:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:05:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:05:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:05:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 14:05:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 14:05:52 INFO - Using env: {'DISPLAY': ':0', 14:05:52 INFO - 'HOME': '/home/cltbld', 14:05:52 INFO - 'LANG': 'en_US.UTF-8', 14:05:52 INFO - 'LANGUAGE': 'en_US:en', 14:05:52 INFO - 'LOGNAME': 'cltbld', 14:05:52 INFO - 'MAIL': '/var/mail/cltbld', 14:05:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:05:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:05:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:52 INFO - 'MOZ_NO_REMOTE': '1', 14:05:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:05:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:52 INFO - 'NO_EM_RESTART': '1', 14:05:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:52 INFO - 'PWD': '/builds/slave/test', 14:05:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:05:52 INFO - 'SHELL': '/bin/bash', 14:05:52 INFO - 'SHLVL': '1', 14:05:52 INFO - 'TERM': 'linux', 14:05:52 INFO - 'TMOUT': '86400', 14:05:52 INFO - 'USER': 'cltbld', 14:05:52 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254', 14:05:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:52 INFO - '_': '/tools/buildbot/bin/python'} 14:05:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 14:05:52 INFO - mozversion application_buildid: 20160909110920 14:05:52 INFO - mozversion application_changeset: 28878b37a89e309b3311bc9cc6aa864a74a96d3e 14:05:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:05:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:05:52 INFO - mozversion application_name: Firefox 14:05:52 INFO - mozversion application_remotingname: firefox-dev 14:05:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:05:52 INFO - mozversion application_vendor: Mozilla 14:05:52 INFO - mozversion application_version: 50.0a2 14:05:52 INFO - mozversion platform_buildid: 20160909110920 14:05:52 INFO - mozversion platform_changeset: 28878b37a89e309b3311bc9cc6aa864a74a96d3e 14:05:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:05:52 INFO - mozversion platform_version: 50.0a2 14:05:52 INFO - using testdate: 1473455152 14:05:52 INFO - actual date: 1473455152 14:05:52 INFO - starting webserver on 'localhost:39111' 14:05:52 INFO - SUITE-START | Running 2 tests 14:05:52 INFO - TEST-START | dromaeo_css 14:05:52 INFO - operating with platform_type : linux_ 14:05:52 INFO - Initialising browser for dromaeo_css test... 14:05:52 INFO - TEST-INFO | started process 3924 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIvVHDs/profile http://localhost:39111/getInfo.html) 14:05:56 INFO - PROCESS | 3924 | __metrics Screen width/height:1600/1200 14:05:56 INFO - PROCESS | 3924 | colorDepth:24 14:05:56 INFO - PROCESS | 3924 | Browser inner width/height: 1024/697 14:05:56 INFO - PROCESS | 3924 | __metrics 14:05:57 INFO - PROCESS | 3924 | 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" 14:05:57 INFO - PROCESS | 3924 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:05:58 INFO - TEST-INFO | 3924: exit 0 14:05:58 INFO - Browser initialized. 14:05:58 INFO - Running cycle 1/1 for dromaeo_css test... 14:05:58 INFO - TEST-INFO | started process 4015 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIvVHDs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:06:10 INFO - PROCESS | 4015 | RSS: Main: 145776640 14:06:10 INFO - PROCESS | 4015 | 14:07:01 INFO - PROCESS | 4015 | 1473455221390 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] 14:09:10 INFO - PROCESS | 4015 | Cycle 1(1): loaded http://localhost:39111/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39111/tests/dromaeo/cssquery-ext.html) 14:09:11 INFO - PROCESS | 4015 | RSS: Main: 268816384 14:09:11 INFO - PROCESS | 4015 | 14:12:13 INFO - PROCESS | 4015 | Cycle 1(1): loaded http://localhost:39111/tests/dromaeo/cssquery-ext.html (next: http://localhost:39111/tests/dromaeo/cssquery-jquery.html) 14:12:14 INFO - PROCESS | 4015 | RSS: Main: 234373120 14:12:14 INFO - PROCESS | 4015 | 14:15:16 INFO - PROCESS | 4015 | Cycle 1(1): loaded http://localhost:39111/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39111/tests/dromaeo/cssquery-mootools.html) 14:15:16 INFO - PROCESS | 4015 | RSS: Main: 174223360 14:15:16 INFO - PROCESS | 4015 | 14:18:18 INFO - PROCESS | 4015 | Cycle 1(1): loaded http://localhost:39111/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39111/tests/dromaeo/cssquery-prototype.html) 14:18:19 INFO - PROCESS | 4015 | RSS: Main: 174620672 14:18:19 INFO - PROCESS | 4015 | 14:21:22 INFO - PROCESS | 4015 | Cycle 1(1): loaded http://localhost:39111/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39111/tests/dromaeo/cssquery-yui.html) 14:21:22 INFO - PROCESS | 4015 | RSS: Main: 175603712 14:21:22 INFO - PROCESS | 4015 | 14:24:25 INFO - PROCESS | 4015 | Cycle 1(1): loaded http://localhost:39111/tests/dromaeo/cssquery-yui.html (next: http://localhost:39111/tests/dromaeo/cssquery-dojo.html) 14:24:25 INFO - PROCESS | 4015 | RSS: Main: 261079040 14:24:25 INFO - PROCESS | 4015 | 14:24:25 INFO - PROCESS | 4015 | __start_tp_report 14:24:25 INFO - PROCESS | 4015 | _x_x_mozilla_page_load 14:24:25 INFO - PROCESS | 4015 | _x_x_mozilla_page_load_details 14:24:25 INFO - PROCESS | 4015 | |i|pagename|runs| 14:24:25 INFO - PROCESS | 4015 | |0;dojo.html;3131.373725254949;3149.5748074010003;3183.4588120019594;3208.5026820842777;3208.5508028875947;5447.046766815806;5488.780448782044;5490.258815059966;5515.558755299577;5548.195511650808;5443.727813609314;5462.984173779674;5512.448755124486;5516.889662206753;5556.583256255777;4895.437024742147;4942.233953737176;4964.056829202458;4986.800527978895;5031.371078615173;4970.502949705036;4982.701037937735;4994.825181118662;5023.346964894558;5062.468440813716;5062.645614806965;5070.5183007614405;5096.286519887219;5100.719460429675;5145.665531740425;5124.154514505102;5125.410577783547;5132.024915266087;5135.152699804527;5173.870653233662;4895.290182923492;4914.852554423337;4949.356583644139;5046.873828154326;5060.2156665716875;5460.208269800896;5481.890362192734;5502;5515.172724091378;5535.114381698909;5870;5903.675297858616;5912.4974377178;5914.260717410324;5915.3788852170765;5665.745041473144;5875.148103524965;5905.763769449217;5922.111683247547;5945.8810823783715;5548.140038294071;5553.972230138864;5564.054110801174;5586.050371436865;5587.161925711135;6073.787417440369;6077.4834139098675;6085.208922840003;6098.32918378978;6101.908471372933;5653.745581448845;5672.659640421588;5705.942940570541;5710.65736055838;5823.4467725566;5800.651960882361;5827.35899051104;5837.036888913367;5839.357670656224;5863.618864773776;5774.162746401748;5825.475707186289;5831.096180092092;5838.182654428383;5853.707314634251;5710.057840456278;5766.855828604234;5799.130130480377;5909.261342332208;6006.099085137264;4632.143053535039;4637.1421287062;4654.232051711498;4662.32396302541;4664.626829853603;4136.627703506639;4139.441175441337;4145.647619952278;4149.190907772955;4161.500619925568;5463.672179669233;5520.282363292747;5761.366249712528;5779.133130030445;5782.132680098019;5869.178315035899;5884.176215329857;5898.203742494708;5911.645301281851;5940.70296485174;5504.972475137599;5541.33503979525;5547;5552.7778888843995;5572.888542229214;5047.823326183566;5105.2086926526445;5108.56577190943;5123.2827404163445;5149.253358263031;6127.5404344674325;6197.690115494207;6198.0702894566175;6206.255249370104;6206.968965155145;5964.373740757158;5992.250968628921;5995.520358371233;5997.760089596367;6039.4262545058145;6035.426634469718;6119.045604113885;6178.505719542336;6189.938100618936;6200.224971878515;5901.232839730807;5934.762609495572;5956.493698035542;5977.372375900642;5989.311229208697;5982.0727787193055;6025.8192254232445;6030.879382412415;6033.456988871023;6034.939650603438;5665.971670141623;5759.481646651821;5918;5920.467157955804;5926.674032928058;5782.161586570008;5848.53211743068;5855.970720146372;5890.204822348928;5903.881922361615;5788.797392091256;5790.247267855152;5793.681347525927;5795.594308398372;5795.855103622274;5802.738876750641;5812.3025236971835;5865.501432378295;5865.882682346414;5887.4406931341455;5795.130730390475;5795.304563452412;5799.594028417971;5812.447817457335;5852.619579727304 14:24:25 INFO - PROCESS | 4015 | |1;ext.html;583.6118980877717;640.8429934666004;647.8833809914215;667.4993754683987;672.0591172358701;46140.769296153514;47469.525304746945;47517;47598.286025709596;51925;49163;49348;49422.50577494224;49643.50356496434;49725.751371243095;8493.66025358985;9233.399829011127;9389.06109389059;9390.436573805559;11048.779024419508;10865.293755905841;11281.266717663366;11282.830757538602;11288.040516556102;11294.548218071268;11358.432078396088;11362.545498180063;11378.943105284461;11413.828792568118;11427.314361138317;8522.531260780655;10933.289336193122;11198.328100313925;11303.491342889589;11342.659720208365;11001.724956876062;11077.667669969915;11080.778384432348;11121.443927803659;11127.33236005834;46278;46560.30159547576;46604.53395466036;46681;46769.29846052312;3633.8728144514816;3670.394384926484;3746.0260332313796;3789.5263092113487;4088.2845502037026;35408.82295588531;35491.29017419663;35755.463668045;35862.46206306908;35985.28029439423;4170.457840480748;4236.491621005499;4246.214450326699;4257.169851878885;4278.63631591312;359059;359297;361419.1929040338;362488;363070.9999999974;62041.68979155075;62306.37693623096;62430.878456077015;62677.686611567566;62947.37052629415;39680;40708.1858362837;40812.1837563253;42387.57612423898;42394;16256.837431625769;16348.591285217964;16349.673006539802;16359.100249486155;16363.59091022734;11580.305181689126;11811.527538898348;11814.763704725858;12073.27560346382;12663.556775512812;19992.700109498077;20741.58516829685;20785.688214676793;21624.70251784898;21687.349379518324;7410.888836667455;7505.8365953277325;7522.210167932398;7594;7605.961970190225;1046.2937517176028;1091.82530795074;1113.17068783755;1166.5742004168587;1226.4480983557257;34900.65099349025;35184.296314073574;35265;35358.646413535535;35494.29011419809;1170.7073231692077;1194.170051813981;1207.4988879614862;1227.6010296653624;1262.3372729317182;18446.631067378577;18459.446216613254;18507.566880251477;18524.629507410045;18621.7206741899;639676;641909;644278;646593;646979;64090;64605.67697161484;64622;64687.67656161783;64838.35161648417;1334.846492653338;1411.2097225553518;1465.5090544667655;1467.1417220925664;1491.328901994107;34067.4889876657;35182.47226291659;36500.81749591235;36892.81553592215;36901.815490922374;56386.718066409405;61370;62569.37430625818;62834.37165628285;62847;534636;539930;540898;541027;541921;31305.8434707825;31737;31747.84126079355;33229.83385083059;49094;52674.47325526696;52813.20780188377;52933.7353313231;52976.47023529715;53114.2032869515;45165.322520161564;46199;46256.30615540837;46920;47646.5235347656;45765;46362.30456543222;47111;47260.29109563291;47406.87650560356;45044;45818.31272530848;45849.312260315455;46059.53940460552;46117.53882461267;36464.27071458609;41127.1774564501;43728.781356093015;44018.01157034697;44048.339274911545;30341.393172136875;30651.386972259985;30720.692793071783;30815.845920770254;31357.216069598442 14:24:25 INFO - PROCESS | 4015 | |2;jquery.html;398.2234642447229;412.77916314771625;427.6343726114172;432.6149726743199;447.6239958434916;15935.84064159358;19718.21127154914;21755.456113597167;21768.12927482903;22056.44858878529;19029.52426189346;19449.708254376197;19555.315563955264;19712.704309435336;19766.407007789752;5506.366767821702;5569.637973531728;5649.435056494338;5655.688937108457;5659.037963546187;5497.890042199154;5518.834434966958;5541.9168712469345;5573.331200255975;5591.552675785948;5573.916391254134;5594.216809646653;5596.832095037135;5603.439656034384;5611.607187496877;5500.532454741352;5584.162375643646;5633.915491267614;5637.689927054011;5643.435656434365;5244.344456942882;5500.4224556421395;5507.366652834935;5513.173024046385;5513.807016754435;14744;14979.850201498064;14997.17515536645;19475;23758;2431.0640403444622;2448.7183973843057;2483.503299340139;2595.6236345729762;2598.1166403422926;10599.841002384894;10775.94612026935;10799.352038877692;10805;12132.878671213351;1974.7432833731666;2001.579668269665;2007.2071531745025;2037.521182522106;2061.3197644777338;88022.5598872008;90830.545847271;90855;90959.54520227357;91657;10565.577376904837;11984.34086125254;12166.57416990401;12365.319907404999;12365.505379784709;15031.248437578297;15852.92073539648;15949.282282297325;16412.671746565004;16491.34034638625;6483.364919975011;8113.350931925432;8311.584420779058;8430.957845210734;8486.448380855349;7756.6509507072315;7758;7904.170062143392;7907.209279072047;7978.521288722695;4984.850454486371;5086.847394578169;5133.25567792668;5161.638685292069;5220.660657057279;2038.775734669185;2099.4491692593265;2111.9788802111784;2129.0844936677377;2156.2345367394664;940.4310392212612;1002.3584905660383;1004.7689031522791;1030.3766221436074;1041.4063983529468;19101.713474297896;20508.589828203352;20593.79406205949;20674.896625517078;20689.79310206909;1029.8764148302103;1044.4673216659407;1049.3546468921559;1056.7463808686014;1092.1699508373533;10614;11574.594889178838;11583.826242606368;11624.418779061014;11624.709382265342;549408;556319;561149;566155;572606;57034.42965570291;57991.4200857986;61005.389946100855;95241.52379238198;95879;1047.8323468245044;1049.2235745548392;1067.7437415020495;1120.2270433400927;1120.5013768872936;15774.526764197093;17231.483055508354;17350;18081.728774068135;18293;41373.37939930843;42375.57624423839;43545.78227108844;46130;47232.7638361806;61432.692836535534;64529;64612.67693661502;65257.34742652513;66131;9095.636174552943;9284.628614855601;9368.531573421165;9379;9546.665866694633;13950.930245348709;14183.432662693376;14240.572782816533;14384.136951782926;14610.707785844012;12091.697707557381;12315.445804938978;12573.497060117494;12635.620931372074;12651.304178269915;11690.590829320932;11778.939895409454;12053.81919271194;12241.755164896475;12326.50693972231;12076;12142.939285303517;12187.939060304641;12702.618921432371;12838.871611283768;7728.227177282227;9048.819023619622;9172.816543669223;9392;9594.66418675343;8427.536485493112;10774.515146818256;12440.129190956546;12605.243685378906;12815.61553153407 14:24:25 INFO - PROCESS | 4015 | |3;mootools.html;1439.100562148657;1560.3758496601354;1584.2158131724793;1773.565476458267;1883.5573640194552;8414.579271036446;8800.735977920665;8809.867851982217;9100.180983711465;9102.499362535058;8901.421407608517;9016.41393309436;9017.684381046665;9027.413218140833;9031.187193152615;2435.756424357568;2484.776370126688;2484.9875750621313;2486.2416962826333;2486.9005239790386;2424.7754883783755;2471.5798314286526;2476.578981573137;2480.045182604701;2482.751724827521;2424.9151279705216;2446.706395232575;2480.5659009673327;2482.019602257107;2482.391814005571;2413.396650837282;2430.966839093387;2445.327534927891;2447.486027934127;2448.4001419652122;2371.3241726107976;2371.3715865295712;2390.7728765767224;2409.506051259488;2411.638254261857;8885.955570222108;8967.820643587156;8993.550322483916;8995.820083598357;9014.909850901538;5503.697296648683;5647.040003199425;5651.265335506399;5652.095664693629;5655.0386434306265;3520.366334059868;3541.38025845476;3552.2717842842158;3589.964100358989;3601.441776524643;1285.9935700321532;1291.6254286257067;1294.851092124399;1313.192386682193;1336.6391074409855;12998.61004169886;13071.281079540622;13115.934420327838;13139.14595551286;13158.14472059313;4679.976600116977;4693.947227235066;4755.286706993978;4779.235322348477;4891.461939186686;10249.538770755335;10250.948745256375;10262.640807571699;10279.331843430153;10315.690529283984;5362.061639213122;5362.061639213122;5362.865928351823;5363.436839131913;5364.30264065669;4529.433820772403;4879.243717223836;4923.852284431473;4962.851114466572;5028.622853285945;3992.441058251847;4089.1003979124544;4089.2434899543678;4132.070284186034;4184.832606695698;1236.9134160608671;1248.2822377133184;1252.5365614722612;1255.6798016505916;1262.6085913366885;1033.7105610429091;1039.6205385034407;1080.0603474976663;1124.7019539821954;1125.0324720740157;6291.307956124822;6549.967250163814;6652.800415987528;6728.495362847806;6730.125083739083;477.63699588312437;481.6989381636477;491.2851800630075;491.8573613652072;500.812195426715;4615.95384046162;4706.88232794176;4721.6694831362165;4759.762011899391;4800.639952003614;11050.889491105147;11279.718007049889;11288.20982531215;11348.09215262777;11375.658730238107;10228.386296822066;10669.573217071385;10685.145188384911;10714;11154.219204655596;7911.485753426009;8095.473794203475;8131.9186808132345;8275.131111233233;8313.750587482386;6282.2147231596055;6385.744570217139;6465.709043092978;6631.867362652816;6701.095352127595;10062.647807326708;10446.52990615409;10628.946855265674;10712.946435267773;10778.02997730208;10658.987396197348;10921.781564368826;10924.945375273073;10962.780744384909;11022.944885275523;8431.19903609156;8660.913390866011;8669.133086691281;8734.039255681742;8873.778155546162;9035.728928132166;9092.545372731469;9093.818123637358;9113.817723645358;9126.863097053681;7624.58064806441;7839.882401764092;7866.6066696666085;7945.1260361359045;7961.9203807963495;7515.135759387522;7811.336036436966;7874.6850125994315;7951.085625153181;8013.078495972804;6888.621125838127;7034.5427547209265;7159.892601610876;7167.139943206848;7170.282971702788;5389.7035663038905;5415.350157981067;5441.7279136043835;5446.183072538993;5559.499645031967;5333.676887400013;5502.752376143163;5513.476219759116;5525.4474552544425;5601.103823388238 14:24:25 INFO - PROCESS | 4015 | |4;prototype.html;321.27712646545274;321.35728542914165;326.70270054250636;337.0797722218342;340.53686985699443;5403.432639572842;5444.346678398588;5457.372402173752;5463.644863083901;5508.779648814043;5358.276632654591;5421.376541697706;5436.3476382834115;5494.532964698005;5494.670319780816;4021.1756589899;4092.611201935826;4193.979030104861;4255.744655320691;4267.850625228118;4190.365757354634;4192.203481338551;4220.282551966173;4224.640905523034;4251.107267473834;4190.224808410454;4191.874243772691;4193.119444916571;4194.475690538683;4195.888089656243;4024.7786371749394;4059.8985025374304;4221.387898754663;4238.791944295892;4249.915001699979;3796.924061518754;3801.4107813288983;3803.904902377435;3804.9809750951345;3808.542974843009;5294.602904782157;5302.693328402157;5354.9196762048605;5365.865853353658;5368.946310536884;4734.502877197913;4809.855704328841;4822.493638168012;4834.057358815033;4849.321095046696;5638.718064096779;5639.6334238274385;5642.238297829782;5645.379008309083;5653.886922261532;3959.366501359769;4080.5919408059253;4097.7541347519245;4103.4870641169855;4123.8969025774295;5806.477417032487;5863.413658634145;5873.70778428745;5896.697849204459;5917.17159597661;4196.349565817303;4198.769067701306;4204.873853784391;4210.663146948237;4234.3860140279485;4042.6159514846045;4275.337322714984;4281.400603915455;4286.721363111388;4304.80628371724;4717.599004084691;4734.076855013308;4751.762411879392;4774.856754297377;4788.209945359046;5670;5672.319321681424;5681.431856814368;5682.954136510853;5700.743466543932;1391.8308620758612;1446.8914831387688;1464.7070585882866;1465.9047161934654;1475.859793319633;921.6313474610075;940.872982147415;943.8537026760863;945.3902233059638;950.7242899559188;5417.5395091416885;5553.583481238923;5561.221428999943;5562.72186390687;5577.247071645357;5808.186853840381;5812.3025236971835;5834.474897259267;5871.7651293947765;5885.823425297248;5365.570754339644;5530.087535556669;5557.472040156179;5624.043912534797;5637.548996080303;3063.7089476499696;3082.2756652186717;3094.1181763197833;3099.395617854497;3099.4730895747775;5898;6041.486473649789;6055.212822333068;6079.240094988127;6095.908561371583;5907.586468947218;5910.290765108214;5928.7332070056955;5942.554308426886;5965.075413310944;6032.849178770478;6064.575479716377;6098.176746139303;6114.205153330127;6142.293636231891;5696.772129114955;5811.53507719373;5818.807144535444;5819.941800582109;5900.291964964232;5697.800576979786;5700.116481945305;5708.315002199763;5750.022496175577;5766.884662306814;5870.53035757147;6021.668808215591;6036;6044.969775151096;6080.90878636812;4125.23683118624;4128.938065928954;4151.958480415157;4175.707700460938;4178.7283826551175;5824.330202026821;5837.532997360286;5849.210356601974;5890.293164820249;5900.675462849413;5527.778888844401;5716.999525083177;5738.302997591126;5742.368339482737;5746.770129194785;5640.492355687844;5651.491365777099;5653.575981801464;5658.20785090096;5685.77256909719;5403.486668766461;5570.610057296112;5624.465675760796;5635.549156067586;5656.971715141398;5266.9736651318035;5538.750756216061;5662.348829884616;5673.574481913791;5713.22871412354;5513.393526711979;5529.391766905717;5536.474034966672;5554.2779438672715;5555.388907220284 14:24:25 INFO - PROCESS | 4015 | |5;yui.html;835.6364981233164;835.8495470815254;855.9101294364092;894.9642014319428;901.0268909577657;14082.788758168623;15216.543503694878;15225.54323370298;15260.847391526082;15265.694686106272;13798.517051903185;13887.305634718274;13907.443702251898;13914.652133696663;13928.930355348235;725.2892165677625;855.2986551028159;871.5816408124105;875.1598465473154;884.2263019857625;862.8576284912982;865.1953683074738;865.4158443050947;865.7446053414227;866.0776273268948;917.206616276919;923.745969858291;926.0507979321216;929.502716046915;931.8648795924587;761.8514389694012;893.2184338703635;914.1041779056495;919.645936314517;926.6061923682441;670.0552221367897;672.3814091036259;673.5891106425076;674.5682763031664;675.5440077947366;13087.083904126715;13088.345582720825;13091.41088651013;13110.803337949843;13123.212607243597;13786.93106534471;13860.4455821767;14019.299035048207;14240.430382784676;14473.348699308452;11166.441677916071;11360.659180224606;11467.139964502612;11959.342236177006;12169.2698438094;4450.558182034864;4464.2187617166865;4541.5231400703115;4599.08018396322;4601.493835678073;68642.65678671574;71010;72176;81632.59183704102;85165.99999999937;11100.333979961228;12093.637190884288;12522.186057906207;12728.363581820871;12959.157654752411;23758.881205594207;24088.638670419958;24108.758912410653;24113;24138.155164569154;9027.593758280893;9053.637854485878;9351.672691455766;9529.761755956157;9572.808543829084;4993.850184494471;5012.674176178537;5023.14606516893;5213.139831927765;5251.868703282448;3793.089658482;3917.314469967744;3922.5292964844402;3931.941020884689;3940.862069827542;594.551113908996;648.6594537867658;659.7394029358377;683.3576438148224;711.370437163104;635.3709827270968;648.8150877000032;750.8047907544078;818.8740481837355;836.8283513888692;10398.294022101993;10539.84190237147;10680.198985076151;10807.621733239299;10936.234463587472;661.5931202310544;686.5057158845723;730.4156674660251;739.8631253218172;760.2739383888373;4641.976790116028;4705.858824235278;4739.644526660445;4756.571908528249;4835.371401717754;130971;134076;135012.3249383747;135651;139209;110584.44707776571;115824.42087789507;116067.41966290114;116703;120437;81631.5918420416;101840;104912;105684.47157764315;109728.4513577427;9717.222622190066;9759.707208783748;9916.008399160026;10127.442990635585;10559.947200263949;55801.44198557962;56006.719966399905;56502.71748641231;56905.14642280286;57303;76656;83038.58480707557;84307.57846210976;84756.15243847482;86984.56507717421;13313.66715832112;13628.250446225553;13842.238676872868;13887.652808679864;14113.717725645634;15890.205489725236;15971.840281597035;16444.917775411046;16899.155042248083;17220.397286094925;12732.809007864704;13045.217286962812;13090;13270.734585308433;13757.79363309531;13667.17996920188;14180.503682371067;14276.42894284217;15023.248837558296;15230.314635841636;13766.93116534451;14024.439022438988;14055.367508461939;14173;14471.421143154155;12106.878931210575;12133.272003679807;12237.571684990982;12346.3826808661;12560.74878502443;11664.358460284766;11681.941590291994;11701;11804.468798903898;11980.820287695517 14:24:25 INFO - PROCESS | 4015 | __end_tp_report 14:24:25 INFO - PROCESS | 4015 | __start_cc_report 14:24:25 INFO - PROCESS | 4015 | _x_x_mozilla_cycle_collect,1101 14:24:25 INFO - PROCESS | 4015 | __end_cc_report 14:24:25 INFO - PROCESS | 4015 | __startTimestamp1473456265469__endTimestamp 14:24:25 INFO - PROCESS | 4015 | 14:24:25 INFO - PROCESS | 4015 | ------- Summary: start ------- 14:24:25 INFO - PROCESS | 4015 | Number of tests: 6 14:24:25 INFO - PROCESS | 4015 | 14:24:25 INFO - PROCESS | 4015 | [#0] dojo.html Cycles:165 Average:5513.25 Median:5774.16 stddev:621.12 (10.8%) stddev-sans-first:594.25 14:24:25 INFO - PROCESS | 4015 | Values: 3131.4 3149.6 3183.5 3208.5 3208.6 5447.0 5488.8 5490.3 5515.6 5548.2 5443.7 5463.0 5512.4 5516.9 5556.6 4895.4 4942.2 4964.1 4986.8 5031.4 4970.5 4982.7 4994.8 5023.3 5062.5 5062.6 5070.5 5096.3 5100.7 5145.7 5124.2 5125.4 5132.0 5135.2 5173.9 4895.3 4914.9 4949.4 5046.9 5060.2 5460.2 5481.9 5502.0 5515.2 5535.1 5870.0 5903.7 5912.5 5914.3 5915.4 5665.7 5875.1 5905.8 5922.1 5945.9 5548.1 5554.0 5564.1 5586.1 5587.2 6073.8 6077.5 6085.2 6098.3 6101.9 5653.7 5672.7 5705.9 5710.7 5823.4 5800.7 5827.4 5837.0 5839.4 5863.6 5774.2 5825.5 5831.1 5838.2 5853.7 5710.1 5766.9 5799.1 5909.3 6006.1 4632.1 4637.1 4654.2 4662.3 4664.6 4136.6 4139.4 4145.6 4149.2 4161.5 5463.7 5520.3 5761.4 5779.1 5782.1 5869.2 5884.2 5898.2 5911.6 5940.7 5505.0 5541.3 5547.0 5552.8 5572.9 5047.8 5105.2 5108.6 5123.3 5149.3 6127.5 6197.7 6198.1 6206.3 6207.0 5964.4 5992.3 5995.5 5997.8 6039.4 6035.4 6119.0 6178.5 6189.9 6200.2 5901.2 5934.8 5956.5 5977.4 5989.3 5982.1 6025.8 6030.9 6033.5 6034.9 5666.0 5759.5 5918.0 5920.5 5926.7 5782.2 5848.5 5856.0 5890.2 5903.9 5788.8 5790.2 5793.7 5795.6 5795.9 5802.7 5812.3 5865.5 5865.9 5887.4 5795.1 5795.3 5799.6 5812.4 5852.6 14:24:25 INFO - PROCESS | 4015 | 14:24:25 INFO - PROCESS | 4015 | [#1] ext.html Cycles:180 Average:68519.29 Median:40760.18 stddev:140490.77 (344.7%) stddev-sans-first:140792.28 14:24:25 INFO - PROCESS | 4015 | Values: 583.6 640.8 647.9 667.5 672.1 46140.8 47469.5 47517.0 47598.3 51925.0 49163.0 49348.0 49422.5 49643.5 49725.8 8493.7 9233.4 9389.1 9390.4 11048.8 10865.3 11281.3 11282.8 11288.0 11294.5 11358.4 11362.5 11378.9 11413.8 11427.3 8522.5 10933.3 11198.3 11303.5 11342.7 11001.7 11077.7 11080.8 11121.4 11127.3 46278.0 46560.3 46604.5 46681.0 46769.3 3633.9 3670.4 3746.0 3789.5 4088.3 35408.8 35491.3 35755.5 35862.5 35985.3 4170.5 4236.5 4246.2 4257.2 4278.6 359059.0 359297.0 361419.2 362488.0 363071.0 62041.7 62306.4 62430.9 62677.7 62947.4 39680.0 40708.2 40812.2 42387.6 42394.0 16256.8 16348.6 16349.7 16359.1 16363.6 11580.3 11811.5 11814.8 12073.3 12663.6 19992.7 20741.6 20785.7 21624.7 21687.3 7410.9 7505.8 7522.2 7594.0 7606.0 1046.3 1091.8 1113.2 1166.6 1226.4 34900.7 35184.3 35265.0 35358.6 35494.3 1170.7 1194.2 1207.5 1227.6 1262.3 18446.6 18459.4 18507.6 18524.6 18621.7 639676.0 641909.0 644278.0 646593.0 646979.0 64090.0 64605.7 64622.0 64687.7 64838.4 1334.8 1411.2 1465.5 1467.1 1491.3 34067.5 35182.5 36500.8 36892.8 36901.8 56386.7 61370.0 62569.4 62834.4 62847.0 534636.0 539930.0 540898.0 541027.0 541921.0 31305.8 31737.0 31747.8 33229.8 49094.0 52674.5 52813.2 52933.7 52976.5 53114.2 45165.3 46199.0 46256.3 46920.0 47646.5 45765.0 46362.3 47111.0 47260.3 47406.9 45044.0 45818.3 45849.3 46059.5 46117.5 36464.3 41127.2 43728.8 44018.0 44048.3 30341.4 30651.4 30720.7 30815.8 31357.2 14:24:25 INFO - PROCESS | 4015 | 14:24:25 INFO - PROCESS | 4015 | [#2] jquery.html Cycles:180 Average:31072.98 Median:21912.29 stddev:92140.87 (420.5%) stddev-sans-first:92370.56 14:24:25 INFO - PROCESS | 4015 | Values: 398.2 412.8 427.6 432.6 447.6 15935.8 19718.2 21755.5 21768.1 22056.4 19029.5 19449.7 19555.3 19712.7 19766.4 5506.4 5569.6 5649.4 5655.7 5659.0 5497.9 5518.8 5541.9 5573.3 5591.6 5573.9 5594.2 5596.8 5603.4 5611.6 5500.5 5584.2 5633.9 5637.7 5643.4 5244.3 5500.4 5507.4 5513.2 5513.8 14744.0 14979.9 14997.2 19475.0 23758.0 2431.1 2448.7 2483.5 2595.6 2598.1 10599.8 10775.9 10799.4 10805.0 12132.9 1974.7 2001.6 2007.2 2037.5 2061.3 88022.6 90830.5 90855.0 90959.5 91657.0 10565.6 11984.3 12166.6 12365.3 12365.5 15031.2 15852.9 15949.3 16412.7 16491.3 6483.4 8113.4 8311.6 8431.0 8486.4 7756.7 7758.0 7904.2 7907.2 7978.5 4984.9 5086.8 5133.3 5161.6 5220.7 2038.8 2099.4 2112.0 2129.1 2156.2 940.4 1002.4 1004.8 1030.4 1041.4 19101.7 20508.6 20593.8 20674.9 20689.8 1029.9 1044.5 1049.4 1056.7 1092.2 10614.0 11574.6 11583.8 11624.4 11624.7 549408.0 556319.0 561149.0 566155.0 572606.0 57034.4 57991.4 61005.4 95241.5 95879.0 1047.8 1049.2 1067.7 1120.2 1120.5 15774.5 17231.5 17350.0 18081.7 18293.0 41373.4 42375.6 43545.8 46130.0 47232.8 61432.7 64529.0 64612.7 65257.3 66131.0 9095.6 9284.6 9368.5 9379.0 9546.7 13950.9 14183.4 14240.6 14384.1 14610.7 12091.7 12315.4 12573.5 12635.6 12651.3 11690.6 11778.9 12053.8 12241.8 12326.5 12076.0 12142.9 12187.9 12702.6 12838.9 7728.2 9048.8 9172.8 9392.0 9594.7 8427.5 10774.5 12440.1 12605.2 12815.6 14:24:25 INFO - PROCESS | 4015 | 14:24:25 INFO - PROCESS | 4015 | [#3] mootools.html Cycles:180 Average:6035.28 Median:4738.48 stddev:3406.55 (71.9%) stddev-sans-first:3398.59 14:24:25 INFO - PROCESS | 4015 | Values: 1439.1 1560.4 1584.2 1773.6 1883.6 8414.6 8800.7 8809.9 9100.2 9102.5 8901.4 9016.4 9017.7 9027.4 9031.2 2435.8 2484.8 2485.0 2486.2 2486.9 2424.8 2471.6 2476.6 2480.0 2482.8 2424.9 2446.7 2480.6 2482.0 2482.4 2413.4 2431.0 2445.3 2447.5 2448.4 2371.3 2371.4 2390.8 2409.5 2411.6 8886.0 8967.8 8993.6 8995.8 9014.9 5503.7 5647.0 5651.3 5652.1 5655.0 3520.4 3541.4 3552.3 3590.0 3601.4 1286.0 1291.6 1294.9 1313.2 1336.6 12998.6 13071.3 13115.9 13139.1 13158.1 4680.0 4693.9 4755.3 4779.2 4891.5 10249.5 10250.9 10262.6 10279.3 10315.7 5362.1 5362.1 5362.9 5363.4 5364.3 4529.4 4879.2 4923.9 4962.9 5028.6 3992.4 4089.1 4089.2 4132.1 4184.8 1236.9 1248.3 1252.5 1255.7 1262.6 1033.7 1039.6 1080.1 1124.7 1125.0 6291.3 6550.0 6652.8 6728.5 6730.1 477.6 481.7 491.3 491.9 500.8 4616.0 4706.9 4721.7 4759.8 4800.6 11050.9 11279.7 11288.2 11348.1 11375.7 10228.4 10669.6 10685.1 10714.0 11154.2 7911.5 8095.5 8131.9 8275.1 8313.8 6282.2 6385.7 6465.7 6631.9 6701.1 10062.6 10446.5 10628.9 10712.9 10778.0 10659.0 10921.8 10924.9 10962.8 11022.9 8431.2 8660.9 8669.1 8734.0 8873.8 9035.7 9092.5 9093.8 9113.8 9126.9 7624.6 7839.9 7866.6 7945.1 7961.9 7515.1 7811.3 7874.7 7951.1 8013.1 6888.6 7034.5 7159.9 7167.1 7170.3 5389.7 5415.4 5441.7 5446.2 5559.5 5333.7 5502.8 5513.5 5525.4 5601.1 14:24:25 INFO - PROCESS | 4015 | 14:24:25 INFO - PROCESS | 4015 | [#4] prototype.html Cycles:180 Average:4789.73 Median:5528.59 stddev:1412.67 (25.6%) stddev-sans-first:1376.25 14:24:25 INFO - PROCESS | 4015 | Values: 321.3 321.4 326.7 337.1 340.5 5403.4 5444.3 5457.4 5463.6 5508.8 5358.3 5421.4 5436.3 5494.5 5494.7 4021.2 4092.6 4194.0 4255.7 4267.9 4190.4 4192.2 4220.3 4224.6 4251.1 4190.2 4191.9 4193.1 4194.5 4195.9 4024.8 4059.9 4221.4 4238.8 4249.9 3796.9 3801.4 3803.9 3805.0 3808.5 5294.6 5302.7 5354.9 5365.9 5368.9 4734.5 4809.9 4822.5 4834.1 4849.3 5638.7 5639.6 5642.2 5645.4 5653.9 3959.4 4080.6 4097.8 4103.5 4123.9 5806.5 5863.4 5873.7 5896.7 5917.2 4196.3 4198.8 4204.9 4210.7 4234.4 4042.6 4275.3 4281.4 4286.7 4304.8 4717.6 4734.1 4751.8 4774.9 4788.2 5670.0 5672.3 5681.4 5683.0 5700.7 1391.8 1446.9 1464.7 1465.9 1475.9 921.6 940.9 943.9 945.4 950.7 5417.5 5553.6 5561.2 5562.7 5577.2 5808.2 5812.3 5834.5 5871.8 5885.8 5365.6 5530.1 5557.5 5624.0 5637.5 3063.7 3082.3 3094.1 3099.4 3099.5 5898.0 6041.5 6055.2 6079.2 6095.9 5907.6 5910.3 5928.7 5942.6 5965.1 6032.8 6064.6 6098.2 6114.2 6142.3 5696.8 5811.5 5818.8 5819.9 5900.3 5697.8 5700.1 5708.3 5750.0 5766.9 5870.5 6021.7 6036.0 6045.0 6080.9 4125.2 4128.9 4152.0 4175.7 4178.7 5824.3 5837.5 5849.2 5890.3 5900.7 5527.8 5717.0 5738.3 5742.4 5746.8 5640.5 5651.5 5653.6 5658.2 5685.8 5403.5 5570.6 5624.5 5635.5 5657.0 5267.0 5538.8 5662.3 5673.6 5713.2 5513.4 5529.4 5536.5 5554.3 5555.4 14:24:25 INFO - PROCESS | 4015 | 14:24:25 INFO - PROCESS | 4015 | [#5] yui.html Cycles:180 Average:22848.18 Median:13965.62 stddev:34416.30 (246.4%) stddev-sans-first:34473.16 14:24:25 INFO - PROCESS | 4015 | Values: 835.6 835.8 855.9 895.0 901.0 14082.8 15216.5 15225.5 15260.8 15265.7 13798.5 13887.3 13907.4 13914.7 13928.9 725.3 855.3 871.6 875.2 884.2 862.9 865.2 865.4 865.7 866.1 917.2 923.7 926.1 929.5 931.9 761.9 893.2 914.1 919.6 926.6 670.1 672.4 673.6 674.6 675.5 13087.1 13088.3 13091.4 13110.8 13123.2 13786.9 13860.4 14019.3 14240.4 14473.3 11166.4 11360.7 11467.1 11959.3 12169.3 4450.6 4464.2 4541.5 4599.1 4601.5 68642.7 71010.0 72176.0 81632.6 85166.0 11100.3 12093.6 12522.2 12728.4 12959.2 23758.9 24088.6 24108.8 24113.0 24138.2 9027.6 9053.6 9351.7 9529.8 9572.8 4993.9 5012.7 5023.1 5213.1 5251.9 3793.1 3917.3 3922.5 3931.9 3940.9 594.6 648.7 659.7 683.4 711.4 635.4 648.8 750.8 818.9 836.8 10398.3 10539.8 10680.2 10807.6 10936.2 661.6 686.5 730.4 739.9 760.3 4642.0 4705.9 4739.6 4756.6 4835.4 130971.0 134076.0 135012.3 135651.0 139209.0 110584.4 115824.4 116067.4 116703.0 120437.0 81631.6 101840.0 104912.0 105684.5 109728.5 9717.2 9759.7 9916.0 10127.4 10559.9 55801.4 56006.7 56502.7 56905.1 57303.0 76656.0 83038.6 84307.6 84756.2 86984.6 13313.7 13628.3 13842.2 13887.7 14113.7 15890.2 15971.8 16444.9 16899.2 17220.4 12732.8 13045.2 13090.0 13270.7 13757.8 13667.2 14180.5 14276.4 15023.2 15230.3 13766.9 14024.4 14055.4 14173.0 14471.4 12106.9 12133.3 12237.6 12346.4 12560.7 11664.4 11681.9 11701.0 11804.5 11980.8 14:24:25 INFO - PROCESS | 4015 | -------- Summary: end -------- 14:24:25 INFO - PROCESS | 4015 | 14:24:26 INFO - TEST-INFO | 4015: exit 0 14:24:26 INFO - TEST-OK | dromaeo_css | took 1113784ms 14:24:26 INFO - TEST-START | kraken 14:24:26 INFO - operating with platform_type : linux_ 14:24:26 INFO - Initialising browser for kraken test... 14:24:26 INFO - TEST-INFO | started process 4221 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp40aVw6/profile http://localhost:39111/getInfo.html) 14:24:30 INFO - PROCESS | 4221 | __metrics Screen width/height:1600/1200 14:24:30 INFO - PROCESS | 4221 | colorDepth:24 14:24:30 INFO - PROCESS | 4221 | Browser inner width/height: 1024/697 14:24:30 INFO - PROCESS | 4221 | __metrics 14:24:31 INFO - PROCESS | 4221 | 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" 14:24:31 INFO - PROCESS | 4221 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:24:32 INFO - TEST-INFO | 4221: exit 0 14:24:32 INFO - Browser initialized. 14:24:32 INFO - Running cycle 1/1 for kraken test... 14:24:32 INFO - TEST-INFO | started process 4310 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp40aVw6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:24:44 INFO - PROCESS | 4310 | RSS: Main: 145985536 14:24:44 INFO - PROCESS | 4310 | 14:24:46 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39111/tests/kraken/driver.html?testName=audio-beat-detection) 14:24:47 INFO - PROCESS | 4310 | RSS: Main: 200740864 14:24:47 INFO - PROCESS | 4310 | 14:24:50 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39111/tests/kraken/driver.html?testName=audio-dft) 14:24:50 INFO - PROCESS | 4310 | RSS: Main: 185372672 14:24:50 INFO - PROCESS | 4310 | 14:24:53 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39111/tests/kraken/driver.html?testName=audio-fft) 14:24:54 INFO - PROCESS | 4310 | RSS: Main: 276525056 14:24:54 INFO - PROCESS | 4310 | 14:24:56 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39111/tests/kraken/driver.html?testName=audio-oscillator) 14:24:56 INFO - PROCESS | 4310 | RSS: Main: 181899264 14:24:56 INFO - PROCESS | 4310 | 14:24:58 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39111/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:24:59 INFO - PROCESS | 4310 | RSS: Main: 180322304 14:24:59 INFO - PROCESS | 4310 | 14:25:02 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39111/tests/kraken/driver.html?testName=imaging-darkroom) 14:25:03 INFO - PROCESS | 4310 | RSS: Main: 216653824 14:25:03 INFO - PROCESS | 4310 | 14:25:06 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39111/tests/kraken/driver.html?testName=imaging-desaturate) 14:25:07 INFO - PROCESS | 4310 | RSS: Main: 215490560 14:25:07 INFO - PROCESS | 4310 | 14:25:09 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39111/tests/kraken/driver.html?testName=json-parse-financial) 14:25:10 INFO - PROCESS | 4310 | RSS: Main: 216174592 14:25:10 INFO - PROCESS | 4310 | 14:25:12 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39111/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:25:12 INFO - PROCESS | 4310 | RSS: Main: 178143232 14:25:12 INFO - PROCESS | 4310 | 14:25:14 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39111/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:25:14 INFO - PROCESS | 4310 | RSS: Main: 184766464 14:25:14 INFO - PROCESS | 4310 | 14:25:16 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39111/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:25:17 INFO - PROCESS | 4310 | RSS: Main: 186953728 14:25:17 INFO - PROCESS | 4310 | 14:25:20 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39111/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:25:20 INFO - PROCESS | 4310 | RSS: Main: 181510144 14:25:20 INFO - PROCESS | 4310 | 14:25:23 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39111/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:25:23 INFO - PROCESS | 4310 | RSS: Main: 184840192 14:25:23 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | Cycle 1(1): loaded http://localhost:39111/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39111/tests/kraken/driver.html?testName=ai-astar) 14:25:25 INFO - PROCESS | 4310 | RSS: Main: 236412928 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | __start_tp_report 14:25:25 INFO - PROCESS | 4310 | _x_x_mozilla_page_load 14:25:25 INFO - PROCESS | 4310 | _x_x_mozilla_page_load_details 14:25:25 INFO - PROCESS | 4310 | |i|pagename|runs| 14:25:25 INFO - PROCESS | 4310 | |0;ai-astar;111;110;120;109;110;110;110;110;110;111 14:25:25 INFO - PROCESS | 4310 | |1;audio-beat-detection;145;121;146;181;117;149;149;117;185;116 14:25:25 INFO - PROCESS | 4310 | |2;audio-dft;161;162;161;160;161;160;160;160;162;161 14:25:25 INFO - PROCESS | 4310 | |3;audio-fft;77;75;76;187;76;74;74;74;162;75 14:25:25 INFO - PROCESS | 4310 | |4;audio-oscillator;84;83;166;83;82;83;151;82;83;140 14:25:25 INFO - PROCESS | 4310 | |5;imaging-gaussian-blur;117;117;117;116;117;116;116;117;116;118 14:25:25 INFO - PROCESS | 4310 | |6;imaging-darkroom;145;146;145;147;145;146;146;146;146;145 14:25:25 INFO - PROCESS | 4310 | |7;imaging-desaturate;95;95;96;95;95;95;95;94;94;95 14:25:25 INFO - PROCESS | 4310 | |8;json-parse-financial;54;58;50;50;50;51;51;49;53;50 14:25:25 INFO - PROCESS | 4310 | |9;json-stringify-tinderbox;61;59;59;60;60;59;58;60;61;59 14:25:25 INFO - PROCESS | 4310 | |10;stanford-crypto-aes;73;72;73;74;77;81;72;77;71;73 14:25:25 INFO - PROCESS | 4310 | |11;stanford-crypto-ccm;133;120;117;117;115;126;116;114;201;122 14:25:25 INFO - PROCESS | 4310 | |12;stanford-crypto-pbkdf2;151;152;151;153;153;154;154;154;151;154 14:25:25 INFO - PROCESS | 4310 | |13;stanford-crypto-sha256-iterative;64;62;62;62;61;61;61;61;60;60 14:25:25 INFO - PROCESS | 4310 | __end_tp_report 14:25:25 INFO - PROCESS | 4310 | __start_cc_report 14:25:25 INFO - PROCESS | 4310 | _x_x_mozilla_cycle_collect,1699 14:25:25 INFO - PROCESS | 4310 | __end_cc_report 14:25:25 INFO - PROCESS | 4310 | __startTimestamp1473456325835__endTimestamp 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | ------- Summary: start ------- 14:25:25 INFO - PROCESS | 4310 | Number of tests: 14 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#0] ai-astar Cycles:10 Average:111.10 Median:110.00 stddev:3.18 (2.9%) stddev-sans-first:3.37 14:25:25 INFO - PROCESS | 4310 | Values: 111.0 110.0 120.0 109.0 110.0 110.0 110.0 110.0 110.0 111.0 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#1] audio-beat-detection Cycles:10 Average:142.60 Median:147.50 stddev:25.51 (17.3%) stddev-sans-first:27.04 14:25:25 INFO - PROCESS | 4310 | Values: 145.0 121.0 146.0 181.0 117.0 149.0 149.0 117.0 185.0 116.0 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 14:25:25 INFO - PROCESS | 4310 | Values: 161.0 162.0 161.0 160.0 161.0 160.0 160.0 160.0 162.0 161.0 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#3] audio-fft Cycles:10 Average:95.00 Median:75.00 stddev:42.32 (56.4%) stddev-sans-first:44.39 14:25:25 INFO - PROCESS | 4310 | Values: 77.0 75.0 76.0 187.0 76.0 74.0 74.0 74.0 162.0 75.0 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#4] audio-oscillator Cycles:10 Average:103.70 Median:83.00 stddev:34.12 (41.1%) stddev-sans-first:35.44 14:25:25 INFO - PROCESS | 4310 | Values: 84.0 83.0 166.0 83.0 82.0 83.0 151.0 82.0 83.0 140.0 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 14:25:25 INFO - PROCESS | 4310 | Values: 117.0 117.0 117.0 116.0 117.0 116.0 116.0 117.0 116.0 118.0 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 14:25:25 INFO - PROCESS | 4310 | Values: 145.0 146.0 145.0 147.0 145.0 146.0 146.0 146.0 146.0 145.0 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 14:25:25 INFO - PROCESS | 4310 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:51.00 stddev:2.72 (5.3%) stddev-sans-first:2.74 14:25:25 INFO - PROCESS | 4310 | Values: 54.0 58.0 50.0 50.0 50.0 51.0 51.0 49.0 53.0 50.0 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:0.97 (1.6%) stddev-sans-first:0.88 14:25:25 INFO - PROCESS | 4310 | Values: 61.0 59.0 59.0 60.0 60.0 59.0 58.0 60.0 61.0 59.0 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:73.50 stddev:3.09 (4.2%) stddev-sans-first:3.24 14:25:25 INFO - PROCESS | 4310 | Values: 73.0 72.0 73.0 74.0 77.0 81.0 72.0 77.0 71.0 73.0 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#11] stanford-crypto-ccm Cycles:10 Average:128.10 Median:121.00 stddev:26.27 (21.7%) stddev-sans-first:27.80 14:25:25 INFO - PROCESS | 4310 | Values: 133.0 120.0 117.0 117.0 115.0 126.0 116.0 114.0 201.0 122.0 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.70 Median:153.50 stddev:1.34 (0.9%) stddev-sans-first:1.27 14:25:25 INFO - PROCESS | 4310 | Values: 151.0 152.0 151.0 153.0 153.0 154.0 154.0 154.0 151.0 154.0 14:25:25 INFO - PROCESS | 4310 | 14:25:25 INFO - PROCESS | 4310 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:61.50 stddev:1.17 (1.9%) stddev-sans-first:0.78 14:25:25 INFO - PROCESS | 4310 | Values: 64.0 62.0 62.0 62.0 61.0 61.0 61.0 61.0 60.0 60.0 14:25:25 INFO - PROCESS | 4310 | -------- Summary: end -------- 14:25:25 INFO - PROCESS | 4310 | 14:25:26 INFO - TEST-INFO | 4310: exit 0 14:25:26 INFO - TEST-OK | kraken | took 60705ms 14:25:26 INFO - SUITE-END | took 1174s 14:25:27 INFO - Completed test suite (00:19:34) 14:25:27 INFO - javascript benchmark 14:25:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3131.373725254949, 3149.5748074010003, 3183.4588120019594, 3208.5026820842777, 3208.5508028875947, 5447.046766815806, 5488.780448782044, 5490.258815059966, 5515.558755299577, 5548.195511650808, 5443.727813609314, 5462.984173779674, 5512.448755124486, 5516.889662206753, 5556.583256255777, 4895.437024742147, 4942.233953737176, 4964.056829202458, 4986.800527978895, 5031.371078615173, 4970.502949705036, 4982.701037937735, 4994.825181118662, 5023.346964894558, 5062.468440813716, 5062.645614806965, 5070.5183007614405, 5096.286519887219, 5100.719460429675, 5145.665531740425, 5124.154514505102, 5125.410577783547, 5132.024915266087, 5135.152699804527, 5173.870653233662, 4895.290182923492, 4914.852554423337, 4949.356583644139, 5046.873828154326, 5060.2156665716875, 5460.208269800896, 5481.890362192734, 5502.0, 5515.172724091378, 5535.114381698909, 5870.0, 5903.675297858616, 5912.4974377178, 5914.260717410324, 5915.3788852170765, 5665.745041473144, 5875.148103524965, 5905.763769449217, 5922.111683247547, 5945.8810823783715, 5548.140038294071, 5553.972230138864, 5564.054110801174, 5586.050371436865, 5587.161925711135, 6073.787417440369, 6077.4834139098675, 6085.208922840003, 6098.32918378978, 6101.908471372933, 5653.745581448845, 5672.659640421588, 5705.942940570541, 5710.65736055838, 5823.4467725566, 5800.651960882361, 5827.35899051104, 5837.036888913367, 5839.357670656224, 5863.618864773776, 5774.162746401748, 5825.475707186289, 5831.096180092092, 5838.182654428383, 5853.707314634251, 5710.057840456278, 5766.855828604234, 5799.130130480377, 5909.261342332208, 6006.099085137264, 4632.143053535039, 4637.1421287062, 4654.232051711498, 4662.32396302541, 4664.626829853603, 4136.627703506639, 4139.441175441337, 4145.647619952278, 4149.190907772955, 4161.500619925568, 5463.672179669233, 5520.282363292747, 5761.366249712528, 5779.133130030445, 5782.132680098019, 5869.178315035899, 5884.176215329857, 5898.203742494708, 5911.645301281851, 5940.70296485174, 5504.972475137599, 5541.33503979525, 5547.0, 5552.7778888843995, 5572.888542229214, 5047.823326183566, 5105.2086926526445, 5108.56577190943, 5123.2827404163445, 5149.253358263031, 6127.5404344674325, 6197.690115494207, 6198.0702894566175, 6206.255249370104, 6206.968965155145, 5964.373740757158, 5992.250968628921, 5995.520358371233, 5997.760089596367, 6039.4262545058145, 6035.426634469718, 6119.045604113885, 6178.505719542336, 6189.938100618936, 6200.224971878515, 5901.232839730807, 5934.762609495572, 5956.493698035542, 5977.372375900642, 5989.311229208697, 5982.0727787193055, 6025.8192254232445, 6030.879382412415, 6033.456988871023, 6034.939650603438, 5665.971670141623, 5759.481646651821, 5918.0, 5920.467157955804, 5926.674032928058, 5782.161586570008, 5848.53211743068, 5855.970720146372, 5890.204822348928, 5903.881922361615, 5788.797392091256, 5790.247267855152, 5793.681347525927, 5795.594308398372, 5795.855103622274, 5802.738876750641, 5812.3025236971835, 5865.501432378295, 5865.882682346414, 5887.4406931341455, 5795.130730390475, 5795.304563452412, 5799.594028417971, 5812.447817457335, 5852.619579727304], "value": 5471.521006221576, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.6118980877717, 640.8429934666004, 647.8833809914215, 667.4993754683987, 672.0591172358701, 46140.769296153514, 47469.525304746945, 47517.0, 47598.286025709596, 51925.0, 49163.0, 49348.0, 49422.50577494224, 49643.50356496434, 49725.751371243095, 8493.66025358985, 9233.399829011127, 9389.06109389059, 9390.436573805559, 11048.779024419508, 10865.293755905841, 11281.266717663366, 11282.830757538602, 11288.040516556102, 11294.548218071268, 11358.432078396088, 11362.545498180063, 11378.943105284461, 11413.828792568118, 11427.314361138317, 8522.531260780655, 10933.289336193122, 11198.328100313925, 11303.491342889589, 11342.659720208365, 11001.724956876062, 11077.667669969915, 11080.778384432348, 11121.443927803659, 11127.33236005834, 46278.0, 46560.30159547576, 46604.53395466036, 46681.0, 46769.29846052312, 3633.8728144514816, 3670.394384926484, 3746.0260332313796, 3789.5263092113487, 4088.2845502037026, 35408.82295588531, 35491.29017419663, 35755.463668045, 35862.46206306908, 35985.28029439423, 4170.457840480748, 4236.491621005499, 4246.214450326699, 4257.169851878885, 4278.63631591312, 359059.0, 359297.0, 361419.1929040338, 362488.0, 363070.9999999974, 62041.68979155075, 62306.37693623096, 62430.878456077015, 62677.686611567566, 62947.37052629415, 39680.0, 40708.1858362837, 40812.1837563253, 42387.57612423898, 42394.0, 16256.837431625769, 16348.591285217964, 16349.673006539802, 16359.100249486155, 16363.59091022734, 11580.305181689126, 11811.527538898348, 11814.763704725858, 12073.27560346382, 12663.556775512812, 19992.700109498077, 20741.58516829685, 20785.688214676793, 21624.70251784898, 21687.349379518324, 7410.888836667455, 7505.8365953277325, 7522.210167932398, 7594.0, 7605.961970190225, 1046.2937517176028, 1091.82530795074, 1113.17068783755, 1166.5742004168587, 1226.4480983557257, 34900.65099349025, 35184.296314073574, 35265.0, 35358.646413535535, 35494.29011419809, 1170.7073231692077, 1194.170051813981, 1207.4988879614862, 1227.6010296653624, 1262.3372729317182, 18446.631067378577, 18459.446216613254, 18507.566880251477, 18524.629507410045, 18621.7206741899, 639676.0, 641909.0, 644278.0, 646593.0, 646979.0, 64090.0, 64605.67697161484, 64622.0, 64687.67656161783, 64838.35161648417, 1334.846492653338, 1411.2097225553518, 1465.5090544667655, 1467.1417220925664, 1491.328901994107, 34067.4889876657, 35182.47226291659, 36500.81749591235, 36892.81553592215, 36901.815490922374, 56386.718066409405, 61370.0, 62569.37430625818, 62834.37165628285, 62847.0, 534636.0, 539930.0, 540898.0, 541027.0, 541921.0, 31305.8434707825, 31737.0, 31747.84126079355, 33229.83385083059, 49094.0, 52674.47325526696, 52813.20780188377, 52933.7353313231, 52976.47023529715, 53114.2032869515, 45165.322520161564, 46199.0, 46256.30615540837, 46920.0, 47646.5235347656, 45765.0, 46362.30456543222, 47111.0, 47260.29109563291, 47406.87650560356, 45044.0, 45818.31272530848, 45849.312260315455, 46059.53940460552, 46117.53882461267, 36464.27071458609, 41127.1774564501, 43728.781356093015, 44018.01157034697, 44048.339274911545, 30341.393172136875, 30651.386972259985, 30720.692793071783, 30815.845920770254, 31357.216069598442], "value": 22113.80788291073, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.2234642447229, 412.77916314771625, 427.6343726114172, 432.6149726743199, 447.6239958434916, 15935.84064159358, 19718.21127154914, 21755.456113597167, 21768.12927482903, 22056.44858878529, 19029.52426189346, 19449.708254376197, 19555.315563955264, 19712.704309435336, 19766.407007789752, 5506.366767821702, 5569.637973531728, 5649.435056494338, 5655.688937108457, 5659.037963546187, 5497.890042199154, 5518.834434966958, 5541.9168712469345, 5573.331200255975, 5591.552675785948, 5573.916391254134, 5594.216809646653, 5596.832095037135, 5603.439656034384, 5611.607187496877, 5500.532454741352, 5584.162375643646, 5633.915491267614, 5637.689927054011, 5643.435656434365, 5244.344456942882, 5500.4224556421395, 5507.366652834935, 5513.173024046385, 5513.807016754435, 14744.0, 14979.850201498064, 14997.17515536645, 19475.0, 23758.0, 2431.0640403444622, 2448.7183973843057, 2483.503299340139, 2595.6236345729762, 2598.1166403422926, 10599.841002384894, 10775.94612026935, 10799.352038877692, 10805.0, 12132.878671213351, 1974.7432833731666, 2001.579668269665, 2007.2071531745025, 2037.521182522106, 2061.3197644777338, 88022.5598872008, 90830.545847271, 90855.0, 90959.54520227357, 91657.0, 10565.577376904837, 11984.34086125254, 12166.57416990401, 12365.319907404999, 12365.505379784709, 15031.248437578297, 15852.92073539648, 15949.282282297325, 16412.671746565004, 16491.34034638625, 6483.364919975011, 8113.350931925432, 8311.584420779058, 8430.957845210734, 8486.448380855349, 7756.6509507072315, 7758.0, 7904.170062143392, 7907.209279072047, 7978.521288722695, 4984.850454486371, 5086.847394578169, 5133.25567792668, 5161.638685292069, 5220.660657057279, 2038.775734669185, 2099.4491692593265, 2111.9788802111784, 2129.0844936677377, 2156.2345367394664, 940.4310392212612, 1002.3584905660383, 1004.7689031522791, 1030.3766221436074, 1041.4063983529468, 19101.713474297896, 20508.589828203352, 20593.79406205949, 20674.896625517078, 20689.79310206909, 1029.8764148302103, 1044.4673216659407, 1049.3546468921559, 1056.7463808686014, 1092.1699508373533, 10614.0, 11574.594889178838, 11583.826242606368, 11624.418779061014, 11624.709382265342, 549408.0, 556319.0, 561149.0, 566155.0, 572606.0, 57034.42965570291, 57991.4200857986, 61005.389946100855, 95241.52379238198, 95879.0, 1047.8323468245044, 1049.2235745548392, 1067.7437415020495, 1120.2270433400927, 1120.5013768872936, 15774.526764197093, 17231.483055508354, 17350.0, 18081.728774068135, 18293.0, 41373.37939930843, 42375.57624423839, 43545.78227108844, 46130.0, 47232.7638361806, 61432.692836535534, 64529.0, 64612.67693661502, 65257.34742652513, 66131.0, 9095.636174552943, 9284.628614855601, 9368.531573421165, 9379.0, 9546.665866694633, 13950.930245348709, 14183.432662693376, 14240.572782816533, 14384.136951782926, 14610.707785844012, 12091.697707557381, 12315.445804938978, 12573.497060117494, 12635.620931372074, 12651.304178269915, 11690.590829320932, 11778.939895409454, 12053.81919271194, 12241.755164896475, 12326.50693972231, 12076.0, 12142.939285303517, 12187.939060304641, 12702.618921432371, 12838.871611283768, 7728.227177282227, 9048.819023619622, 9172.816543669223, 9392.0, 9594.66418675343, 8427.536485493112, 10774.515146818256, 12440.129190956546, 12605.243685378906, 12815.61553153407], "value": 9466.256727883778, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.100562148657, 1560.3758496601354, 1584.2158131724793, 1773.565476458267, 1883.5573640194552, 8414.579271036446, 8800.735977920665, 8809.867851982217, 9100.180983711465, 9102.499362535058, 8901.421407608517, 9016.41393309436, 9017.684381046665, 9027.413218140833, 9031.187193152615, 2435.756424357568, 2484.776370126688, 2484.9875750621313, 2486.2416962826333, 2486.9005239790386, 2424.7754883783755, 2471.5798314286526, 2476.578981573137, 2480.045182604701, 2482.751724827521, 2424.9151279705216, 2446.706395232575, 2480.5659009673327, 2482.019602257107, 2482.391814005571, 2413.396650837282, 2430.966839093387, 2445.327534927891, 2447.486027934127, 2448.4001419652122, 2371.3241726107976, 2371.3715865295712, 2390.7728765767224, 2409.506051259488, 2411.638254261857, 8885.955570222108, 8967.820643587156, 8993.550322483916, 8995.820083598357, 9014.909850901538, 5503.697296648683, 5647.040003199425, 5651.265335506399, 5652.095664693629, 5655.0386434306265, 3520.366334059868, 3541.38025845476, 3552.2717842842158, 3589.964100358989, 3601.441776524643, 1285.9935700321532, 1291.6254286257067, 1294.851092124399, 1313.192386682193, 1336.6391074409855, 12998.61004169886, 13071.281079540622, 13115.934420327838, 13139.14595551286, 13158.14472059313, 4679.976600116977, 4693.947227235066, 4755.286706993978, 4779.235322348477, 4891.461939186686, 10249.538770755335, 10250.948745256375, 10262.640807571699, 10279.331843430153, 10315.690529283984, 5362.061639213122, 5362.061639213122, 5362.865928351823, 5363.436839131913, 5364.30264065669, 4529.433820772403, 4879.243717223836, 4923.852284431473, 4962.851114466572, 5028.622853285945, 3992.441058251847, 4089.1003979124544, 4089.2434899543678, 4132.070284186034, 4184.832606695698, 1236.9134160608671, 1248.2822377133184, 1252.5365614722612, 1255.6798016505916, 1262.6085913366885, 1033.7105610429091, 1039.6205385034407, 1080.0603474976663, 1124.7019539821954, 1125.0324720740157, 6291.307956124822, 6549.967250163814, 6652.800415987528, 6728.495362847806, 6730.125083739083, 477.63699588312437, 481.6989381636477, 491.2851800630075, 491.8573613652072, 500.812195426715, 4615.95384046162, 4706.88232794176, 4721.6694831362165, 4759.762011899391, 4800.639952003614, 11050.889491105147, 11279.718007049889, 11288.20982531215, 11348.09215262777, 11375.658730238107, 10228.386296822066, 10669.573217071385, 10685.145188384911, 10714.0, 11154.219204655596, 7911.485753426009, 8095.473794203475, 8131.9186808132345, 8275.131111233233, 8313.750587482386, 6282.2147231596055, 6385.744570217139, 6465.709043092978, 6631.867362652816, 6701.095352127595, 10062.647807326708, 10446.52990615409, 10628.946855265674, 10712.946435267773, 10778.02997730208, 10658.987396197348, 10921.781564368826, 10924.945375273073, 10962.780744384909, 11022.944885275523, 8431.19903609156, 8660.913390866011, 8669.133086691281, 8734.039255681742, 8873.778155546162, 9035.728928132166, 9092.545372731469, 9093.818123637358, 9113.817723645358, 9126.863097053681, 7624.58064806441, 7839.882401764092, 7866.6066696666085, 7945.1260361359045, 7961.9203807963495, 7515.135759387522, 7811.336036436966, 7874.6850125994315, 7951.085625153181, 8013.078495972804, 6888.621125838127, 7034.5427547209265, 7159.892601610876, 7167.139943206848, 7170.282971702788, 5389.7035663038905, 5415.350157981067, 5441.7279136043835, 5446.183072538993, 5559.499645031967, 5333.676887400013, 5502.752376143163, 5513.476219759116, 5525.4474552544425, 5601.103823388238], "value": 4779.120910176652, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.27712646545274, 321.35728542914165, 326.70270054250636, 337.0797722218342, 340.53686985699443, 5403.432639572842, 5444.346678398588, 5457.372402173752, 5463.644863083901, 5508.779648814043, 5358.276632654591, 5421.376541697706, 5436.3476382834115, 5494.532964698005, 5494.670319780816, 4021.1756589899, 4092.611201935826, 4193.979030104861, 4255.744655320691, 4267.850625228118, 4190.365757354634, 4192.203481338551, 4220.282551966173, 4224.640905523034, 4251.107267473834, 4190.224808410454, 4191.874243772691, 4193.119444916571, 4194.475690538683, 4195.888089656243, 4024.7786371749394, 4059.8985025374304, 4221.387898754663, 4238.791944295892, 4249.915001699979, 3796.924061518754, 3801.4107813288983, 3803.904902377435, 3804.9809750951345, 3808.542974843009, 5294.602904782157, 5302.693328402157, 5354.9196762048605, 5365.865853353658, 5368.946310536884, 4734.502877197913, 4809.855704328841, 4822.493638168012, 4834.057358815033, 4849.321095046696, 5638.718064096779, 5639.6334238274385, 5642.238297829782, 5645.379008309083, 5653.886922261532, 3959.366501359769, 4080.5919408059253, 4097.7541347519245, 4103.4870641169855, 4123.8969025774295, 5806.477417032487, 5863.413658634145, 5873.70778428745, 5896.697849204459, 5917.17159597661, 4196.349565817303, 4198.769067701306, 4204.873853784391, 4210.663146948237, 4234.3860140279485, 4042.6159514846045, 4275.337322714984, 4281.400603915455, 4286.721363111388, 4304.80628371724, 4717.599004084691, 4734.076855013308, 4751.762411879392, 4774.856754297377, 4788.209945359046, 5670.0, 5672.319321681424, 5681.431856814368, 5682.954136510853, 5700.743466543932, 1391.8308620758612, 1446.8914831387688, 1464.7070585882866, 1465.9047161934654, 1475.859793319633, 921.6313474610075, 940.872982147415, 943.8537026760863, 945.3902233059638, 950.7242899559188, 5417.5395091416885, 5553.583481238923, 5561.221428999943, 5562.72186390687, 5577.247071645357, 5808.186853840381, 5812.3025236971835, 5834.474897259267, 5871.7651293947765, 5885.823425297248, 5365.570754339644, 5530.087535556669, 5557.472040156179, 5624.043912534797, 5637.548996080303, 3063.7089476499696, 3082.2756652186717, 3094.1181763197833, 3099.395617854497, 3099.4730895747775, 5898.0, 6041.486473649789, 6055.212822333068, 6079.240094988127, 6095.908561371583, 5907.586468947218, 5910.290765108214, 5928.7332070056955, 5942.554308426886, 5965.075413310944, 6032.849178770478, 6064.575479716377, 6098.176746139303, 6114.205153330127, 6142.293636231891, 5696.772129114955, 5811.53507719373, 5818.807144535444, 5819.941800582109, 5900.291964964232, 5697.800576979786, 5700.116481945305, 5708.315002199763, 5750.022496175577, 5766.884662306814, 5870.53035757147, 6021.668808215591, 6036.0, 6044.969775151096, 6080.90878636812, 4125.23683118624, 4128.938065928954, 4151.958480415157, 4175.707700460938, 4178.7283826551175, 5824.330202026821, 5837.532997360286, 5849.210356601974, 5890.293164820249, 5900.675462849413, 5527.778888844401, 5716.999525083177, 5738.302997591126, 5742.368339482737, 5746.770129194785, 5640.492355687844, 5651.491365777099, 5653.575981801464, 5658.20785090096, 5685.77256909719, 5403.486668766461, 5570.610057296112, 5624.465675760796, 5635.549156067586, 5656.971715141398, 5266.9736651318035, 5538.750756216061, 5662.348829884616, 5673.574481913791, 5713.22871412354, 5513.393526711979, 5529.391766905717, 5536.474034966672, 5554.2779438672715, 5555.388907220284], "value": 4333.764026482416, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [835.6364981233164, 835.8495470815254, 855.9101294364092, 894.9642014319428, 901.0268909577657, 14082.788758168623, 15216.543503694878, 15225.54323370298, 15260.847391526082, 15265.694686106272, 13798.517051903185, 13887.305634718274, 13907.443702251898, 13914.652133696663, 13928.930355348235, 725.2892165677625, 855.2986551028159, 871.5816408124105, 875.1598465473154, 884.2263019857625, 862.8576284912982, 865.1953683074738, 865.4158443050947, 865.7446053414227, 866.0776273268948, 917.206616276919, 923.745969858291, 926.0507979321216, 929.502716046915, 931.8648795924587, 761.8514389694012, 893.2184338703635, 914.1041779056495, 919.645936314517, 926.6061923682441, 670.0552221367897, 672.3814091036259, 673.5891106425076, 674.5682763031664, 675.5440077947366, 13087.083904126715, 13088.345582720825, 13091.41088651013, 13110.803337949843, 13123.212607243597, 13786.93106534471, 13860.4455821767, 14019.299035048207, 14240.430382784676, 14473.348699308452, 11166.441677916071, 11360.659180224606, 11467.139964502612, 11959.342236177006, 12169.2698438094, 4450.558182034864, 4464.2187617166865, 4541.5231400703115, 4599.08018396322, 4601.493835678073, 68642.65678671574, 71010.0, 72176.0, 81632.59183704102, 85165.99999999937, 11100.333979961228, 12093.637190884288, 12522.186057906207, 12728.363581820871, 12959.157654752411, 23758.881205594207, 24088.638670419958, 24108.758912410653, 24113.0, 24138.155164569154, 9027.593758280893, 9053.637854485878, 9351.672691455766, 9529.761755956157, 9572.808543829084, 4993.850184494471, 5012.674176178537, 5023.14606516893, 5213.139831927765, 5251.868703282448, 3793.089658482, 3917.314469967744, 3922.5292964844402, 3931.941020884689, 3940.862069827542, 594.551113908996, 648.6594537867658, 659.7394029358377, 683.3576438148224, 711.370437163104, 635.3709827270968, 648.8150877000032, 750.8047907544078, 818.8740481837355, 836.8283513888692, 10398.294022101993, 10539.84190237147, 10680.198985076151, 10807.621733239299, 10936.234463587472, 661.5931202310544, 686.5057158845723, 730.4156674660251, 739.8631253218172, 760.2739383888373, 4641.976790116028, 4705.858824235278, 4739.644526660445, 4756.571908528249, 4835.371401717754, 130971.0, 134076.0, 135012.3249383747, 135651.0, 139209.0, 110584.44707776571, 115824.42087789507, 116067.41966290114, 116703.0, 120437.0, 81631.5918420416, 101840.0, 104912.0, 105684.47157764315, 109728.4513577427, 9717.222622190066, 9759.707208783748, 9916.008399160026, 10127.442990635585, 10559.947200263949, 55801.44198557962, 56006.719966399905, 56502.71748641231, 56905.14642280286, 57303.0, 76656.0, 83038.58480707557, 84307.57846210976, 84756.15243847482, 86984.56507717421, 13313.66715832112, 13628.250446225553, 13842.238676872868, 13887.652808679864, 14113.717725645634, 15890.205489725236, 15971.840281597035, 16444.917775411046, 16899.155042248083, 17220.397286094925, 12732.809007864704, 13045.217286962812, 13090.0, 13270.734585308433, 13757.79363309531, 13667.17996920188, 14180.503682371067, 14276.42894284217, 15023.248837558296, 15230.314635841636, 13766.93116534451, 14024.439022438988, 14055.367508461939, 14173.0, 14471.421143154155, 12106.878931210575, 12133.272003679807, 12237.571684990982, 12346.3826808661, 12560.74878502443, 11664.358460284766, 11681.941590291994, 11701.0, 11804.468798903898, 11980.820287695517], "value": 8008.838779548592, "unit": "score"}], "name": "dromaeo_css", "value": 7582.222752133716, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 120.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 121.0, 146.0, 181.0, 117.0, 149.0, 149.0, 117.0, 185.0, 116.0], "value": 142.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 160.0, 161.0, 160.0, 160.0, 160.0, 162.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 76.0, 187.0, 76.0, 74.0, 74.0, 74.0, 162.0, 75.0], "value": 95.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 166.0, 83.0, 82.0, 83.0, 151.0, 82.0, 83.0, 140.0], "value": 103.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 118.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 58.0, 50.0, 50.0, 50.0, 51.0, 51.0, 49.0, 53.0, 50.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 59.0, 60.0, 60.0, 59.0, 58.0, 60.0, 61.0, 59.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 73.0, 74.0, 77.0, 81.0, 72.0, 77.0, 71.0, 73.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 120.0, 117.0, 117.0, 115.0, 126.0, 116.0, 114.0, 201.0, 122.0], "value": 128.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 151.0, 153.0, 153.0, 154.0, 154.0, 154.0, 151.0, 154.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 62.0, 62.0, 61.0, 61.0, 61.0, 61.0, 60.0, 60.0], "value": 61.4, "unit": "score"}], "name": "kraken", "value": 1498.2, "alertThreshold": 2.0}]} 14:25:27 INFO - Return code: 0 14:25:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:25:27 INFO - # TBPL SUCCESS # 14:25:27 INFO - Running post-action listener: _resource_record_post_action 14:25:27 INFO - [mozharness: 2016-09-09 21:25:27.138446Z] Finished run-tests step (success) 14:25:27 INFO - Running post-run listener: _resource_record_post_run 14:25:27 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 0; Write bytes: 325918720; Read time: 0; Write time: 336100 14:25:27 INFO - TinderboxPrint: CPU usage
13.0% 14:25:27 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 14:25:27 INFO - TinderboxPrint: I/O write bytes / time
325,918,720 / 336,100 14:25:27 INFO - TinderboxPrint: CPU idle
8,280.3 (87.0%) 14:25:27 INFO - TinderboxPrint: CPU user
1,183.6 (12.4%) 14:25:27 INFO - TinderboxPrint: Swap in / out
0 / 0 14:25:27 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78569472; Read time: 0; Write time: 121896 14:25:27 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 246321152; Read time: 0; Write time: 214108 14:25:27 INFO - Running post-run listener: _upload_blobber_files 14:25:27 INFO - Blob upload gear active. 14:25:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:25:27 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 14:25:27 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:25:27 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:25:28 INFO - (blobuploader) - INFO - Open directory for files ... 14:25:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:25:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:25:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:25:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:25:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:25:29 INFO - (blobuploader) - INFO - Done attempting. 14:25:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:25:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:25:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:25:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:25:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:25:41 INFO - (blobuploader) - INFO - Done attempting. 14:25:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:25:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:25:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:25:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:25:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:25:42 INFO - (blobuploader) - INFO - Done attempting. 14:25:42 INFO - (blobuploader) - INFO - Iteration through files over. 14:25:42 INFO - Return code: 0 14:25:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:25:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:25:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e00ee280ac01cfbeaab7e45a544444c87b5c0803262a85ceebc2d1f910f0a0eca85d1fd46222bf0c7ea132dbf579155484efd89130a6da40c1af68ac2b0b9364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/682d1fd8209fcaef37379bd62061ebc946b5e1a27e4c6b5c96bf5af40adc58dfce7ac1f3183907fa1a7d10af4c7b3dd47c8568abe82c8ec01f1195af5ffb8f72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3eccc8b030cdb710f3dffbad225c547061f9925b9e72db93b76fbd7db3d12b3bf66a0d239c977965780c2e00e2108b2497289fd0aec4fe16ccfec31415c4e920"} 14:25:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:25:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:25:42 INFO - Contents: 14:25:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e00ee280ac01cfbeaab7e45a544444c87b5c0803262a85ceebc2d1f910f0a0eca85d1fd46222bf0c7ea132dbf579155484efd89130a6da40c1af68ac2b0b9364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/682d1fd8209fcaef37379bd62061ebc946b5e1a27e4c6b5c96bf5af40adc58dfce7ac1f3183907fa1a7d10af4c7b3dd47c8568abe82c8ec01f1195af5ffb8f72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3eccc8b030cdb710f3dffbad225c547061f9925b9e72db93b76fbd7db3d12b3bf66a0d239c977965780c2e00e2108b2497289fd0aec4fe16ccfec31415c4e920"} 14:25:42 INFO - Running post-run listener: copy_logs_to_upload_dir 14:25:42 INFO - Copying logs to upload dir... 14:25:42 INFO - mkdir: /builds/slave/test/build/upload/logs 14:25:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1688.251019 ========= master_lag: 0.37 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 8 secs) (at 2016-09-09 14:25:42.046521) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 14:25:42.054372) ========= 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=eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254 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-Aurora/sha512/e00ee280ac01cfbeaab7e45a544444c87b5c0803262a85ceebc2d1f910f0a0eca85d1fd46222bf0c7ea132dbf579155484efd89130a6da40c1af68ac2b0b9364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/682d1fd8209fcaef37379bd62061ebc946b5e1a27e4c6b5c96bf5af40adc58dfce7ac1f3183907fa1a7d10af4c7b3dd47c8568abe82c8ec01f1195af5ffb8f72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3eccc8b030cdb710f3dffbad225c547061f9925b9e72db93b76fbd7db3d12b3bf66a0d239c977965780c2e00e2108b2497289fd0aec4fe16ccfec31415c4e920"} build_url:https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/ZLr8DDnyQH-TysTbJlaReQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e00ee280ac01cfbeaab7e45a544444c87b5c0803262a85ceebc2d1f910f0a0eca85d1fd46222bf0c7ea132dbf579155484efd89130a6da40c1af68ac2b0b9364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/682d1fd8209fcaef37379bd62061ebc946b5e1a27e4c6b5c96bf5af40adc58dfce7ac1f3183907fa1a7d10af4c7b3dd47c8568abe82c8ec01f1195af5ffb8f72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3eccc8b030cdb710f3dffbad225c547061f9925b9e72db93b76fbd7db3d12b3bf66a0d239c977965780c2e00e2108b2497289fd0aec4fe16ccfec31415c4e920"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 14:25:42.082266) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 14:25:42.082602) ========= 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=eca2ab57546de7b41f256b19000001ef-1473453692.435552-855204254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004512 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 14:25:42.131923) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 14:25:42.132248) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 14:25:42.132527) ========= ========= Total master_lag: 0.51 =========