builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-060 starttime: 1474919518.75 results: success (0) buildid: 20160926095751 builduid: 7b5887e5524049a7bf957f3c0a108f3b revision: 27ce6db0b235243ccddffc0ec3ffd1e1963cbb84 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:58.754004) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:58.754917) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:58.755646) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:58.858267) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:58.858660) ========= 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=337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-26 12:51:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-09-26 12:51:59 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.154493 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:59.033573) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:59.033898) ========= 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=337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016132 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:59.085451) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:59.085898) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 27ce6db0b235243ccddffc0ec3ffd1e1963cbb84 --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 27ce6db0b235243ccddffc0ec3ffd1e1963cbb84 --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=337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-26 12:51:59,694 truncating revision to first 12 chars 2016-09-26 12:51:59,694 Setting DEBUG logging. 2016-09-26 12:51:59,694 attempt 1/10 2016-09-26 12:51:59,694 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/27ce6db0b235?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-26 12:51:59,993 unpacking tar archive at: mozilla-beta-27ce6db0b235/testing/mozharness/ program finished with exit code 0 elapsedTime=0.621456 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:59.724111) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:59.724817) ========= script_repo_revision: 27ce6db0b235243ccddffc0ec3ffd1e1963cbb84 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:59.725742) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:59.726450) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 12:51:59.737605) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-09-26 12:51:59.737907) ========= /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=337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:52:00 INFO - MultiFileLogger online at 20160926 12:52:00 in /builds/slave/test 12:52:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:52:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:52:00 INFO - {'append_to_log': False, 12:52:00 INFO - 'base_work_dir': '/builds/slave/test', 12:52:00 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:52:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:52:00 INFO - 'branch': 'Mozilla-Beta', 12:52:00 INFO - 'buildbot_json_path': 'buildprops.json', 12:52:00 INFO - 'config_files': ('talos/linux_config.py',), 12:52:00 INFO - 'default_actions': ('clobber', 12:52:00 INFO - 'read-buildbot-config', 12:52:00 INFO - 'download-and-extract', 12:52:00 INFO - 'populate-webroot', 12:52:00 INFO - 'create-virtualenv', 12:52:00 INFO - 'install', 12:52:00 INFO - 'run-tests'), 12:52:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:52:00 INFO - 'download_minidump_stackwalk': True, 12:52:00 INFO - 'download_symbols': 'ondemand', 12:52:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:52:00 INFO - 'tooltool.py': '/tools/tooltool.py', 12:52:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:52:00 INFO - '/tools/misc-python/virtualenv.py')}, 12:52:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:52:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:52:00 INFO - 'installer_path': 'installer.exe', 12:52:00 INFO - 'log_level': 'info', 12:52:00 INFO - 'log_name': 'talos', 12:52:00 INFO - 'log_to_console': True, 12:52:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:52:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:52:00 INFO - 'opt_config_files': (), 12:52:00 INFO - 'pip_index': False, 12:52:00 INFO - 'sps_profile': False, 12:52:00 INFO - 'sps_profile_interval': 0, 12:52:00 INFO - 'suite': 'dromaeojs', 12:52:00 INFO - 'system_bits': '32', 12:52:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:52:00 INFO - 'title': 'talos-linux64-ix-060', 12:52:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:52:00 INFO - 'use_talos_json': True, 12:52:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:52:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:52:00 INFO - 'work_dir': 'build'} 12:52:00 INFO - [mozharness: 2016-09-26 19:52:00.443825Z] Running clobber step. 12:52:00 INFO - Running pre-action listener: _resource_record_pre_action 12:52:00 INFO - Running main action method: clobber 12:52:00 INFO - rmtree: /builds/slave/test/build 12:52:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:52:01 INFO - Running post-action listener: _resource_record_post_action 12:52:01 INFO - [mozharness: 2016-09-26 19:52:01.413013Z] Finished clobber step (success) 12:52:01 INFO - [mozharness: 2016-09-26 19:52:01.413114Z] Running read-buildbot-config step. 12:52:01 INFO - Running pre-action listener: _resource_record_pre_action 12:52:01 INFO - Running main action method: read_buildbot_config 12:52:01 INFO - Using buildbot properties: 12:52:01 INFO - { 12:52:01 INFO - "project": "", 12:52:01 INFO - "product": "firefox", 12:52:01 INFO - "script_repo_revision": "production", 12:52:01 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 12:52:01 INFO - "repository": "", 12:52:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 12:52:01 INFO - "buildid": "20160926095751", 12:52:01 INFO - "pgo_build": "True", 12:52:01 INFO - "basedir": "/builds/slave/test", 12:52:01 INFO - "buildnumber": 172, 12:52:01 INFO - "slavename": "talos-linux64-ix-060", 12:52:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:52:01 INFO - "platform": "ubuntu64_hw", 12:52:01 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 12:52:01 INFO - "branch": "mozilla-beta", 12:52:01 INFO - "repo_path": "releases/mozilla-beta", 12:52:01 INFO - "revision": "27ce6db0b235243ccddffc0ec3ffd1e1963cbb84", 12:52:01 INFO - "stage_platform": "linux64-pgo", 12:52:01 INFO - "builduid": "7b5887e5524049a7bf957f3c0a108f3b", 12:52:01 INFO - "slavebuilddir": "test" 12:52:01 INFO - } 12:52:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 12:52:01 INFO - Running post-action listener: _resource_record_post_action 12:52:01 INFO - [mozharness: 2016-09-26 19:52:01.415578Z] Finished read-buildbot-config step (success) 12:52:01 INFO - [mozharness: 2016-09-26 19:52:01.415667Z] Running download-and-extract step. 12:52:01 INFO - Running pre-action listener: _resource_record_pre_action 12:52:01 INFO - Running main action method: download_and_extract 12:52:01 INFO - mkdir: /builds/slave/test/build/tests 12:52: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')]} 12:52:01 INFO - https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:52:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:52:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:52:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:52:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:52:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:52:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:52:01 INFO - retry: Failed, sleeping 30 seconds before retrying 12:52:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:52:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:52:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:52:31 INFO - retry: Failed, sleeping 60 seconds before retrying 12:53:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:53:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:53:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:53:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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! 12:53:31 INFO - Caught exception: HTTP Error 404: Not Found 12:53:31 INFO - Caught exception: HTTP Error 404: Not Found 12:53:31 INFO - Caught exception: HTTP Error 404: Not Found 12:53:31 INFO - trying https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:53:31 INFO - Downloading https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:53:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:53:32 INFO - Downloaded 1327 bytes. 12:53:32 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:53:32 INFO - Using the following test package requirements: 12:53:32 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 12:53:32 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:53:32 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 12:53:32 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:53:32 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 12:53:32 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:53:32 INFO - u'jsshell-linux-x86_64.zip'], 12:53:32 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:53:32 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 12:53:32 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 12:53:32 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:53:32 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 12:53:32 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:53:32 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 12:53:32 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:53:32 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 12:53:32 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:53:32 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 12:53:32 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:53:32 INFO - https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:53:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:53:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:53:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:53:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:53:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:53:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:53:32 INFO - retry: Failed, sleeping 30 seconds before retrying 12:54:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:54:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:54:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:54:02 INFO - retry: Failed, sleeping 60 seconds before retrying 12:55:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:55:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:55:02 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:55:02 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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! 12:55:02 INFO - Caught exception: HTTP Error 404: Not Found 12:55:02 INFO - Caught exception: HTTP Error 404: Not Found 12:55:02 INFO - Caught exception: HTTP Error 404: Not Found 12:55:02 INFO - trying https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:55:02 INFO - Downloading https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:55:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:55:03 INFO - Downloaded 21771552 bytes. 12:55:03 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'] 12:55:03 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 12:55:04 INFO - Return code: 0 12:55:04 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 12:55:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:55:04 INFO - https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:55:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:55:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:55:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:55:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:55:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:55:04 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:55:04 INFO - retry: Failed, sleeping 30 seconds before retrying 12:55:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:55:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:55:34 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:55:34 INFO - retry: Failed, sleeping 60 seconds before retrying 12:56:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:56:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:56:34 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:56:34 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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! 12:56:34 INFO - Caught exception: HTTP Error 404: Not Found 12:56:34 INFO - Caught exception: HTTP Error 404: Not Found 12:56:34 INFO - Caught exception: HTTP Error 404: Not Found 12:56:34 INFO - trying https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:56:34 INFO - Downloading https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:56:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:56:35 INFO - Downloaded 21771552 bytes. 12:56:35 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'] 12:56:35 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 12:56:36 INFO - Return code: 0 12:56:36 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')]} 12:56:36 INFO - https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:56:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:56:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:56:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:56:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:56:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:56:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:56:36 INFO - retry: Failed, sleeping 30 seconds before retrying 12:57:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:57:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:57:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:57:06 INFO - retry: Failed, sleeping 60 seconds before retrying 12:58:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:58:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:58:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:58:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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! 12:58:06 INFO - Caught exception: HTTP Error 404: Not Found 12:58:06 INFO - Caught exception: HTTP Error 404: Not Found 12:58:06 INFO - Caught exception: HTTP Error 404: Not Found 12:58:06 INFO - trying https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:58:06 INFO - Downloading https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:58:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:58:07 INFO - Downloaded 14413740 bytes. 12:58:07 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'] 12:58:07 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 12:58:07 INFO - Return code: 0 12:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:07 INFO - https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:58:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:58:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:58:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:58:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:58:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:58:07 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:58:07 INFO - retry: Failed, sleeping 30 seconds before retrying 12:58:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:58:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:58:37 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:58:37 INFO - retry: Failed, sleeping 60 seconds before retrying 12:59:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:59:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:59:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:59:37 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LTic8qpgShOwrT12RCwBCw/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! 12:59:37 INFO - Caught exception: HTTP Error 404: Not Found 12:59:37 INFO - Caught exception: HTTP Error 404: Not Found 12:59:37 INFO - Caught exception: HTTP Error 404: Not Found 12:59:37 INFO - trying https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:59:37 INFO - Downloading https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:59:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/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 12:59:39 INFO - Downloaded 56770833 bytes. 12:59:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:59:39 INFO - mkdir: /builds/slave/test/properties 12:59:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:59:39 INFO - Writing to file /builds/slave/test/properties/build_url 12:59:39 INFO - Contents: 12:59:39 INFO - build_url:https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:59:40 INFO - Running post-action listener: _resource_record_post_action 12:59:40 INFO - Running post-action listener: set_extra_try_arguments 12:59:40 INFO - [mozharness: 2016-09-26 19:59:40.046861Z] Finished download-and-extract step (success) 12:59:40 INFO - [mozharness: 2016-09-26 19:59:40.047093Z] Running populate-webroot step. 12:59:40 INFO - Running pre-action listener: _resource_record_pre_action 12:59:40 INFO - Running main action method: populate_webroot 12:59:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:59:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:59:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:59:40 INFO - '--tppagecycles', 12:59:40 INFO - '7'], 12:59:40 INFO - 'tests': ['tsvgm']}}, 12:59:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:59:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:59:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:59:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:59:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:59:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:59:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:40 INFO - 'talos_options': ['--disable-e10s'], 12:59:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:59:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:59:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:40 INFO - 'talos_options': ['--disable-e10s'], 12:59:40 INFO - 'tests': ['damp', 'tps']}, 12:59:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:40 INFO - 'tests': ['damp', 'tps']}, 12:59:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:59:40 INFO - 'tests': ['dromaeo_dom']}, 12:59:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:59:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:59:40 INFO - 'tests': ['basic_compositor_video']}, 12:59:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:59:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:59:40 INFO - 'tests': ['a11yr', 12:59:40 INFO - 'ts_paint', 12:59:40 INFO - 'tpaint', 12:59:40 INFO - 'sessionrestore', 12:59:40 INFO - 'sessionrestore_no_auto_restore', 12:59:40 INFO - 'tabpaint']}, 12:59:40 INFO - 'other-e10s': {'tests': ['a11yr', 12:59:40 INFO - 'ts_paint', 12:59:40 INFO - 'tpaint', 12:59:40 INFO - 'sessionrestore', 12:59:40 INFO - 'sessionrestore_no_auto_restore', 12:59:40 INFO - 'tabpaint']}, 12:59:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:59:40 INFO - 'ts_paint', 12:59:40 INFO - 'tpaint', 12:59:40 INFO - 'sessionrestore', 12:59:40 INFO - 'sessionrestore_no_auto_restore', 12:59:40 INFO - 'tabpaint']}, 12:59:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:59:40 INFO - 'ts_paint', 12:59:40 INFO - 'tpaint', 12:59:40 INFO - 'sessionrestore', 12:59:40 INFO - 'sessionrestore_no_auto_restore', 12:59:40 INFO - 'tabpaint']}, 12:59:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:59:40 INFO - 'tests': ['a11yr', 12:59:40 INFO - 'ts_paint', 12:59:40 INFO - 'tpaint', 12:59:40 INFO - 'sessionrestore', 12:59:40 INFO - 'sessionrestore_no_auto_restore', 12:59:40 INFO - 'tabpaint']}, 12:59:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:59:40 INFO - 'tests': ['a11yr', 12:59:40 INFO - 'ts_paint', 12:59:40 INFO - 'tpaint', 12:59:40 INFO - 'sessionrestore', 12:59:40 INFO - 'sessionrestore_no_auto_restore', 12:59:40 INFO - 'tabpaint']}, 12:59:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:59:40 INFO - 'tests': ['tsvgx', 12:59:40 INFO - 'tsvgr_opacity', 12:59:40 INFO - 'tart', 12:59:40 INFO - 'tscrollx', 12:59:40 INFO - 'cart']}, 12:59:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:59:40 INFO - 'tsvgr_opacity', 12:59:40 INFO - 'tart', 12:59:40 INFO - 'tscrollx', 12:59:40 INFO - 'cart']}, 12:59:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:40 INFO - 'talos_options': ['--disable-e10s'], 12:59:40 INFO - 'tests': ['tp5o']}, 12:59:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:40 INFO - 'tests': ['tp5o']}, 12:59:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:40 INFO - 'talos_options': ['--disable-e10s', 12:59:40 INFO - '--xperf_path', 12:59:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:59:40 INFO - 'tests': ['tp5n']}, 12:59:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:59:40 INFO - 'talos_options': ['--xperf_path', 12:59:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:59:40 INFO - 'tests': ['tp5n']}}, 12:59:40 INFO - 'talos.zip': {'path': '', 12:59:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:59:40 INFO - Running post-action listener: _resource_record_post_action 12:59:40 INFO - [mozharness: 2016-09-26 19:59:40.065499Z] Finished populate-webroot step (success) 12:59:40 INFO - [mozharness: 2016-09-26 19:59:40.065587Z] Running create-virtualenv step. 12:59:40 INFO - Running pre-action listener: _resource_record_pre_action 12:59:40 INFO - Running main action method: create_virtualenv 12:59:40 INFO - Creating virtualenv /builds/slave/test/build/venv 12:59:40 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:59:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:59:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:59:40 INFO - Using real prefix '/usr' 12:59:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:59:40 INFO - Installing distribute.............................................................................................................................................................................................done. 12:59:42 INFO - Installing pip.................done. 12:59:42 INFO - Return code: 0 12:59:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:59:42 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')]} 12:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:42 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')]} 12:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:42 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 0x1df8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b73b0>, '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 0x20b82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b8740>, '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': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', '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 12:59:42 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 12:59:42 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 12:59:42 INFO - Using env: {'DISPLAY': ':0', 12:59:42 INFO - 'HOME': '/home/cltbld', 12:59:42 INFO - 'LANG': 'en_US.UTF-8', 12:59:42 INFO - 'LANGUAGE': 'en_US:en', 12:59:42 INFO - 'LOGNAME': 'cltbld', 12:59:42 INFO - 'MAIL': '/var/mail/cltbld', 12:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:42 INFO - 'MOZ_NO_REMOTE': '1', 12:59:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:59:42 INFO - 'NO_EM_RESTART': '1', 12:59:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:42 INFO - 'PWD': '/builds/slave/test', 12:59:42 INFO - 'SHELL': '/bin/bash', 12:59:42 INFO - 'SHLVL': '1', 12:59:42 INFO - 'TERM': 'linux', 12:59:42 INFO - 'TMOUT': '86400', 12:59:42 INFO - 'USER': 'cltbld', 12:59:42 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', 12:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:42 INFO - '_': '/tools/buildbot/bin/python'} 12:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:42 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 12:59:42 INFO - Cleaning up... 12:59:42 INFO - Return code: 0 12:59:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:59:42 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')]} 12:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:42 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')]} 12:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:42 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 0x1df8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b73b0>, '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 0x20b82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b8740>, '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': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', '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 12:59:42 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 12:59:42 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 12:59:42 INFO - Using env: {'DISPLAY': ':0', 12:59:42 INFO - 'HOME': '/home/cltbld', 12:59:42 INFO - 'LANG': 'en_US.UTF-8', 12:59:42 INFO - 'LANGUAGE': 'en_US:en', 12:59:42 INFO - 'LOGNAME': 'cltbld', 12:59:42 INFO - 'MAIL': '/var/mail/cltbld', 12:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:42 INFO - 'MOZ_NO_REMOTE': '1', 12:59:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:59:42 INFO - 'NO_EM_RESTART': '1', 12:59:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:42 INFO - 'PWD': '/builds/slave/test', 12:59:42 INFO - 'SHELL': '/bin/bash', 12:59:42 INFO - 'SHLVL': '1', 12:59:42 INFO - 'TERM': 'linux', 12:59:42 INFO - 'TMOUT': '86400', 12:59:42 INFO - 'USER': 'cltbld', 12:59:42 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', 12:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:42 INFO - '_': '/tools/buildbot/bin/python'} 12:59:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:43 INFO - Downloading/unpacking psutil>=3.1.1 12:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:59:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:59:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:59:45 INFO - Installing collected packages: psutil 12:59:45 INFO - Running setup.py install for psutil 12:59:45 INFO - building 'psutil._psutil_linux' extension 12:59:45 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 12:59:45 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 12:59:45 INFO - building 'psutil._psutil_posix' extension 12:59:45 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 12:59:45 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 12:59:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:59:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:59:45 INFO - Successfully installed psutil 12:59:45 INFO - Cleaning up... 12:59:45 INFO - Return code: 0 12:59:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:59:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:45 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 0x1df8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b73b0>, '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 0x20b82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b8740>, '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': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', '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 12:59:45 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 12:59:45 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 12:59:45 INFO - Using env: {'DISPLAY': ':0', 12:59:45 INFO - 'HOME': '/home/cltbld', 12:59:45 INFO - 'LANG': 'en_US.UTF-8', 12:59:45 INFO - 'LANGUAGE': 'en_US:en', 12:59:45 INFO - 'LOGNAME': 'cltbld', 12:59:45 INFO - 'MAIL': '/var/mail/cltbld', 12:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:45 INFO - 'MOZ_NO_REMOTE': '1', 12:59:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:59:45 INFO - 'NO_EM_RESTART': '1', 12:59:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:45 INFO - 'PWD': '/builds/slave/test', 12:59:45 INFO - 'SHELL': '/bin/bash', 12:59:45 INFO - 'SHLVL': '1', 12:59:45 INFO - 'TERM': 'linux', 12:59:45 INFO - 'TMOUT': '86400', 12:59:45 INFO - 'USER': 'cltbld', 12:59:45 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', 12:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:45 INFO - '_': '/tools/buildbot/bin/python'} 12:59:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:59:45 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 12:59:45 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 12:59:45 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 12:59:45 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 12:59:45 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 12:59:45 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 12:59:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:59:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:59:47 INFO - Installing collected packages: mozsystemmonitor 12:59:47 INFO - Running setup.py install for mozsystemmonitor 12:59:47 INFO - Successfully installed mozsystemmonitor 12:59:47 INFO - Cleaning up... 12:59:47 INFO - Return code: 0 12:59:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:59:47 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')]} 12:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:47 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')]} 12:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:47 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 0x1df8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b73b0>, '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 0x20b82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b8740>, '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': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', '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 12:59:47 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 12:59:47 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 12:59:47 INFO - Using env: {'DISPLAY': ':0', 12:59:47 INFO - 'HOME': '/home/cltbld', 12:59:47 INFO - 'LANG': 'en_US.UTF-8', 12:59:47 INFO - 'LANGUAGE': 'en_US:en', 12:59:47 INFO - 'LOGNAME': 'cltbld', 12:59:47 INFO - 'MAIL': '/var/mail/cltbld', 12:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:47 INFO - 'MOZ_NO_REMOTE': '1', 12:59:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:59:47 INFO - 'NO_EM_RESTART': '1', 12:59:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:47 INFO - 'PWD': '/builds/slave/test', 12:59:47 INFO - 'SHELL': '/bin/bash', 12:59:47 INFO - 'SHLVL': '1', 12:59:47 INFO - 'TERM': 'linux', 12:59:47 INFO - 'TMOUT': '86400', 12:59:47 INFO - 'USER': 'cltbld', 12:59:47 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', 12:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:47 INFO - '_': '/tools/buildbot/bin/python'} 12:59:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:48 INFO - Downloading/unpacking jsonschema==2.5.1 12:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:59:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:50 INFO - Downloading functools32-3.2.3-2.tar.gz 12:59:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:59:50 INFO - warning: no files found matching '*.txt' 12:59:50 INFO - no previously-included directories found matching 'build' 12:59:50 INFO - no previously-included directories found matching 'dist' 12:59:50 INFO - no previously-included directories found matching '.git*' 12:59:50 INFO - Installing collected packages: jsonschema, functools32 12:59:50 INFO - Running setup.py install for functools32 12:59:50 INFO - warning: no files found matching '*.txt' 12:59:50 INFO - no previously-included directories found matching 'build' 12:59:50 INFO - no previously-included directories found matching 'dist' 12:59:50 INFO - no previously-included directories found matching '.git*' 12:59:50 INFO - Successfully installed jsonschema functools32 12:59:50 INFO - Cleaning up... 12:59:50 INFO - Return code: 0 12:59:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:59:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:50 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 0x1df8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b73b0>, '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 0x20b82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b8740>, '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': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', '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 12:59:50 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 12:59:50 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 12:59:50 INFO - Using env: {'DISPLAY': ':0', 12:59:50 INFO - 'HOME': '/home/cltbld', 12:59:50 INFO - 'LANG': 'en_US.UTF-8', 12:59:50 INFO - 'LANGUAGE': 'en_US:en', 12:59:50 INFO - 'LOGNAME': 'cltbld', 12:59:50 INFO - 'MAIL': '/var/mail/cltbld', 12:59:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:50 INFO - 'MOZ_NO_REMOTE': '1', 12:59:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:59:50 INFO - 'NO_EM_RESTART': '1', 12:59:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:59:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:50 INFO - 'PWD': '/builds/slave/test', 12:59:50 INFO - 'SHELL': '/bin/bash', 12:59:50 INFO - 'SHLVL': '1', 12:59:50 INFO - 'TERM': 'linux', 12:59:50 INFO - 'TMOUT': '86400', 12:59:50 INFO - 'USER': 'cltbld', 12:59:50 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', 12:59:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:50 INFO - '_': '/tools/buildbot/bin/python'} 12:59:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:59:50 INFO - Cleaning up... 12:59:50 INFO - Return code: 0 12:59:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:59:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:50 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 0x1df8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b73b0>, '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 0x20b82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b8740>, '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': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', '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 12:59:50 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 12:59:50 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 12:59:50 INFO - Using env: {'DISPLAY': ':0', 12:59:50 INFO - 'HOME': '/home/cltbld', 12:59:50 INFO - 'LANG': 'en_US.UTF-8', 12:59:50 INFO - 'LANGUAGE': 'en_US:en', 12:59:50 INFO - 'LOGNAME': 'cltbld', 12:59:50 INFO - 'MAIL': '/var/mail/cltbld', 12:59:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:50 INFO - 'MOZ_NO_REMOTE': '1', 12:59:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:59:50 INFO - 'NO_EM_RESTART': '1', 12:59:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:59:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:50 INFO - 'PWD': '/builds/slave/test', 12:59:50 INFO - 'SHELL': '/bin/bash', 12:59:50 INFO - 'SHLVL': '1', 12:59:50 INFO - 'TERM': 'linux', 12:59:50 INFO - 'TMOUT': '86400', 12:59:50 INFO - 'USER': 'cltbld', 12:59:50 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', 12:59:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:50 INFO - '_': '/tools/buildbot/bin/python'} 12:59:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:50 INFO - Downloading/unpacking blobuploader==1.2.4 12:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:52 INFO - Downloading blobuploader-1.2.4.tar.gz 12:59:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:59:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:59:52 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 12:59:52 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 12:59:52 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 12:59:52 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 12:59:52 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 12:59:52 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 12:59:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:59:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:53 INFO - Downloading docopt-0.6.1.tar.gz 12:59:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:59:53 INFO - Installing collected packages: blobuploader, requests, docopt 12:59:53 INFO - Running setup.py install for blobuploader 12:59:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:59:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:59:53 INFO - Running setup.py install for requests 12:59:54 INFO - Running setup.py install for docopt 12:59:54 INFO - Successfully installed blobuploader requests docopt 12:59:54 INFO - Cleaning up... 12:59:54 INFO - Return code: 0 12:59:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:59:54 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')]} 12:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:54 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')]} 12:59:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:54 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 0x1df8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b73b0>, '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 0x20b82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b8740>, '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': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', '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 12:59:54 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 12:59:54 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 12:59:54 INFO - Using env: {'DISPLAY': ':0', 12:59:54 INFO - 'HOME': '/home/cltbld', 12:59:54 INFO - 'LANG': 'en_US.UTF-8', 12:59:54 INFO - 'LANGUAGE': 'en_US:en', 12:59:54 INFO - 'LOGNAME': 'cltbld', 12:59:54 INFO - 'MAIL': '/var/mail/cltbld', 12:59:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:54 INFO - 'MOZ_NO_REMOTE': '1', 12:59:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:59:54 INFO - 'NO_EM_RESTART': '1', 12:59:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:59:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:54 INFO - 'PWD': '/builds/slave/test', 12:59:54 INFO - 'SHELL': '/bin/bash', 12:59:54 INFO - 'SHLVL': '1', 12:59:54 INFO - 'TERM': 'linux', 12:59:54 INFO - 'TMOUT': '86400', 12:59:54 INFO - 'USER': 'cltbld', 12:59:54 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', 12:59:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:54 INFO - '_': '/tools/buildbot/bin/python'} 12:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:59:54 INFO - Running setup.py (path:/tmp/pip-CtFmv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:59:54 INFO - Running setup.py (path:/tmp/pip-Txr94V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:59:54 INFO - Running setup.py (path:/tmp/pip-bVzHn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:59:54 INFO - Running setup.py (path:/tmp/pip-QYCB11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:59:54 INFO - Running setup.py (path:/tmp/pip-lLNgv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:59:54 INFO - Running setup.py (path:/tmp/pip-o33bIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:59:54 INFO - Running setup.py (path:/tmp/pip-avRIjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:59:54 INFO - Running setup.py (path:/tmp/pip-uXsIBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:59:55 INFO - Running setup.py (path:/tmp/pip-UuBwKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:59:55 INFO - Running setup.py (path:/tmp/pip-S2sbUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:59:55 INFO - Running setup.py (path:/tmp/pip-ck7_tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:59:55 INFO - Running setup.py (path:/tmp/pip-fTYy15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:59:55 INFO - Running setup.py (path:/tmp/pip-x3MRnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:59:55 INFO - Running setup.py (path:/tmp/pip-PyoSCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:55 INFO - Running setup.py (path:/tmp/pip-bC6K0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:59:55 INFO - Running setup.py (path:/tmp/pip-_Q0ISH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:59:55 INFO - Running setup.py (path:/tmp/pip-CWP1th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:59:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:59:55 INFO - Running setup.py install for manifestparser 12:59:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:59:55 INFO - Running setup.py install for mozcrash 12:59:55 INFO - Running setup.py install for mozdebug 12:59:55 INFO - Running setup.py install for mozdevice 12:59:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:59:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:59:56 INFO - Running setup.py install for mozfile 12:59:56 INFO - Running setup.py install for mozhttpd 12:59:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:59:56 INFO - Running setup.py install for mozinfo 12:59:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:59:56 INFO - Running setup.py install for mozInstall 12:59:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:59:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:59:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:59:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:59:56 INFO - Running setup.py install for mozleak 12:59:56 INFO - Running setup.py install for mozlog 12:59:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:59:56 INFO - Running setup.py install for moznetwork 12:59:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:59:56 INFO - Running setup.py install for mozprocess 12:59:56 INFO - Running setup.py install for mozprofile 12:59:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:59:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:59:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:59:56 INFO - Running setup.py install for mozrunner 12:59:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:59:57 INFO - Running setup.py install for mozscreenshot 12:59:57 INFO - Running setup.py install for moztest 12:59:57 INFO - Running setup.py install for mozversion 12:59:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:59:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:59:57 INFO - Cleaning up... 12:59:57 INFO - Return code: 0 12:59:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:59:57 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')]} 12:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:57 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')]} 12:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:57 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 0x1df8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b73b0>, '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 0x20b82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b8740>, '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': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', '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 12:59:57 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 12:59:57 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 12:59:57 INFO - Using env: {'DISPLAY': ':0', 12:59:57 INFO - 'HOME': '/home/cltbld', 12:59:57 INFO - 'LANG': 'en_US.UTF-8', 12:59:57 INFO - 'LANGUAGE': 'en_US:en', 12:59:57 INFO - 'LOGNAME': 'cltbld', 12:59:57 INFO - 'MAIL': '/var/mail/cltbld', 12:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:57 INFO - 'MOZ_NO_REMOTE': '1', 12:59:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:59:57 INFO - 'NO_EM_RESTART': '1', 12:59:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:57 INFO - 'PWD': '/builds/slave/test', 12:59:57 INFO - 'SHELL': '/bin/bash', 12:59:57 INFO - 'SHLVL': '1', 12:59:57 INFO - 'TERM': 'linux', 12:59:57 INFO - 'TMOUT': '86400', 12:59:57 INFO - 'USER': 'cltbld', 12:59:57 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', 12:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:57 INFO - '_': '/tools/buildbot/bin/python'} 12:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:59:57 INFO - Running setup.py (path:/tmp/pip-zC7OPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:59:57 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)) 12:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:59:57 INFO - Running setup.py (path:/tmp/pip-4jHxL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:59:57 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)) 12:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:59:57 INFO - Running setup.py (path:/tmp/pip-3iEkU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:59:57 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)) 12:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:59:57 INFO - Running setup.py (path:/tmp/pip-9u3S1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:59:57 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)) 12:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:59:57 INFO - Running setup.py (path:/tmp/pip-qKmoHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:59:58 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)) 12:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:59:58 INFO - Running setup.py (path:/tmp/pip-bMJ_Er-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:59:58 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)) 12:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:59:58 INFO - Running setup.py (path:/tmp/pip-NCAPBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:59:58 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)) 12:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:59:58 INFO - Running setup.py (path:/tmp/pip-z8_mxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:59:58 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)) 12:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:59:58 INFO - Running setup.py (path:/tmp/pip-r50Id_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:59:58 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)) 12:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:59:58 INFO - Running setup.py (path:/tmp/pip-n5b_n1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:59:58 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)) 12:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:59:58 INFO - Running setup.py (path:/tmp/pip-AbuK6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:59:58 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)) 12:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:59:58 INFO - Running setup.py (path:/tmp/pip-Q6TTBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:59:58 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)) 12:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:59:58 INFO - Running setup.py (path:/tmp/pip-VjY9KO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:59:58 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)) 12:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:59:58 INFO - Running setup.py (path:/tmp/pip-zgs1iO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:59:58 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)) 12:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:58 INFO - Running setup.py (path:/tmp/pip-yAawcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:58 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)) 12:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:59:58 INFO - Running setup.py (path:/tmp/pip-tDQf0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:59:58 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)) 12:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:59:58 INFO - Running setup.py (path:/tmp/pip-gvzfG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:59:58 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)) 12:59:58 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)) 12:59:58 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)) 12:59:58 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)) 12:59:58 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)) 12:59:58 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)) 12:59:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:59: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 12:59: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 12:59: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 12:59: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 12:59: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 12:59: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 13:00:00 INFO - Downloading blessings-1.6.tar.gz 13:00:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:00:00 INFO - Installing collected packages: blessings 13:00:00 INFO - Running setup.py install for blessings 13:00:01 INFO - Successfully installed blessings 13:00:01 INFO - Cleaning up... 13:00:01 INFO - Return code: 0 13:00:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:00:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:00:01 INFO - Reading from file tmpfile_stdout 13:00:01 INFO - Current package versions: 13:00:01 INFO - argparse == 1.2.1 13:00:01 INFO - blessings == 1.6 13:00:01 INFO - blobuploader == 1.2.4 13:00:01 INFO - docopt == 0.6.1 13:00:01 INFO - functools32 == 3.2.3-2 13:00:01 INFO - jsonschema == 2.5.1 13:00:01 INFO - manifestparser == 1.1 13:00:01 INFO - mozInstall == 1.12 13:00:01 INFO - mozcrash == 0.17 13:00:01 INFO - mozdebug == 0.1 13:00:01 INFO - mozdevice == 0.48 13:00:01 INFO - mozfile == 1.2 13:00:01 INFO - mozhttpd == 0.7 13:00:01 INFO - mozinfo == 0.9 13:00:01 INFO - mozleak == 0.1 13:00:01 INFO - mozlog == 3.3 13:00:01 INFO - moznetwork == 0.27 13:00:01 INFO - mozprocess == 0.23 13:00:01 INFO - mozprofile == 0.28 13:00:01 INFO - mozrunner == 6.12 13:00:01 INFO - mozscreenshot == 0.1 13:00:01 INFO - mozsystemmonitor == 0.3 13:00:01 INFO - moztest == 0.7 13:00:01 INFO - mozversion == 1.4 13:00:01 INFO - psutil == 3.1.1 13:00:01 INFO - requests == 1.2.3 13:00:01 INFO - wsgiref == 0.1.2 13:00:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00: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')]} 13:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00: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')]} 13:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:01 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 0x1df8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b73b0>, '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 0x20b82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b8740>, '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': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', '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 13:00:01 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 13:00:01 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 13:00:01 INFO - Using env: {'DISPLAY': ':0', 13:00:01 INFO - 'HOME': '/home/cltbld', 13:00:01 INFO - 'LANG': 'en_US.UTF-8', 13:00:01 INFO - 'LANGUAGE': 'en_US:en', 13:00:01 INFO - 'LOGNAME': 'cltbld', 13:00:01 INFO - 'MAIL': '/var/mail/cltbld', 13:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:01 INFO - 'MOZ_NO_REMOTE': '1', 13:00:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:01 INFO - 'NO_EM_RESTART': '1', 13:00:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:01 INFO - 'PWD': '/builds/slave/test', 13:00:01 INFO - 'SHELL': '/bin/bash', 13:00:01 INFO - 'SHLVL': '1', 13:00:01 INFO - 'TERM': 'linux', 13:00:01 INFO - 'TMOUT': '86400', 13:00:01 INFO - 'USER': 'cltbld', 13:00:01 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', 13:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:01 INFO - '_': '/tools/buildbot/bin/python'} 13:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:01 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)) 13:00:01 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)) 13:00:01 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)) 13:00:01 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)) 13:00:01 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)) 13:00:01 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)) 13:00:01 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)) 13:00:01 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)) 13:00:01 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)) 13:00:01 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)) 13:00:01 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)) 13:00:01 INFO - Cleaning up... 13:00:01 INFO - Return code: 0 13:00:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:00: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')]} 13:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00: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')]} 13:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b73b0>, '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 0x20b82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b8740>, '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': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', '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 13:00:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:00:01 INFO - Using env: {'DISPLAY': ':0', 13:00:01 INFO - 'HOME': '/home/cltbld', 13:00:01 INFO - 'LANG': 'en_US.UTF-8', 13:00:01 INFO - 'LANGUAGE': 'en_US:en', 13:00:01 INFO - 'LOGNAME': 'cltbld', 13:00:01 INFO - 'MAIL': '/var/mail/cltbld', 13:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:01 INFO - 'MOZ_NO_REMOTE': '1', 13:00:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:01 INFO - 'NO_EM_RESTART': '1', 13:00:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:01 INFO - 'PWD': '/builds/slave/test', 13:00:01 INFO - 'SHELL': '/bin/bash', 13:00:01 INFO - 'SHLVL': '1', 13:00:01 INFO - 'TERM': 'linux', 13:00:01 INFO - 'TMOUT': '86400', 13:00:01 INFO - 'USER': 'cltbld', 13:00:01 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', 13:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:01 INFO - '_': '/tools/buildbot/bin/python'} 13:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:00:01 INFO - Cleaning up... 13:00:01 INFO - Return code: 0 13:00:01 INFO - Running post-action listener: _resource_record_post_action 13:00:01 INFO - Running post-action listener: _start_resource_monitoring 13:00:01 INFO - Starting resource monitoring. 13:00:01 INFO - [mozharness: 2016-09-26 20:00:01.764384Z] Finished create-virtualenv step (success) 13:00:01 INFO - [mozharness: 2016-09-26 20:00:01.764820Z] Running install step. 13:00:01 INFO - Running pre-action listener: _resource_record_pre_action 13:00:01 INFO - Running main action method: install 13:00:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:00:01 INFO - Reading from file tmpfile_stdout 13:00:01 INFO - Detecting whether we're running mozinstall >=1.0... 13:00:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:00:02 INFO - Reading from file tmpfile_stdout 13:00:02 INFO - Output received: 13:00:02 INFO - Usage: mozinstall [options] installer 13:00:02 INFO - Options: 13:00:02 INFO - -h, --help show this help message and exit 13:00:02 INFO - -d DEST, --destination=DEST 13:00:02 INFO - Directory to install application into. [default: 13:00:02 INFO - "/builds/slave/test"] 13:00:02 INFO - --app=APP Application being installed. [default: firefox] 13:00:02 INFO - mkdir: /builds/slave/test/build/application 13:00:02 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'] 13:00:02 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 13:00:15 INFO - Reading from file tmpfile_stdout 13:00:15 INFO - Output received: 13:00:15 INFO - /builds/slave/test/build/application/firefox/firefox 13:00:15 INFO - Running post-action listener: _resource_record_post_action 13:00:15 INFO - [mozharness: 2016-09-26 20:00:15.568494Z] Finished install step (success) 13:00:15 INFO - [mozharness: 2016-09-26 20:00:15.568769Z] Running run-tests step. 13:00:15 INFO - Running pre-action listener: _resource_record_pre_action 13:00:15 INFO - Running main action method: run_tests 13:00:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:00:15 INFO - Python 2.7.3 13:00:15 INFO - Return code: 0 13:00:15 INFO - grabbing minidump binary from tooltool 13:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:15 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 0x20b73b0>, '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 0x20b82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20b8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:00:15 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 13:00:15 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 13:00:15 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 13:00:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:00:15 INFO - Return code: 0 13:00:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:00:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:00:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:00:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:00:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:00:15 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/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 13:00:15 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/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --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 13:00:15 INFO - Using env: {'DISPLAY': ':0', 13:00:15 INFO - 'HOME': '/home/cltbld', 13:00:15 INFO - 'LANG': 'en_US.UTF-8', 13:00:15 INFO - 'LANGUAGE': 'en_US:en', 13:00:15 INFO - 'LOGNAME': 'cltbld', 13:00:15 INFO - 'MAIL': '/var/mail/cltbld', 13:00:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:00:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:15 INFO - 'MOZ_NO_REMOTE': '1', 13:00:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:00:15 INFO - 'NO_EM_RESTART': '1', 13:00:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:15 INFO - 'PWD': '/builds/slave/test', 13:00:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:00:15 INFO - 'SHELL': '/bin/bash', 13:00:15 INFO - 'SHLVL': '1', 13:00:15 INFO - 'TERM': 'linux', 13:00:15 INFO - 'TMOUT': '86400', 13:00:15 INFO - 'USER': 'cltbld', 13:00:15 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455', 13:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:15 INFO - '_': '/tools/buildbot/bin/python'} 13:00:15 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/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 13:00:15 INFO - mozversion application_buildid: 20160926095751 13:00:15 INFO - mozversion application_changeset: 27ce6db0b235243ccddffc0ec3ffd1e1963cbb84 13:00:15 INFO - mozversion application_display_name: Firefox 13:00:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:00:15 INFO - mozversion application_name: Firefox 13:00:15 INFO - mozversion application_remotingname: firefox 13:00:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:00:15 INFO - mozversion application_vendor: Mozilla 13:00:15 INFO - mozversion application_version: 50.0 13:00:15 INFO - mozversion platform_buildid: 20160926095751 13:00:15 INFO - mozversion platform_changeset: 27ce6db0b235243ccddffc0ec3ffd1e1963cbb84 13:00:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:00:15 INFO - mozversion platform_version: 50.0 13:00:15 INFO - using testdate: 1474920015 13:00:15 INFO - actual date: 1474920015 13:00:15 INFO - starting webserver on 'localhost:42290' 13:00:15 INFO - SUITE-START | Running 2 tests 13:00:15 INFO - TEST-START | dromaeo_css 13:00:15 INFO - operating with platform_type : linux_ 13:00:15 INFO - Initialising browser for dromaeo_css test... 13:00:15 INFO - TEST-INFO | started process 31772 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL1tIAT/profile http://localhost:42290/getInfo.html) 13:00:19 INFO - PROCESS | 31772 | __metrics Screen width/height:1600/1200 13:00:19 INFO - PROCESS | 31772 | colorDepth:24 13:00:19 INFO - PROCESS | 31772 | Browser inner width/height: 1024/697 13:00:19 INFO - PROCESS | 31772 | __metrics 13:00:20 INFO - PROCESS | 31772 | 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" 13:00:20 INFO - PROCESS | 31772 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:00:21 INFO - TEST-INFO | 31772: exit 0 13:00:21 INFO - Browser initialized. 13:00:21 INFO - Running cycle 1/1 for dromaeo_css test... 13:00:21 INFO - TEST-INFO | started process 31863 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL1tIAT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:00:33 INFO - PROCESS | 31863 | RSS: Main: 143503360 13:00:33 INFO - PROCESS | 31863 | 13:01:24 INFO - PROCESS | 31863 | 1474920084256 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] 13:03:32 INFO - PROCESS | 31863 | Cycle 1(1): loaded http://localhost:42290/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42290/tests/dromaeo/cssquery-ext.html) 13:03:33 INFO - PROCESS | 31863 | RSS: Main: 266772480 13:03:33 INFO - PROCESS | 31863 | 13:06:36 INFO - PROCESS | 31863 | Cycle 1(1): loaded http://localhost:42290/tests/dromaeo/cssquery-ext.html (next: http://localhost:42290/tests/dromaeo/cssquery-jquery.html) 13:06:36 INFO - PROCESS | 31863 | RSS: Main: 226250752 13:06:36 INFO - PROCESS | 31863 | 13:09:38 INFO - PROCESS | 31863 | Cycle 1(1): loaded http://localhost:42290/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42290/tests/dromaeo/cssquery-mootools.html) 13:09:39 INFO - PROCESS | 31863 | RSS: Main: 198352896 13:09:39 INFO - PROCESS | 31863 | 13:12:41 INFO - PROCESS | 31863 | Cycle 1(1): loaded http://localhost:42290/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42290/tests/dromaeo/cssquery-prototype.html) 13:12:41 INFO - PROCESS | 31863 | RSS: Main: 174186496 13:12:41 INFO - PROCESS | 31863 | 13:15:44 INFO - PROCESS | 31863 | Cycle 1(1): loaded http://localhost:42290/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42290/tests/dromaeo/cssquery-yui.html) 13:15:44 INFO - PROCESS | 31863 | RSS: Main: 175751168 13:15:44 INFO - PROCESS | 31863 | 13:18:47 INFO - PROCESS | 31863 | Cycle 1(1): loaded http://localhost:42290/tests/dromaeo/cssquery-yui.html (next: http://localhost:42290/tests/dromaeo/cssquery-dojo.html) 13:18:47 INFO - PROCESS | 31863 | RSS: Main: 252821504 13:18:47 INFO - PROCESS | 31863 | 13:18:47 INFO - PROCESS | 31863 | __start_tp_report 13:18:47 INFO - PROCESS | 31863 | _x_x_mozilla_page_load 13:18:47 INFO - PROCESS | 31863 | _x_x_mozilla_page_load_details 13:18:47 INFO - PROCESS | 31863 | |i|pagename|runs| 13:18:47 INFO - PROCESS | 31863 | |0;dojo.html;3184.5382419549164;3194.9041528754133;3210.165357007179;3216;3243.0595127413053;5460.754266058027;5551.583631227664;5571.693556854371;5572.219889215513;5577.135543990683;5565.638233514819;5628.71856407179;5629.211910332557;5629.831105066854;5664.0937450008005;4917.262410638415;4961.900761984758;5051.166557518005;5088.23676448532;5141.665791723526;4976.701397916137;5008.724520151391;5028.321176641143;5028.572571331441;5112.437631860511;5065.645404821664;5085.0592640361465;5091.770870310845;5097.872553186163;5135.12702840518;5111.821086261982;5145.434002259748;5157.819476318348;5161.096808058613;5190.974045129788;4837.95162048382;4957.107720610289;4998.375203099613;5066.214736715815;5071.594272458195;5488.588355873286;5526.834194974157;5539.750711217965;5553.111502159675;5593;5941.405859414024;5955.880882382371;5958.433948774859;5958.851028724316;5961.076033214858;5786.276715410574;5803.622764520335;5864.824055278305;5886.411358864122;5990.640561566276;5510.366307874606;5512.6968016759065;5514.724263786835;5520.503154716095;5532.197831314477;6005.6997150142315;6005.819825405245;6006.339302676702;6010.068439391901;6021.036634138517;5664.575156863251;5736.196932429463;5825.504832089235;5832.708364581838;5886.175935369051;5988.580799344091;5994.460498555151;5995.760169593167;5999.970000149971;6028.758849645965;5928.555358348141;5937.079752638348;5970.462658360681;5983.222181116514;5986.670733109634;5624.437556244343;5754.280714910637;5807.593468457252;5820.447057529528;5976.073708575178;4535.841245556389;4539.273716205391;4553.681242313072;4553.749543775058;4560.384348113029;4037.7577345359373;4041.7170798043853;4048.473698419187;4052.858149964737;4062.614051665087;5491.807786727525;5631.831045068655;5641.915371269434;5656.971715141398;5663.093904975267;5966.015607424726;6002.249718785152;6029.427204415573;6056.303525094582;6065.363136870653;5679.403662615448;5718.79984200551;5729.627574207663;5731.512821410143;5739.540836733051;5051;5145.536901678867;5212.582993360522;5213.113770658922;5218.9739051304505;6121.816345509642;6178.382161783876;6190.876182476325;6192.256929168529;6199.225096862892;5973.49225315853;6012.7294271757;6020.729067191899;6032.065029920193;6067.939320606737;5880;6089.391060893875;6190.845228869314;6193.38066193377;6198.535109866688;5865.032269675456;5899.085641725539;5905.881882362414;5923.970380148072;5934.287885453773;5914.822555323347;5987.520998320211;5993.550483713651;5997.160397544434;6004.819855404345;5673.744681489426;5740.885182296247;5864.765409383577;5873.119032145212;5874.471297583238;5807.883842323215;5846.590738648254;5866.149408335682;5896.292444906639;5913.911291330547;5827.038538641077;5839.737211825393;5839.824805255849;5839.8540036497725;5846.239988801429;5704.828855134353;5762.30852297727;5817.0983497557945;5848.824535263949;5875.3243377010485;5679.9432005681065;5727.255456790591;5729.885402292014;5733.02538568436;5767.625104368202 13:18:47 INFO - PROCESS | 31863 | |1;ext.html;578.5574035862563;620.7268801727241;649.5680372552252;650.2424675253333;658.9720036742679;35703.107422314424;38895.22209555811;39015.41476877849;47747.76126119364;48431;49751;50078.49921500784;50240.49759502404;50269.24596131061;50308.748456257665;9372;9510.429374237534;9517.238620910346;9519.23846092311;9525.857112143323;8614.52620105894;11129.387883666395;11333.716657083556;11374.146938979567;11718.707032324175;11385.48765305559;11424.657260282205;11453.026492748126;11458;11481.712957176096;11315.490802913888;11381.374024428653;11390.430478476126;11391.373474458902;11394.031507321846;10938.890611093866;10950.06924411426;10953.780924381548;10969.506372213269;10977.83533247002;47411.76294118542;48041.27938080932;48137.277940830914;48188;48235.276470852965;3826.7321287509885;3995.4805875236325;4043.312636851713;4292.742435453852;4645.883852903705;35763.82118089419;35810.104747381265;35918.8204058978;36157.81921090404;37911.24177516462;4580.541945805426;4629.513901040376;4636.11913736389;4644.326572646947;4722.716637001757;379190;379617;381323;384517;385088;67673;68079;68125.65937170283;68138.31861681418;68318.65840670864;40203.195936081116;41306.38040429396;41489;43941.78029109834;70468;18956.81043189578;18994.81005189958;19177.424677259703;19226.8077319225;19241.326553570558;13168.275744833934;13487.05590608648;14565.126092434488;14672.559823205322;14706.338214780146;21145.154193832383;21707.674384884238;21744.13023479043;22559.661605075937;22581;7387.298206670358;7555.282248186523;7610.733624323122;7677.692892284246;7853;955.7515046088067;1070.29895418501;1097.2757979733497;1113.0205419231024;1165.0213820390911;33170.50244246338;34273.48589771155;34774.652253477645;35148.648513514534;35510.82244588812;1142.754755671472;1146.9655910322704;1168.2231316174648;1207.7886369885234;1247.5009996001672;18069.457916262534;18118.909405453152;18165.636687266182;18227.72658410125;18234.54413639643;637675.3046112936;643790;644536;645860;647173;65433;66101.66949165224;66131.33868661348;66214;66247.33752662508;1300.6033159886135;1421.864788142144;1440.8126943497277;1449.8695117439481;1478.8077549918441;35023;35941.58415841584;36070;36631.26737465183;36823.26353472862;56343.71828140833;57120.42879571151;57153.428465716475;57214.42785572091;57227.42772572387;461310;462962;465774;469769;469905;28359;31278.53082203814;31411.84294078515;32457.51313730343;32956;53054.204186936455;53074.469255306954;53519;53886.73056634849;54023.729881350344;44749;46093.30860037035;46663.30005049859;46729.76635116803;47102.29346559736;44656.553434466536;44670.77664611656;44810.77594612006;45347.77326113348;46908.7654561725;44144.55855441533;44902.32646510239;45077;45290;45430;36192.27615447729;39575.40636890507;39981;40365.79817100896;40496.595034049285;29696;30081.398372032;30181.69818301877;30285.848570757888;30326.241843954078 13:18:47 INFO - PROCESS | 31863 | |2;jquery.html;410.8048676878481;425.58493966087383;431.6093934988835;434.7891272732725;438.43004094676934;19607.313744018946;20645.896770516145;20753.16987320509;20956;21795.237166699168;19475.90262048688;20792.168313267488;20868.269610563635;20931.16275348988;20991.895040524814;5790.160426738131;6090.96954515229;6092.908606370908;6097.878042439149;6099;6008.819735407945;6019.187409699678;6247.875042499148;6284.183056202687;6349.1746073010445;6321.6523091229965;6341.556091073627;6343.429091381775;6352.4918006559365;6376.36236376361;6795.2185498667795;6944.895826562605;6964.79105626832;6977.616231107312;6981.476389270774;5986.132010858445;6005.039193728982;6039.124326972608;6052.122442245894;6264.812055638338;15439.768403473958;15571.766423503657;15590.532284031497;15659.217039147998;15795.921020394939;2403.543326767926;2524.305815900642;2724.713905039962;2871.798974071816;3023.4104349651825;10790.406527640976;12376.443060062229;12647.49410023598;15518.767218491732;15822.76265856013;1955.8337541309006;1956.3739603326942;2000.3098930868828;2024.4533975826444;2077.6364136276093;66489.66755166241;86381.56809215977;89837.55081224552;90370.5481472595;90680.91935862902;10994.175436842268;11110.61112861046;11450.568678915135;11794.292342459308;11979.940100299442;17102.914485427493;17121.22954467052;17141.657166856592;17143.91428042852;17164.141792910556;8648.913510864812;8903.821923561492;9040.683576074805;9094.36339456245;9276.443413395218;8916.821663566821;8979.10208979097;8995;9138.771530711785;9166.266698664223;5053.646244762831;5194.428612852583;5272;5281.9207711884355;5316.441773613715;1853.9907300463412;1892.262017813054;1911.5698967732428;1926.9903650481938;1946.7177259297323;964.6816550538306;1093.781243751253;1094.1137678480297;1105.237386203517;1117.3910218930703;19770.40688779339;20116.497087572636;20682;20788.064537095866;20821.167153314;1034.1158309645296;1046.4401545173296;1050.9316894401838;1063.186662203415;1084.9077828384675;11024.834627480594;11502.769944601061;11531.88468115325;11551.422428878523;11644.41777911118;553096;563002;572537;573124;574585;100983;103441;107391;108027;109634.45182774194;1039.8336266197373;1055.5883205549842;1071.164491696478;1076.9461526923621;1083.6315652678127;17813.554661133574;17830.46508604744;18114.637707246045;18436;18513.629727405107;48345.5165448341;48408;48482.5151748478;48696;50024.74987625039;65443.67278163579;65865;66296;66744.3325566738;67723.66138169277;7387.741429049957;8519.531425771642;8636.82726345457;8818.86771698438;8917.643294268417;10580.523876425425;13188.670283242613;13461.528846490326;13645.522406715718;13776.173429594257;11788.115891308014;11806.763864722829;12004.279743215435;12086.214396064228;12302.876971230173;11420.200585958903;11427.257228280469;11757;11904.285742855456;12023.338716370683;12033.939830300793;12070.379890323538;12146.453409596412;12328.50685972551;12328.630141095782;7915.327197188073;8411.074781774123;8706.04233534298;8960.372773906025;9172.633094676139;11748.29510229389;11825.586104486301;12252.632421027383;12461.252324860538;12642.557510487457 13:18:47 INFO - PROCESS | 31863 | |3;mootools.html;1368.9315534223285;1537.9692406151878;1554.5336399080277;1755.1926113987565;1880.031783631429;8770.956145219274;9003.684871029514;9100.90899091009;9356.953215233914;9389.295802814782;8845.425047371917;9017.909820901788;9316.8602470963;9333.953330233338;9351.438913665184;2526.974730252697;2549.260714392824;2564.846109233452;2575.240304110292;2577.7680008799202;2435.7198922123916;2503.198976327576;2505.3987043109705;2508.2600632813364;2509.6737424134835;2473.3940184654784;2488.825782195247;2509.510645424143;2510.623406489023;2516.421223118675;2411.8794060297005;2423.0671191591277;2432.7567243275707;2443.718972318188;2451.963220551693;2403.5072810073893;2424.9757502425105;2434.5739495588377;2435.537247922889;2447.877606119687;9116.08839116083;9252.491112988782;9288.303377246666;9366.765830854216;9373.437593744397;5505.256790333294;5735.913961290584;5751.539876809845;5754.280714910637;5757.021306377926;3577.83899724513;3588.3540962626716;3640.6177351377987;3642.8724994625063;3678.1540245743627;1213.5145941623332;1235.9567415140427;1243.0490674633913;1245.993770031144;1273.447082639435;13472.461101555926;13548.86451135486;13591.796123058162;13725.725485490235;13744.175349479063;4820.855374338776;4863.902721945541;4980.153373926441;5075.619328550373;5139.383274007143;11423.485943132579;11428.599998999995;11478.885211147948;11486;11486.597969071043;5793.507551858139;5807.361190269067;5828.154917536934;5831.854203644858;5834.795782147605;4546.499885012646;4550.0899820035465;5100.974495127501;5207.479252074762;5322.733863306897;4285;4328.675349348811;4378.912421751611;4430.911381772346;4433.6009759121835;1329.3751936589788;1351.290572449472;1371;1375.8211432513908;1389.485890220625;927;1006.7483129217695;1075.655790147161;1079.2499213046858;1079.422508957704;6454.9677251614385;6553.442957348679;6682.131322928086;6718.798436046926;6786.830329241809;499.61280007993366;510.3875349580444;513.0636588226502;514.8609875333639;516.4938360406823;4415.602595766332;4661.813527458864;4724.811007559728;4886.877828054327;4930.852074437773;11363.772724545463;11867.109966752356;11981.580644677395;12005.339706316236;12014.879851201551;11658.475368608264;11876.821847672292;11896.583619573274;11898.702532436586;11905.345206013579;8377.83244335122;8554.101819308882;8583.141685831491;8586.914130858737;8589.226969572643;6435.871282574229;6453.322401148;6495.480361571157;6537.8692426152165;6556.573822701509;10201.897981020094;10313.071823535918;10473.633422830164;10494.47527623631;10500.737481563023;10372.11836993864;10459.738506537398;10527.157827373945;10606.310589811637;10675.199360048182;7205.243449437943;8296.336293096418;8445.282151017233;8613.870791938001;8760.824783504167;9395.154436100784;9475.384100033476;9480.810383792423;9482.38364506305;9486.478243696387;8194.877076843972;8233.752987410388;8271.62777674994;8306.543140127109;8472.152784721478;8304;8347.457415267989;8366.707165249187;8403.537805420761;8619.655213791379;6464;6565.606063636006;6608.240052394036;6816.897746533707;7357.0803649543805;5377.112776391851;5424.864378390572;5505.587080969023;5532.6680399176175;5618.157276408408;5577.832665020056;5608.327000759935;5614.438556144353;5621.690807005491;5731.484166425042 13:18:47 INFO - PROCESS | 31863 | |4;prototype.html;356.03692013104546;357.2301689858355;357.8121486219736;358.62344538234845;365.35879531422364;5328.227407025979;5333.280007199027;5367.919481207785;5372.7313634318225;5380.515753582177;5232.973835130829;5265.183896496049;5287.576993840493;5294.470552944713;5302.575793936486;3845.615438456146;3969.6106362773016;4072.857449989251;4175.770332631704;4213.978930105345;4157.542670306264;4176.770277634744;4179.414881916534;4197.832086716528;4200.390943313218;4188.895277618053;4190.853320133796;4195.559466256045;4198.223328684188;4228.175505776375;4145.233131870614;4203.369494575808;4223.809928553192;4225.457707936596;4240.597143271415;3716.758410703296;3736.794476303802;3747.194353214045;3765.0775559987705;3769.246150769857;5084.211947148198;5108;5112.233165025239;5130.97434512825;5257.526822585947;4685.250359942427;4751.857444276678;4805.6155507559315;4820.614350851958;4833.202521583935;5495.203195536665;5499.835004949858;5515.61390702651;5525.917111243334;5539.086050801613;4140.296149654566;4141.213169497786;4155.148194620096;4171.165766846643;4189.350650649154;5207.6354655174155;5449.828286918331;5727;5804.941950580483;5825.766969321222;4221.261279276115;4231.344141657986;4237.788110594458;4241.427407299975;4244.596763307481;4282.571742825755;4285.97857010713;4296.226679197712;4303.591158839905;4309.59058889405;4802.6638135330195;4802.711837289774;4825.372701548847;4833.830815921426;4840.419149701988;5397.379301380313;5500.964643082085;5518.668879867221;5518.730691940876;5529.391766905637;1445.9204743738985;1461.0211158523814;1465.8973871828869;1466.3548038862866;1470.154661069889;948.1324588002038;964.5129209749032;983.8917719050899;991.2070343724992;1003.8795344558554;4826.276058591239;4832.903341933142;4854.907645779742;4857.344258525054;4858.58702010326;5037.29477873098;5052.292679024939;5070.645054846125;5078.257740819916;5088.135017047037;4608.400907882021;4841.685290456109;4850.369451971221;4864.756762161877;4871.732054737024;2598.337423956879;2637.902867220564;2652.5755879059257;2669.8131130820657;2682.3830518980744;5128.333316668809;5182.53357197854;5227.372715274116;5237.214417837278;5277.604179686539;4844.418669759581;5029.346184995927;5040.546350828443;5083.416191584924;5586.24585680936;5755.884882302331;5818.7672493100645;5824.660328124471;5899.144624029577;5920.276672730506;5606.943930560642;5676.829695109153;5706.3152421709665;5708;5733.942660573508;5532.778688852401;5583.162525621189;5618.4662457066515;5640.689762063126;5678.4605462480995;5832.708364581838;5851.180834683233;5852.678102704393;5866.472017518444;5876;4222.092250166229;4284.10033892886;4284.935725964051;4288.67834912389;4293.97853010733;5528.889422211614;5680.573956953328;5693.971530142323;5700.914486282792;5727.656340619576;5516.889662206813;5546.36216835053;5557.221988921635;5563.271219463424;5576.525995290446;5283.916797056518;5533.6403133796175;5544.334679838284;5557.333120025461;5580.944190558205;5543.168524721163;5560.5829562783765;5562.944370556243;5572.749226284745;5579.442055794388;5538.16927460868;5541.473560011793;5579.326202139423;5594.804181853616;5601.24383208278;5276.736163191748;5377.059014672495;5390.652336915771;5422.891542169214;5430.40265570779 13:18:47 INFO - PROCESS | 31863 | |5;yui.html;842.2798507276278;864.7492227254093;880.5465185429503;894.6421431427428;900.4571248423708;15735.842641573596;16509.917450412748;16691.83308166918;16732.665346693087;16862.494125176265;15431.922840385781;15904.045757254573;16146.596335091628;16317.755233671474;16321.673566528661;856.9957150214256;876.0101085773067;878.5607196401799;880.5113162194979;881.18930583863;874.1433395272605;878.8813510176109;879.683314006957;881.3610132653839;883.5626364949327;930.3394589841221;931.1805611062256;932.1238036245941;932.4498545857942;933.2394098809449;919.4804935211585;924.4360939826701;924.5192499900014;925.4401087342162;925.8148370325928;675.6993138053568;682.6962001909152;682.9282925292872;684.1721516964479;684.5345165287603;14660.633484162874;15285.770713439308;15308.693826123415;15340.079595224322;15373.1544765039;14137.434502619986;14295.494865195855;14548.636284092876;14714.190719510423;14935.25323733809;12559.183653062522;12691.301978391184;12883.549075782303;12949.611511654666;13135.146215495963;4080.9387859182134;4089.9386509202386;4141.834326626931;4143.08852052548;4175.081482073969;58537.41462585362;71676.28323716749;73055.63472182605;73977.63011184963;84127;13049.086563940433;13259.270740109194;13286.933565332112;13312.334383280797;13338.93330533341;24086.277411677678;24299.63550546743;24370.390740231636;24495.142670006466;25237.369065773502;9311.394759340621;9312.255019598417;9325.393849399767;9544.427334359822;9806.901930980599;4827.975860120677;4927.556519913225;4996.200607902718;5108.591312694975;5216.739163041832;3712.6844218241754;3849.7882616456363;3853.7687738735203;3858.3247931611854;3864.401017842239;662.7647185249264;671.9798406047827;679.7383007542034;679.8436359637311;683.8837397642412;690.3131384272681;692.9778576599475;710.1300906389735;745.5116898431536;779.7855589712761;10335.74160645975;10414.739631509121;10515.158787296998;10530.947345263225;10923.508442120125;653.9934600654028;709.8580283943129;740.3226048165868;781.5584194929846;789.1437789997916;5012.398512178489;5020.472850350734;5047.772850221749;5077.898442031213;5092.490750924952;76020.61989690016;76472.23527764651;77226;135077;136501;81781.59109204415;99616;112938;113108;115151;66514;73704.26295737081;84454.15545844586;84769.57615211884;86468.70296945551;10247.436390998566;10618.203634727271;10666.146708263475;10669.955442393355;10824.67525974222;55361.44638553724;56355.43644563666;57087.42912570821;58117.709411454365;58133;71528.28471715424;75868.62065689637;76464;76682;77402.6129869347;11501.53993840237;11521.366324852213;12556.748865022832;13068.085234033906;13585.932070339584;15154.3180556873;15393.076415415111;15655.37378504847;16036.36436053692;16272.837271627131;14832.925835370754;15015.324310406277;15528.611784705472;15725.921370393075;16067.91966040209;14750.483733069721;15103.4713785017;15369.923150384177;15697.686046278783;16057.678846422772;12594.685132871751;12777.93611031976;12821.358932053174;13212.867871321163;13293.867061329263;11601.12991525622;11763.117766167401;11931.880681193077;11934.224275422417;12103.213291136048;12037.699057523632;12346.691332716395;12400.875991240333;12504.187227830162;12731.872681273439 13:18:47 INFO - PROCESS | 31863 | __end_tp_report 13:18:47 INFO - PROCESS | 31863 | __start_cc_report 13:18:47 INFO - PROCESS | 31863 | _x_x_mozilla_cycle_collect,1015 13:18:47 INFO - PROCESS | 31863 | __end_cc_report 13:18:47 INFO - PROCESS | 31863 | __startTimestamp1474921127627__endTimestamp 13:18:47 INFO - PROCESS | 31863 | 13:18:47 INFO - PROCESS | 31863 | ------- Summary: start ------- 13:18:47 INFO - PROCESS | 31863 | Number of tests: 6 13:18:47 INFO - PROCESS | 31863 | 13:18:47 INFO - PROCESS | 31863 | [#0] dojo.html Cycles:165 Average:5529.54 Median:5736.20 stddev:629.26 (11.0%) stddev-sans-first:603.70 13:18:47 INFO - PROCESS | 31863 | Values: 3184.5 3194.9 3210.2 3216.0 3243.1 5460.8 5551.6 5571.7 5572.2 5577.1 5565.6 5628.7 5629.2 5629.8 5664.1 4917.3 4961.9 5051.2 5088.2 5141.7 4976.7 5008.7 5028.3 5028.6 5112.4 5065.6 5085.1 5091.8 5097.9 5135.1 5111.8 5145.4 5157.8 5161.1 5191.0 4838.0 4957.1 4998.4 5066.2 5071.6 5488.6 5526.8 5539.8 5553.1 5593.0 5941.4 5955.9 5958.4 5958.9 5961.1 5786.3 5803.6 5864.8 5886.4 5990.6 5510.4 5512.7 5514.7 5520.5 5532.2 6005.7 6005.8 6006.3 6010.1 6021.0 5664.6 5736.2 5825.5 5832.7 5886.2 5988.6 5994.5 5995.8 6000.0 6028.8 5928.6 5937.1 5970.5 5983.2 5986.7 5624.4 5754.3 5807.6 5820.4 5976.1 4535.8 4539.3 4553.7 4553.7 4560.4 4037.8 4041.7 4048.5 4052.9 4062.6 5491.8 5631.8 5641.9 5657.0 5663.1 5966.0 6002.2 6029.4 6056.3 6065.4 5679.4 5718.8 5729.6 5731.5 5739.5 5051.0 5145.5 5212.6 5213.1 5219.0 6121.8 6178.4 6190.9 6192.3 6199.2 5973.5 6012.7 6020.7 6032.1 6067.9 5880.0 6089.4 6190.8 6193.4 6198.5 5865.0 5899.1 5905.9 5924.0 5934.3 5914.8 5987.5 5993.6 5997.2 6004.8 5673.7 5740.9 5864.8 5873.1 5874.5 5807.9 5846.6 5866.1 5896.3 5913.9 5827.0 5839.7 5839.8 5839.9 5846.2 5704.8 5762.3 5817.1 5848.8 5875.3 5679.9 5727.3 5729.9 5733.0 5767.6 13:18:47 INFO - PROCESS | 31863 | 13:18:47 INFO - PROCESS | 31863 | [#1] ext.html Cycles:180 Average:67119.33 Median:40092.10 stddev:135259.09 (337.4%) stddev-sans-first:135546.26 13:18:47 INFO - PROCESS | 31863 | Values: 578.6 620.7 649.6 650.2 659.0 35703.1 38895.2 39015.4 47747.8 48431.0 49751.0 50078.5 50240.5 50269.2 50308.7 9372.0 9510.4 9517.2 9519.2 9525.9 8614.5 11129.4 11333.7 11374.1 11718.7 11385.5 11424.7 11453.0 11458.0 11481.7 11315.5 11381.4 11390.4 11391.4 11394.0 10938.9 10950.1 10953.8 10969.5 10977.8 47411.8 48041.3 48137.3 48188.0 48235.3 3826.7 3995.5 4043.3 4292.7 4645.9 35763.8 35810.1 35918.8 36157.8 37911.2 4580.5 4629.5 4636.1 4644.3 4722.7 379190.0 379617.0 381323.0 384517.0 385088.0 67673.0 68079.0 68125.7 68138.3 68318.7 40203.2 41306.4 41489.0 43941.8 70468.0 18956.8 18994.8 19177.4 19226.8 19241.3 13168.3 13487.1 14565.1 14672.6 14706.3 21145.2 21707.7 21744.1 22559.7 22581.0 7387.3 7555.3 7610.7 7677.7 7853.0 955.8 1070.3 1097.3 1113.0 1165.0 33170.5 34273.5 34774.7 35148.6 35510.8 1142.8 1147.0 1168.2 1207.8 1247.5 18069.5 18118.9 18165.6 18227.7 18234.5 637675.3 643790.0 644536.0 645860.0 647173.0 65433.0 66101.7 66131.3 66214.0 66247.3 1300.6 1421.9 1440.8 1449.9 1478.8 35023.0 35941.6 36070.0 36631.3 36823.3 56343.7 57120.4 57153.4 57214.4 57227.4 461310.0 462962.0 465774.0 469769.0 469905.0 28359.0 31278.5 31411.8 32457.5 32956.0 53054.2 53074.5 53519.0 53886.7 54023.7 44749.0 46093.3 46663.3 46729.8 47102.3 44656.6 44670.8 44810.8 45347.8 46908.8 44144.6 44902.3 45077.0 45290.0 45430.0 36192.3 39575.4 39981.0 40365.8 40496.6 29696.0 30081.4 30181.7 30285.8 30326.2 13:18:47 INFO - PROCESS | 31863 | 13:18:47 INFO - PROCESS | 31863 | [#2] jquery.html Cycles:180 Average:32369.81 Median:11432.85 stddev:93551.03 (818.3%) stddev-sans-first:93782.69 13:18:47 INFO - PROCESS | 31863 | Values: 410.8 425.6 431.6 434.8 438.4 19607.3 20645.9 20753.2 20956.0 21795.2 19475.9 20792.2 20868.3 20931.2 20991.9 5790.2 6091.0 6092.9 6097.9 6099.0 6008.8 6019.2 6247.9 6284.2 6349.2 6321.7 6341.6 6343.4 6352.5 6376.4 6795.2 6944.9 6964.8 6977.6 6981.5 5986.1 6005.0 6039.1 6052.1 6264.8 15439.8 15571.8 15590.5 15659.2 15795.9 2403.5 2524.3 2724.7 2871.8 3023.4 10790.4 12376.4 12647.5 15518.8 15822.8 1955.8 1956.4 2000.3 2024.5 2077.6 66489.7 86381.6 89837.6 90370.5 90680.9 10994.2 11110.6 11450.6 11794.3 11979.9 17102.9 17121.2 17141.7 17143.9 17164.1 8648.9 8903.8 9040.7 9094.4 9276.4 8916.8 8979.1 8995.0 9138.8 9166.3 5053.6 5194.4 5272.0 5281.9 5316.4 1854.0 1892.3 1911.6 1927.0 1946.7 964.7 1093.8 1094.1 1105.2 1117.4 19770.4 20116.5 20682.0 20788.1 20821.2 1034.1 1046.4 1050.9 1063.2 1084.9 11024.8 11502.8 11531.9 11551.4 11644.4 553096.0 563002.0 572537.0 573124.0 574585.0 100983.0 103441.0 107391.0 108027.0 109634.5 1039.8 1055.6 1071.2 1076.9 1083.6 17813.6 17830.5 18114.6 18436.0 18513.6 48345.5 48408.0 48482.5 48696.0 50024.7 65443.7 65865.0 66296.0 66744.3 67723.7 7387.7 8519.5 8636.8 8818.9 8917.6 10580.5 13188.7 13461.5 13645.5 13776.2 11788.1 11806.8 12004.3 12086.2 12302.9 11420.2 11427.3 11757.0 11904.3 12023.3 12033.9 12070.4 12146.5 12328.5 12328.6 7915.3 8411.1 8706.0 8960.4 9172.6 11748.3 11825.6 12252.6 12461.3 12642.6 13:18:47 INFO - PROCESS | 31863 | 13:18:47 INFO - PROCESS | 31863 | [#3] mootools.html Cycles:180 Average:6211.34 Median:4875.39 stddev:3562.54 (73.1%) stddev-sans-first:3553.94 13:18:47 INFO - PROCESS | 31863 | Values: 1368.9 1538.0 1554.5 1755.2 1880.0 8771.0 9003.7 9100.9 9357.0 9389.3 8845.4 9017.9 9316.9 9334.0 9351.4 2527.0 2549.3 2564.8 2575.2 2577.8 2435.7 2503.2 2505.4 2508.3 2509.7 2473.4 2488.8 2509.5 2510.6 2516.4 2411.9 2423.1 2432.8 2443.7 2452.0 2403.5 2425.0 2434.6 2435.5 2447.9 9116.1 9252.5 9288.3 9366.8 9373.4 5505.3 5735.9 5751.5 5754.3 5757.0 3577.8 3588.4 3640.6 3642.9 3678.2 1213.5 1236.0 1243.0 1246.0 1273.4 13472.5 13548.9 13591.8 13725.7 13744.2 4820.9 4863.9 4980.2 5075.6 5139.4 11423.5 11428.6 11478.9 11486.0 11486.6 5793.5 5807.4 5828.2 5831.9 5834.8 4546.5 4550.1 5101.0 5207.5 5322.7 4285.0 4328.7 4378.9 4430.9 4433.6 1329.4 1351.3 1371.0 1375.8 1389.5 927.0 1006.7 1075.7 1079.2 1079.4 6455.0 6553.4 6682.1 6718.8 6786.8 499.6 510.4 513.1 514.9 516.5 4415.6 4661.8 4724.8 4886.9 4930.9 11363.8 11867.1 11981.6 12005.3 12014.9 11658.5 11876.8 11896.6 11898.7 11905.3 8377.8 8554.1 8583.1 8586.9 8589.2 6435.9 6453.3 6495.5 6537.9 6556.6 10201.9 10313.1 10473.6 10494.5 10500.7 10372.1 10459.7 10527.2 10606.3 10675.2 7205.2 8296.3 8445.3 8613.9 8760.8 9395.2 9475.4 9480.8 9482.4 9486.5 8194.9 8233.8 8271.6 8306.5 8472.2 8304.0 8347.5 8366.7 8403.5 8619.7 6464.0 6565.6 6608.2 6816.9 7357.1 5377.1 5424.9 5505.6 5532.7 5618.2 5577.8 5608.3 5614.4 5621.7 5731.5 13:18:47 INFO - PROCESS | 31863 | 13:18:47 INFO - PROCESS | 31863 | [#4] prototype.html Cycles:180 Average:4612.90 Median:5098.07 stddev:1322.85 (25.9%) stddev-sans-first:1287.39 13:18:47 INFO - PROCESS | 31863 | Values: 356.0 357.2 357.8 358.6 365.4 5328.2 5333.3 5367.9 5372.7 5380.5 5233.0 5265.2 5287.6 5294.5 5302.6 3845.6 3969.6 4072.9 4175.8 4214.0 4157.5 4176.8 4179.4 4197.8 4200.4 4188.9 4190.9 4195.6 4198.2 4228.2 4145.2 4203.4 4223.8 4225.5 4240.6 3716.8 3736.8 3747.2 3765.1 3769.2 5084.2 5108.0 5112.2 5131.0 5257.5 4685.3 4751.9 4805.6 4820.6 4833.2 5495.2 5499.8 5515.6 5525.9 5539.1 4140.3 4141.2 4155.1 4171.2 4189.4 5207.6 5449.8 5727.0 5804.9 5825.8 4221.3 4231.3 4237.8 4241.4 4244.6 4282.6 4286.0 4296.2 4303.6 4309.6 4802.7 4802.7 4825.4 4833.8 4840.4 5397.4 5501.0 5518.7 5518.7 5529.4 1445.9 1461.0 1465.9 1466.4 1470.2 948.1 964.5 983.9 991.2 1003.9 4826.3 4832.9 4854.9 4857.3 4858.6 5037.3 5052.3 5070.6 5078.3 5088.1 4608.4 4841.7 4850.4 4864.8 4871.7 2598.3 2637.9 2652.6 2669.8 2682.4 5128.3 5182.5 5227.4 5237.2 5277.6 4844.4 5029.3 5040.5 5083.4 5586.2 5755.9 5818.8 5824.7 5899.1 5920.3 5606.9 5676.8 5706.3 5708.0 5733.9 5532.8 5583.2 5618.5 5640.7 5678.5 5832.7 5851.2 5852.7 5866.5 5876.0 4222.1 4284.1 4284.9 4288.7 4294.0 5528.9 5680.6 5694.0 5700.9 5727.7 5516.9 5546.4 5557.2 5563.3 5576.5 5283.9 5533.6 5544.3 5557.3 5580.9 5543.2 5560.6 5562.9 5572.7 5579.4 5538.2 5541.5 5579.3 5594.8 5601.2 5276.7 5377.1 5390.7 5422.9 5430.4 13:18:47 INFO - PROCESS | 31863 | 13:18:47 INFO - PROCESS | 31863 | [#5] yui.html Cycles:180 Average:20938.86 Median:4085.44 stddev:29158.81 (713.7%) stddev-sans-first:29201.57 13:18:47 INFO - PROCESS | 31863 | Values: 842.3 864.7 880.5 894.6 900.5 15735.8 16509.9 16691.8 16732.7 16862.5 15431.9 15904.0 16146.6 16317.8 16321.7 857.0 876.0 878.6 880.5 881.2 874.1 878.9 879.7 881.4 883.6 930.3 931.2 932.1 932.4 933.2 919.5 924.4 924.5 925.4 925.8 675.7 682.7 682.9 684.2 684.5 14660.6 15285.8 15308.7 15340.1 15373.2 14137.4 14295.5 14548.6 14714.2 14935.3 12559.2 12691.3 12883.5 12949.6 13135.1 4080.9 4089.9 4141.8 4143.1 4175.1 58537.4 71676.3 73055.6 73977.6 84127.0 13049.1 13259.3 13286.9 13312.3 13338.9 24086.3 24299.6 24370.4 24495.1 25237.4 9311.4 9312.3 9325.4 9544.4 9806.9 4828.0 4927.6 4996.2 5108.6 5216.7 3712.7 3849.8 3853.8 3858.3 3864.4 662.8 672.0 679.7 679.8 683.9 690.3 693.0 710.1 745.5 779.8 10335.7 10414.7 10515.2 10530.9 10923.5 654.0 709.9 740.3 781.6 789.1 5012.4 5020.5 5047.8 5077.9 5092.5 76020.6 76472.2 77226.0 135077.0 136501.0 81781.6 99616.0 112938.0 113108.0 115151.0 66514.0 73704.3 84454.2 84769.6 86468.7 10247.4 10618.2 10666.1 10670.0 10824.7 55361.4 56355.4 57087.4 58117.7 58133.0 71528.3 75868.6 76464.0 76682.0 77402.6 11501.5 11521.4 12556.7 13068.1 13585.9 15154.3 15393.1 15655.4 16036.4 16272.8 14832.9 15015.3 15528.6 15725.9 16067.9 14750.5 15103.5 15369.9 15697.7 16057.7 12594.7 12777.9 12821.4 13212.9 13293.9 11601.1 11763.1 11931.9 11934.2 12103.2 12037.7 12346.7 12400.9 12504.2 12731.9 13:18:47 INFO - PROCESS | 31863 | -------- Summary: end -------- 13:18:47 INFO - PROCESS | 31863 | 13:18:48 INFO - TEST-INFO | 31863: exit 0 13:18:48 INFO - TEST-OK | dromaeo_css | took 1112515ms 13:18:48 INFO - TEST-START | kraken 13:18:48 INFO - operating with platform_type : linux_ 13:18:48 INFO - Initialising browser for kraken test... 13:18:48 INFO - TEST-INFO | started process 32055 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLg5q6D/profile http://localhost:42290/getInfo.html) 13:18:52 INFO - PROCESS | 32055 | __metrics Screen width/height:1600/1200 13:18:52 INFO - PROCESS | 32055 | colorDepth:24 13:18:52 INFO - PROCESS | 32055 | Browser inner width/height: 1024/697 13:18:52 INFO - PROCESS | 32055 | __metrics 13:18:52 INFO - PROCESS | 32055 | 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" 13:18:52 INFO - PROCESS | 32055 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:18:53 INFO - PROCESS | 32055 | console.error: PushService: 13:18:53 INFO - PROCESS | 32055 | stateChangeProcessEnqueue: Error transitioning state 13:18:53 INFO - PROCESS | 32055 | UnknownError 13:18:53 INFO - TEST-INFO | 32055: exit 0 13:18:53 INFO - Browser initialized. 13:18:53 INFO - Running cycle 1/1 for kraken test... 13:18:53 INFO - TEST-INFO | started process 32143 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLg5q6D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:19:05 INFO - PROCESS | 32143 | RSS: Main: 143388672 13:19:05 INFO - PROCESS | 32143 | 13:19:08 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42290/tests/kraken/driver.html?testName=audio-beat-detection) 13:19:09 INFO - PROCESS | 32143 | RSS: Main: 198664192 13:19:09 INFO - PROCESS | 32143 | 13:19:11 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42290/tests/kraken/driver.html?testName=audio-dft) 13:19:12 INFO - PROCESS | 32143 | RSS: Main: 182390784 13:19:12 INFO - PROCESS | 32143 | 13:19:14 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42290/tests/kraken/driver.html?testName=audio-fft) 13:19:15 INFO - PROCESS | 32143 | RSS: Main: 274251776 13:19:15 INFO - PROCESS | 32143 | 13:19:17 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42290/tests/kraken/driver.html?testName=audio-oscillator) 13:19:18 INFO - PROCESS | 32143 | RSS: Main: 179822592 13:19:18 INFO - PROCESS | 32143 | 13:19:20 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42290/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:19:20 INFO - PROCESS | 32143 | RSS: Main: 178655232 13:19:20 INFO - PROCESS | 32143 | 13:19:23 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42290/tests/kraken/driver.html?testName=imaging-darkroom) 13:19:24 INFO - PROCESS | 32143 | RSS: Main: 214704128 13:19:24 INFO - PROCESS | 32143 | 13:19:27 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42290/tests/kraken/driver.html?testName=imaging-desaturate) 13:19:28 INFO - PROCESS | 32143 | RSS: Main: 213565440 13:19:28 INFO - PROCESS | 32143 | 13:19:31 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42290/tests/kraken/driver.html?testName=json-parse-financial) 13:19:31 INFO - PROCESS | 32143 | RSS: Main: 213397504 13:19:31 INFO - PROCESS | 32143 | 13:19:33 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42290/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:19:33 INFO - PROCESS | 32143 | RSS: Main: 175697920 13:19:33 INFO - PROCESS | 32143 | 13:19:35 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:19:36 INFO - PROCESS | 32143 | RSS: Main: 182849536 13:19:36 INFO - PROCESS | 32143 | 13:19:38 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:19:38 INFO - PROCESS | 32143 | RSS: Main: 184610816 13:19:38 INFO - PROCESS | 32143 | 13:19:41 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:19:41 INFO - PROCESS | 32143 | RSS: Main: 184659968 13:19:41 INFO - PROCESS | 32143 | 13:19:44 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:19:45 INFO - PROCESS | 32143 | RSS: Main: 182669312 13:19:45 INFO - PROCESS | 32143 | 13:19:46 INFO - PROCESS | 32143 | Cycle 1(1): loaded http://localhost:42290/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42290/tests/kraken/driver.html?testName=ai-astar) 13:19:47 INFO - PROCESS | 32143 | RSS: Main: 233943040 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | __start_tp_report 13:19:47 INFO - PROCESS | 32143 | _x_x_mozilla_page_load 13:19:47 INFO - PROCESS | 32143 | _x_x_mozilla_page_load_details 13:19:47 INFO - PROCESS | 32143 | |i|pagename|runs| 13:19:47 INFO - PROCESS | 32143 | |0;ai-astar;113;111;121;111;111;112;112;112;111;111 13:19:47 INFO - PROCESS | 32143 | |1;audio-beat-detection;141;115;144;179;117;151;147;117;187;118 13:19:47 INFO - PROCESS | 32143 | |2;audio-dft;160;161;160;161;160;160;160;160;159;159 13:19:47 INFO - PROCESS | 32143 | |3;audio-fft;75;74;75;165;74;76;76;75;180;75 13:19:47 INFO - PROCESS | 32143 | |4;audio-oscillator;83;83;145;83;83;84;137;84;84;140 13:19:47 INFO - PROCESS | 32143 | |5;imaging-gaussian-blur;117;116;117;117;117;117;116;117;116;117 13:19:47 INFO - PROCESS | 32143 | |6;imaging-darkroom;146;146;146;146;146;146;145;146;145;146 13:19:47 INFO - PROCESS | 32143 | |7;imaging-desaturate;95;95;95;94;94;95;95;95;95;94 13:19:47 INFO - PROCESS | 32143 | |8;json-parse-financial;50;50;52;51;49;51;50;50;52;50 13:19:47 INFO - PROCESS | 32143 | |9;json-stringify-tinderbox;59;60;61;59;60;60;60;60;59;59 13:19:47 INFO - PROCESS | 32143 | |10;stanford-crypto-aes;73;78;72;72;76;73;72;73;72;73 13:19:47 INFO - PROCESS | 32143 | |11;stanford-crypto-ccm;118;124;116;115;130;118;116;124;158;117 13:19:47 INFO - PROCESS | 32143 | |12;stanford-crypto-pbkdf2;154;154;153;154;152;152;151;152;155;152 13:19:47 INFO - PROCESS | 32143 | |13;stanford-crypto-sha256-iterative;62;66;61;63;62;63;62;67;62;61 13:19:47 INFO - PROCESS | 32143 | __end_tp_report 13:19:47 INFO - PROCESS | 32143 | __start_cc_report 13:19:47 INFO - PROCESS | 32143 | _x_x_mozilla_cycle_collect,1699 13:19:47 INFO - PROCESS | 32143 | __end_cc_report 13:19:47 INFO - PROCESS | 32143 | __startTimestamp1474921187036__endTimestamp 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | ------- Summary: start ------- 13:19:47 INFO - PROCESS | 32143 | Number of tests: 14 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#0] ai-astar Cycles:10 Average:112.50 Median:112.00 stddev:3.06 (2.7%) stddev-sans-first:3.24 13:19:47 INFO - PROCESS | 32143 | Values: 113.0 111.0 121.0 111.0 111.0 112.0 112.0 112.0 111.0 111.0 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#1] audio-beat-detection Cycles:10 Average:141.60 Median:145.50 stddev:25.95 (17.8%) stddev-sans-first:27.52 13:19:47 INFO - PROCESS | 32143 | Values: 141.0 115.0 144.0 179.0 117.0 151.0 147.0 117.0 187.0 118.0 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 13:19:47 INFO - PROCESS | 32143 | Values: 160.0 161.0 160.0 161.0 160.0 160.0 160.0 160.0 159.0 159.0 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#3] audio-fft Cycles:10 Average:94.50 Median:75.00 stddev:41.27 (55.0%) stddev-sans-first:43.16 13:19:47 INFO - PROCESS | 32143 | Values: 75.0 74.0 75.0 165.0 74.0 76.0 76.0 75.0 180.0 75.0 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#4] audio-oscillator Cycles:10 Average:100.60 Median:83.00 stddev:27.72 (33.4%) stddev-sans-first:28.66 13:19:47 INFO - PROCESS | 32143 | Values: 83.0 83.0 145.0 83.0 83.0 84.0 137.0 84.0 84.0 140.0 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 13:19:47 INFO - PROCESS | 32143 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 13:19:47 INFO - PROCESS | 32143 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 145.0 146.0 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 13:19:47 INFO - PROCESS | 32143 | Values: 95.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 94.0 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.50 stddev:0.97 (1.9%) stddev-sans-first:1.01 13:19:47 INFO - PROCESS | 32143 | Values: 50.0 50.0 52.0 51.0 49.0 51.0 50.0 50.0 52.0 50.0 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 13:19:47 INFO - PROCESS | 32143 | Values: 59.0 60.0 61.0 59.0 60.0 60.0 60.0 60.0 59.0 59.0 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#10] stanford-crypto-aes Cycles:10 Average:73.40 Median:73.00 stddev:2.01 (2.8%) stddev-sans-first:2.13 13:19:47 INFO - PROCESS | 32143 | Values: 73.0 78.0 72.0 72.0 76.0 73.0 72.0 73.0 72.0 73.0 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#11] stanford-crypto-ccm Cycles:10 Average:123.60 Median:121.00 stddev:13.00 (10.7%) stddev-sans-first:13.63 13:19:47 INFO - PROCESS | 32143 | Values: 118.0 124.0 116.0 115.0 130.0 118.0 116.0 124.0 158.0 117.0 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.90 Median:153.50 stddev:1.29 (0.8%) stddev-sans-first:1.30 13:19:47 INFO - PROCESS | 32143 | Values: 154.0 154.0 153.0 154.0 152.0 152.0 151.0 152.0 155.0 152.0 13:19:47 INFO - PROCESS | 32143 | 13:19:47 INFO - PROCESS | 32143 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:62.50 stddev:2.02 (3.2%) stddev-sans-first:2.12 13:19:47 INFO - PROCESS | 32143 | Values: 62.0 66.0 61.0 63.0 62.0 63.0 62.0 67.0 62.0 61.0 13:19:47 INFO - PROCESS | 32143 | -------- Summary: end -------- 13:19:47 INFO - PROCESS | 32143 | 13:19:48 INFO - TEST-INFO | 32143: exit 0 13:19:48 INFO - TEST-OK | kraken | took 59865ms 13:19:48 INFO - SUITE-END | took 1172s 13:19:48 INFO - Completed test suite (00:19:32) 13:19:48 INFO - javascript benchmark 13:19:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3184.5382419549164, 3194.9041528754133, 3210.165357007179, 3216.0, 3243.0595127413053, 5460.754266058027, 5551.583631227664, 5571.693556854371, 5572.219889215513, 5577.135543990683, 5565.638233514819, 5628.71856407179, 5629.211910332557, 5629.831105066854, 5664.0937450008005, 4917.262410638415, 4961.900761984758, 5051.166557518005, 5088.23676448532, 5141.665791723526, 4976.701397916137, 5008.724520151391, 5028.321176641143, 5028.572571331441, 5112.437631860511, 5065.645404821664, 5085.0592640361465, 5091.770870310845, 5097.872553186163, 5135.12702840518, 5111.821086261982, 5145.434002259748, 5157.819476318348, 5161.096808058613, 5190.974045129788, 4837.95162048382, 4957.107720610289, 4998.375203099613, 5066.214736715815, 5071.594272458195, 5488.588355873286, 5526.834194974157, 5539.750711217965, 5553.111502159675, 5593.0, 5941.405859414024, 5955.880882382371, 5958.433948774859, 5958.851028724316, 5961.076033214858, 5786.276715410574, 5803.622764520335, 5864.824055278305, 5886.411358864122, 5990.640561566276, 5510.366307874606, 5512.6968016759065, 5514.724263786835, 5520.503154716095, 5532.197831314477, 6005.6997150142315, 6005.819825405245, 6006.339302676702, 6010.068439391901, 6021.036634138517, 5664.575156863251, 5736.196932429463, 5825.504832089235, 5832.708364581838, 5886.175935369051, 5988.580799344091, 5994.460498555151, 5995.760169593167, 5999.970000149971, 6028.758849645965, 5928.555358348141, 5937.079752638348, 5970.462658360681, 5983.222181116514, 5986.670733109634, 5624.437556244343, 5754.280714910637, 5807.593468457252, 5820.447057529528, 5976.073708575178, 4535.841245556389, 4539.273716205391, 4553.681242313072, 4553.749543775058, 4560.384348113029, 4037.7577345359373, 4041.7170798043853, 4048.473698419187, 4052.858149964737, 4062.614051665087, 5491.807786727525, 5631.831045068655, 5641.915371269434, 5656.971715141398, 5663.093904975267, 5966.015607424726, 6002.249718785152, 6029.427204415573, 6056.303525094582, 6065.363136870653, 5679.403662615448, 5718.79984200551, 5729.627574207663, 5731.512821410143, 5739.540836733051, 5051.0, 5145.536901678867, 5212.582993360522, 5213.113770658922, 5218.9739051304505, 6121.816345509642, 6178.382161783876, 6190.876182476325, 6192.256929168529, 6199.225096862892, 5973.49225315853, 6012.7294271757, 6020.729067191899, 6032.065029920193, 6067.939320606737, 5880.0, 6089.391060893875, 6190.845228869314, 6193.38066193377, 6198.535109866688, 5865.032269675456, 5899.085641725539, 5905.881882362414, 5923.970380148072, 5934.287885453773, 5914.822555323347, 5987.520998320211, 5993.550483713651, 5997.160397544434, 6004.819855404345, 5673.744681489426, 5740.885182296247, 5864.765409383577, 5873.119032145212, 5874.471297583238, 5807.883842323215, 5846.590738648254, 5866.149408335682, 5896.292444906639, 5913.911291330547, 5827.038538641077, 5839.737211825393, 5839.824805255849, 5839.8540036497725, 5846.239988801429, 5704.828855134353, 5762.30852297727, 5817.0983497557945, 5848.824535263949, 5875.3243377010485, 5679.9432005681065, 5727.255456790591, 5729.885402292014, 5733.02538568436, 5767.625104368202], "value": 5486.797398424355, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.5574035862563, 620.7268801727241, 649.5680372552252, 650.2424675253333, 658.9720036742679, 35703.107422314424, 38895.22209555811, 39015.41476877849, 47747.76126119364, 48431.0, 49751.0, 50078.49921500784, 50240.49759502404, 50269.24596131061, 50308.748456257665, 9372.0, 9510.429374237534, 9517.238620910346, 9519.23846092311, 9525.857112143323, 8614.52620105894, 11129.387883666395, 11333.716657083556, 11374.146938979567, 11718.707032324175, 11385.48765305559, 11424.657260282205, 11453.026492748126, 11458.0, 11481.712957176096, 11315.490802913888, 11381.374024428653, 11390.430478476126, 11391.373474458902, 11394.031507321846, 10938.890611093866, 10950.06924411426, 10953.780924381548, 10969.506372213269, 10977.83533247002, 47411.76294118542, 48041.27938080932, 48137.277940830914, 48188.0, 48235.276470852965, 3826.7321287509885, 3995.4805875236325, 4043.312636851713, 4292.742435453852, 4645.883852903705, 35763.82118089419, 35810.104747381265, 35918.8204058978, 36157.81921090404, 37911.24177516462, 4580.541945805426, 4629.513901040376, 4636.11913736389, 4644.326572646947, 4722.716637001757, 379190.0, 379617.0, 381323.0, 384517.0, 385088.0, 67673.0, 68079.0, 68125.65937170283, 68138.31861681418, 68318.65840670864, 40203.195936081116, 41306.38040429396, 41489.0, 43941.78029109834, 70468.0, 18956.81043189578, 18994.81005189958, 19177.424677259703, 19226.8077319225, 19241.326553570558, 13168.275744833934, 13487.05590608648, 14565.126092434488, 14672.559823205322, 14706.338214780146, 21145.154193832383, 21707.674384884238, 21744.13023479043, 22559.661605075937, 22581.0, 7387.298206670358, 7555.282248186523, 7610.733624323122, 7677.692892284246, 7853.0, 955.7515046088067, 1070.29895418501, 1097.2757979733497, 1113.0205419231024, 1165.0213820390911, 33170.50244246338, 34273.48589771155, 34774.652253477645, 35148.648513514534, 35510.82244588812, 1142.754755671472, 1146.9655910322704, 1168.2231316174648, 1207.7886369885234, 1247.5009996001672, 18069.457916262534, 18118.909405453152, 18165.636687266182, 18227.72658410125, 18234.54413639643, 637675.3046112936, 643790.0, 644536.0, 645860.0, 647173.0, 65433.0, 66101.66949165224, 66131.33868661348, 66214.0, 66247.33752662508, 1300.6033159886135, 1421.864788142144, 1440.8126943497277, 1449.8695117439481, 1478.8077549918441, 35023.0, 35941.58415841584, 36070.0, 36631.26737465183, 36823.26353472862, 56343.71828140833, 57120.42879571151, 57153.428465716475, 57214.42785572091, 57227.42772572387, 461310.0, 462962.0, 465774.0, 469769.0, 469905.0, 28359.0, 31278.53082203814, 31411.84294078515, 32457.51313730343, 32956.0, 53054.204186936455, 53074.469255306954, 53519.0, 53886.73056634849, 54023.729881350344, 44749.0, 46093.30860037035, 46663.30005049859, 46729.76635116803, 47102.29346559736, 44656.553434466536, 44670.77664611656, 44810.77594612006, 45347.77326113348, 46908.7654561725, 44144.55855441533, 44902.32646510239, 45077.0, 45290.0, 45430.0, 36192.27615447729, 39575.40636890507, 39981.0, 40365.79817100896, 40496.595034049285, 29696.0, 30081.398372032, 30181.69818301877, 30285.848570757888, 30326.241843954078], "value": 22247.679721568882, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.8048676878481, 425.58493966087383, 431.6093934988835, 434.7891272732725, 438.43004094676934, 19607.313744018946, 20645.896770516145, 20753.16987320509, 20956.0, 21795.237166699168, 19475.90262048688, 20792.168313267488, 20868.269610563635, 20931.16275348988, 20991.895040524814, 5790.160426738131, 6090.96954515229, 6092.908606370908, 6097.878042439149, 6099.0, 6008.819735407945, 6019.187409699678, 6247.875042499148, 6284.183056202687, 6349.1746073010445, 6321.6523091229965, 6341.556091073627, 6343.429091381775, 6352.4918006559365, 6376.36236376361, 6795.2185498667795, 6944.895826562605, 6964.79105626832, 6977.616231107312, 6981.476389270774, 5986.132010858445, 6005.039193728982, 6039.124326972608, 6052.122442245894, 6264.812055638338, 15439.768403473958, 15571.766423503657, 15590.532284031497, 15659.217039147998, 15795.921020394939, 2403.543326767926, 2524.305815900642, 2724.713905039962, 2871.798974071816, 3023.4104349651825, 10790.406527640976, 12376.443060062229, 12647.49410023598, 15518.767218491732, 15822.76265856013, 1955.8337541309006, 1956.3739603326942, 2000.3098930868828, 2024.4533975826444, 2077.6364136276093, 66489.66755166241, 86381.56809215977, 89837.55081224552, 90370.5481472595, 90680.91935862902, 10994.175436842268, 11110.61112861046, 11450.568678915135, 11794.292342459308, 11979.940100299442, 17102.914485427493, 17121.22954467052, 17141.657166856592, 17143.91428042852, 17164.141792910556, 8648.913510864812, 8903.821923561492, 9040.683576074805, 9094.36339456245, 9276.443413395218, 8916.821663566821, 8979.10208979097, 8995.0, 9138.771530711785, 9166.266698664223, 5053.646244762831, 5194.428612852583, 5272.0, 5281.9207711884355, 5316.441773613715, 1853.9907300463412, 1892.262017813054, 1911.5698967732428, 1926.9903650481938, 1946.7177259297323, 964.6816550538306, 1093.781243751253, 1094.1137678480297, 1105.237386203517, 1117.3910218930703, 19770.40688779339, 20116.497087572636, 20682.0, 20788.064537095866, 20821.167153314, 1034.1158309645296, 1046.4401545173296, 1050.9316894401838, 1063.186662203415, 1084.9077828384675, 11024.834627480594, 11502.769944601061, 11531.88468115325, 11551.422428878523, 11644.41777911118, 553096.0, 563002.0, 572537.0, 573124.0, 574585.0, 100983.0, 103441.0, 107391.0, 108027.0, 109634.45182774194, 1039.8336266197373, 1055.5883205549842, 1071.164491696478, 1076.9461526923621, 1083.6315652678127, 17813.554661133574, 17830.46508604744, 18114.637707246045, 18436.0, 18513.629727405107, 48345.5165448341, 48408.0, 48482.5151748478, 48696.0, 50024.74987625039, 65443.67278163579, 65865.0, 66296.0, 66744.3325566738, 67723.66138169277, 7387.741429049957, 8519.531425771642, 8636.82726345457, 8818.86771698438, 8917.643294268417, 10580.523876425425, 13188.670283242613, 13461.528846490326, 13645.522406715718, 13776.173429594257, 11788.115891308014, 11806.763864722829, 12004.279743215435, 12086.214396064228, 12302.876971230173, 11420.200585958903, 11427.257228280469, 11757.0, 11904.285742855456, 12023.338716370683, 12033.939830300793, 12070.379890323538, 12146.453409596412, 12328.50685972551, 12328.630141095782, 7915.327197188073, 8411.074781774123, 8706.04233534298, 8960.372773906025, 9172.633094676139, 11748.29510229389, 11825.586104486301, 12252.632421027383, 12461.252324860538, 12642.557510487457], "value": 9837.035454696612, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.9315534223285, 1537.9692406151878, 1554.5336399080277, 1755.1926113987565, 1880.031783631429, 8770.956145219274, 9003.684871029514, 9100.90899091009, 9356.953215233914, 9389.295802814782, 8845.425047371917, 9017.909820901788, 9316.8602470963, 9333.953330233338, 9351.438913665184, 2526.974730252697, 2549.260714392824, 2564.846109233452, 2575.240304110292, 2577.7680008799202, 2435.7198922123916, 2503.198976327576, 2505.3987043109705, 2508.2600632813364, 2509.6737424134835, 2473.3940184654784, 2488.825782195247, 2509.510645424143, 2510.623406489023, 2516.421223118675, 2411.8794060297005, 2423.0671191591277, 2432.7567243275707, 2443.718972318188, 2451.963220551693, 2403.5072810073893, 2424.9757502425105, 2434.5739495588377, 2435.537247922889, 2447.877606119687, 9116.08839116083, 9252.491112988782, 9288.303377246666, 9366.765830854216, 9373.437593744397, 5505.256790333294, 5735.913961290584, 5751.539876809845, 5754.280714910637, 5757.021306377926, 3577.83899724513, 3588.3540962626716, 3640.6177351377987, 3642.8724994625063, 3678.1540245743627, 1213.5145941623332, 1235.9567415140427, 1243.0490674633913, 1245.993770031144, 1273.447082639435, 13472.461101555926, 13548.86451135486, 13591.796123058162, 13725.725485490235, 13744.175349479063, 4820.855374338776, 4863.902721945541, 4980.153373926441, 5075.619328550373, 5139.383274007143, 11423.485943132579, 11428.599998999995, 11478.885211147948, 11486.0, 11486.597969071043, 5793.507551858139, 5807.361190269067, 5828.154917536934, 5831.854203644858, 5834.795782147605, 4546.499885012646, 4550.0899820035465, 5100.974495127501, 5207.479252074762, 5322.733863306897, 4285.0, 4328.675349348811, 4378.912421751611, 4430.911381772346, 4433.6009759121835, 1329.3751936589788, 1351.290572449472, 1371.0, 1375.8211432513908, 1389.485890220625, 927.0, 1006.7483129217695, 1075.655790147161, 1079.2499213046858, 1079.422508957704, 6454.9677251614385, 6553.442957348679, 6682.131322928086, 6718.798436046926, 6786.830329241809, 499.61280007993366, 510.3875349580444, 513.0636588226502, 514.8609875333639, 516.4938360406823, 4415.602595766332, 4661.813527458864, 4724.811007559728, 4886.877828054327, 4930.852074437773, 11363.772724545463, 11867.109966752356, 11981.580644677395, 12005.339706316236, 12014.879851201551, 11658.475368608264, 11876.821847672292, 11896.583619573274, 11898.702532436586, 11905.345206013579, 8377.83244335122, 8554.101819308882, 8583.141685831491, 8586.914130858737, 8589.226969572643, 6435.871282574229, 6453.322401148, 6495.480361571157, 6537.8692426152165, 6556.573822701509, 10201.897981020094, 10313.071823535918, 10473.633422830164, 10494.47527623631, 10500.737481563023, 10372.11836993864, 10459.738506537398, 10527.157827373945, 10606.310589811637, 10675.199360048182, 7205.243449437943, 8296.336293096418, 8445.282151017233, 8613.870791938001, 8760.824783504167, 9395.154436100784, 9475.384100033476, 9480.810383792423, 9482.38364506305, 9486.478243696387, 8194.877076843972, 8233.752987410388, 8271.62777674994, 8306.543140127109, 8472.152784721478, 8304.0, 8347.457415267989, 8366.707165249187, 8403.537805420761, 8619.655213791379, 6464.0, 6565.606063636006, 6608.240052394036, 6816.897746533707, 7357.0803649543805, 5377.112776391851, 5424.864378390572, 5505.587080969023, 5532.6680399176175, 5618.157276408408, 5577.832665020056, 5608.327000759935, 5614.438556144353, 5621.690807005491, 5731.484166425042], "value": 4890.284787447272, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.03692013104546, 357.2301689858355, 357.8121486219736, 358.62344538234845, 365.35879531422364, 5328.227407025979, 5333.280007199027, 5367.919481207785, 5372.7313634318225, 5380.515753582177, 5232.973835130829, 5265.183896496049, 5287.576993840493, 5294.470552944713, 5302.575793936486, 3845.615438456146, 3969.6106362773016, 4072.857449989251, 4175.770332631704, 4213.978930105345, 4157.542670306264, 4176.770277634744, 4179.414881916534, 4197.832086716528, 4200.390943313218, 4188.895277618053, 4190.853320133796, 4195.559466256045, 4198.223328684188, 4228.175505776375, 4145.233131870614, 4203.369494575808, 4223.809928553192, 4225.457707936596, 4240.597143271415, 3716.758410703296, 3736.794476303802, 3747.194353214045, 3765.0775559987705, 3769.246150769857, 5084.211947148198, 5108.0, 5112.233165025239, 5130.97434512825, 5257.526822585947, 4685.250359942427, 4751.857444276678, 4805.6155507559315, 4820.614350851958, 4833.202521583935, 5495.203195536665, 5499.835004949858, 5515.61390702651, 5525.917111243334, 5539.086050801613, 4140.296149654566, 4141.213169497786, 4155.148194620096, 4171.165766846643, 4189.350650649154, 5207.6354655174155, 5449.828286918331, 5727.0, 5804.941950580483, 5825.766969321222, 4221.261279276115, 4231.344141657986, 4237.788110594458, 4241.427407299975, 4244.596763307481, 4282.571742825755, 4285.97857010713, 4296.226679197712, 4303.591158839905, 4309.59058889405, 4802.6638135330195, 4802.711837289774, 4825.372701548847, 4833.830815921426, 4840.419149701988, 5397.379301380313, 5500.964643082085, 5518.668879867221, 5518.730691940876, 5529.391766905637, 1445.9204743738985, 1461.0211158523814, 1465.8973871828869, 1466.3548038862866, 1470.154661069889, 948.1324588002038, 964.5129209749032, 983.8917719050899, 991.2070343724992, 1003.8795344558554, 4826.276058591239, 4832.903341933142, 4854.907645779742, 4857.344258525054, 4858.58702010326, 5037.29477873098, 5052.292679024939, 5070.645054846125, 5078.257740819916, 5088.135017047037, 4608.400907882021, 4841.685290456109, 4850.369451971221, 4864.756762161877, 4871.732054737024, 2598.337423956879, 2637.902867220564, 2652.5755879059257, 2669.8131130820657, 2682.3830518980744, 5128.333316668809, 5182.53357197854, 5227.372715274116, 5237.214417837278, 5277.604179686539, 4844.418669759581, 5029.346184995927, 5040.546350828443, 5083.416191584924, 5586.24585680936, 5755.884882302331, 5818.7672493100645, 5824.660328124471, 5899.144624029577, 5920.276672730506, 5606.943930560642, 5676.829695109153, 5706.3152421709665, 5708.0, 5733.942660573508, 5532.778688852401, 5583.162525621189, 5618.4662457066515, 5640.689762063126, 5678.4605462480995, 5832.708364581838, 5851.180834683233, 5852.678102704393, 5866.472017518444, 5876.0, 4222.092250166229, 4284.10033892886, 4284.935725964051, 4288.67834912389, 4293.97853010733, 5528.889422211614, 5680.573956953328, 5693.971530142323, 5700.914486282792, 5727.656340619576, 5516.889662206813, 5546.36216835053, 5557.221988921635, 5563.271219463424, 5576.525995290446, 5283.916797056518, 5533.6403133796175, 5544.334679838284, 5557.333120025461, 5580.944190558205, 5543.168524721163, 5560.5829562783765, 5562.944370556243, 5572.749226284745, 5579.442055794388, 5538.16927460868, 5541.473560011793, 5579.326202139423, 5594.804181853616, 5601.24383208278, 5276.736163191748, 5377.059014672495, 5390.652336915771, 5422.891542169214, 5430.40265570779], "value": 4202.282193190374, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [842.2798507276278, 864.7492227254093, 880.5465185429503, 894.6421431427428, 900.4571248423708, 15735.842641573596, 16509.917450412748, 16691.83308166918, 16732.665346693087, 16862.494125176265, 15431.922840385781, 15904.045757254573, 16146.596335091628, 16317.755233671474, 16321.673566528661, 856.9957150214256, 876.0101085773067, 878.5607196401799, 880.5113162194979, 881.18930583863, 874.1433395272605, 878.8813510176109, 879.683314006957, 881.3610132653839, 883.5626364949327, 930.3394589841221, 931.1805611062256, 932.1238036245941, 932.4498545857942, 933.2394098809449, 919.4804935211585, 924.4360939826701, 924.5192499900014, 925.4401087342162, 925.8148370325928, 675.6993138053568, 682.6962001909152, 682.9282925292872, 684.1721516964479, 684.5345165287603, 14660.633484162874, 15285.770713439308, 15308.693826123415, 15340.079595224322, 15373.1544765039, 14137.434502619986, 14295.494865195855, 14548.636284092876, 14714.190719510423, 14935.25323733809, 12559.183653062522, 12691.301978391184, 12883.549075782303, 12949.611511654666, 13135.146215495963, 4080.9387859182134, 4089.9386509202386, 4141.834326626931, 4143.08852052548, 4175.081482073969, 58537.41462585362, 71676.28323716749, 73055.63472182605, 73977.63011184963, 84127.0, 13049.086563940433, 13259.270740109194, 13286.933565332112, 13312.334383280797, 13338.93330533341, 24086.277411677678, 24299.63550546743, 24370.390740231636, 24495.142670006466, 25237.369065773502, 9311.394759340621, 9312.255019598417, 9325.393849399767, 9544.427334359822, 9806.901930980599, 4827.975860120677, 4927.556519913225, 4996.200607902718, 5108.591312694975, 5216.739163041832, 3712.6844218241754, 3849.7882616456363, 3853.7687738735203, 3858.3247931611854, 3864.401017842239, 662.7647185249264, 671.9798406047827, 679.7383007542034, 679.8436359637311, 683.8837397642412, 690.3131384272681, 692.9778576599475, 710.1300906389735, 745.5116898431536, 779.7855589712761, 10335.74160645975, 10414.739631509121, 10515.158787296998, 10530.947345263225, 10923.508442120125, 653.9934600654028, 709.8580283943129, 740.3226048165868, 781.5584194929846, 789.1437789997916, 5012.398512178489, 5020.472850350734, 5047.772850221749, 5077.898442031213, 5092.490750924952, 76020.61989690016, 76472.23527764651, 77226.0, 135077.0, 136501.0, 81781.59109204415, 99616.0, 112938.0, 113108.0, 115151.0, 66514.0, 73704.26295737081, 84454.15545844586, 84769.57615211884, 86468.70296945551, 10247.436390998566, 10618.203634727271, 10666.146708263475, 10669.955442393355, 10824.67525974222, 55361.44638553724, 56355.43644563666, 57087.42912570821, 58117.709411454365, 58133.0, 71528.28471715424, 75868.62065689637, 76464.0, 76682.0, 77402.6129869347, 11501.53993840237, 11521.366324852213, 12556.748865022832, 13068.085234033906, 13585.932070339584, 15154.3180556873, 15393.076415415111, 15655.37378504847, 16036.36436053692, 16272.837271627131, 14832.925835370754, 15015.324310406277, 15528.611784705472, 15725.921370393075, 16067.91966040209, 14750.483733069721, 15103.4713785017, 15369.923150384177, 15697.686046278783, 16057.678846422772, 12594.685132871751, 12777.93611031976, 12821.358932053174, 13212.867871321163, 13293.867061329263, 11601.12991525622, 11763.117766167401, 11931.880681193077, 11934.224275422417, 12103.213291136048, 12037.699057523632, 12346.691332716395, 12400.875991240333, 12504.187227830162, 12731.872681273439], "value": 8009.104702120155, "unit": "score"}], "name": "dromaeo_css", "value": 7632.260162700935, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 121.0, 111.0, 111.0, 112.0, 112.0, 112.0, 111.0, 111.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 115.0, 144.0, 179.0, 117.0, 151.0, 147.0, 117.0, 187.0, 118.0], "value": 141.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 161.0, 160.0, 160.0, 160.0, 160.0, 159.0, 159.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 165.0, 74.0, 76.0, 76.0, 75.0, 180.0, 75.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 145.0, 83.0, 83.0, 84.0, 137.0, 84.0, 84.0, 140.0], "value": 100.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 52.0, 51.0, 49.0, 51.0, 50.0, 50.0, 52.0, 50.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 61.0, 59.0, 60.0, 60.0, 60.0, 60.0, 59.0, 59.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 78.0, 72.0, 72.0, 76.0, 73.0, 72.0, 73.0, 72.0, 73.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 124.0, 116.0, 115.0, 130.0, 118.0, 116.0, 124.0, 158.0, 117.0], "value": 123.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 153.0, 154.0, 152.0, 152.0, 151.0, 152.0, 155.0, 152.0], "value": 152.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 61.0, 63.0, 62.0, 63.0, 62.0, 67.0, 62.0, 61.0], "value": 62.9, "unit": "score"}], "name": "kraken", "value": 1489.4000000000003, "alertThreshold": 2.0}]} 13:19:48 INFO - Return code: 0 13:19:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:19:48 INFO - # TBPL SUCCESS # 13:19:48 INFO - Running post-action listener: _resource_record_post_action 13:19:48 INFO - [mozharness: 2016-09-26 20:19:48.863624Z] Finished run-tests step (success) 13:19:48 INFO - Running post-run listener: _resource_record_post_run 13:19:48 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 291045376; Read time: 188; Write time: 163632 13:19:48 INFO - TinderboxPrint: CPU usage
12.9% 13:19:48 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 188 13:19:48 INFO - TinderboxPrint: I/O write bytes / time
291,045,376 / 163,632 13:19:48 INFO - TinderboxPrint: CPU idle
8,251.1 (87.1%) 13:19:48 INFO - TinderboxPrint: CPU user
1,181.4 (12.5%) 13:19:48 INFO - TinderboxPrint: Swap in / out
0 / 0 13:19:48 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 4096; Write bytes: 71426048; Read time: 176; Write time: 59780 13:19:48 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 219619328; Read time: 12; Write time: 103852 13:19:49 INFO - Running post-run listener: _upload_blobber_files 13:19:49 INFO - Blob upload gear active. 13:19:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:19:49 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 13:19:49 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'] 13:19:49 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 13:19:49 INFO - (blobuploader) - INFO - Open directory for files ... 13:19:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:19:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:19:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:19:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:19:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:19:50 INFO - (blobuploader) - INFO - Done attempting. 13:19:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:19:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:19:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:19:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:19:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:19:51 INFO - (blobuploader) - INFO - Done attempting. 13:19:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:19:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:19:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:19:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:19:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:19:55 INFO - (blobuploader) - INFO - Done attempting. 13:19:55 INFO - (blobuploader) - INFO - Iteration through files over. 13:19:55 INFO - Return code: 0 13:19:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:19:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:19:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3eeb28f1508cfb4a31e917d9ea8e7d600f1fd568dc7247fd8ce99abec7aa3ded24f6f4f68ce01c8a1def9627848b0c019ee65e68b6e049b5051bca7e09fec652", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30fd809bfcd29cdb2e887cb56bceb6a5e5ef548aaf1cfdabdc71745f8d5c38a84fa737fc77c039eac5b6e0fe845bb49a576444f41560527750ace2cadc58224d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9605d4ace3365ff16f8ac2a89719341642684b2fe3f914230cbac2366a486c758f1a23c7a9a21afd7caa510bcab20f99195272cde24183895e4f561be162ff15"} 13:19:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:19:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:19:55 INFO - Contents: 13:19:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3eeb28f1508cfb4a31e917d9ea8e7d600f1fd568dc7247fd8ce99abec7aa3ded24f6f4f68ce01c8a1def9627848b0c019ee65e68b6e049b5051bca7e09fec652", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30fd809bfcd29cdb2e887cb56bceb6a5e5ef548aaf1cfdabdc71745f8d5c38a84fa737fc77c039eac5b6e0fe845bb49a576444f41560527750ace2cadc58224d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9605d4ace3365ff16f8ac2a89719341642684b2fe3f914230cbac2366a486c758f1a23c7a9a21afd7caa510bcab20f99195272cde24183895e4f561be162ff15"} 13:19:55 INFO - Running post-run listener: copy_logs_to_upload_dir 13:19:55 INFO - Copying logs to upload dir... 13:19:55 INFO - mkdir: /builds/slave/test/build/upload/logs 13:19:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.795951 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-09-26 13:19:54.582753) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 13:19:54.586616) ========= 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=337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455 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/3eeb28f1508cfb4a31e917d9ea8e7d600f1fd568dc7247fd8ce99abec7aa3ded24f6f4f68ce01c8a1def9627848b0c019ee65e68b6e049b5051bca7e09fec652", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30fd809bfcd29cdb2e887cb56bceb6a5e5ef548aaf1cfdabdc71745f8d5c38a84fa737fc77c039eac5b6e0fe845bb49a576444f41560527750ace2cadc58224d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9605d4ace3365ff16f8ac2a89719341642684b2fe3f914230cbac2366a486c758f1a23c7a9a21afd7caa510bcab20f99195272cde24183895e4f561be162ff15"} build_url:https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011382 build_url: 'https://queue.taskcluster.net/v1/task/LTic8qpgShOwrT12RCwBCw/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/3eeb28f1508cfb4a31e917d9ea8e7d600f1fd568dc7247fd8ce99abec7aa3ded24f6f4f68ce01c8a1def9627848b0c019ee65e68b6e049b5051bca7e09fec652", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/30fd809bfcd29cdb2e887cb56bceb6a5e5ef548aaf1cfdabdc71745f8d5c38a84fa737fc77c039eac5b6e0fe845bb49a576444f41560527750ace2cadc58224d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9605d4ace3365ff16f8ac2a89719341642684b2fe3f914230cbac2366a486c758f1a23c7a9a21afd7caa510bcab20f99195272cde24183895e4f561be162ff15"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 13:19:54.615938) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 13:19:54.616657) ========= 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=337fe3f4b1febe37b85fccc40000019e-1474918975.606083-2058585455 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004426 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 13:19:54.699822) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-26 13:19:54.700457) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-26 13:19:54.701115) ========= ========= Total master_lag: 0.22 =========