builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-014 starttime: 1476139575.0 results: success (0) buildid: 20161010132824 builduid: 6e8b1014dcfe43768723ecbb1981f197 revision: 9f5ef22fc76fcfe7cdd48ab0e6cf8733e4279f30 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:14.996629) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:14.997093) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:14.997447) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:15.020549) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:15.020875) ========= 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=31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-10 15:46:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-10-10 15:46:15 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105371 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:15.140852) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:15.141179) ========= 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=31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018395 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:15.190390) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:15.190747) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9f5ef22fc76fcfe7cdd48ab0e6cf8733e4279f30 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9f5ef22fc76fcfe7cdd48ab0e6cf8733e4279f30 --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=31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-10 15:46:15,793 truncating revision to first 12 chars 2016-10-10 15:46:15,793 Setting DEBUG logging. 2016-10-10 15:46:15,793 attempt 1/10 2016-10-10 15:46:15,793 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9f5ef22fc76f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-10 15:46:16,119 unpacking tar archive at: mozilla-beta-9f5ef22fc76f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.634680 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:15.838820) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:15.839126) ========= script_repo_revision: 9f5ef22fc76fcfe7cdd48ab0e6cf8733e4279f30 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:15.839518) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:15.839794) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 15:46:15.850048) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-10 15:46:15.850348) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:46:16 INFO - MultiFileLogger online at 20161010 15:46:16 in /builds/slave/test 15:46:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:46:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:46:16 INFO - {'append_to_log': False, 15:46:16 INFO - 'base_work_dir': '/builds/slave/test', 15:46:16 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:46:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:46:16 INFO - 'branch': 'Mozilla-Beta', 15:46:16 INFO - 'buildbot_json_path': 'buildprops.json', 15:46:16 INFO - 'config_files': ('talos/linux_config.py',), 15:46:16 INFO - 'default_actions': ('clobber', 15:46:16 INFO - 'read-buildbot-config', 15:46:16 INFO - 'download-and-extract', 15:46:16 INFO - 'populate-webroot', 15:46:16 INFO - 'create-virtualenv', 15:46:16 INFO - 'install', 15:46:16 INFO - 'run-tests'), 15:46:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:46:16 INFO - 'download_minidump_stackwalk': True, 15:46:16 INFO - 'download_symbols': 'ondemand', 15:46:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:46:16 INFO - 'tooltool.py': '/tools/tooltool.py', 15:46:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:46:16 INFO - '/tools/misc-python/virtualenv.py')}, 15:46:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:46:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:46:16 INFO - 'installer_path': 'installer.exe', 15:46:16 INFO - 'log_level': 'info', 15:46:16 INFO - 'log_name': 'talos', 15:46:16 INFO - 'log_to_console': True, 15:46:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:46:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:46:16 INFO - 'opt_config_files': (), 15:46:16 INFO - 'pip_index': False, 15:46:16 INFO - 'sps_profile': False, 15:46:16 INFO - 'sps_profile_interval': 0, 15:46:16 INFO - 'suite': 'dromaeojs', 15:46:16 INFO - 'system_bits': '32', 15:46:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:46:16 INFO - 'title': 'talos-linux64-ix-014', 15:46:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:46:16 INFO - 'use_talos_json': True, 15:46:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:46:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:46:16 INFO - 'work_dir': 'build'} 15:46:16 INFO - [mozharness: 2016-10-10 22:46:16.550064Z] Running clobber step. 15:46:16 INFO - Running pre-action listener: _resource_record_pre_action 15:46:16 INFO - Running main action method: clobber 15:46:16 INFO - rmtree: /builds/slave/test/build 15:46:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:46:17 INFO - Running post-action listener: _resource_record_post_action 15:46:17 INFO - [mozharness: 2016-10-10 22:46:17.531396Z] Finished clobber step (success) 15:46:17 INFO - [mozharness: 2016-10-10 22:46:17.531496Z] Running read-buildbot-config step. 15:46:17 INFO - Running pre-action listener: _resource_record_pre_action 15:46:17 INFO - Running main action method: read_buildbot_config 15:46:17 INFO - Using buildbot properties: 15:46:17 INFO - { 15:46:17 INFO - "project": "", 15:46:17 INFO - "product": "firefox", 15:46:17 INFO - "script_repo_revision": "production", 15:46:17 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:46:17 INFO - "repository": "", 15:46:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:46:17 INFO - "buildid": "20161010132824", 15:46:17 INFO - "pgo_build": "True", 15:46:17 INFO - "basedir": "/builds/slave/test", 15:46:17 INFO - "buildnumber": 271, 15:46:17 INFO - "slavename": "talos-linux64-ix-014", 15:46:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:46:17 INFO - "platform": "ubuntu64_hw", 15:46:17 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:46:17 INFO - "branch": "mozilla-beta", 15:46:17 INFO - "repo_path": "releases/mozilla-beta", 15:46:17 INFO - "revision": "9f5ef22fc76fcfe7cdd48ab0e6cf8733e4279f30", 15:46:17 INFO - "stage_platform": "linux64-pgo", 15:46:17 INFO - "builduid": "6e8b1014dcfe43768723ecbb1981f197", 15:46:17 INFO - "slavebuilddir": "test" 15:46:17 INFO - } 15:46:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 15:46:17 INFO - Running post-action listener: _resource_record_post_action 15:46:17 INFO - [mozharness: 2016-10-10 22:46:17.533930Z] Finished read-buildbot-config step (success) 15:46:17 INFO - [mozharness: 2016-10-10 22:46:17.534019Z] Running download-and-extract step. 15:46:17 INFO - Running pre-action listener: _resource_record_pre_action 15:46:17 INFO - Running main action method: download_and_extract 15:46:17 INFO - mkdir: /builds/slave/test/build/tests 15:46:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:46:17 INFO - https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:46:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:46:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:46:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:46:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:46:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:46:17 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:46:17 INFO - retry: Failed, sleeping 30 seconds before retrying 15:46:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 15:46:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:46:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:46:47 INFO - retry: Failed, sleeping 60 seconds before retrying 15:47:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 15:47:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:47:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:47:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 15:47:47 INFO - Caught exception: HTTP Error 404: Not Found 15:47:47 INFO - Caught exception: HTTP Error 404: Not Found 15:47:47 INFO - Caught exception: HTTP Error 404: Not Found 15:47:47 INFO - trying https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:47:47 INFO - Downloading https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:47:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:47:48 INFO - Downloaded 1327 bytes. 15:47:48 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:47:48 INFO - Using the following test package requirements: 15:47:48 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:47:48 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:47:48 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:47:48 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:47:48 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 15:47:48 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:47:48 INFO - u'jsshell-linux-x86_64.zip'], 15:47:48 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:47:48 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:47:48 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:47:48 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:47:48 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 15:47:48 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:47:48 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 15:47:48 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:47:48 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:47:48 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:47:48 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:47:48 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:47:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:48 INFO - https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:47:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:47:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:47:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:47:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:47:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:47:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:47:48 INFO - retry: Failed, sleeping 30 seconds before retrying 15:48:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:48:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:48:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:48:18 INFO - retry: Failed, sleeping 60 seconds before retrying 15:49:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:49:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:49:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:49:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 15:49:18 INFO - Caught exception: HTTP Error 404: Not Found 15:49:18 INFO - Caught exception: HTTP Error 404: Not Found 15:49:18 INFO - Caught exception: HTTP Error 404: Not Found 15:49:18 INFO - trying https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:49:18 INFO - Downloading https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:49:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:49:19 INFO - Downloaded 21773377 bytes. 15:49:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:49:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:49:20 INFO - Return code: 0 15:49:20 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:49:20 INFO - https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:49:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:49:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:49:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:49:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:49:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:49:20 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:49:20 INFO - retry: Failed, sleeping 30 seconds before retrying 15:49:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 15:49:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:49:50 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:49:50 INFO - retry: Failed, sleeping 60 seconds before retrying 15:50:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 15:50:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:50:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:50:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 15:50:50 INFO - Caught exception: HTTP Error 404: Not Found 15:50:50 INFO - Caught exception: HTTP Error 404: Not Found 15:50:50 INFO - Caught exception: HTTP Error 404: Not Found 15:50:50 INFO - trying https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:50:50 INFO - Downloading https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:50:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:50:51 INFO - Downloaded 21773377 bytes. 15:50:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:50:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:50:52 INFO - Return code: 0 15:50: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')]} 15:50:52 INFO - https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:50:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:50:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:50:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:50:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:50:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:50:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:50:52 INFO - retry: Failed, sleeping 30 seconds before retrying 15:51:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 15:51:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:51:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:51:22 INFO - retry: Failed, sleeping 60 seconds before retrying 15:52:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 15:52:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:52:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:52:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 15:52:22 INFO - Caught exception: HTTP Error 404: Not Found 15:52:22 INFO - Caught exception: HTTP Error 404: Not Found 15:52:22 INFO - Caught exception: HTTP Error 404: Not Found 15:52:22 INFO - trying https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:52:22 INFO - Downloading https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:52:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:52:23 INFO - Downloaded 14413740 bytes. 15:52:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:52:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:52:23 INFO - Return code: 0 15:52: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')]} 15:52:23 INFO - https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:52:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:52:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:52:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:52:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:52:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:52:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:52:23 INFO - retry: Failed, sleeping 30 seconds before retrying 15:52:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 15:52:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:52:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:52:53 INFO - retry: Failed, sleeping 60 seconds before retrying 15:53:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 15:53:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:53:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:53:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 15:53:53 INFO - Caught exception: HTTP Error 404: Not Found 15:53:53 INFO - Caught exception: HTTP Error 404: Not Found 15:53:53 INFO - Caught exception: HTTP Error 404: Not Found 15:53:53 INFO - trying https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:53:53 INFO - Downloading https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:53:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:53:55 INFO - Downloaded 56813478 bytes. 15:53:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:53:55 INFO - mkdir: /builds/slave/test/properties 15:53:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:53:55 INFO - Writing to file /builds/slave/test/properties/build_url 15:53:55 INFO - Contents: 15:53:55 INFO - build_url:https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:53:55 INFO - Running post-action listener: _resource_record_post_action 15:53:55 INFO - Running post-action listener: set_extra_try_arguments 15:53:55 INFO - [mozharness: 2016-10-10 22:53:55.737132Z] Finished download-and-extract step (success) 15:53:55 INFO - [mozharness: 2016-10-10 22:53:55.737397Z] Running populate-webroot step. 15:53:55 INFO - Running pre-action listener: _resource_record_pre_action 15:53:55 INFO - Running main action method: populate_webroot 15:53:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:53:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:53:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:53:55 INFO - '--tppagecycles', 15:53:55 INFO - '7'], 15:53:55 INFO - 'tests': ['tsvgm']}}, 15:53:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:53:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:53:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:53:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:53:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:53:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:53:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:55 INFO - 'talos_options': ['--disable-e10s'], 15:53:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:53:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:53:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:55 INFO - 'talos_options': ['--disable-e10s'], 15:53:55 INFO - 'tests': ['damp', 'tps']}, 15:53:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:55 INFO - 'tests': ['damp', 'tps']}, 15:53:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:53:55 INFO - 'tests': ['dromaeo_dom']}, 15:53:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:53:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:53:55 INFO - 'tests': ['basic_compositor_video']}, 15:53:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:53:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:53:55 INFO - 'tests': ['a11yr', 15:53:55 INFO - 'ts_paint', 15:53:55 INFO - 'tpaint', 15:53:55 INFO - 'sessionrestore', 15:53:55 INFO - 'sessionrestore_no_auto_restore', 15:53:55 INFO - 'tabpaint']}, 15:53:55 INFO - 'other-e10s': {'tests': ['a11yr', 15:53:55 INFO - 'ts_paint', 15:53:55 INFO - 'tpaint', 15:53:55 INFO - 'sessionrestore', 15:53:55 INFO - 'sessionrestore_no_auto_restore', 15:53:55 INFO - 'tabpaint']}, 15:53:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:53:55 INFO - 'ts_paint', 15:53:55 INFO - 'tpaint', 15:53:55 INFO - 'sessionrestore', 15:53:55 INFO - 'sessionrestore_no_auto_restore', 15:53:55 INFO - 'tabpaint']}, 15:53:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:53:55 INFO - 'ts_paint', 15:53:55 INFO - 'tpaint', 15:53:55 INFO - 'sessionrestore', 15:53:55 INFO - 'sessionrestore_no_auto_restore', 15:53:55 INFO - 'tabpaint']}, 15:53:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:53:55 INFO - 'tests': ['a11yr', 15:53:55 INFO - 'ts_paint', 15:53:55 INFO - 'tpaint', 15:53:55 INFO - 'sessionrestore', 15:53:55 INFO - 'sessionrestore_no_auto_restore', 15:53:55 INFO - 'tabpaint']}, 15:53:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:53:55 INFO - 'tests': ['a11yr', 15:53:55 INFO - 'ts_paint', 15:53:55 INFO - 'tpaint', 15:53:55 INFO - 'sessionrestore', 15:53:55 INFO - 'sessionrestore_no_auto_restore', 15:53:55 INFO - 'tabpaint']}, 15:53:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:53:55 INFO - 'tests': ['tsvgx', 15:53:55 INFO - 'tsvgr_opacity', 15:53:55 INFO - 'tart', 15:53:55 INFO - 'tscrollx', 15:53:55 INFO - 'cart']}, 15:53:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:53:55 INFO - 'tsvgr_opacity', 15:53:55 INFO - 'tart', 15:53:55 INFO - 'tscrollx', 15:53:55 INFO - 'cart']}, 15:53:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:55 INFO - 'talos_options': ['--disable-e10s'], 15:53:55 INFO - 'tests': ['tp5o']}, 15:53:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:55 INFO - 'tests': ['tp5o']}, 15:53:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:55 INFO - 'talos_options': ['--disable-e10s', 15:53:55 INFO - '--xperf_path', 15:53:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:53:55 INFO - 'tests': ['tp5n']}, 15:53:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:53:55 INFO - 'talos_options': ['--xperf_path', 15:53:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:53:55 INFO - 'tests': ['tp5n']}}, 15:53:55 INFO - 'talos.zip': {'path': '', 15:53:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:53:55 INFO - Running post-action listener: _resource_record_post_action 15:53:55 INFO - [mozharness: 2016-10-10 22:53:55.753239Z] Finished populate-webroot step (success) 15:53:55 INFO - [mozharness: 2016-10-10 22:53:55.753328Z] Running create-virtualenv step. 15:53:55 INFO - Running pre-action listener: _resource_record_pre_action 15:53:55 INFO - Running main action method: create_virtualenv 15:53:55 INFO - Creating virtualenv /builds/slave/test/build/venv 15:53:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:53:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:53:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:53:55 INFO - Using real prefix '/usr' 15:53:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:53:56 INFO - Installing distribute.............................................................................................................................................................................................done. 15:53:58 INFO - Installing pip.................done. 15:53:58 INFO - Return code: 0 15:53:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:58 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 0x1f06030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c53b0>, '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 0x21c62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c6740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:53:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:53:58 INFO - Using env: {'DISPLAY': ':0', 15:53:58 INFO - 'HOME': '/home/cltbld', 15:53:58 INFO - 'LANG': 'en_US.UTF-8', 15:53:58 INFO - 'LANGUAGE': 'en_US:en', 15:53:58 INFO - 'LOGNAME': 'cltbld', 15:53:58 INFO - 'MAIL': '/var/mail/cltbld', 15:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:58 INFO - 'MOZ_NO_REMOTE': '1', 15:53:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:53:58 INFO - 'NO_EM_RESTART': '1', 15:53:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:58 INFO - 'PWD': '/builds/slave/test', 15:53:58 INFO - 'SHELL': '/bin/bash', 15:53:58 INFO - 'SHLVL': '1', 15:53:58 INFO - 'TERM': 'linux', 15:53:58 INFO - 'TMOUT': '86400', 15:53:58 INFO - 'USER': 'cltbld', 15:53:58 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 15:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:58 INFO - '_': '/tools/buildbot/bin/python'} 15:53:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:53:58 INFO - Cleaning up... 15:53:58 INFO - Return code: 0 15:53:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:53:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:53:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:53:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:53:58 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 0x1f06030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c53b0>, '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 0x21c62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c6740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:53:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:53:58 INFO - Using env: {'DISPLAY': ':0', 15:53:58 INFO - 'HOME': '/home/cltbld', 15:53:58 INFO - 'LANG': 'en_US.UTF-8', 15:53:58 INFO - 'LANGUAGE': 'en_US:en', 15:53:58 INFO - 'LOGNAME': 'cltbld', 15:53:58 INFO - 'MAIL': '/var/mail/cltbld', 15:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:58 INFO - 'MOZ_NO_REMOTE': '1', 15:53:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:53:58 INFO - 'NO_EM_RESTART': '1', 15:53:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:58 INFO - 'PWD': '/builds/slave/test', 15:53:58 INFO - 'SHELL': '/bin/bash', 15:53:58 INFO - 'SHLVL': '1', 15:53:58 INFO - 'TERM': 'linux', 15:53:58 INFO - 'TMOUT': '86400', 15:53:58 INFO - 'USER': 'cltbld', 15:53:58 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 15:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:58 INFO - '_': '/tools/buildbot/bin/python'} 15:53:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:53:58 INFO - Downloading/unpacking psutil>=3.1.1 15:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:53:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:53:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:54:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:00 INFO - Installing collected packages: psutil 15:54:00 INFO - Running setup.py install for psutil 15:54:00 INFO - building 'psutil._psutil_linux' extension 15:54:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:54:01 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:54:01 INFO - building 'psutil._psutil_posix' extension 15:54:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:54:01 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:54:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:01 INFO - Successfully installed psutil 15:54:01 INFO - Cleaning up... 15:54:01 INFO - Return code: 0 15:54:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f06030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c53b0>, '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 0x21c62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c6740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:54:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:54:01 INFO - Using env: {'DISPLAY': ':0', 15:54:01 INFO - 'HOME': '/home/cltbld', 15:54:01 INFO - 'LANG': 'en_US.UTF-8', 15:54:01 INFO - 'LANGUAGE': 'en_US:en', 15:54:01 INFO - 'LOGNAME': 'cltbld', 15:54:01 INFO - 'MAIL': '/var/mail/cltbld', 15:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:01 INFO - 'MOZ_NO_REMOTE': '1', 15:54:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:01 INFO - 'NO_EM_RESTART': '1', 15:54:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:01 INFO - 'PWD': '/builds/slave/test', 15:54:01 INFO - 'SHELL': '/bin/bash', 15:54:01 INFO - 'SHLVL': '1', 15:54:01 INFO - 'TERM': 'linux', 15:54:01 INFO - 'TMOUT': '86400', 15:54:01 INFO - 'USER': 'cltbld', 15:54:01 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 15:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:01 INFO - '_': '/tools/buildbot/bin/python'} 15:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:54:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:54:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:54:03 INFO - Installing collected packages: mozsystemmonitor 15:54:03 INFO - Running setup.py install for mozsystemmonitor 15:54:03 INFO - Successfully installed mozsystemmonitor 15:54:03 INFO - Cleaning up... 15:54:03 INFO - Return code: 0 15:54:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:03 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 0x1f06030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c53b0>, '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 0x21c62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c6740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:54:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:54:03 INFO - Using env: {'DISPLAY': ':0', 15:54:03 INFO - 'HOME': '/home/cltbld', 15:54:03 INFO - 'LANG': 'en_US.UTF-8', 15:54:03 INFO - 'LANGUAGE': 'en_US:en', 15:54:03 INFO - 'LOGNAME': 'cltbld', 15:54:03 INFO - 'MAIL': '/var/mail/cltbld', 15:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:03 INFO - 'MOZ_NO_REMOTE': '1', 15:54:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:03 INFO - 'NO_EM_RESTART': '1', 15:54:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:03 INFO - 'PWD': '/builds/slave/test', 15:54:03 INFO - 'SHELL': '/bin/bash', 15:54:03 INFO - 'SHLVL': '1', 15:54:03 INFO - 'TERM': 'linux', 15:54:03 INFO - 'TMOUT': '86400', 15:54:03 INFO - 'USER': 'cltbld', 15:54:03 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 15:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:03 INFO - '_': '/tools/buildbot/bin/python'} 15:54:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:03 INFO - Downloading/unpacking jsonschema==2.5.1 15:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:54:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:05 INFO - Downloading functools32-3.2.3-2.tar.gz 15:54:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:54:06 INFO - warning: no files found matching '*.txt' 15:54:06 INFO - no previously-included directories found matching 'build' 15:54:06 INFO - no previously-included directories found matching 'dist' 15:54:06 INFO - no previously-included directories found matching '.git*' 15:54:06 INFO - Installing collected packages: jsonschema, functools32 15:54:06 INFO - Running setup.py install for functools32 15:54:06 INFO - warning: no files found matching '*.txt' 15:54:06 INFO - no previously-included directories found matching 'build' 15:54:06 INFO - no previously-included directories found matching 'dist' 15:54:06 INFO - no previously-included directories found matching '.git*' 15:54:06 INFO - Successfully installed jsonschema functools32 15:54:06 INFO - Cleaning up... 15:54:06 INFO - Return code: 0 15:54:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:06 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 0x1f06030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c53b0>, '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 0x21c62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c6740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:54:06 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 15:54:06 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 15:54:06 INFO - Using env: {'DISPLAY': ':0', 15:54:06 INFO - 'HOME': '/home/cltbld', 15:54:06 INFO - 'LANG': 'en_US.UTF-8', 15:54:06 INFO - 'LANGUAGE': 'en_US:en', 15:54:06 INFO - 'LOGNAME': 'cltbld', 15:54:06 INFO - 'MAIL': '/var/mail/cltbld', 15:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:06 INFO - 'MOZ_NO_REMOTE': '1', 15:54:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:06 INFO - 'NO_EM_RESTART': '1', 15:54:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:06 INFO - 'PWD': '/builds/slave/test', 15:54:06 INFO - 'SHELL': '/bin/bash', 15:54:06 INFO - 'SHLVL': '1', 15:54:06 INFO - 'TERM': 'linux', 15:54:06 INFO - 'TMOUT': '86400', 15:54:06 INFO - 'USER': 'cltbld', 15:54:06 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 15:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:06 INFO - '_': '/tools/buildbot/bin/python'} 15:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:54:06 INFO - Cleaning up... 15:54:06 INFO - Return code: 0 15:54:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:06 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 0x1f06030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c53b0>, '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 0x21c62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c6740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:54:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:54:06 INFO - Using env: {'DISPLAY': ':0', 15:54:06 INFO - 'HOME': '/home/cltbld', 15:54:06 INFO - 'LANG': 'en_US.UTF-8', 15:54:06 INFO - 'LANGUAGE': 'en_US:en', 15:54:06 INFO - 'LOGNAME': 'cltbld', 15:54:06 INFO - 'MAIL': '/var/mail/cltbld', 15:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:06 INFO - 'MOZ_NO_REMOTE': '1', 15:54:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:06 INFO - 'NO_EM_RESTART': '1', 15:54:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:06 INFO - 'PWD': '/builds/slave/test', 15:54:06 INFO - 'SHELL': '/bin/bash', 15:54:06 INFO - 'SHLVL': '1', 15:54:06 INFO - 'TERM': 'linux', 15:54:06 INFO - 'TMOUT': '86400', 15:54:06 INFO - 'USER': 'cltbld', 15:54:06 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 15:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:06 INFO - '_': '/tools/buildbot/bin/python'} 15:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:06 INFO - Downloading/unpacking blobuploader==1.2.4 15:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:08 INFO - Downloading blobuploader-1.2.4.tar.gz 15:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:54:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:54:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:09 INFO - Downloading docopt-0.6.1.tar.gz 15:54:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:54:09 INFO - Installing collected packages: blobuploader, requests, docopt 15:54:09 INFO - Running setup.py install for blobuploader 15:54:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:54:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:54:09 INFO - Running setup.py install for requests 15:54:09 INFO - Running setup.py install for docopt 15:54:09 INFO - Successfully installed blobuploader requests docopt 15:54:09 INFO - Cleaning up... 15:54:09 INFO - Return code: 0 15:54:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:09 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 0x1f06030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c53b0>, '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 0x21c62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c6740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:54:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:54:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:54:09 INFO - Using env: {'DISPLAY': ':0', 15:54:09 INFO - 'HOME': '/home/cltbld', 15:54:09 INFO - 'LANG': 'en_US.UTF-8', 15:54:09 INFO - 'LANGUAGE': 'en_US:en', 15:54:09 INFO - 'LOGNAME': 'cltbld', 15:54:09 INFO - 'MAIL': '/var/mail/cltbld', 15:54:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:09 INFO - 'MOZ_NO_REMOTE': '1', 15:54:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:09 INFO - 'NO_EM_RESTART': '1', 15:54:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:09 INFO - 'PWD': '/builds/slave/test', 15:54:09 INFO - 'SHELL': '/bin/bash', 15:54:09 INFO - 'SHLVL': '1', 15:54:09 INFO - 'TERM': 'linux', 15:54:09 INFO - 'TMOUT': '86400', 15:54:09 INFO - 'USER': 'cltbld', 15:54:09 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 15:54:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:09 INFO - '_': '/tools/buildbot/bin/python'} 15:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:54:10 INFO - Running setup.py (path:/tmp/pip-3VPlgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:54:10 INFO - Running setup.py (path:/tmp/pip-glB8ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:54:10 INFO - Running setup.py (path:/tmp/pip-0f8TCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:54:10 INFO - Running setup.py (path:/tmp/pip-Egbd5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:54:10 INFO - Running setup.py (path:/tmp/pip-Xd8eyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:54:10 INFO - Running setup.py (path:/tmp/pip-u0jpyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:54:10 INFO - Running setup.py (path:/tmp/pip-TSiWuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:54:10 INFO - Running setup.py (path:/tmp/pip-b9sin9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:54:10 INFO - Running setup.py (path:/tmp/pip-fy2jvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:54:10 INFO - Running setup.py (path:/tmp/pip-qT40cf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:54:10 INFO - Running setup.py (path:/tmp/pip-_0hcSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:54:10 INFO - Running setup.py (path:/tmp/pip-lTlqzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:54:11 INFO - Running setup.py (path:/tmp/pip-CrCd__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:54:11 INFO - Running setup.py (path:/tmp/pip-YOy63B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:11 INFO - Running setup.py (path:/tmp/pip-5NWITU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:54:11 INFO - Running setup.py (path:/tmp/pip-5KTbtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:54:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:54:11 INFO - Running setup.py (path:/tmp/pip-OkHaxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:54:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:54:11 INFO - Running setup.py install for manifestparser 15:54:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:54:11 INFO - Running setup.py install for mozcrash 15:54:11 INFO - Running setup.py install for mozdebug 15:54:11 INFO - Running setup.py install for mozdevice 15:54:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:54:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:54:11 INFO - Running setup.py install for mozfile 15:54:11 INFO - Running setup.py install for mozhttpd 15:54:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:54:12 INFO - Running setup.py install for mozinfo 15:54:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:54:12 INFO - Running setup.py install for mozInstall 15:54:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:54:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:54:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:54:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:54:12 INFO - Running setup.py install for mozleak 15:54:12 INFO - Running setup.py install for mozlog 15:54:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:54:12 INFO - Running setup.py install for moznetwork 15:54:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:54:12 INFO - Running setup.py install for mozprocess 15:54:12 INFO - Running setup.py install for mozprofile 15:54:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:54:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:54:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:54:12 INFO - Running setup.py install for mozrunner 15:54:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:54:12 INFO - Running setup.py install for mozscreenshot 15:54:13 INFO - Running setup.py install for moztest 15:54:13 INFO - Running setup.py install for mozversion 15:54:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:54:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:54:13 INFO - Cleaning up... 15:54:13 INFO - Return code: 0 15:54:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:13 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 0x1f06030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c53b0>, '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 0x21c62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c6740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:54:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:54:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:54:13 INFO - Using env: {'DISPLAY': ':0', 15:54:13 INFO - 'HOME': '/home/cltbld', 15:54:13 INFO - 'LANG': 'en_US.UTF-8', 15:54:13 INFO - 'LANGUAGE': 'en_US:en', 15:54:13 INFO - 'LOGNAME': 'cltbld', 15:54:13 INFO - 'MAIL': '/var/mail/cltbld', 15:54:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:13 INFO - 'MOZ_NO_REMOTE': '1', 15:54:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:13 INFO - 'NO_EM_RESTART': '1', 15:54:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:13 INFO - 'PWD': '/builds/slave/test', 15:54:13 INFO - 'SHELL': '/bin/bash', 15:54:13 INFO - 'SHLVL': '1', 15:54:13 INFO - 'TERM': 'linux', 15:54:13 INFO - 'TMOUT': '86400', 15:54:13 INFO - 'USER': 'cltbld', 15:54:13 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 15:54:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:13 INFO - '_': '/tools/buildbot/bin/python'} 15:54:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:54:13 INFO - Running setup.py (path:/tmp/pip-hEe8Fk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:54:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:54:13 INFO - Running setup.py (path:/tmp/pip-rNavq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:54:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:54:13 INFO - Running setup.py (path:/tmp/pip-agADdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:54:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:54:13 INFO - Running setup.py (path:/tmp/pip-C1eXtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:54:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:54:13 INFO - Running setup.py (path:/tmp/pip-1lnIuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:54:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:54:13 INFO - Running setup.py (path:/tmp/pip-S273ph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:54:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:54:13 INFO - Running setup.py (path:/tmp/pip-dEAiCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:54:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:54:13 INFO - Running setup.py (path:/tmp/pip-Uxtlur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:54:14 INFO - Running setup.py (path:/tmp/pip-jnRLW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:54:14 INFO - Running setup.py (path:/tmp/pip-DNDPVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:54:14 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)) 15:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:54:14 INFO - Running setup.py (path:/tmp/pip-CKrCnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:54:14 INFO - Running setup.py (path:/tmp/pip-7oT9q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:54:14 INFO - Running setup.py (path:/tmp/pip-Xv9eBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:54:14 INFO - Running setup.py (path:/tmp/pip-8lOApK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:14 INFO - Running setup.py (path:/tmp/pip-FWajv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:54:14 INFO - Running setup.py (path:/tmp/pip-m4yU7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:54:14 INFO - Running setup.py (path:/tmp/pip-J3DJB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:54:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:16 INFO - Downloading blessings-1.6.tar.gz 15:54:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:54:16 INFO - Installing collected packages: blessings 15:54:16 INFO - Running setup.py install for blessings 15:54:16 INFO - Successfully installed blessings 15:54:16 INFO - Cleaning up... 15:54:16 INFO - Return code: 0 15:54:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:54:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:54:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:54:17 INFO - Reading from file tmpfile_stdout 15:54:17 INFO - Current package versions: 15:54:17 INFO - argparse == 1.2.1 15:54:17 INFO - blessings == 1.6 15:54:17 INFO - blobuploader == 1.2.4 15:54:17 INFO - docopt == 0.6.1 15:54:17 INFO - functools32 == 3.2.3-2 15:54:17 INFO - jsonschema == 2.5.1 15:54:17 INFO - manifestparser == 1.1 15:54:17 INFO - mozInstall == 1.12 15:54:17 INFO - mozcrash == 0.17 15:54:17 INFO - mozdebug == 0.1 15:54:17 INFO - mozdevice == 0.48 15:54:17 INFO - mozfile == 1.2 15:54:17 INFO - mozhttpd == 0.7 15:54:17 INFO - mozinfo == 0.9 15:54:17 INFO - mozleak == 0.1 15:54:17 INFO - mozlog == 3.3 15:54:17 INFO - moznetwork == 0.27 15:54:17 INFO - mozprocess == 0.23 15:54:17 INFO - mozprofile == 0.28 15:54:17 INFO - mozrunner == 6.12 15:54:17 INFO - mozscreenshot == 0.1 15:54:17 INFO - mozsystemmonitor == 0.3 15:54:17 INFO - moztest == 0.7 15:54:17 INFO - mozversion == 1.4 15:54:17 INFO - psutil == 3.1.1 15:54:17 INFO - requests == 1.2.3 15:54:17 INFO - wsgiref == 0.1.2 15:54:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:17 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 0x1f06030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c53b0>, '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 0x21c62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c6740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:54:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:54:17 INFO - Using env: {'DISPLAY': ':0', 15:54:17 INFO - 'HOME': '/home/cltbld', 15:54:17 INFO - 'LANG': 'en_US.UTF-8', 15:54:17 INFO - 'LANGUAGE': 'en_US:en', 15:54:17 INFO - 'LOGNAME': 'cltbld', 15:54:17 INFO - 'MAIL': '/var/mail/cltbld', 15:54:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:17 INFO - 'MOZ_NO_REMOTE': '1', 15:54:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:17 INFO - 'NO_EM_RESTART': '1', 15:54:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:17 INFO - 'PWD': '/builds/slave/test', 15:54:17 INFO - 'SHELL': '/bin/bash', 15:54:17 INFO - 'SHLVL': '1', 15:54:17 INFO - 'TERM': 'linux', 15:54:17 INFO - 'TMOUT': '86400', 15:54:17 INFO - 'USER': 'cltbld', 15:54:17 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 15:54:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:17 INFO - '_': '/tools/buildbot/bin/python'} 15:54:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:54:17 INFO - Cleaning up... 15:54:17 INFO - Return code: 0 15:54:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:17 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 0x1f06030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c53b0>, '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 0x21c62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c6740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:54:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:54:17 INFO - Using env: {'DISPLAY': ':0', 15:54:17 INFO - 'HOME': '/home/cltbld', 15:54:17 INFO - 'LANG': 'en_US.UTF-8', 15:54:17 INFO - 'LANGUAGE': 'en_US:en', 15:54:17 INFO - 'LOGNAME': 'cltbld', 15:54:17 INFO - 'MAIL': '/var/mail/cltbld', 15:54:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:17 INFO - 'MOZ_NO_REMOTE': '1', 15:54:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:17 INFO - 'NO_EM_RESTART': '1', 15:54:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:17 INFO - 'PWD': '/builds/slave/test', 15:54:17 INFO - 'SHELL': '/bin/bash', 15:54:17 INFO - 'SHLVL': '1', 15:54:17 INFO - 'TERM': 'linux', 15:54:17 INFO - 'TMOUT': '86400', 15:54:17 INFO - 'USER': 'cltbld', 15:54:17 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 15:54:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:17 INFO - '_': '/tools/buildbot/bin/python'} 15:54:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:54:17 INFO - Cleaning up... 15:54:17 INFO - Return code: 0 15:54:17 INFO - Running post-action listener: _resource_record_post_action 15:54:17 INFO - Running post-action listener: _start_resource_monitoring 15:54:17 INFO - Starting resource monitoring. 15:54:17 INFO - [mozharness: 2016-10-10 22:54:17.532963Z] Finished create-virtualenv step (success) 15:54:17 INFO - [mozharness: 2016-10-10 22:54:17.533564Z] Running install step. 15:54:17 INFO - Running pre-action listener: _resource_record_pre_action 15:54:17 INFO - Running main action method: install 15:54:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:54:17 INFO - Reading from file tmpfile_stdout 15:54:17 INFO - Detecting whether we're running mozinstall >=1.0... 15:54:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:54:17 INFO - Reading from file tmpfile_stdout 15:54:17 INFO - Output received: 15:54:17 INFO - Usage: mozinstall [options] installer 15:54:17 INFO - Options: 15:54:17 INFO - -h, --help show this help message and exit 15:54:17 INFO - -d DEST, --destination=DEST 15:54:17 INFO - Directory to install application into. [default: 15:54:17 INFO - "/builds/slave/test"] 15:54:17 INFO - --app=APP Application being installed. [default: firefox] 15:54:17 INFO - mkdir: /builds/slave/test/build/application 15:54:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:54:31 INFO - Reading from file tmpfile_stdout 15:54:31 INFO - Output received: 15:54:31 INFO - /builds/slave/test/build/application/firefox/firefox 15:54:31 INFO - Running post-action listener: _resource_record_post_action 15:54:31 INFO - [mozharness: 2016-10-10 22:54:31.785189Z] Finished install step (success) 15:54:31 INFO - [mozharness: 2016-10-10 22:54:31.785540Z] Running run-tests step. 15:54:31 INFO - Running pre-action listener: _resource_record_pre_action 15:54:31 INFO - Running main action method: run_tests 15:54:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:54:31 INFO - Python 2.7.3 15:54:31 INFO - Return code: 0 15:54:31 INFO - grabbing minidump binary from tooltool 15:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:31 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 0x21c53b0>, '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 0x21c62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c6740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:54:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:54:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:54:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:54:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:54:31 INFO - Return code: 0 15:54:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:54:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:54:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:54:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:54:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:54:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:54:31 INFO - Using env: {'DISPLAY': ':0', 15:54:31 INFO - 'HOME': '/home/cltbld', 15:54:31 INFO - 'LANG': 'en_US.UTF-8', 15:54:31 INFO - 'LANGUAGE': 'en_US:en', 15:54:31 INFO - 'LOGNAME': 'cltbld', 15:54:31 INFO - 'MAIL': '/var/mail/cltbld', 15:54:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:54:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:31 INFO - 'MOZ_NO_REMOTE': '1', 15:54:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:54:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:54:31 INFO - 'NO_EM_RESTART': '1', 15:54:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:31 INFO - 'PWD': '/builds/slave/test', 15:54:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:54:31 INFO - 'SHELL': '/bin/bash', 15:54:31 INFO - 'SHLVL': '1', 15:54:31 INFO - 'TERM': 'linux', 15:54:31 INFO - 'TMOUT': '86400', 15:54:31 INFO - 'USER': 'cltbld', 15:54:31 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841', 15:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:31 INFO - '_': '/tools/buildbot/bin/python'} 15:54:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:54:32 INFO - mozversion application_buildid: 20161010132824 15:54:32 INFO - mozversion application_changeset: 9f5ef22fc76fcfe7cdd48ab0e6cf8733e4279f30 15:54:32 INFO - mozversion application_display_name: Firefox 15:54:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:54:32 INFO - mozversion application_name: Firefox 15:54:32 INFO - mozversion application_remotingname: firefox 15:54:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:54:32 INFO - mozversion application_vendor: Mozilla 15:54:32 INFO - mozversion application_version: 50.0 15:54:32 INFO - mozversion platform_buildid: 20161010132824 15:54:32 INFO - mozversion platform_changeset: 9f5ef22fc76fcfe7cdd48ab0e6cf8733e4279f30 15:54:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:54:32 INFO - mozversion platform_version: 50.0 15:54:32 INFO - using testdate: 1476140072 15:54:32 INFO - actual date: 1476140072 15:54:32 INFO - starting webserver on 'localhost:60088' 15:54:32 INFO - SUITE-START | Running 2 tests 15:54:32 INFO - TEST-START | dromaeo_css 15:54:32 INFO - operating with platform_type : linux_ 15:54:32 INFO - Initialising browser for dromaeo_css test... 15:54:32 INFO - TEST-INFO | started process 5557 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpB7LqCR/profile http://localhost:60088/getInfo.html) 15:54:35 INFO - PROCESS | 5557 | __metrics Screen width/height:1600/1200 15:54:35 INFO - PROCESS | 5557 | colorDepth:24 15:54:35 INFO - PROCESS | 5557 | Browser inner width/height: 1024/697 15:54:35 INFO - PROCESS | 5557 | __metrics 15:54:36 INFO - PROCESS | 5557 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:54:36 INFO - PROCESS | 5557 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:54:37 INFO - PROCESS | 5557 | console.error: PushService: 15:54:37 INFO - PROCESS | 5557 | stateChangeProcessEnqueue: Error transitioning state 15:54:37 INFO - PROCESS | 5557 | UnknownError 15:54:37 INFO - TEST-INFO | 5557: exit 0 15:54:37 INFO - Browser initialized. 15:54:37 INFO - Running cycle 1/1 for dromaeo_css test... 15:54:37 INFO - TEST-INFO | started process 5645 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpB7LqCR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:54:49 INFO - PROCESS | 5645 | RSS: Main: 144097280 15:54:49 INFO - PROCESS | 5645 | 15:55:40 INFO - PROCESS | 5645 | 1476140140566 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:57:48 INFO - PROCESS | 5645 | Cycle 1(1): loaded http://localhost:60088/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60088/tests/dromaeo/cssquery-ext.html) 15:57:49 INFO - PROCESS | 5645 | RSS: Main: 260796416 15:57:49 INFO - PROCESS | 5645 | 16:00:51 INFO - PROCESS | 5645 | Cycle 1(1): loaded http://localhost:60088/tests/dromaeo/cssquery-ext.html (next: http://localhost:60088/tests/dromaeo/cssquery-jquery.html) 16:00:52 INFO - PROCESS | 5645 | RSS: Main: 235749376 16:00:52 INFO - PROCESS | 5645 | 16:03:54 INFO - PROCESS | 5645 | Cycle 1(1): loaded http://localhost:60088/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60088/tests/dromaeo/cssquery-mootools.html) 16:03:55 INFO - PROCESS | 5645 | RSS: Main: 176607232 16:03:55 INFO - PROCESS | 5645 | 16:06:56 INFO - PROCESS | 5645 | Cycle 1(1): loaded http://localhost:60088/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60088/tests/dromaeo/cssquery-prototype.html) 16:06:57 INFO - PROCESS | 5645 | RSS: Main: 226004992 16:06:57 INFO - PROCESS | 5645 | 16:10:00 INFO - PROCESS | 5645 | Cycle 1(1): loaded http://localhost:60088/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60088/tests/dromaeo/cssquery-yui.html) 16:10:00 INFO - PROCESS | 5645 | RSS: Main: 173780992 16:10:00 INFO - PROCESS | 5645 | 16:13:03 INFO - PROCESS | 5645 | Cycle 1(1): loaded http://localhost:60088/tests/dromaeo/cssquery-yui.html (next: http://localhost:60088/tests/dromaeo/cssquery-dojo.html) 16:13:03 INFO - PROCESS | 5645 | RSS: Main: 249950208 16:13:03 INFO - PROCESS | 5645 | 16:13:03 INFO - PROCESS | 5645 | __start_tp_report 16:13:03 INFO - PROCESS | 5645 | _x_x_mozilla_page_load 16:13:03 INFO - PROCESS | 5645 | _x_x_mozilla_page_load_details 16:13:03 INFO - PROCESS | 5645 | |i|pagename|runs| 16:13:03 INFO - PROCESS | 5645 | |0;dojo.html;3192.0742984534486;3202.4075546023987;3235.3852767974067;3253.7071663550278;3269.313444176723;5517.862053448661;5617.887642247153;5619.072852979264;5626.521745651614;5641.492265696092;5606.495415412623;5627.6060675752715;5648.858778530539;5656.717164141797;5702.8289151325425;5084.77118529667;5102.668326558781;5138.922916156261;5159.871003224931;5170.586353091762;4969.751512424383;5029.597632189434;5169.043726910515;5179.119549676545;5181.896362072756;5027.648064635477;5035.068512325213;5071.467495912932;5131.7434128293435;5153.81961631343;5133.82031628893;5172.250023746555;5174.146265866128;5182.119039763249;5200.92198617021;4864.173090574611;5076.543111120017;5082.567981721558;5130.666506677091;5153.793848246066;5455.427180146107;5512.39363669996;5574.386817450078;5626.915596266059;5662.150677398382;5963.4334738199805;6000.909986350208;6010.519158467312;6014.248218972629;6017.217761690996;6025.698715064229;6036.728347224342;6065.909011364833;6069.271687397496;6070.21087258658;5406.135018397077;5498.285222920995;5569.275994120779;5633.014222511044;5637.80267690633;6114.357992410733;6120.663363515049;6124.908126378108;6140.785072522441;6140.815775526741;5838.386969368155;5838.970805145947;5884.764609415661;5897.793577224862;5903.321118071477;5787.88424231513;5862.824115276549;5885.764569417175;5894.941050589525;5961.195238642815;5882.117682347596;5917.704114794243;5925.40745925404;5935.406459354116;5942.732577034044;5887.175795388649;5912.320083190402;5926.940730592725;5951.613145145551;5957.910631340534;4446.444194475691;4530.909381812346;4562.247229207143;4562.543745625411;4598.931016034762;4093.2222877653153;4096.959030409717;4100.569440208795;4109.753414795122;4134.131832315187;5722.170285308607;5732.197492351074;5746.166805813134;5753.280839895013;5779.3642699303045;5816.941830581724;5887.911681324784;5932.49573786233;5974.910376344358;6069.180660610849;5713.657180569179;5715.142728590661;5715.514181294637;5716.256886604798;5716.914246286309;5161.509656582619;5189.195674670432;5215.556677682363;5223.973880130651;5240.921386179134;6019.849503762441;6115.240341084264;6127.662978536134;6141.271221159682;6249.6875156242;6016.2479690038745;6017.338092809699;6029.007488861511;6040.7583696651645;6072.119542666377;6216.564840461125;6222.719977601109;6224.06639004153;6232.314445411092;6234.220722409699;5704.885902281848;5773.1051686988585;5773.8556536086935;5796.884062318814;5855.063189889597;5940.287165540163;5945.048792193228;5947.434993675594;5948.048312270016;5954.285485741739;5801.448862358069;5827.184194212899;5857.765689372377;5859.44335288147;5864.794732184353;5652.604317697722;5732.140178973187;5843.211166492469;5846.912296315644;5937;5849;5852.853678657897;5853.502452291603;5854.912176317273;5857.765689372377;5868.2077919480325;5947.256592925884;5948.940510595012;5950.672713000827;5963.2844058713235;5499.697516636624;5643.71781410936;5677.403872593318;5677.460641239076;5784.739686714024 16:13:03 INFO - PROCESS | 5645 | |1;ext.html;579.5334755521806;606.5966132521874;629.6096420219465;640.6956695569605;645.715885010595;48192.51807481929;49783.50216497834;49820.50179498204;49908.25137622934;53284.73357633216;48894.51105488944;49055.50944490554;49132.75433622836;49369.75315123428;49440;8693.304535637166;9469.668561600345;9620.182284505825;9623.133917947383;9624.855627165598;11116.388598627072;11469.08247340215;11476.4835582399;11829.7634047319;11849;11551;11611.071114310835;11625.825612615818;11638;11645.650630481057;11405.771884562304;11451.59919402817;11454.828177577343;11463.598774042868;11465;11199.496022678999;11237.887621123848;11265.887341126565;11269.323840569592;11270.211085224038;46305.30542041872;46395.30407043897;46728;46793;46806.29790553144;3870.9032274193087;3927.9018024549327;4231.1114665920195;4272.957270427287;4746.715197088151;35712.821435892656;35759.33287503366;35966.82016589926;36009.81995090034;36207.63792362069;4808.35087263222;4869.9269510957365;4893.7063776173545;4908.901821963576;4996.550310472038;384793;387828;389283;389322;389569;38066.42900356497;64926;65153.34846651568;65303;65327.673361632886;39422.40866387007;41223.38164927528;41242.79378603088;41262.58737412647;41317.1736565267;17880.642387152446;18025.54936126581;18028.188731507114;18035.909820450815;18036.90981545084;13322.467101316033;13582.728345433234;13598.660033499043;14125.364358603887;14157.575272741835;9366.391184572982;9434.481103539234;9443.716688499357;9504.619815207314;9507.429554226768;3561.7506774526055;3570.732195085379;3579.874704385334;3584.408572585546;3640.9453858192146;1049.3913530152493;1076.8384742288563;1161.1001473857827;1179.1981452612306;1219.1671341133367;35514.25117287957;35522;35798.463023054675;35854.73906072846;35915.640843591755;1129.2321221569248;1155.4511606986782;1165.539611853313;1176.4999875053068;1184.2006645514243;18498.16758245882;18807.341743038927;19203.32788352401;20156.395308140778;20230.898845505973;620203;621000;621658;624051;624202;66527.66736166288;67000.6649966747;67114.32885671179;67143.66428167828;67187.66406167937;1349.5209200733796;1423.8504956979575;1471.7350876842056;1502.384022550749;1506.3221550302626;34444.48333275053;36922.26155476929;37228.62771372252;37308.6269137316;37375;56163;56950.43049569451;56980.43019569917;57138.714306429865;57181.714091429276;447263;451872;451991;452062;452896;28031.43937121287;30861;31599.684003159673;31959.201019974687;33299.167520812174;53103;53171.46828531665;53295.73352133214;53605.19592206198;53740.73129634327;44176.33735494035;45263.77368113138;45290.547094528636;46282;47279.58161255168;44274.778626106665;45769.62691119272;46153.307700385194;46774.53225467702;47250;44928;45182.54817451783;45425.318620221384;45803;46188.53811461842;38895;39859.40210896897;40279.59720402876;40684.71932384681;40979.95100244916;29951.850240748656;30204.395912082076;30441.695583043886;30691.846540767154;30711 16:13:03 INFO - PROCESS | 5645 | |2;jquery.html;412.54619918090106;414.24813962657794;416.33802254415474;416.4939598387959;427.61728253213374;19268.13293401797;20324.983750812455;20620.38138855833;20757.481062973435;21175.364739057834;19931.40205793825;20296.492587685316;20531.692024619606;20578.0739866706;20663.1734730611;5738.655680659163;5971.164037034819;5974.581779275452;5984.431479009509;5987.610805297663;5792.4497172768515;5808.273965754281;5861.472467477926;5867.823965281027;6034.69826508675;6150.323464418911;6150.876982460348;6154.938450615504;6157.0456579230295;6161.969190154043;5957.434043765857;6024.698765061751;6102.359252278536;6108.083787431877;6160.045192995093;5832.533397328203;5913.704314784243;5923.022701254288;5931.199288096095;5938.554608404387;15119.848801512064;15186.088834669845;15312.157831319273;15411.075335479907;15426.922865385714;2378.250850981935;2400.0399840063938;2458.9508209835703;2637.9868100659373;2675.331167208198;10722.517486713117;10899.400532970683;10927.235093543455;10994.450277486174;11486.023687986532;1979.6436641404541;2032.9491762705902;2067.524469372038;2109.5991761565256;2123.872567645946;63790.68104659447;87467;88827.55586222092;88901.55549222212;90601;11809.287653290225;11896.64310070682;12103.636890893287;12163.63509094711;12260.141790074787;16812.495625131265;16825.663486730442;16860.66278674444;16864.747028794576;16886.915565422096;8489.151084891584;8572.099929507436;8620.78448038804;8750.081241469681;8780.121987801169;8149.348052155813;8150.470219435718;8150.877736833952;8339.374546908884;8339.708110216234;5084.46613105626;5155.896882062338;5169.922451163236;5185.714785686823;5272.7100009499845;2010.2963962612969;2072.2081781420966;2077.6883467479997;2125.7874212578618;2148.8710677359095;950.9001554836781;1029.361795686679;1039.4542864995788;1048.1457612046158;1080.978380432387;20441.591168176852;20824.791752082587;21101.894490527447;21314.57370852605;21428.250011249842;986.921046316293;1017.765913839821;1021.6577446555339;1046.1944302886734;1056.587930707025;11111;11526.59656912004;11649.534018639159;11688.649340519798;11696.713361530225;414562;442997;532464;533119;537421;71175.64412177906;72655.2734472659;93029.53485232666;105915;109754;1067.0876400677464;1076.6554702495127;1126.9492872820742;1128.4752590045669;1130.4912789244872;18789.436316910516;18959.526011849815;19220.71168932495;19286.517837054187;19335.903320483307;40510.392344115455;46142.30786538137;46333.30500042435;47599.5240047609;48212.03575928532;62843;65099.34900650933;65956.34043659693;66538;67051;9135.954320228357;9178.173964343241;9335.906640933776;9359.251259899327;9664.371815831948;11381.829272560753;14003.929980350033;14066;14205.786913196103;14514.709705806035;12725.745485090061;12836.743265134832;12868.678283042998;12935.159214651017;13516.25660588677;12034.819477707666;12238.980568206502;12286.324252166216;12534.059945504307;12879.356032198166;12107.576234831738;12942;13071.673208169872;13155.868441315464;13305.336832895888;9211.815763684823;9225.354225204439;9226.446413215228;9459.337846350689;9570.879579424975;11874.168808183344;12784.041196910082;12929.54746583865;12977.870221297666;13083.869161308647 16:13:03 INFO - PROCESS | 5645 | |3;mootools.html;1498.445575137199;1530.0972426268502;1677.6728537935107;1786.7409225662288;1829.6706592813296;8238.752837414871;8653.221210091091;8690.522021288834;8692.695755648545;8729.519876406795;7716.498427602202;7978.281954624083;7980.683187274108;8067.35461163107;8534.146585341463;2380.83334166608;2458.2502336787275;2465.100238412975;2465.5315490056923;2472.0359059966627;2469.580171370871;2477.5911974524183;2514.371407148213;2522.155078048856;2526.9620955685587;2416.142269494331;2449.448874003353;2469.814763892706;2505.7243703192635;2506.8119891008155;2397.0891061396733;2406.133791834938;2415.5772739770646;2425.4300239443714;2470.4441500662206;2433.3065076453304;2452.1172377943926;2458.5082983403213;2458.963115553268;2459.2376363327426;8682.305415566803;8747.956260218722;8759.562021898879;8772.780680482974;8856.424332418372;5153.56194723449;5283.682979021271;5283.841484755425;5289.523942845124;5290.100682883919;3377.2570034592595;3431.588209414861;3440.2431465077643;3466.5666791651042;3472.149323415754;1130.4969288666546;1130.5590819580289;1143.9027682646986;1218.3360068762456;1222.144498850808;12035.097367697457;12086.879131208663;12099.818502722466;12102.515899364014;12102.939485302606;4530.841420550265;4652.255639097796;4799.544043315879;4815.2054910939305;4845.6123510119105;10009.899901000897;10015.949920250352;10020.69937901864;10021.098101170785;10023.949880250551;5340.30576025122;5389.946100538945;5396.109641909041;5400.108982018001;5400.891982160335;4441.422615060086;4815.2054910939305;4913.877153071202;4948.05986862495;4948.307236986825;3995.5604883462797;4026.9597304026584;4087.4686290782006;4120.237756015147;4126.855560055444;1242.5961562492046;1254.1033161289722;1258.672745086284;1264.8482182138018;1283.865194154619;920.2729843423621;945.6264775413673;956.3353469338742;1081.0594782539083;1086.8369744538381;6077.75688972445;6189.535784816157;6319.368063193644;6340.968295158495;6501.0573466847045;483.0845547687113;488.4187816498356;492.9531694489018;496.3919198981291;505.74965392130395;4399.12017596482;4447.911041779146;4460.77696115193;4482.708623939434;4511.616512596401;10341.172706183488;10571.577136914591;10657.094146997437;10674.893251067546;10680.092192163753;10128.696139115838;10536.947315263375;10541.947290263499;10625.46872656364;10648.414337211372;8043;8149.592520373958;8215.055268644062;8224.671013159406;8241.8351632967;6143.139960405458;6208.317085120543;6227.221597300338;6354.3963323484195;6507.609543427409;9840.065193806577;9851.605935762489;9909.603615855285;9962;9982.101610855058;9419.717408477756;10061.245406594388;10063.446510441996;10070.043345882423;10075.899241007495;7038.753643622448;7535.208803075599;7780.183080776664;7973.122956474667;8064.596770161351;8400.915990840258;8534.189252021048;8536.44513106646;8539.658413663385;8540.658373664985;7567.848643027219;7652.464327497022;7788.376929845485;7861.1745766693675;7919.841603167789;7315.122185337794;7456.32893039629;7464.962675186589;7513.549187048576;7643.006409166773;7104.289571043061;7166.53417527859;7195.532290401107;7230.168530618836;7245.311695388929;5433.538149257357;5493.752781124939;5513.724313784375;5537.579643278027;5569.387367389503;5304.496072873071;5330.333708286465;5372.973135134299;5412.566994640498;5441 16:13:03 INFO - PROCESS | 5645 | |4;prototype.html;343.87276707618184;354.1217779905833;355.61771096071726;358.05473549828474;359.35316430425246;5572.498475137242;5629.859253518664;5630;5635.774569017234;5653.915191272129;5287.735613219334;5323.893522129555;5324.512444348948;5353.437889021655;5587.636803607763;4310.590493903074;4320.1575692739925;4367.58507941745;4391.67062470316;4395.538468460813;4359.7602131882895;4362.040351122748;4365.738055716652;4367.606915377615;4396.1427521633295;4160.33434650456;4307.061822180016;4328.97835510822;4348.695591308609;4370.120848035343;4289.506706728704;4334.45819272591;4343.155233322179;4371.431713877208;4400.273954797454;3897.5907529709257;3931.6854651628087;3933.370660694275;3942.8619998299923;3949.605039496056;5142.840007196986;5315.920261196085;5329.387120481156;5433.1850222466555;5438.7280635968045;4909.337239472697;4930.4330002049865;4957.231629097496;5001.299818025479;5002.499750025005;5710.371859095496;5725.914111288334;5735.541156707495;5803.245578074865;5809.3609702932645;4214.136102099063;4247.129338485603;4281.886709455533;4321.049369138785;4338.3058710606465;5926.851828704273;5943.673097979609;5952.339311323548;5961.403859614047;5973.462388385066;4382.452193475816;4387.605115539553;4393.494748103945;4396.12077584478;4408.377015675668;4361.647889154372;4424.84513042042;4450.46594408673;4451.154280686659;4451.621612162938;4950.4059512858685;4959.603231741452;4960.429550601655;4966.329545511309;4982;5758.021136406821;5784.248047753765;5793.565482588822;5798.855028624318;5831.300243970751;1430.5207755402057;1479.8594133557294;1490.8881833862504;1525.3364786317986;1533.516942163215;1051.6529545250048;1066.861308029951;1087.516055355375;1096.786126705285;1107.379867274329;5521.806736764274;5537.916931246034;5540.695261760642;5541.639793413416;5543.279373681396;5824.534037277008;5826.621269617462;5832.9708351457975;5833.154192642043;5851.64890106595;5390.568754499631;5442.700651464207;5530.087535556669;5535.564351685246;5536.612437129443;2719.9528181650207;2742.245882382361;2758.6482623514335;2770.044334704524;2786.9442611147665;5181.616456430084;5300.44345343741;5302.893942121136;5321.962217367576;5327.242010136646;4827.879303017382;5118;5211.778459447859;5221.530062294411;5233;5210.374755029345;5269.55208807248;5269.57843372537;5289.841304760864;5295.311609490818;5095.898082038264;5100.642954993264;5127.53852153308;5169.121249387688;5169.612279079009;5453.26380938584;5899.705014749159;5911.315275146734;5915.526757859275;5924.363658172072;6173.59871608344;6185.381461853779;6211.9689401554515;6247.156633854372;6303.369663033844;4242.300020496584;4272.230998420314;4315.784210789385;4328.177646247203;4341.60925516705;5910.52715782745;5948.910766338595;5977.9103313451205;6004.979153543821;6017.819465416044;5611.158326251095;5785.971070144622;5843.444872737253;5852.18069470266;5881.5005071982105;5795.217645617788;5812.854678633068;5826.368616787307;5832.812695239285;5835.416458354131;5580;5706.915686019643;5734.569907256889;5761.193432919311;5784.681842498703;5718.456746609066;5770.845830833766;5798.6810725410505;5806.187133801355;5809.331926828299;5657;5679.119736440858;5730.111832665943;5730.71346432685;5739.942600573941 16:13:03 INFO - PROCESS | 5645 | |5;yui.html;834.1575009240669;854.3037424499029;877.7279043496515;889.3641046651643;919.8325229337293;15906.68186636268;17448.56378590536;17455.563610909732;17455.650886982254;17480;15207.23963801811;15292.84707152928;16339;16808.74786878198;16811.747823782654;739.6375775869813;873.7378786364097;885.583775625455;904.2991681446898;907.7322189953946;891.4606662968907;893.8614514750224;899.2086963472135;899.8695189197596;901.6393442622971;932.9626814927394;945.0313428735533;947.5262368815593;949.6391371278904;949.9905000950006;939.4504215034214;941.1341565759449;941.29873244433;941.4774799986005;943.3632298198688;687.1170545848561;690.182134171009;692.7783109404993;693.8959156126571;694.8645014222228;16591;16746.41387551442;16822.24299906507;16925.23836427364;16936.576585585335;16393.754093688603;16711.247993840305;17287;17332.82667173337;17406.56483587908;13471.528496502575;13498;13511;13560.254186019765;13694.246816425093;4186.162767446493;4196.286631272691;4212.9157416851795;4290.828366865321;4314.395984562164;90054;90286.54856725674;91501.54249228777;93735.53132234364;94580.05419945781;12782.424790884432;12939.805902911465;13113.27876966776;13141;13194.736105277841;25311.87344063268;25338.619920701203;25364.492710145692;25436;25438.872805635852;9423.764405889908;9726.367786094022;9780;9780.706578802647;9876.259280553844;5192.143296356135;5194.1429664105;5243.475652434726;5280.313559237275;5385.649932754359;3874.7093967952515;3993.6405723485027;4003.0592810689463;4005.639492445694;4007.1184339445276;639.280809089774;643.3855667837204;716.2586722741937;726.4297526441718;741.810838236257;660.131926516629;740.5963749756396;769.2730369800485;811.3874025111004;816.4733746733442;11060.17048721349;11100;11279.718007049889;11392.829107563393;11590.536378544764;650.5998810731461;740.6629983357559;750.3546949623319;776.9844603107906;821.0721884270736;5309.734513274321;5363.275957745733;5404.162354835007;5455.34535855692;5480.177973304037;129047;133763;134272;134629;136731.31634341765;65916;98596.50701746445;107821;111700.441497792;112350;59639;75337.24662753411;80350.59824700956;90258;95145.5242723782;9107.817843642957;9251;9533.666321678707;10079.546420410952;10114.342567733074;51362.48637513577;51843.740781295855;53052.46947530475;53552.73223633857;53950.460495394545;76972;77616;79113.20886791058;79129.60435197788;80469.59765201136;13892.861071389158;15009.324580394126;15051.849481505045;15170.10373703287;15218.391264349302;15995.760063598822;16082.517524474284;16137.527942208904;16347.836521634632;17539;15637.687246254784;15670.451534196249;15903.363865445715;16547.586310342336;16993.660126797644;15199.544013679608;15291.235438228267;15684.764728528853;16014.839851601335;16036.35854565851;15222.619434514225;15711.450099246471;16697;16995;17023.7446438306;11914;12107.576234831738;12390.3185324808;12691.238525688488;13065.542706005624;12918.400938869125;13198.472061117447;13608;13618.114822536503;13718.681340580597 16:13:03 INFO - PROCESS | 5645 | __end_tp_report 16:13:03 INFO - PROCESS | 5645 | __start_cc_report 16:13:03 INFO - PROCESS | 5645 | _x_x_mozilla_cycle_collect,1007 16:13:03 INFO - PROCESS | 5645 | __end_cc_report 16:13:03 INFO - PROCESS | 5645 | __startTimestamp1476141183517__endTimestamp 16:13:03 INFO - PROCESS | 5645 | 16:13:03 INFO - PROCESS | 5645 | ------- Summary: start ------- 16:13:03 INFO - PROCESS | 5645 | Number of tests: 6 16:13:03 INFO - PROCESS | 5645 | 16:13:03 INFO - PROCESS | 5645 | [#0] dojo.html Cycles:165 Average:5557.52 Median:5773.86 stddev:624.87 (10.8%) stddev-sans-first:598.60 16:13:03 INFO - PROCESS | 5645 | Values: 3192.1 3202.4 3235.4 3253.7 3269.3 5517.9 5617.9 5619.1 5626.5 5641.5 5606.5 5627.6 5648.9 5656.7 5702.8 5084.8 5102.7 5138.9 5159.9 5170.6 4969.8 5029.6 5169.0 5179.1 5181.9 5027.6 5035.1 5071.5 5131.7 5153.8 5133.8 5172.3 5174.1 5182.1 5200.9 4864.2 5076.5 5082.6 5130.7 5153.8 5455.4 5512.4 5574.4 5626.9 5662.2 5963.4 6000.9 6010.5 6014.2 6017.2 6025.7 6036.7 6065.9 6069.3 6070.2 5406.1 5498.3 5569.3 5633.0 5637.8 6114.4 6120.7 6124.9 6140.8 6140.8 5838.4 5839.0 5884.8 5897.8 5903.3 5787.9 5862.8 5885.8 5894.9 5961.2 5882.1 5917.7 5925.4 5935.4 5942.7 5887.2 5912.3 5926.9 5951.6 5957.9 4446.4 4530.9 4562.2 4562.5 4598.9 4093.2 4097.0 4100.6 4109.8 4134.1 5722.2 5732.2 5746.2 5753.3 5779.4 5816.9 5887.9 5932.5 5974.9 6069.2 5713.7 5715.1 5715.5 5716.3 5716.9 5161.5 5189.2 5215.6 5224.0 5240.9 6019.8 6115.2 6127.7 6141.3 6249.7 6016.2 6017.3 6029.0 6040.8 6072.1 6216.6 6222.7 6224.1 6232.3 6234.2 5704.9 5773.1 5773.9 5796.9 5855.1 5940.3 5945.0 5947.4 5948.0 5954.3 5801.4 5827.2 5857.8 5859.4 5864.8 5652.6 5732.1 5843.2 5846.9 5937.0 5849.0 5852.9 5853.5 5854.9 5857.8 5868.2 5947.3 5948.9 5950.7 5963.3 5499.7 5643.7 5677.4 5677.5 5784.7 16:13:03 INFO - PROCESS | 5645 | 16:13:03 INFO - PROCESS | 5645 | [#1] ext.html Cycles:180 Average:65711.53 Median:40069.50 stddev:132064.37 (329.6%) stddev-sans-first:132344.31 16:13:03 INFO - PROCESS | 5645 | Values: 579.5 606.6 629.6 640.7 645.7 48192.5 49783.5 49820.5 49908.3 53284.7 48894.5 49055.5 49132.8 49369.8 49440.0 8693.3 9469.7 9620.2 9623.1 9624.9 11116.4 11469.1 11476.5 11829.8 11849.0 11551.0 11611.1 11625.8 11638.0 11645.7 11405.8 11451.6 11454.8 11463.6 11465.0 11199.5 11237.9 11265.9 11269.3 11270.2 46305.3 46395.3 46728.0 46793.0 46806.3 3870.9 3927.9 4231.1 4273.0 4746.7 35712.8 35759.3 35966.8 36009.8 36207.6 4808.4 4869.9 4893.7 4908.9 4996.6 384793.0 387828.0 389283.0 389322.0 389569.0 38066.4 64926.0 65153.3 65303.0 65327.7 39422.4 41223.4 41242.8 41262.6 41317.2 17880.6 18025.5 18028.2 18035.9 18036.9 13322.5 13582.7 13598.7 14125.4 14157.6 9366.4 9434.5 9443.7 9504.6 9507.4 3561.8 3570.7 3579.9 3584.4 3640.9 1049.4 1076.8 1161.1 1179.2 1219.2 35514.3 35522.0 35798.5 35854.7 35915.6 1129.2 1155.5 1165.5 1176.5 1184.2 18498.2 18807.3 19203.3 20156.4 20230.9 620203.0 621000.0 621658.0 624051.0 624202.0 66527.7 67000.7 67114.3 67143.7 67187.7 1349.5 1423.9 1471.7 1502.4 1506.3 34444.5 36922.3 37228.6 37308.6 37375.0 56163.0 56950.4 56980.4 57138.7 57181.7 447263.0 451872.0 451991.0 452062.0 452896.0 28031.4 30861.0 31599.7 31959.2 33299.2 53103.0 53171.5 53295.7 53605.2 53740.7 44176.3 45263.8 45290.5 46282.0 47279.6 44274.8 45769.6 46153.3 46774.5 47250.0 44928.0 45182.5 45425.3 45803.0 46188.5 38895.0 39859.4 40279.6 40684.7 40980.0 29951.9 30204.4 30441.7 30691.8 30711.0 16:13:03 INFO - PROCESS | 5645 | 16:13:03 INFO - PROCESS | 5645 | [#2] jquery.html Cycles:180 Average:29750.99 Median:2124.83 stddev:81706.25 (3845.3%) stddev-sans-first:81905.76 16:13:03 INFO - PROCESS | 5645 | Values: 412.5 414.2 416.3 416.5 427.6 19268.1 20325.0 20620.4 20757.5 21175.4 19931.4 20296.5 20531.7 20578.1 20663.2 5738.7 5971.2 5974.6 5984.4 5987.6 5792.4 5808.3 5861.5 5867.8 6034.7 6150.3 6150.9 6154.9 6157.0 6162.0 5957.4 6024.7 6102.4 6108.1 6160.0 5832.5 5913.7 5923.0 5931.2 5938.6 15119.8 15186.1 15312.2 15411.1 15426.9 2378.3 2400.0 2459.0 2638.0 2675.3 10722.5 10899.4 10927.2 10994.5 11486.0 1979.6 2032.9 2067.5 2109.6 2123.9 63790.7 87467.0 88827.6 88901.6 90601.0 11809.3 11896.6 12103.6 12163.6 12260.1 16812.5 16825.7 16860.7 16864.7 16886.9 8489.2 8572.1 8620.8 8750.1 8780.1 8149.3 8150.5 8150.9 8339.4 8339.7 5084.5 5155.9 5169.9 5185.7 5272.7 2010.3 2072.2 2077.7 2125.8 2148.9 950.9 1029.4 1039.5 1048.1 1081.0 20441.6 20824.8 21101.9 21314.6 21428.3 986.9 1017.8 1021.7 1046.2 1056.6 11111.0 11526.6 11649.5 11688.6 11696.7 414562.0 442997.0 532464.0 533119.0 537421.0 71175.6 72655.3 93029.5 105915.0 109754.0 1067.1 1076.7 1126.9 1128.5 1130.5 18789.4 18959.5 19220.7 19286.5 19335.9 40510.4 46142.3 46333.3 47599.5 48212.0 62843.0 65099.3 65956.3 66538.0 67051.0 9136.0 9178.2 9335.9 9359.3 9664.4 11381.8 14003.9 14066.0 14205.8 14514.7 12725.7 12836.7 12868.7 12935.2 13516.3 12034.8 12239.0 12286.3 12534.1 12879.4 12107.6 12942.0 13071.7 13155.9 13305.3 9211.8 9225.4 9226.4 9459.3 9570.9 11874.2 12784.0 12929.5 12977.9 13083.9 16:13:03 INFO - PROCESS | 5645 | 16:13:03 INFO - PROCESS | 5645 | [#3] mootools.html Cycles:180 Average:5798.19 Median:2703.42 stddev:3186.38 (117.9%) stddev-sans-first:3178.93 16:13:03 INFO - PROCESS | 5645 | Values: 1498.4 1530.1 1677.7 1786.7 1829.7 8238.8 8653.2 8690.5 8692.7 8729.5 7716.5 7978.3 7980.7 8067.4 8534.1 2380.8 2458.3 2465.1 2465.5 2472.0 2469.6 2477.6 2514.4 2522.2 2527.0 2416.1 2449.4 2469.8 2505.7 2506.8 2397.1 2406.1 2415.6 2425.4 2470.4 2433.3 2452.1 2458.5 2459.0 2459.2 8682.3 8748.0 8759.6 8772.8 8856.4 5153.6 5283.7 5283.8 5289.5 5290.1 3377.3 3431.6 3440.2 3466.6 3472.1 1130.5 1130.6 1143.9 1218.3 1222.1 12035.1 12086.9 12099.8 12102.5 12102.9 4530.8 4652.3 4799.5 4815.2 4845.6 10009.9 10015.9 10020.7 10021.1 10023.9 5340.3 5389.9 5396.1 5400.1 5400.9 4441.4 4815.2 4913.9 4948.1 4948.3 3995.6 4027.0 4087.5 4120.2 4126.9 1242.6 1254.1 1258.7 1264.8 1283.9 920.3 945.6 956.3 1081.1 1086.8 6077.8 6189.5 6319.4 6341.0 6501.1 483.1 488.4 493.0 496.4 505.7 4399.1 4447.9 4460.8 4482.7 4511.6 10341.2 10571.6 10657.1 10674.9 10680.1 10128.7 10536.9 10541.9 10625.5 10648.4 8043.0 8149.6 8215.1 8224.7 8241.8 6143.1 6208.3 6227.2 6354.4 6507.6 9840.1 9851.6 9909.6 9962.0 9982.1 9419.7 10061.2 10063.4 10070.0 10075.9 7038.8 7535.2 7780.2 7973.1 8064.6 8400.9 8534.2 8536.4 8539.7 8540.7 7567.8 7652.5 7788.4 7861.2 7919.8 7315.1 7456.3 7465.0 7513.5 7643.0 7104.3 7166.5 7195.5 7230.2 7245.3 5433.5 5493.8 5513.7 5537.6 5569.4 5304.5 5330.3 5373.0 5412.6 5441.0 16:13:03 INFO - PROCESS | 5645 | 16:13:03 INFO - PROCESS | 5645 | [#4] prototype.html Cycles:180 Average:4795.93 Median:5288.79 stddev:1374.90 (26.0%) stddev-sans-first:1337.53 16:13:03 INFO - PROCESS | 5645 | Values: 343.9 354.1 355.6 358.1 359.4 5572.5 5629.9 5630.0 5635.8 5653.9 5287.7 5323.9 5324.5 5353.4 5587.6 4310.6 4320.2 4367.6 4391.7 4395.5 4359.8 4362.0 4365.7 4367.6 4396.1 4160.3 4307.1 4329.0 4348.7 4370.1 4289.5 4334.5 4343.2 4371.4 4400.3 3897.6 3931.7 3933.4 3942.9 3949.6 5142.8 5315.9 5329.4 5433.2 5438.7 4909.3 4930.4 4957.2 5001.3 5002.5 5710.4 5725.9 5735.5 5803.2 5809.4 4214.1 4247.1 4281.9 4321.0 4338.3 5926.9 5943.7 5952.3 5961.4 5973.5 4382.5 4387.6 4393.5 4396.1 4408.4 4361.6 4424.8 4450.5 4451.2 4451.6 4950.4 4959.6 4960.4 4966.3 4982.0 5758.0 5784.2 5793.6 5798.9 5831.3 1430.5 1479.9 1490.9 1525.3 1533.5 1051.7 1066.9 1087.5 1096.8 1107.4 5521.8 5537.9 5540.7 5541.6 5543.3 5824.5 5826.6 5833.0 5833.2 5851.6 5390.6 5442.7 5530.1 5535.6 5536.6 2720.0 2742.2 2758.6 2770.0 2786.9 5181.6 5300.4 5302.9 5322.0 5327.2 4827.9 5118.0 5211.8 5221.5 5233.0 5210.4 5269.6 5269.6 5289.8 5295.3 5095.9 5100.6 5127.5 5169.1 5169.6 5453.3 5899.7 5911.3 5915.5 5924.4 6173.6 6185.4 6212.0 6247.2 6303.4 4242.3 4272.2 4315.8 4328.2 4341.6 5910.5 5948.9 5977.9 6005.0 6017.8 5611.2 5786.0 5843.4 5852.2 5881.5 5795.2 5812.9 5826.4 5832.8 5835.4 5580.0 5706.9 5734.6 5761.2 5784.7 5718.5 5770.8 5798.7 5806.2 5809.3 5657.0 5679.1 5730.1 5730.7 5739.9 16:13:03 INFO - PROCESS | 5645 | 16:13:03 INFO - PROCESS | 5645 | [#5] yui.html Cycles:180 Average:22715.52 Median:4191.22 stddev:32416.35 (773.4%) stddev-sans-first:32465.64 16:13:03 INFO - PROCESS | 5645 | Values: 834.2 854.3 877.7 889.4 919.8 15906.7 17448.6 17455.6 17455.7 17480.0 15207.2 15292.8 16339.0 16808.7 16811.7 739.6 873.7 885.6 904.3 907.7 891.5 893.9 899.2 899.9 901.6 933.0 945.0 947.5 949.6 950.0 939.5 941.1 941.3 941.5 943.4 687.1 690.2 692.8 693.9 694.9 16591.0 16746.4 16822.2 16925.2 16936.6 16393.8 16711.2 17287.0 17332.8 17406.6 13471.5 13498.0 13511.0 13560.3 13694.2 4186.2 4196.3 4212.9 4290.8 4314.4 90054.0 90286.5 91501.5 93735.5 94580.1 12782.4 12939.8 13113.3 13141.0 13194.7 25311.9 25338.6 25364.5 25436.0 25438.9 9423.8 9726.4 9780.0 9780.7 9876.3 5192.1 5194.1 5243.5 5280.3 5385.6 3874.7 3993.6 4003.1 4005.6 4007.1 639.3 643.4 716.3 726.4 741.8 660.1 740.6 769.3 811.4 816.5 11060.2 11100.0 11279.7 11392.8 11590.5 650.6 740.7 750.4 777.0 821.1 5309.7 5363.3 5404.2 5455.3 5480.2 129047.0 133763.0 134272.0 134629.0 136731.3 65916.0 98596.5 107821.0 111700.4 112350.0 59639.0 75337.2 80350.6 90258.0 95145.5 9107.8 9251.0 9533.7 10079.5 10114.3 51362.5 51843.7 53052.5 53552.7 53950.5 76972.0 77616.0 79113.2 79129.6 80469.6 13892.9 15009.3 15051.8 15170.1 15218.4 15995.8 16082.5 16137.5 16347.8 17539.0 15637.7 15670.5 15903.4 16547.6 16993.7 15199.5 15291.2 15684.8 16014.8 16036.4 15222.6 15711.5 16697.0 16995.0 17023.7 11914.0 12107.6 12390.3 12691.2 13065.5 12918.4 13198.5 13608.0 13618.1 13718.7 16:13:03 INFO - PROCESS | 5645 | -------- Summary: end -------- 16:13:03 INFO - PROCESS | 5645 | 16:13:04 INFO - TEST-INFO | 5645: exit 0 16:13:04 INFO - TEST-OK | dromaeo_css | took 1112236ms 16:13:04 INFO - TEST-START | kraken 16:13:04 INFO - operating with platform_type : linux_ 16:13:04 INFO - Initialising browser for kraken test... 16:13:04 INFO - TEST-INFO | started process 5838 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJRjn18/profile http://localhost:60088/getInfo.html) 16:13:08 INFO - PROCESS | 5838 | __metrics Screen width/height:1600/1200 16:13:08 INFO - PROCESS | 5838 | colorDepth:24 16:13:08 INFO - PROCESS | 5838 | Browser inner width/height: 1024/697 16:13:08 INFO - PROCESS | 5838 | __metrics 16:13:08 INFO - PROCESS | 5838 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:13:08 INFO - PROCESS | 5838 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:13:09 INFO - TEST-INFO | 5838: exit 0 16:13:09 INFO - Browser initialized. 16:13:09 INFO - Running cycle 1/1 for kraken test... 16:13:09 INFO - TEST-INFO | started process 5927 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJRjn18/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:13:21 INFO - PROCESS | 5927 | RSS: Main: 144338944 16:13:21 INFO - PROCESS | 5927 | 16:13:24 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60088/tests/kraken/driver.html?testName=audio-beat-detection) 16:13:24 INFO - PROCESS | 5927 | RSS: Main: 199430144 16:13:24 INFO - PROCESS | 5927 | 16:13:27 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60088/tests/kraken/driver.html?testName=audio-dft) 16:13:28 INFO - PROCESS | 5927 | RSS: Main: 184205312 16:13:28 INFO - PROCESS | 5927 | 16:13:30 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60088/tests/kraken/driver.html?testName=audio-fft) 16:13:31 INFO - PROCESS | 5927 | RSS: Main: 275075072 16:13:31 INFO - PROCESS | 5927 | 16:13:33 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60088/tests/kraken/driver.html?testName=audio-oscillator) 16:13:34 INFO - PROCESS | 5927 | RSS: Main: 180875264 16:13:34 INFO - PROCESS | 5927 | 16:13:36 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60088/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:13:36 INFO - PROCESS | 5927 | RSS: Main: 179691520 16:13:36 INFO - PROCESS | 5927 | 16:13:39 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60088/tests/kraken/driver.html?testName=imaging-darkroom) 16:13:40 INFO - PROCESS | 5927 | RSS: Main: 215138304 16:13:40 INFO - PROCESS | 5927 | 16:13:43 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60088/tests/kraken/driver.html?testName=imaging-desaturate) 16:13:44 INFO - PROCESS | 5927 | RSS: Main: 213647360 16:13:44 INFO - PROCESS | 5927 | 16:13:47 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60088/tests/kraken/driver.html?testName=json-parse-financial) 16:13:47 INFO - PROCESS | 5927 | RSS: Main: 213770240 16:13:47 INFO - PROCESS | 5927 | 16:13:49 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60088/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:13:49 INFO - PROCESS | 5927 | RSS: Main: 176099328 16:13:49 INFO - PROCESS | 5927 | 16:13:51 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60088/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:13:52 INFO - PROCESS | 5927 | RSS: Main: 182984704 16:13:52 INFO - PROCESS | 5927 | 16:13:54 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60088/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:13:54 INFO - PROCESS | 5927 | RSS: Main: 189689856 16:13:54 INFO - PROCESS | 5927 | 16:13:57 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60088/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:13:57 INFO - PROCESS | 5927 | RSS: Main: 196755456 16:13:57 INFO - PROCESS | 5927 | 16:14:00 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60088/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:14:01 INFO - PROCESS | 5927 | RSS: Main: 194351104 16:14:01 INFO - PROCESS | 5927 | 16:14:02 INFO - PROCESS | 5927 | Cycle 1(1): loaded http://localhost:60088/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60088/tests/kraken/driver.html?testName=ai-astar) 16:14:03 INFO - PROCESS | 5927 | RSS: Main: 244584448 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | __start_tp_report 16:14:03 INFO - PROCESS | 5927 | _x_x_mozilla_page_load 16:14:03 INFO - PROCESS | 5927 | _x_x_mozilla_page_load_details 16:14:03 INFO - PROCESS | 5927 | |i|pagename|runs| 16:14:03 INFO - PROCESS | 5927 | |0;ai-astar;112;110;118;112;110;111;110;109;111;111 16:14:03 INFO - PROCESS | 5927 | |1;audio-beat-detection;141;115;143;178;121;145;146;115;188;120 16:14:03 INFO - PROCESS | 5927 | |2;audio-dft;161;159;160;159;161;159;159;160;161;158 16:14:03 INFO - PROCESS | 5927 | |3;audio-fft;74;74;74;190;75;76;74;75;172;75 16:14:03 INFO - PROCESS | 5927 | |4;audio-oscillator;84;83;153;83;83;83;141;82;84;154 16:14:03 INFO - PROCESS | 5927 | |5;imaging-gaussian-blur;116;117;117;117;117;116;116;116;117;116 16:14:03 INFO - PROCESS | 5927 | |6;imaging-darkroom;145;145;145;145;146;145;145;146;146;145 16:14:03 INFO - PROCESS | 5927 | |7;imaging-desaturate;96;96;94;95;95;94;94;94;95;94 16:14:03 INFO - PROCESS | 5927 | |8;json-parse-financial;51;51;50;50;49;50;51;49;50;50 16:14:03 INFO - PROCESS | 5927 | |9;json-stringify-tinderbox;59;60;59;59;60;60;59;59;58;60 16:14:03 INFO - PROCESS | 5927 | |10;stanford-crypto-aes;82;73;82;72;76;78;72;74;74;74 16:14:03 INFO - PROCESS | 5927 | |11;stanford-crypto-ccm;125;120;128;118;116;118;117;116;158;129 16:14:03 INFO - PROCESS | 5927 | |12;stanford-crypto-pbkdf2;156;158;155;154;154;156;152;154;155;156 16:14:03 INFO - PROCESS | 5927 | |13;stanford-crypto-sha256-iterative;71;62;64;62;62;64;63;62;61;60 16:14:03 INFO - PROCESS | 5927 | __end_tp_report 16:14:03 INFO - PROCESS | 5927 | __start_cc_report 16:14:03 INFO - PROCESS | 5927 | _x_x_mozilla_cycle_collect,1689 16:14:03 INFO - PROCESS | 5927 | __end_cc_report 16:14:03 INFO - PROCESS | 5927 | __startTimestamp1476141243125__endTimestamp 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | ------- Summary: start ------- 16:14:03 INFO - PROCESS | 5927 | Number of tests: 14 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#0] ai-astar Cycles:10 Average:111.40 Median:111.00 stddev:2.50 (2.3%) stddev-sans-first:2.65 16:14:03 INFO - PROCESS | 5927 | Values: 112.0 110.0 118.0 112.0 110.0 111.0 110.0 109.0 111.0 111.0 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:144.00 stddev:25.42 (17.7%) stddev-sans-first:26.96 16:14:03 INFO - PROCESS | 5927 | Values: 141.0 115.0 143.0 178.0 121.0 145.0 146.0 115.0 188.0 120.0 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#2] audio-dft Cycles:10 Average:159.70 Median:160.00 stddev:1.06 (0.7%) stddev-sans-first:1.01 16:14:03 INFO - PROCESS | 5927 | Values: 161.0 159.0 160.0 159.0 161.0 159.0 159.0 160.0 161.0 158.0 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#3] audio-fft Cycles:10 Average:95.90 Median:74.50 stddev:45.06 (60.5%) stddev-sans-first:47.09 16:14:03 INFO - PROCESS | 5927 | Values: 74.0 74.0 74.0 190.0 75.0 76.0 74.0 75.0 172.0 75.0 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#4] audio-oscillator Cycles:10 Average:103.00 Median:83.00 stddev:32.16 (38.7%) stddev-sans-first:33.37 16:14:03 INFO - PROCESS | 5927 | Values: 84.0 83.0 153.0 83.0 83.0 83.0 141.0 82.0 84.0 154.0 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 16:14:03 INFO - PROCESS | 5927 | Values: 116.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 117.0 116.0 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 16:14:03 INFO - PROCESS | 5927 | Values: 145.0 145.0 145.0 145.0 146.0 145.0 145.0 146.0 146.0 145.0 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.73 16:14:03 INFO - PROCESS | 5927 | Values: 96.0 96.0 94.0 95.0 95.0 94.0 94.0 94.0 95.0 94.0 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.74 (1.5%) stddev-sans-first:0.71 16:14:03 INFO - PROCESS | 5927 | Values: 51.0 51.0 50.0 50.0 49.0 50.0 51.0 49.0 50.0 50.0 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.50 stddev:0.67 (1.1%) stddev-sans-first:0.71 16:14:03 INFO - PROCESS | 5927 | Values: 59.0 60.0 59.0 59.0 60.0 60.0 59.0 59.0 58.0 60.0 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:75.00 stddev:3.77 (5.0%) stddev-sans-first:3.24 16:14:03 INFO - PROCESS | 5927 | Values: 82.0 73.0 82.0 72.0 76.0 78.0 72.0 74.0 74.0 74.0 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#11] stanford-crypto-ccm Cycles:10 Average:124.50 Median:122.50 stddev:12.74 (10.4%) stddev-sans-first:13.51 16:14:03 INFO - PROCESS | 5927 | Values: 125.0 120.0 128.0 118.0 116.0 118.0 117.0 116.0 158.0 129.0 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.00 Median:155.50 stddev:1.63 (1.1%) stddev-sans-first:1.69 16:14:03 INFO - PROCESS | 5927 | Values: 156.0 158.0 155.0 154.0 154.0 156.0 152.0 154.0 155.0 156.0 16:14:03 INFO - PROCESS | 5927 | 16:14:03 INFO - PROCESS | 5927 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:62.50 stddev:3.03 (4.9%) stddev-sans-first:1.30 16:14:03 INFO - PROCESS | 5927 | Values: 71.0 62.0 64.0 62.0 62.0 64.0 63.0 62.0 61.0 60.0 16:14:03 INFO - PROCESS | 5927 | -------- Summary: end -------- 16:14:03 INFO - PROCESS | 5927 | 16:14:04 INFO - TEST-INFO | 5927: exit 0 16:14:04 INFO - TEST-OK | kraken | took 59863ms 16:14:04 INFO - SUITE-END | took 1172s 16:14:04 INFO - Completed test suite (00:19:32) 16:14:04 INFO - javascript benchmark 16:14:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3192.0742984534486, 3202.4075546023987, 3235.3852767974067, 3253.7071663550278, 3269.313444176723, 5517.862053448661, 5617.887642247153, 5619.072852979264, 5626.521745651614, 5641.492265696092, 5606.495415412623, 5627.6060675752715, 5648.858778530539, 5656.717164141797, 5702.8289151325425, 5084.77118529667, 5102.668326558781, 5138.922916156261, 5159.871003224931, 5170.586353091762, 4969.751512424383, 5029.597632189434, 5169.043726910515, 5179.119549676545, 5181.896362072756, 5027.648064635477, 5035.068512325213, 5071.467495912932, 5131.7434128293435, 5153.81961631343, 5133.82031628893, 5172.250023746555, 5174.146265866128, 5182.119039763249, 5200.92198617021, 4864.173090574611, 5076.543111120017, 5082.567981721558, 5130.666506677091, 5153.793848246066, 5455.427180146107, 5512.39363669996, 5574.386817450078, 5626.915596266059, 5662.150677398382, 5963.4334738199805, 6000.909986350208, 6010.519158467312, 6014.248218972629, 6017.217761690996, 6025.698715064229, 6036.728347224342, 6065.909011364833, 6069.271687397496, 6070.21087258658, 5406.135018397077, 5498.285222920995, 5569.275994120779, 5633.014222511044, 5637.80267690633, 6114.357992410733, 6120.663363515049, 6124.908126378108, 6140.785072522441, 6140.815775526741, 5838.386969368155, 5838.970805145947, 5884.764609415661, 5897.793577224862, 5903.321118071477, 5787.88424231513, 5862.824115276549, 5885.764569417175, 5894.941050589525, 5961.195238642815, 5882.117682347596, 5917.704114794243, 5925.40745925404, 5935.406459354116, 5942.732577034044, 5887.175795388649, 5912.320083190402, 5926.940730592725, 5951.613145145551, 5957.910631340534, 4446.444194475691, 4530.909381812346, 4562.247229207143, 4562.543745625411, 4598.931016034762, 4093.2222877653153, 4096.959030409717, 4100.569440208795, 4109.753414795122, 4134.131832315187, 5722.170285308607, 5732.197492351074, 5746.166805813134, 5753.280839895013, 5779.3642699303045, 5816.941830581724, 5887.911681324784, 5932.49573786233, 5974.910376344358, 6069.180660610849, 5713.657180569179, 5715.142728590661, 5715.514181294637, 5716.256886604798, 5716.914246286309, 5161.509656582619, 5189.195674670432, 5215.556677682363, 5223.973880130651, 5240.921386179134, 6019.849503762441, 6115.240341084264, 6127.662978536134, 6141.271221159682, 6249.6875156242, 6016.2479690038745, 6017.338092809699, 6029.007488861511, 6040.7583696651645, 6072.119542666377, 6216.564840461125, 6222.719977601109, 6224.06639004153, 6232.314445411092, 6234.220722409699, 5704.885902281848, 5773.1051686988585, 5773.8556536086935, 5796.884062318814, 5855.063189889597, 5940.287165540163, 5945.048792193228, 5947.434993675594, 5948.048312270016, 5954.285485741739, 5801.448862358069, 5827.184194212899, 5857.765689372377, 5859.44335288147, 5864.794732184353, 5652.604317697722, 5732.140178973187, 5843.211166492469, 5846.912296315644, 5937.0, 5849.0, 5852.853678657897, 5853.502452291603, 5854.912176317273, 5857.765689372377, 5868.2077919480325, 5947.256592925884, 5948.940510595012, 5950.672713000827, 5963.2844058713235, 5499.697516636624, 5643.71781410936, 5677.403872593318, 5677.460641239076, 5784.739686714024], "value": 5515.53133633492, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.5334755521806, 606.5966132521874, 629.6096420219465, 640.6956695569605, 645.715885010595, 48192.51807481929, 49783.50216497834, 49820.50179498204, 49908.25137622934, 53284.73357633216, 48894.51105488944, 49055.50944490554, 49132.75433622836, 49369.75315123428, 49440.0, 8693.304535637166, 9469.668561600345, 9620.182284505825, 9623.133917947383, 9624.855627165598, 11116.388598627072, 11469.08247340215, 11476.4835582399, 11829.7634047319, 11849.0, 11551.0, 11611.071114310835, 11625.825612615818, 11638.0, 11645.650630481057, 11405.771884562304, 11451.59919402817, 11454.828177577343, 11463.598774042868, 11465.0, 11199.496022678999, 11237.887621123848, 11265.887341126565, 11269.323840569592, 11270.211085224038, 46305.30542041872, 46395.30407043897, 46728.0, 46793.0, 46806.29790553144, 3870.9032274193087, 3927.9018024549327, 4231.1114665920195, 4272.957270427287, 4746.715197088151, 35712.821435892656, 35759.33287503366, 35966.82016589926, 36009.81995090034, 36207.63792362069, 4808.35087263222, 4869.9269510957365, 4893.7063776173545, 4908.901821963576, 4996.550310472038, 384793.0, 387828.0, 389283.0, 389322.0, 389569.0, 38066.42900356497, 64926.0, 65153.34846651568, 65303.0, 65327.673361632886, 39422.40866387007, 41223.38164927528, 41242.79378603088, 41262.58737412647, 41317.1736565267, 17880.642387152446, 18025.54936126581, 18028.188731507114, 18035.909820450815, 18036.90981545084, 13322.467101316033, 13582.728345433234, 13598.660033499043, 14125.364358603887, 14157.575272741835, 9366.391184572982, 9434.481103539234, 9443.716688499357, 9504.619815207314, 9507.429554226768, 3561.7506774526055, 3570.732195085379, 3579.874704385334, 3584.408572585546, 3640.9453858192146, 1049.3913530152493, 1076.8384742288563, 1161.1001473857827, 1179.1981452612306, 1219.1671341133367, 35514.25117287957, 35522.0, 35798.463023054675, 35854.73906072846, 35915.640843591755, 1129.2321221569248, 1155.4511606986782, 1165.539611853313, 1176.4999875053068, 1184.2006645514243, 18498.16758245882, 18807.341743038927, 19203.32788352401, 20156.395308140778, 20230.898845505973, 620203.0, 621000.0, 621658.0, 624051.0, 624202.0, 66527.66736166288, 67000.6649966747, 67114.32885671179, 67143.66428167828, 67187.66406167937, 1349.5209200733796, 1423.8504956979575, 1471.7350876842056, 1502.384022550749, 1506.3221550302626, 34444.48333275053, 36922.26155476929, 37228.62771372252, 37308.6269137316, 37375.0, 56163.0, 56950.43049569451, 56980.43019569917, 57138.714306429865, 57181.714091429276, 447263.0, 451872.0, 451991.0, 452062.0, 452896.0, 28031.43937121287, 30861.0, 31599.684003159673, 31959.201019974687, 33299.167520812174, 53103.0, 53171.46828531665, 53295.73352133214, 53605.19592206198, 53740.73129634327, 44176.33735494035, 45263.77368113138, 45290.547094528636, 46282.0, 47279.58161255168, 44274.778626106665, 45769.62691119272, 46153.307700385194, 46774.53225467702, 47250.0, 44928.0, 45182.54817451783, 45425.318620221384, 45803.0, 46188.53811461842, 38895.0, 39859.40210896897, 40279.59720402876, 40684.71932384681, 40979.95100244916, 29951.850240748656, 30204.395912082076, 30441.695583043886, 30691.846540767154, 30711.0], "value": 21326.915055457546, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.54619918090106, 414.24813962657794, 416.33802254415474, 416.4939598387959, 427.61728253213374, 19268.13293401797, 20324.983750812455, 20620.38138855833, 20757.481062973435, 21175.364739057834, 19931.40205793825, 20296.492587685316, 20531.692024619606, 20578.0739866706, 20663.1734730611, 5738.655680659163, 5971.164037034819, 5974.581779275452, 5984.431479009509, 5987.610805297663, 5792.4497172768515, 5808.273965754281, 5861.472467477926, 5867.823965281027, 6034.69826508675, 6150.323464418911, 6150.876982460348, 6154.938450615504, 6157.0456579230295, 6161.969190154043, 5957.434043765857, 6024.698765061751, 6102.359252278536, 6108.083787431877, 6160.045192995093, 5832.533397328203, 5913.704314784243, 5923.022701254288, 5931.199288096095, 5938.554608404387, 15119.848801512064, 15186.088834669845, 15312.157831319273, 15411.075335479907, 15426.922865385714, 2378.250850981935, 2400.0399840063938, 2458.9508209835703, 2637.9868100659373, 2675.331167208198, 10722.517486713117, 10899.400532970683, 10927.235093543455, 10994.450277486174, 11486.023687986532, 1979.6436641404541, 2032.9491762705902, 2067.524469372038, 2109.5991761565256, 2123.872567645946, 63790.68104659447, 87467.0, 88827.55586222092, 88901.55549222212, 90601.0, 11809.287653290225, 11896.64310070682, 12103.636890893287, 12163.63509094711, 12260.141790074787, 16812.495625131265, 16825.663486730442, 16860.66278674444, 16864.747028794576, 16886.915565422096, 8489.151084891584, 8572.099929507436, 8620.78448038804, 8750.081241469681, 8780.121987801169, 8149.348052155813, 8150.470219435718, 8150.877736833952, 8339.374546908884, 8339.708110216234, 5084.46613105626, 5155.896882062338, 5169.922451163236, 5185.714785686823, 5272.7100009499845, 2010.2963962612969, 2072.2081781420966, 2077.6883467479997, 2125.7874212578618, 2148.8710677359095, 950.9001554836781, 1029.361795686679, 1039.4542864995788, 1048.1457612046158, 1080.978380432387, 20441.591168176852, 20824.791752082587, 21101.894490527447, 21314.57370852605, 21428.250011249842, 986.921046316293, 1017.765913839821, 1021.6577446555339, 1046.1944302886734, 1056.587930707025, 11111.0, 11526.59656912004, 11649.534018639159, 11688.649340519798, 11696.713361530225, 414562.0, 442997.0, 532464.0, 533119.0, 537421.0, 71175.64412177906, 72655.2734472659, 93029.53485232666, 105915.0, 109754.0, 1067.0876400677464, 1076.6554702495127, 1126.9492872820742, 1128.4752590045669, 1130.4912789244872, 18789.436316910516, 18959.526011849815, 19220.71168932495, 19286.517837054187, 19335.903320483307, 40510.392344115455, 46142.30786538137, 46333.30500042435, 47599.5240047609, 48212.03575928532, 62843.0, 65099.34900650933, 65956.34043659693, 66538.0, 67051.0, 9135.954320228357, 9178.173964343241, 9335.906640933776, 9359.251259899327, 9664.371815831948, 11381.829272560753, 14003.929980350033, 14066.0, 14205.786913196103, 14514.709705806035, 12725.745485090061, 12836.743265134832, 12868.678283042998, 12935.159214651017, 13516.25660588677, 12034.819477707666, 12238.980568206502, 12286.324252166216, 12534.059945504307, 12879.356032198166, 12107.576234831738, 12942.0, 13071.673208169872, 13155.868441315464, 13305.336832895888, 9211.815763684823, 9225.354225204439, 9226.446413215228, 9459.337846350689, 9570.879579424975, 11874.168808183344, 12784.041196910082, 12929.54746583865, 12977.870221297666, 13083.869161308647], "value": 9711.25020409957, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1498.445575137199, 1530.0972426268502, 1677.6728537935107, 1786.7409225662288, 1829.6706592813296, 8238.752837414871, 8653.221210091091, 8690.522021288834, 8692.695755648545, 8729.519876406795, 7716.498427602202, 7978.281954624083, 7980.683187274108, 8067.35461163107, 8534.146585341463, 2380.83334166608, 2458.2502336787275, 2465.100238412975, 2465.5315490056923, 2472.0359059966627, 2469.580171370871, 2477.5911974524183, 2514.371407148213, 2522.155078048856, 2526.9620955685587, 2416.142269494331, 2449.448874003353, 2469.814763892706, 2505.7243703192635, 2506.8119891008155, 2397.0891061396733, 2406.133791834938, 2415.5772739770646, 2425.4300239443714, 2470.4441500662206, 2433.3065076453304, 2452.1172377943926, 2458.5082983403213, 2458.963115553268, 2459.2376363327426, 8682.305415566803, 8747.956260218722, 8759.562021898879, 8772.780680482974, 8856.424332418372, 5153.56194723449, 5283.682979021271, 5283.841484755425, 5289.523942845124, 5290.100682883919, 3377.2570034592595, 3431.588209414861, 3440.2431465077643, 3466.5666791651042, 3472.149323415754, 1130.4969288666546, 1130.5590819580289, 1143.9027682646986, 1218.3360068762456, 1222.144498850808, 12035.097367697457, 12086.879131208663, 12099.818502722466, 12102.515899364014, 12102.939485302606, 4530.841420550265, 4652.255639097796, 4799.544043315879, 4815.2054910939305, 4845.6123510119105, 10009.899901000897, 10015.949920250352, 10020.69937901864, 10021.098101170785, 10023.949880250551, 5340.30576025122, 5389.946100538945, 5396.109641909041, 5400.108982018001, 5400.891982160335, 4441.422615060086, 4815.2054910939305, 4913.877153071202, 4948.05986862495, 4948.307236986825, 3995.5604883462797, 4026.9597304026584, 4087.4686290782006, 4120.237756015147, 4126.855560055444, 1242.5961562492046, 1254.1033161289722, 1258.672745086284, 1264.8482182138018, 1283.865194154619, 920.2729843423621, 945.6264775413673, 956.3353469338742, 1081.0594782539083, 1086.8369744538381, 6077.75688972445, 6189.535784816157, 6319.368063193644, 6340.968295158495, 6501.0573466847045, 483.0845547687113, 488.4187816498356, 492.9531694489018, 496.3919198981291, 505.74965392130395, 4399.12017596482, 4447.911041779146, 4460.77696115193, 4482.708623939434, 4511.616512596401, 10341.172706183488, 10571.577136914591, 10657.094146997437, 10674.893251067546, 10680.092192163753, 10128.696139115838, 10536.947315263375, 10541.947290263499, 10625.46872656364, 10648.414337211372, 8043.0, 8149.592520373958, 8215.055268644062, 8224.671013159406, 8241.8351632967, 6143.139960405458, 6208.317085120543, 6227.221597300338, 6354.3963323484195, 6507.609543427409, 9840.065193806577, 9851.605935762489, 9909.603615855285, 9962.0, 9982.101610855058, 9419.717408477756, 10061.245406594388, 10063.446510441996, 10070.043345882423, 10075.899241007495, 7038.753643622448, 7535.208803075599, 7780.183080776664, 7973.122956474667, 8064.596770161351, 8400.915990840258, 8534.189252021048, 8536.44513106646, 8539.658413663385, 8540.658373664985, 7567.848643027219, 7652.464327497022, 7788.376929845485, 7861.1745766693675, 7919.841603167789, 7315.122185337794, 7456.32893039629, 7464.962675186589, 7513.549187048576, 7643.006409166773, 7104.289571043061, 7166.53417527859, 7195.532290401107, 7230.168530618836, 7245.311695388929, 5433.538149257357, 5493.752781124939, 5513.724313784375, 5537.579643278027, 5569.387367389503, 5304.496072873071, 5330.333708286465, 5372.973135134299, 5412.566994640498, 5441.0], "value": 4630.793048861071, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.87276707618184, 354.1217779905833, 355.61771096071726, 358.05473549828474, 359.35316430425246, 5572.498475137242, 5629.859253518664, 5630.0, 5635.774569017234, 5653.915191272129, 5287.735613219334, 5323.893522129555, 5324.512444348948, 5353.437889021655, 5587.636803607763, 4310.590493903074, 4320.1575692739925, 4367.58507941745, 4391.67062470316, 4395.538468460813, 4359.7602131882895, 4362.040351122748, 4365.738055716652, 4367.606915377615, 4396.1427521633295, 4160.33434650456, 4307.061822180016, 4328.97835510822, 4348.695591308609, 4370.120848035343, 4289.506706728704, 4334.45819272591, 4343.155233322179, 4371.431713877208, 4400.273954797454, 3897.5907529709257, 3931.6854651628087, 3933.370660694275, 3942.8619998299923, 3949.605039496056, 5142.840007196986, 5315.920261196085, 5329.387120481156, 5433.1850222466555, 5438.7280635968045, 4909.337239472697, 4930.4330002049865, 4957.231629097496, 5001.299818025479, 5002.499750025005, 5710.371859095496, 5725.914111288334, 5735.541156707495, 5803.245578074865, 5809.3609702932645, 4214.136102099063, 4247.129338485603, 4281.886709455533, 4321.049369138785, 4338.3058710606465, 5926.851828704273, 5943.673097979609, 5952.339311323548, 5961.403859614047, 5973.462388385066, 4382.452193475816, 4387.605115539553, 4393.494748103945, 4396.12077584478, 4408.377015675668, 4361.647889154372, 4424.84513042042, 4450.46594408673, 4451.154280686659, 4451.621612162938, 4950.4059512858685, 4959.603231741452, 4960.429550601655, 4966.329545511309, 4982.0, 5758.021136406821, 5784.248047753765, 5793.565482588822, 5798.855028624318, 5831.300243970751, 1430.5207755402057, 1479.8594133557294, 1490.8881833862504, 1525.3364786317986, 1533.516942163215, 1051.6529545250048, 1066.861308029951, 1087.516055355375, 1096.786126705285, 1107.379867274329, 5521.806736764274, 5537.916931246034, 5540.695261760642, 5541.639793413416, 5543.279373681396, 5824.534037277008, 5826.621269617462, 5832.9708351457975, 5833.154192642043, 5851.64890106595, 5390.568754499631, 5442.700651464207, 5530.087535556669, 5535.564351685246, 5536.612437129443, 2719.9528181650207, 2742.245882382361, 2758.6482623514335, 2770.044334704524, 2786.9442611147665, 5181.616456430084, 5300.44345343741, 5302.893942121136, 5321.962217367576, 5327.242010136646, 4827.879303017382, 5118.0, 5211.778459447859, 5221.530062294411, 5233.0, 5210.374755029345, 5269.55208807248, 5269.57843372537, 5289.841304760864, 5295.311609490818, 5095.898082038264, 5100.642954993264, 5127.53852153308, 5169.121249387688, 5169.612279079009, 5453.26380938584, 5899.705014749159, 5911.315275146734, 5915.526757859275, 5924.363658172072, 6173.59871608344, 6185.381461853779, 6211.9689401554515, 6247.156633854372, 6303.369663033844, 4242.300020496584, 4272.230998420314, 4315.784210789385, 4328.177646247203, 4341.60925516705, 5910.52715782745, 5948.910766338595, 5977.9103313451205, 6004.979153543821, 6017.819465416044, 5611.158326251095, 5785.971070144622, 5843.444872737253, 5852.18069470266, 5881.5005071982105, 5795.217645617788, 5812.854678633068, 5826.368616787307, 5832.812695239285, 5835.416458354131, 5580.0, 5706.915686019643, 5734.569907256889, 5761.193432919311, 5784.681842498703, 5718.456746609066, 5770.845830833766, 5798.6810725410505, 5806.187133801355, 5809.331926828299, 5657.0, 5679.119736440858, 5730.111832665943, 5730.71346432685, 5739.942600573941], "value": 4367.115419189846, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.1575009240669, 854.3037424499029, 877.7279043496515, 889.3641046651643, 919.8325229337293, 15906.68186636268, 17448.56378590536, 17455.563610909732, 17455.650886982254, 17480.0, 15207.23963801811, 15292.84707152928, 16339.0, 16808.74786878198, 16811.747823782654, 739.6375775869813, 873.7378786364097, 885.583775625455, 904.2991681446898, 907.7322189953946, 891.4606662968907, 893.8614514750224, 899.2086963472135, 899.8695189197596, 901.6393442622971, 932.9626814927394, 945.0313428735533, 947.5262368815593, 949.6391371278904, 949.9905000950006, 939.4504215034214, 941.1341565759449, 941.29873244433, 941.4774799986005, 943.3632298198688, 687.1170545848561, 690.182134171009, 692.7783109404993, 693.8959156126571, 694.8645014222228, 16591.0, 16746.41387551442, 16822.24299906507, 16925.23836427364, 16936.576585585335, 16393.754093688603, 16711.247993840305, 17287.0, 17332.82667173337, 17406.56483587908, 13471.528496502575, 13498.0, 13511.0, 13560.254186019765, 13694.246816425093, 4186.162767446493, 4196.286631272691, 4212.9157416851795, 4290.828366865321, 4314.395984562164, 90054.0, 90286.54856725674, 91501.54249228777, 93735.53132234364, 94580.05419945781, 12782.424790884432, 12939.805902911465, 13113.27876966776, 13141.0, 13194.736105277841, 25311.87344063268, 25338.619920701203, 25364.492710145692, 25436.0, 25438.872805635852, 9423.764405889908, 9726.367786094022, 9780.0, 9780.706578802647, 9876.259280553844, 5192.143296356135, 5194.1429664105, 5243.475652434726, 5280.313559237275, 5385.649932754359, 3874.7093967952515, 3993.6405723485027, 4003.0592810689463, 4005.639492445694, 4007.1184339445276, 639.280809089774, 643.3855667837204, 716.2586722741937, 726.4297526441718, 741.810838236257, 660.131926516629, 740.5963749756396, 769.2730369800485, 811.3874025111004, 816.4733746733442, 11060.17048721349, 11100.0, 11279.718007049889, 11392.829107563393, 11590.536378544764, 650.5998810731461, 740.6629983357559, 750.3546949623319, 776.9844603107906, 821.0721884270736, 5309.734513274321, 5363.275957745733, 5404.162354835007, 5455.34535855692, 5480.177973304037, 129047.0, 133763.0, 134272.0, 134629.0, 136731.31634341765, 65916.0, 98596.50701746445, 107821.0, 111700.441497792, 112350.0, 59639.0, 75337.24662753411, 80350.59824700956, 90258.0, 95145.5242723782, 9107.817843642957, 9251.0, 9533.666321678707, 10079.546420410952, 10114.342567733074, 51362.48637513577, 51843.740781295855, 53052.46947530475, 53552.73223633857, 53950.460495394545, 76972.0, 77616.0, 79113.20886791058, 79129.60435197788, 80469.59765201136, 13892.861071389158, 15009.324580394126, 15051.849481505045, 15170.10373703287, 15218.391264349302, 15995.760063598822, 16082.517524474284, 16137.527942208904, 16347.836521634632, 17539.0, 15637.687246254784, 15670.451534196249, 15903.363865445715, 16547.586310342336, 16993.660126797644, 15199.544013679608, 15291.235438228267, 15684.764728528853, 16014.839851601335, 16036.35854565851, 15222.619434514225, 15711.450099246471, 16697.0, 16995.0, 17023.7446438306, 11914.0, 12107.576234831738, 12390.3185324808, 12691.238525688488, 13065.542706005624, 12918.400938869125, 13198.472061117447, 13608.0, 13618.114822536503, 13718.681340580597], "value": 8416.852253022735, "unit": "score"}], "name": "dromaeo_css", "value": 7611.544028226206, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 118.0, 112.0, 110.0, 111.0, 110.0, 109.0, 111.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 115.0, 143.0, 178.0, 121.0, 145.0, 146.0, 115.0, 188.0, 120.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 159.0, 161.0, 159.0, 159.0, 160.0, 161.0, 158.0], "value": 159.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 190.0, 75.0, 76.0, 74.0, 75.0, 172.0, 75.0], "value": 95.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 153.0, 83.0, 83.0, 83.0, 141.0, 82.0, 84.0, 154.0], "value": 103.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 50.0, 50.0, 49.0, 50.0, 51.0, 49.0, 50.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 60.0, 60.0, 59.0, 59.0, 58.0, 60.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 73.0, 82.0, 72.0, 76.0, 78.0, 72.0, 74.0, 74.0, 74.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 120.0, 128.0, 118.0, 116.0, 118.0, 117.0, 116.0, 158.0, 129.0], "value": 124.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 158.0, 155.0, 154.0, 154.0, 156.0, 152.0, 154.0, 155.0, 156.0], "value": 155.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 62.0, 64.0, 62.0, 62.0, 64.0, 63.0, 62.0, 61.0, 60.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1495.4, "alertThreshold": 2.0}]} 16:14:04 INFO - Return code: 0 16:14:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:14:04 INFO - # TBPL SUCCESS # 16:14:04 INFO - Running post-action listener: _resource_record_post_action 16:14:04 INFO - [mozharness: 2016-10-10 23:14:04.414832Z] Finished run-tests step (success) 16:14:04 INFO - Running post-run listener: _resource_record_post_run 16:14:04 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290738176; Read time: 136; Write time: 172560 16:14:04 INFO - TinderboxPrint: CPU usage
12.9% 16:14:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 136 16:14:04 INFO - TinderboxPrint: I/O write bytes / time
290,738,176 / 172,560 16:14:04 INFO - TinderboxPrint: CPU idle
8,243.5 (87.1%) 16:14:04 INFO - TinderboxPrint: CPU user
1,180.8 (12.5%) 16:14:04 INFO - TinderboxPrint: Swap in / out
0 / 0 16:14:04 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71356416; Read time: 0; Write time: 71244 16:14:04 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 218824704; Read time: 136; Write time: 101308 16:14:05 INFO - Running post-run listener: _upload_blobber_files 16:14:05 INFO - Blob upload gear active. 16:14:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:14:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:14:05 INFO - (blobuploader) - INFO - Open directory for files ... 16:14:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:14:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:14:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:06 INFO - (blobuploader) - INFO - Done attempting. 16:14:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:14:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:14:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:10 INFO - (blobuploader) - INFO - Done attempting. 16:14:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:14:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:14:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:14:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:14:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:14:11 INFO - (blobuploader) - INFO - Done attempting. 16:14:11 INFO - (blobuploader) - INFO - Iteration through files over. 16:14:11 INFO - Return code: 0 16:14:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:14:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:14:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/79254fc990d3d7f2ddf31d11ba0ccac98078ffa3c50f8c3303370c0f8aca753c75066f5b9ed72f199f18e3848b4ffff56e32ddd0b81bdb991e6023d8b13a6fbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c130ee6b7df02ecce451fc7ad1d0c3b084750971981d4a4f3b0e3a22bc3a0dc1933d707f52273465fa11978412b85a94dcd55245983650852003661aba429d2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7a609ed0252e69b17841fd6940e0565d8a632c448f5d0881fdb94e9c44fd14171e01f0222d9011879518f29f7ddc597d5e71adf24b5c6feba16f3fa7ce4fb7ea"} 16:14:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:14:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:14:11 INFO - Contents: 16:14:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/79254fc990d3d7f2ddf31d11ba0ccac98078ffa3c50f8c3303370c0f8aca753c75066f5b9ed72f199f18e3848b4ffff56e32ddd0b81bdb991e6023d8b13a6fbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c130ee6b7df02ecce451fc7ad1d0c3b084750971981d4a4f3b0e3a22bc3a0dc1933d707f52273465fa11978412b85a94dcd55245983650852003661aba429d2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7a609ed0252e69b17841fd6940e0565d8a632c448f5d0881fdb94e9c44fd14171e01f0222d9011879518f29f7ddc597d5e71adf24b5c6feba16f3fa7ce4fb7ea"} 16:14:11 INFO - Running post-run listener: copy_logs_to_upload_dir 16:14:11 INFO - Copying logs to upload dir... 16:14:11 INFO - mkdir: /builds/slave/test/build/upload/logs 16:14:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1675.046732 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-10 16:14:10.912466) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 16:14:10.916283) ========= 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=31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/79254fc990d3d7f2ddf31d11ba0ccac98078ffa3c50f8c3303370c0f8aca753c75066f5b9ed72f199f18e3848b4ffff56e32ddd0b81bdb991e6023d8b13a6fbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c130ee6b7df02ecce451fc7ad1d0c3b084750971981d4a4f3b0e3a22bc3a0dc1933d707f52273465fa11978412b85a94dcd55245983650852003661aba429d2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7a609ed0252e69b17841fd6940e0565d8a632c448f5d0881fdb94e9c44fd14171e01f0222d9011879518f29f7ddc597d5e71adf24b5c6feba16f3fa7ce4fb7ea"} build_url:https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011208 build_url: 'https://queue.taskcluster.net/v1/task/RAWXIckmQgiv5Jreg6hiCg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/79254fc990d3d7f2ddf31d11ba0ccac98078ffa3c50f8c3303370c0f8aca753c75066f5b9ed72f199f18e3848b4ffff56e32ddd0b81bdb991e6023d8b13a6fbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c130ee6b7df02ecce451fc7ad1d0c3b084750971981d4a4f3b0e3a22bc3a0dc1933d707f52273465fa11978412b85a94dcd55245983650852003661aba429d2a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7a609ed0252e69b17841fd6940e0565d8a632c448f5d0881fdb94e9c44fd14171e01f0222d9011879518f29f7ddc597d5e71adf24b5c6feba16f3fa7ce4fb7ea"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 16:14:10.941994) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 16:14:10.942356) ========= 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=31acecf42b6a79ec49471d4a000001bb-1476139530.193253-1446157841 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 16:14:11.026994) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 16:14:11.027348) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 16:14:11.027767) ========= ========= Total master_lag: 0.17 =========