builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1472849217.28 results: success (0) buildid: 20160902105149 builduid: 93624b1aea1b4123b9211dd52a73b59f revision: 1dd122df980683688de68d91fa8a3bf90eac19b4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 13:46:57.283496) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 13:46:57.283973) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 13:46:57.284274) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 13:46:57.298889) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 13:46:57.299258) ========= 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=652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-02 13:46:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2016-09-02 13:46:57 (46.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112126 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 13:46:57.425479) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 13:46:57.425806) ========= 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=652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016252 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 13:46:57.475038) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-02 13:46:57.475464) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1dd122df980683688de68d91fa8a3bf90eac19b4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1dd122df980683688de68d91fa8a3bf90eac19b4 --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=652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-02 13:46:57,791 truncating revision to first 12 chars 2016-09-02 13:46:57,791 Setting DEBUG logging. 2016-09-02 13:46:57,791 attempt 1/10 2016-09-02 13:46:57,791 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1dd122df9806?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-02 13:46:58,852 unpacking tar archive at: mozilla-aurora-1dd122df9806/testing/mozharness/ program finished with exit code 0 elapsedTime=1.465798 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-02 13:46:58.954794) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 13:46:58.955129) ========= script_repo_revision: 1dd122df980683688de68d91fa8a3bf90eac19b4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 13:46:58.955507) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 13:46:58.955772) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 13:46:58.965456) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-09-02 13:46:58.965807) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:46:59 INFO - MultiFileLogger online at 20160902 13:46:59 in /builds/slave/test 13:46:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:46:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:46:59 INFO - {'append_to_log': False, 13:46:59 INFO - 'base_work_dir': '/builds/slave/test', 13:46:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:46:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:46:59 INFO - 'branch': 'Mozilla-Aurora', 13:46:59 INFO - 'buildbot_json_path': 'buildprops.json', 13:46:59 INFO - 'config_files': ('talos/linux_config.py',), 13:46:59 INFO - 'default_actions': ('clobber', 13:46:59 INFO - 'read-buildbot-config', 13:46:59 INFO - 'download-and-extract', 13:46:59 INFO - 'populate-webroot', 13:46:59 INFO - 'create-virtualenv', 13:46:59 INFO - 'install', 13:46:59 INFO - 'run-tests'), 13:46:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:46:59 INFO - 'download_minidump_stackwalk': True, 13:46:59 INFO - 'download_symbols': 'ondemand', 13:46:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:46:59 INFO - 'tooltool.py': '/tools/tooltool.py', 13:46:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:46:59 INFO - '/tools/misc-python/virtualenv.py')}, 13:46:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:46:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:46:59 INFO - 'installer_path': 'installer.exe', 13:46:59 INFO - 'log_level': 'info', 13:46:59 INFO - 'log_name': 'talos', 13:46:59 INFO - 'log_to_console': True, 13:46:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:46:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:46:59 INFO - 'opt_config_files': (), 13:46:59 INFO - 'pip_index': False, 13:46:59 INFO - 'sps_profile': False, 13:46:59 INFO - 'sps_profile_interval': 0, 13:46:59 INFO - 'suite': 'dromaeojs', 13:46:59 INFO - 'system_bits': '32', 13:46:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:46:59 INFO - 'title': 'talos-linux64-ix-046', 13:46:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:46:59 INFO - 'use_talos_json': True, 13:46:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:46:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:46:59 INFO - 'work_dir': 'build'} 13:46:59 INFO - [mozharness: 2016-09-02 20:46:59.377571Z] Running clobber step. 13:46:59 INFO - Running pre-action listener: _resource_record_pre_action 13:46:59 INFO - Running main action method: clobber 13:46:59 INFO - rmtree: /builds/slave/test/build 13:46:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:46:59 INFO - Running post-action listener: _resource_record_post_action 13:46:59 INFO - [mozharness: 2016-09-02 20:46:59.838414Z] Finished clobber step (success) 13:46:59 INFO - [mozharness: 2016-09-02 20:46:59.838514Z] Running read-buildbot-config step. 13:46:59 INFO - Running pre-action listener: _resource_record_pre_action 13:46:59 INFO - Running main action method: read_buildbot_config 13:46:59 INFO - Using buildbot properties: 13:46:59 INFO - { 13:46:59 INFO - "project": "", 13:46:59 INFO - "product": "firefox", 13:46:59 INFO - "script_repo_revision": "production", 13:46:59 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 13:46:59 INFO - "repository": "", 13:46:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 13:46:59 INFO - "buildid": "20160902105149", 13:46:59 INFO - "pgo_build": "True", 13:46:59 INFO - "basedir": "/builds/slave/test", 13:46:59 INFO - "buildnumber": 445, 13:46:59 INFO - "slavename": "talos-linux64-ix-046", 13:46:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:46:59 INFO - "platform": "ubuntu64_hw", 13:46:59 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 13:46:59 INFO - "branch": "mozilla-aurora", 13:46:59 INFO - "repo_path": "releases/mozilla-aurora", 13:46:59 INFO - "revision": "1dd122df980683688de68d91fa8a3bf90eac19b4", 13:46:59 INFO - "stage_platform": "linux64-pgo", 13:46:59 INFO - "builduid": "93624b1aea1b4123b9211dd52a73b59f", 13:46:59 INFO - "slavebuilddir": "test" 13:46:59 INFO - } 13:46:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 13:46:59 INFO - Running post-action listener: _resource_record_post_action 13:46:59 INFO - [mozharness: 2016-09-02 20:46:59.840948Z] Finished read-buildbot-config step (success) 13:46:59 INFO - [mozharness: 2016-09-02 20:46:59.841037Z] Running download-and-extract step. 13:46:59 INFO - Running pre-action listener: _resource_record_pre_action 13:46:59 INFO - Running main action method: download_and_extract 13:46:59 INFO - mkdir: /builds/slave/test/build/tests 13:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:59 INFO - https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:46:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:46:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:46:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:46:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 13:46:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:46:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:46:59 INFO - retry: Failed, sleeping 30 seconds before retrying 13:47:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 13:47:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:47:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:47:29 INFO - retry: Failed, sleeping 60 seconds before retrying 13:48:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 13:48:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:48:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:48:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 13:48:29 INFO - Caught exception: HTTP Error 404: Not Found 13:48:29 INFO - Caught exception: HTTP Error 404: Not Found 13:48:29 INFO - Caught exception: HTTP Error 404: Not Found 13:48:29 INFO - trying https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:48:29 INFO - Downloading https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:48:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 13:48:30 INFO - Downloaded 1361 bytes. 13:48:30 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:48:30 INFO - Using the following test package requirements: 13:48:30 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:48:30 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:48:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 13:48:30 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:48:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 13:48:30 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:48:30 INFO - u'jsshell-linux-x86_64.zip'], 13:48:30 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:48:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 13:48:30 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:48:30 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:48:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 13:48:30 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:48:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 13:48:30 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:48:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 13:48:30 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:48:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 13:48:30 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:30 INFO - https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:48:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:48:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:48:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:48:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:48:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:48:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:48:30 INFO - retry: Failed, sleeping 30 seconds before retrying 13:49:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 13:49:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:49:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:49:00 INFO - retry: Failed, sleeping 60 seconds before retrying 13:50:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 13:50:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:50:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:50:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 13:50:00 INFO - Caught exception: HTTP Error 404: Not Found 13:50:00 INFO - Caught exception: HTTP Error 404: Not Found 13:50:00 INFO - Caught exception: HTTP Error 404: Not Found 13:50:00 INFO - trying https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:50:00 INFO - Downloading https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:50:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:50:01 INFO - Downloaded 21770198 bytes. 13:50:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:50:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 13:50:02 INFO - Return code: 0 13:50:02 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 13:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:50:02 INFO - https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:50:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:50:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:50:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:50:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:50:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:50:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:50:02 INFO - retry: Failed, sleeping 30 seconds before retrying 13:50:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 13:50:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:50:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:50:32 INFO - retry: Failed, sleeping 60 seconds before retrying 13:51:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 13:51:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:51:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:51:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 13:51:32 INFO - Caught exception: HTTP Error 404: Not Found 13:51:32 INFO - Caught exception: HTTP Error 404: Not Found 13:51:32 INFO - Caught exception: HTTP Error 404: Not Found 13:51:32 INFO - trying https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:51:32 INFO - Downloading https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:51:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:51:33 INFO - Downloaded 21770198 bytes. 13:51:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:51:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 13:51:35 INFO - Return code: 0 13:51:35 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:51:35 INFO - https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:51:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:51:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:51:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:51:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:51:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:51:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:51:35 INFO - retry: Failed, sleeping 30 seconds before retrying 13:52:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 13:52:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:52:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:52:05 INFO - retry: Failed, sleeping 60 seconds before retrying 13:53:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 13:53:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:53:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:53:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 13:53:05 INFO - Caught exception: HTTP Error 404: Not Found 13:53:05 INFO - Caught exception: HTTP Error 404: Not Found 13:53:05 INFO - Caught exception: HTTP Error 404: Not Found 13:53:05 INFO - trying https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:53:05 INFO - Downloading https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:53:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:53:05 INFO - Downloaded 14413740 bytes. 13:53:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:53:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 13:53:06 INFO - Return code: 0 13:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:53:06 INFO - https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:53:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:53:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:53:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:53:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 13:53:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:53:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:53:06 INFO - retry: Failed, sleeping 30 seconds before retrying 13:53:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 13:53:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:53:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:53:36 INFO - retry: Failed, sleeping 60 seconds before retrying 13:54:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 13:54:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:54:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:54:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 13:54:36 INFO - Caught exception: HTTP Error 404: Not Found 13:54:36 INFO - Caught exception: HTTP Error 404: Not Found 13:54:36 INFO - Caught exception: HTTP Error 404: Not Found 13:54:36 INFO - trying https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:54:36 INFO - Downloading https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:54:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 13:54:37 INFO - Downloaded 63889622 bytes. 13:54:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:54:37 INFO - mkdir: /builds/slave/test/properties 13:54:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:54:37 INFO - Writing to file /builds/slave/test/properties/build_url 13:54:37 INFO - Contents: 13:54:37 INFO - build_url:https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:54:38 INFO - Running post-action listener: _resource_record_post_action 13:54:38 INFO - Running post-action listener: set_extra_try_arguments 13:54:38 INFO - [mozharness: 2016-09-02 20:54:38.067904Z] Finished download-and-extract step (success) 13:54:38 INFO - [mozharness: 2016-09-02 20:54:38.068135Z] Running populate-webroot step. 13:54:38 INFO - Running pre-action listener: _resource_record_pre_action 13:54:38 INFO - Running main action method: populate_webroot 13:54:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:54:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:54:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:54:38 INFO - '--tppagecycles', 13:54:38 INFO - '7'], 13:54:38 INFO - 'tests': ['tsvgm']}}, 13:54:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:54:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:54:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:54:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:54:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:54:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:54:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:38 INFO - 'talos_options': ['--disable-e10s'], 13:54:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:54:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:54:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:38 INFO - 'talos_options': ['--disable-e10s'], 13:54:38 INFO - 'tests': ['damp', 'tps']}, 13:54:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:38 INFO - 'tests': ['damp', 'tps']}, 13:54:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:54:38 INFO - 'tests': ['dromaeo_dom']}, 13:54:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:54:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:54:38 INFO - 'tests': ['basic_compositor_video']}, 13:54:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:54:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:54:38 INFO - 'tests': ['a11yr', 13:54:38 INFO - 'ts_paint', 13:54:38 INFO - 'tpaint', 13:54:38 INFO - 'sessionrestore', 13:54:38 INFO - 'sessionrestore_no_auto_restore', 13:54:38 INFO - 'tabpaint']}, 13:54:38 INFO - 'other-e10s': {'tests': ['a11yr', 13:54:38 INFO - 'ts_paint', 13:54:38 INFO - 'tpaint', 13:54:38 INFO - 'sessionrestore', 13:54:38 INFO - 'sessionrestore_no_auto_restore', 13:54:38 INFO - 'tabpaint']}, 13:54:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:54:38 INFO - 'ts_paint', 13:54:38 INFO - 'tpaint', 13:54:38 INFO - 'sessionrestore', 13:54:38 INFO - 'sessionrestore_no_auto_restore', 13:54:38 INFO - 'tabpaint']}, 13:54:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:54:38 INFO - 'ts_paint', 13:54:38 INFO - 'tpaint', 13:54:38 INFO - 'sessionrestore', 13:54:38 INFO - 'sessionrestore_no_auto_restore', 13:54:38 INFO - 'tabpaint']}, 13:54:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:54:38 INFO - 'tests': ['a11yr', 13:54:38 INFO - 'ts_paint', 13:54:38 INFO - 'tpaint', 13:54:38 INFO - 'sessionrestore', 13:54:38 INFO - 'sessionrestore_no_auto_restore', 13:54:38 INFO - 'tabpaint']}, 13:54:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:54:38 INFO - 'tests': ['a11yr', 13:54:38 INFO - 'ts_paint', 13:54:38 INFO - 'tpaint', 13:54:38 INFO - 'sessionrestore', 13:54:38 INFO - 'sessionrestore_no_auto_restore', 13:54:38 INFO - 'tabpaint']}, 13:54:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:54:38 INFO - 'tests': ['tsvgx', 13:54:38 INFO - 'tsvgr_opacity', 13:54:38 INFO - 'tart', 13:54:38 INFO - 'tscrollx', 13:54:38 INFO - 'cart']}, 13:54:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:54:38 INFO - 'tsvgr_opacity', 13:54:38 INFO - 'tart', 13:54:38 INFO - 'tscrollx', 13:54:38 INFO - 'cart']}, 13:54:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:38 INFO - 'talos_options': ['--disable-e10s'], 13:54:38 INFO - 'tests': ['tp5o']}, 13:54:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:38 INFO - 'tests': ['tp5o']}, 13:54:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:38 INFO - 'talos_options': ['--disable-e10s', 13:54:38 INFO - '--xperf_path', 13:54:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:54:38 INFO - 'tests': ['tp5n']}, 13:54:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:38 INFO - 'talos_options': ['--xperf_path', 13:54:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:54:38 INFO - 'tests': ['tp5n']}}, 13:54:38 INFO - 'talos.zip': {'path': '', 13:54:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:54:38 INFO - Running post-action listener: _resource_record_post_action 13:54:38 INFO - [mozharness: 2016-09-02 20:54:38.087752Z] Finished populate-webroot step (success) 13:54:38 INFO - [mozharness: 2016-09-02 20:54:38.087841Z] Running create-virtualenv step. 13:54:38 INFO - Running pre-action listener: _resource_record_pre_action 13:54:38 INFO - Running main action method: create_virtualenv 13:54:38 INFO - Creating virtualenv /builds/slave/test/build/venv 13:54:38 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 13:54:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:54:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:54:38 INFO - Using real prefix '/usr' 13:54:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:54:38 INFO - Installing distribute.............................................................................................................................................................................................done. 13:54:40 INFO - Installing pip.................done. 13:54:40 INFO - Return code: 0 13:54:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:54:40 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:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:40 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:54:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:40 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 0x1c2d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eec3b0>, '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 0x1eed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eed740>, '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': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', '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:54:40 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 13:54:40 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 13:54:40 INFO - Using env: {'DISPLAY': ':0', 13:54:40 INFO - 'HOME': '/home/cltbld', 13:54:40 INFO - 'LANG': 'en_US.UTF-8', 13:54:40 INFO - 'LANGUAGE': 'en_US:en', 13:54:40 INFO - 'LOGNAME': 'cltbld', 13:54:40 INFO - 'MAIL': '/var/mail/cltbld', 13:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:40 INFO - 'MOZ_NO_REMOTE': '1', 13:54:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:40 INFO - 'NO_EM_RESTART': '1', 13:54:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:40 INFO - 'PWD': '/builds/slave/test', 13:54:40 INFO - 'SHELL': '/bin/bash', 13:54:40 INFO - 'SHLVL': '1', 13:54:40 INFO - 'TERM': 'linux', 13:54:40 INFO - 'TMOUT': '86400', 13:54:40 INFO - 'USER': 'cltbld', 13:54:40 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', 13:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:40 INFO - '_': '/tools/buildbot/bin/python'} 13:54:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:40 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 13:54:40 INFO - Cleaning up... 13:54:40 INFO - Return code: 0 13:54:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:54:40 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:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:40 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:54:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:40 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 0x1c2d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eec3b0>, '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 0x1eed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eed740>, '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': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', '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:54:40 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 13:54:40 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 13:54:40 INFO - Using env: {'DISPLAY': ':0', 13:54:40 INFO - 'HOME': '/home/cltbld', 13:54:40 INFO - 'LANG': 'en_US.UTF-8', 13:54:40 INFO - 'LANGUAGE': 'en_US:en', 13:54:40 INFO - 'LOGNAME': 'cltbld', 13:54:40 INFO - 'MAIL': '/var/mail/cltbld', 13:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:40 INFO - 'MOZ_NO_REMOTE': '1', 13:54:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:40 INFO - 'NO_EM_RESTART': '1', 13:54:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:40 INFO - 'PWD': '/builds/slave/test', 13:54:40 INFO - 'SHELL': '/bin/bash', 13:54:40 INFO - 'SHLVL': '1', 13:54:40 INFO - 'TERM': 'linux', 13:54:40 INFO - 'TMOUT': '86400', 13:54:40 INFO - 'USER': 'cltbld', 13:54:40 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', 13:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:40 INFO - '_': '/tools/buildbot/bin/python'} 13:54:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:41 INFO - Downloading/unpacking psutil>=3.1.1 13:54:41 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 13:54:41 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:54:41 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 13:54:41 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 13:54:41 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 13:54:41 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:54:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:54:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:54:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:54:43 INFO - Installing collected packages: psutil 13:54:43 INFO - Running setup.py install for psutil 13:54:43 INFO - building 'psutil._psutil_linux' extension 13:54:43 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 13:54:43 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 13:54:43 INFO - building 'psutil._psutil_posix' extension 13:54:43 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 13:54:43 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 13:54:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:54:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:54:43 INFO - Successfully installed psutil 13:54:43 INFO - Cleaning up... 13:54:43 INFO - Return code: 0 13:54:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:54:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eec3b0>, '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 0x1eed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eed740>, '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': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', '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:54:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:54:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:54:43 INFO - Using env: {'DISPLAY': ':0', 13:54:43 INFO - 'HOME': '/home/cltbld', 13:54:43 INFO - 'LANG': 'en_US.UTF-8', 13:54:43 INFO - 'LANGUAGE': 'en_US:en', 13:54:43 INFO - 'LOGNAME': 'cltbld', 13:54:43 INFO - 'MAIL': '/var/mail/cltbld', 13:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:43 INFO - 'MOZ_NO_REMOTE': '1', 13:54:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:43 INFO - 'NO_EM_RESTART': '1', 13:54:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:43 INFO - 'PWD': '/builds/slave/test', 13:54:43 INFO - 'SHELL': '/bin/bash', 13:54:43 INFO - 'SHLVL': '1', 13:54:43 INFO - 'TERM': 'linux', 13:54:43 INFO - 'TMOUT': '86400', 13:54:43 INFO - 'USER': 'cltbld', 13:54:43 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', 13:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:43 INFO - '_': '/tools/buildbot/bin/python'} 13:54:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:54: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 13:54: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 13:54: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 13:54: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 13:54: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 13:54: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 13:54:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:54:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:54:45 INFO - Installing collected packages: mozsystemmonitor 13:54:45 INFO - Running setup.py install for mozsystemmonitor 13:54:45 INFO - Successfully installed mozsystemmonitor 13:54:45 INFO - Cleaning up... 13:54:45 INFO - Return code: 0 13:54:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:54: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')]} 13:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54: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')]} 13:54:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54: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', '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 0x1c2d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eec3b0>, '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 0x1eed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eed740>, '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': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', '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:54: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:54: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 jsonschema==2.5.1 13:54:45 INFO - Using env: {'DISPLAY': ':0', 13:54:45 INFO - 'HOME': '/home/cltbld', 13:54:45 INFO - 'LANG': 'en_US.UTF-8', 13:54:45 INFO - 'LANGUAGE': 'en_US:en', 13:54:45 INFO - 'LOGNAME': 'cltbld', 13:54:45 INFO - 'MAIL': '/var/mail/cltbld', 13:54:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:45 INFO - 'MOZ_NO_REMOTE': '1', 13:54:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:45 INFO - 'NO_EM_RESTART': '1', 13:54:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:45 INFO - 'PWD': '/builds/slave/test', 13:54:45 INFO - 'SHELL': '/bin/bash', 13:54:45 INFO - 'SHLVL': '1', 13:54:45 INFO - 'TERM': 'linux', 13:54:45 INFO - 'TMOUT': '86400', 13:54:45 INFO - 'USER': 'cltbld', 13:54:45 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', 13:54:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:45 INFO - '_': '/tools/buildbot/bin/python'} 13:54:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:46 INFO - Downloading/unpacking jsonschema==2.5.1 13:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:54:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:54:47 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 13:54:47 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:54:47 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 13:54:47 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 13:54:47 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 13:54:47 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:54:48 INFO - Downloading functools32-3.2.3-2.tar.gz 13:54:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:54:48 INFO - warning: no files found matching '*.txt' 13:54:48 INFO - no previously-included directories found matching 'build' 13:54:48 INFO - no previously-included directories found matching 'dist' 13:54:48 INFO - no previously-included directories found matching '.git*' 13:54:48 INFO - Installing collected packages: jsonschema, functools32 13:54:48 INFO - Running setup.py install for functools32 13:54:48 INFO - warning: no files found matching '*.txt' 13:54:48 INFO - no previously-included directories found matching 'build' 13:54:48 INFO - no previously-included directories found matching 'dist' 13:54:48 INFO - no previously-included directories found matching '.git*' 13:54:48 INFO - Successfully installed jsonschema functools32 13:54:48 INFO - Cleaning up... 13:54:48 INFO - Return code: 0 13:54:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1c2d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eec3b0>, '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 0x1eed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eed740>, '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': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', '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:54:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:54:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:54:48 INFO - Using env: {'DISPLAY': ':0', 13:54:48 INFO - 'HOME': '/home/cltbld', 13:54:48 INFO - 'LANG': 'en_US.UTF-8', 13:54:48 INFO - 'LANGUAGE': 'en_US:en', 13:54:48 INFO - 'LOGNAME': 'cltbld', 13:54:48 INFO - 'MAIL': '/var/mail/cltbld', 13:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:48 INFO - 'MOZ_NO_REMOTE': '1', 13:54:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:48 INFO - 'NO_EM_RESTART': '1', 13:54:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:48 INFO - 'PWD': '/builds/slave/test', 13:54:48 INFO - 'SHELL': '/bin/bash', 13:54:48 INFO - 'SHLVL': '1', 13:54:48 INFO - 'TERM': 'linux', 13:54:48 INFO - 'TMOUT': '86400', 13:54:48 INFO - 'USER': 'cltbld', 13:54:48 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', 13:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:48 INFO - '_': '/tools/buildbot/bin/python'} 13:54:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:54:48 INFO - Cleaning up... 13:54:48 INFO - Return code: 0 13:54:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1c2d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eec3b0>, '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 0x1eed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eed740>, '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': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', '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:54:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:54:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:54:48 INFO - Using env: {'DISPLAY': ':0', 13:54:48 INFO - 'HOME': '/home/cltbld', 13:54:48 INFO - 'LANG': 'en_US.UTF-8', 13:54:48 INFO - 'LANGUAGE': 'en_US:en', 13:54:48 INFO - 'LOGNAME': 'cltbld', 13:54:48 INFO - 'MAIL': '/var/mail/cltbld', 13:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:48 INFO - 'MOZ_NO_REMOTE': '1', 13:54:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:48 INFO - 'NO_EM_RESTART': '1', 13:54:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:48 INFO - 'PWD': '/builds/slave/test', 13:54:48 INFO - 'SHELL': '/bin/bash', 13:54:48 INFO - 'SHLVL': '1', 13:54:48 INFO - 'TERM': 'linux', 13:54:48 INFO - 'TMOUT': '86400', 13:54:48 INFO - 'USER': 'cltbld', 13:54:48 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', 13:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:48 INFO - '_': '/tools/buildbot/bin/python'} 13:54:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:48 INFO - Downloading/unpacking blobuploader==1.2.4 13:54: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 13:54: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 13:54: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 13:54: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 13:54: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 13:54: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 13:54:50 INFO - Downloading blobuploader-1.2.4.tar.gz 13:54:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:54:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:54:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:51 INFO - Downloading docopt-0.6.1.tar.gz 13:54:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:54:51 INFO - Installing collected packages: blobuploader, requests, docopt 13:54:51 INFO - Running setup.py install for blobuploader 13:54:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:54:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:54:51 INFO - Running setup.py install for requests 13:54:52 INFO - Running setup.py install for docopt 13:54:52 INFO - Successfully installed blobuploader requests docopt 13:54:52 INFO - Cleaning up... 13:54:52 INFO - Return code: 0 13:54:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:52 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 0x1c2d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eec3b0>, '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 0x1eed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eed740>, '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': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', '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:54:52 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 13:54:52 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 13:54:52 INFO - Using env: {'DISPLAY': ':0', 13:54:52 INFO - 'HOME': '/home/cltbld', 13:54:52 INFO - 'LANG': 'en_US.UTF-8', 13:54:52 INFO - 'LANGUAGE': 'en_US:en', 13:54:52 INFO - 'LOGNAME': 'cltbld', 13:54:52 INFO - 'MAIL': '/var/mail/cltbld', 13:54:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:52 INFO - 'MOZ_NO_REMOTE': '1', 13:54:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:52 INFO - 'NO_EM_RESTART': '1', 13:54:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:52 INFO - 'PWD': '/builds/slave/test', 13:54:52 INFO - 'SHELL': '/bin/bash', 13:54:52 INFO - 'SHLVL': '1', 13:54:52 INFO - 'TERM': 'linux', 13:54:52 INFO - 'TMOUT': '86400', 13:54:52 INFO - 'USER': 'cltbld', 13:54:52 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', 13:54:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:52 INFO - '_': '/tools/buildbot/bin/python'} 13:54:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:54:52 INFO - Running setup.py (path:/tmp/pip-rIrMH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:54:52 INFO - Running setup.py (path:/tmp/pip-0n8Zsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:54:52 INFO - Running setup.py (path:/tmp/pip-YwYIXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:54:52 INFO - Running setup.py (path:/tmp/pip-3mSwvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:54:52 INFO - Running setup.py (path:/tmp/pip-r6_3P_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:54:52 INFO - Running setup.py (path:/tmp/pip-5jqOoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:54:53 INFO - Running setup.py (path:/tmp/pip-6TdlOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:54:53 INFO - Running setup.py (path:/tmp/pip-TKenuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:54:53 INFO - Running setup.py (path:/tmp/pip-fggbQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:54:53 INFO - Running setup.py (path:/tmp/pip-SW88Ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:54:53 INFO - Running setup.py (path:/tmp/pip-w890j_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:54:53 INFO - Running setup.py (path:/tmp/pip-ivxQUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:54:53 INFO - Running setup.py (path:/tmp/pip-vKWsbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:54:53 INFO - Running setup.py (path:/tmp/pip-fyGIkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:54:53 INFO - Running setup.py (path:/tmp/pip-RN1uot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:54:53 INFO - Running setup.py (path:/tmp/pip-kpeVTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:54:53 INFO - Running setup.py (path:/tmp/pip-_mJWaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:54:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:54:53 INFO - Running setup.py install for manifestparser 13:54:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:54:53 INFO - Running setup.py install for mozcrash 13:54:53 INFO - Running setup.py install for mozdebug 13:54:54 INFO - Running setup.py install for mozdevice 13:54:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:54:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:54:54 INFO - Running setup.py install for mozfile 13:54:54 INFO - Running setup.py install for mozhttpd 13:54:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:54:54 INFO - Running setup.py install for mozinfo 13:54:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:54:54 INFO - Running setup.py install for mozInstall 13:54:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:54:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:54:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:54:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:54:54 INFO - Running setup.py install for mozleak 13:54:54 INFO - Running setup.py install for mozlog 13:54:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:54:54 INFO - Running setup.py install for moznetwork 13:54:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:54:54 INFO - Running setup.py install for mozprocess 13:54:55 INFO - Running setup.py install for mozprofile 13:54:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:54:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:54:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:54:55 INFO - Running setup.py install for mozrunner 13:54:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:54:55 INFO - Running setup.py install for mozscreenshot 13:54:55 INFO - Running setup.py install for moztest 13:54:55 INFO - Running setup.py install for mozversion 13:54:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:54:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:54:55 INFO - Cleaning up... 13:54:55 INFO - Return code: 0 13:54:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:54:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:55 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 0x1c2d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eec3b0>, '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 0x1eed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eed740>, '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': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', '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:54:55 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 13:54:55 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 13:54:55 INFO - Using env: {'DISPLAY': ':0', 13:54:55 INFO - 'HOME': '/home/cltbld', 13:54:55 INFO - 'LANG': 'en_US.UTF-8', 13:54:55 INFO - 'LANGUAGE': 'en_US:en', 13:54:55 INFO - 'LOGNAME': 'cltbld', 13:54:55 INFO - 'MAIL': '/var/mail/cltbld', 13:54:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:55 INFO - 'MOZ_NO_REMOTE': '1', 13:54:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:55 INFO - 'NO_EM_RESTART': '1', 13:54:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:55 INFO - 'PWD': '/builds/slave/test', 13:54:55 INFO - 'SHELL': '/bin/bash', 13:54:55 INFO - 'SHLVL': '1', 13:54:55 INFO - 'TERM': 'linux', 13:54:55 INFO - 'TMOUT': '86400', 13:54:55 INFO - 'USER': 'cltbld', 13:54:55 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', 13:54:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:55 INFO - '_': '/tools/buildbot/bin/python'} 13:54:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:54:55 INFO - Running setup.py (path:/tmp/pip-bO0ab6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:54:55 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)) 13:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:54:55 INFO - Running setup.py (path:/tmp/pip-snU4tm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:54:55 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)) 13:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:54:55 INFO - Running setup.py (path:/tmp/pip-Yf1gp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:54:56 INFO - Running setup.py (path:/tmp/pip-YFKokV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:54:56 INFO - Running setup.py (path:/tmp/pip-CTXxWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:54:56 INFO - Running setup.py (path:/tmp/pip-7IBrRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:54:56 INFO - Running setup.py (path:/tmp/pip-65UaeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:54:56 INFO - Running setup.py (path:/tmp/pip-IZ7SiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:54:56 INFO - Running setup.py (path:/tmp/pip-p7lcWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:54:56 INFO - Running setup.py (path:/tmp/pip-_rIuMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:54:56 INFO - Running setup.py (path:/tmp/pip-uFzJm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:54:56 INFO - Running setup.py (path:/tmp/pip-fLt1_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:54:56 INFO - Running setup.py (path:/tmp/pip-z4ISpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:54:56 INFO - Running setup.py (path:/tmp/pip-HIX4NB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:54:56 INFO - Running setup.py (path:/tmp/pip-qHQA4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:54:56 INFO - Running setup.py (path:/tmp/pip-LrVPxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:54:56 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)) 13:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:54:56 INFO - Running setup.py (path:/tmp/pip-CgYK7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:54:57 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)) 13:54:57 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)) 13:54:57 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)) 13:54:57 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)) 13:54:57 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)) 13:54:57 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)) 13:54:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:54:57 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 13:54:57 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:54:57 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 13:54:57 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 13:54:57 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 13:54:57 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:54:58 INFO - Downloading blessings-1.6.tar.gz 13:54:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:54:58 INFO - Installing collected packages: blessings 13:54:58 INFO - Running setup.py install for blessings 13:54:59 INFO - Successfully installed blessings 13:54:59 INFO - Cleaning up... 13:54:59 INFO - Return code: 0 13:54:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:54:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:54:59 INFO - Reading from file tmpfile_stdout 13:54:59 INFO - Current package versions: 13:54:59 INFO - argparse == 1.2.1 13:54:59 INFO - blessings == 1.6 13:54:59 INFO - blobuploader == 1.2.4 13:54:59 INFO - docopt == 0.6.1 13:54:59 INFO - functools32 == 3.2.3-2 13:54:59 INFO - jsonschema == 2.5.1 13:54:59 INFO - manifestparser == 1.1 13:54:59 INFO - mozInstall == 1.12 13:54:59 INFO - mozcrash == 0.17 13:54:59 INFO - mozdebug == 0.1 13:54:59 INFO - mozdevice == 0.48 13:54:59 INFO - mozfile == 1.2 13:54:59 INFO - mozhttpd == 0.7 13:54:59 INFO - mozinfo == 0.9 13:54:59 INFO - mozleak == 0.1 13:54:59 INFO - mozlog == 3.3 13:54:59 INFO - moznetwork == 0.27 13:54:59 INFO - mozprocess == 0.23 13:54:59 INFO - mozprofile == 0.28 13:54:59 INFO - mozrunner == 6.12 13:54:59 INFO - mozscreenshot == 0.1 13:54:59 INFO - mozsystemmonitor == 0.3 13:54:59 INFO - moztest == 0.7 13:54:59 INFO - mozversion == 1.4 13:54:59 INFO - psutil == 3.1.1 13:54:59 INFO - requests == 1.2.3 13:54:59 INFO - wsgiref == 0.1.2 13:54:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:59 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 0x1c2d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eec3b0>, '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 0x1eed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eed740>, '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': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', '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:54:59 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:54:59 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:54:59 INFO - Using env: {'DISPLAY': ':0', 13:54:59 INFO - 'HOME': '/home/cltbld', 13:54:59 INFO - 'LANG': 'en_US.UTF-8', 13:54:59 INFO - 'LANGUAGE': 'en_US:en', 13:54:59 INFO - 'LOGNAME': 'cltbld', 13:54:59 INFO - 'MAIL': '/var/mail/cltbld', 13:54:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:59 INFO - 'MOZ_NO_REMOTE': '1', 13:54:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:59 INFO - 'NO_EM_RESTART': '1', 13:54:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:59 INFO - 'PWD': '/builds/slave/test', 13:54:59 INFO - 'SHELL': '/bin/bash', 13:54:59 INFO - 'SHLVL': '1', 13:54:59 INFO - 'TERM': 'linux', 13:54:59 INFO - 'TMOUT': '86400', 13:54:59 INFO - 'USER': 'cltbld', 13:54:59 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', 13:54:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:59 INFO - '_': '/tools/buildbot/bin/python'} 13:54:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 INFO - Cleaning up... 13:54:59 INFO - Return code: 0 13:54:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c2d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eee180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eec3b0>, '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 0x1eed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eed740>, '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': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', '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:54:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:54:59 INFO - Using env: {'DISPLAY': ':0', 13:54:59 INFO - 'HOME': '/home/cltbld', 13:54:59 INFO - 'LANG': 'en_US.UTF-8', 13:54:59 INFO - 'LANGUAGE': 'en_US:en', 13:54:59 INFO - 'LOGNAME': 'cltbld', 13:54:59 INFO - 'MAIL': '/var/mail/cltbld', 13:54:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:59 INFO - 'MOZ_NO_REMOTE': '1', 13:54:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:59 INFO - 'NO_EM_RESTART': '1', 13:54:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:59 INFO - 'PWD': '/builds/slave/test', 13:54:59 INFO - 'SHELL': '/bin/bash', 13:54:59 INFO - 'SHLVL': '1', 13:54:59 INFO - 'TERM': 'linux', 13:54:59 INFO - 'TMOUT': '86400', 13:54:59 INFO - 'USER': 'cltbld', 13:54:59 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', 13:54:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:59 INFO - '_': '/tools/buildbot/bin/python'} 13:54:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:54:59 INFO - Cleaning up... 13:54:59 INFO - Return code: 0 13:54:59 INFO - Running post-action listener: _resource_record_post_action 13:54:59 INFO - Running post-action listener: _start_resource_monitoring 13:54:59 INFO - Starting resource monitoring. 13:54:59 INFO - [mozharness: 2016-09-02 20:54:59.850265Z] Finished create-virtualenv step (success) 13:54:59 INFO - [mozharness: 2016-09-02 20:54:59.850691Z] Running install step. 13:54:59 INFO - Running pre-action listener: _resource_record_pre_action 13:54:59 INFO - Running main action method: install 13:54:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:55:00 INFO - Reading from file tmpfile_stdout 13:55:00 INFO - Detecting whether we're running mozinstall >=1.0... 13:55:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:55:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:55:00 INFO - Reading from file tmpfile_stdout 13:55:00 INFO - Output received: 13:55:00 INFO - Usage: mozinstall [options] installer 13:55:00 INFO - Options: 13:55:00 INFO - -h, --help show this help message and exit 13:55:00 INFO - -d DEST, --destination=DEST 13:55:00 INFO - Directory to install application into. [default: 13:55:00 INFO - "/builds/slave/test"] 13:55:00 INFO - --app=APP Application being installed. [default: firefox] 13:55:00 INFO - mkdir: /builds/slave/test/build/application 13:55:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 13:55:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 13:55:16 INFO - Reading from file tmpfile_stdout 13:55:16 INFO - Output received: 13:55:16 INFO - /builds/slave/test/build/application/firefox/firefox 13:55:16 INFO - Running post-action listener: _resource_record_post_action 13:55:16 INFO - [mozharness: 2016-09-02 20:55:16.911852Z] Finished install step (success) 13:55:16 INFO - [mozharness: 2016-09-02 20:55:16.912124Z] Running run-tests step. 13:55:16 INFO - Running pre-action listener: _resource_record_pre_action 13:55:16 INFO - Running main action method: run_tests 13:55:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:55:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:55:16 INFO - Python 2.7.3 13:55:16 INFO - Return code: 0 13:55:16 INFO - grabbing minidump binary from tooltool 13:55:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:55:16 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eec3b0>, '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 0x1eed2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eed740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:55:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:55:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 13:55:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 13:55:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:55:16 INFO - Return code: 0 13:55:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:55:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:55:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:55:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:55:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:55:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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:55:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --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:55:16 INFO - Using env: {'DISPLAY': ':0', 13:55:16 INFO - 'HOME': '/home/cltbld', 13:55:16 INFO - 'LANG': 'en_US.UTF-8', 13:55:16 INFO - 'LANGUAGE': 'en_US:en', 13:55:16 INFO - 'LOGNAME': 'cltbld', 13:55:16 INFO - 'MAIL': '/var/mail/cltbld', 13:55:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:55:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:55:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:16 INFO - 'MOZ_NO_REMOTE': '1', 13:55:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:55:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:55:16 INFO - 'NO_EM_RESTART': '1', 13:55:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:55:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:16 INFO - 'PWD': '/builds/slave/test', 13:55:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:55:16 INFO - 'SHELL': '/bin/bash', 13:55:16 INFO - 'SHLVL': '1', 13:55:16 INFO - 'TERM': 'linux', 13:55:16 INFO - 'TMOUT': '86400', 13:55:16 INFO - 'USER': 'cltbld', 13:55:16 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790', 13:55:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:16 INFO - '_': '/tools/buildbot/bin/python'} 13:55:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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:55:17 INFO - mozversion application_buildid: 20160902105149 13:55:17 INFO - mozversion application_changeset: 1dd122df980683688de68d91fa8a3bf90eac19b4 13:55:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:55:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:55:17 INFO - mozversion application_name: Firefox 13:55:17 INFO - mozversion application_remotingname: firefox-dev 13:55:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:55:17 INFO - mozversion application_vendor: Mozilla 13:55:17 INFO - mozversion application_version: 50.0a2 13:55:17 INFO - mozversion platform_buildid: 20160902105149 13:55:17 INFO - mozversion platform_changeset: 1dd122df980683688de68d91fa8a3bf90eac19b4 13:55:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:55:17 INFO - mozversion platform_version: 50.0a2 13:55:17 INFO - using testdate: 1472849717 13:55:17 INFO - actual date: 1472849717 13:55:17 INFO - starting webserver on 'localhost:58139' 13:55:17 INFO - SUITE-START | Running 2 tests 13:55:17 INFO - TEST-START | dromaeo_css 13:55:17 INFO - operating with platform_type : linux_ 13:55:17 INFO - Initialising browser for dromaeo_css test... 13:55:17 INFO - TEST-INFO | started process 28510 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWIWMMx/profile http://localhost:58139/getInfo.html) 13:55:21 INFO - PROCESS | 28510 | __metrics Screen width/height:1600/1200 13:55:21 INFO - PROCESS | 28510 | colorDepth:24 13:55:21 INFO - PROCESS | 28510 | Browser inner width/height: 1024/697 13:55:21 INFO - PROCESS | 28510 | __metrics 13:55:21 INFO - PROCESS | 28510 | 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:55:21 INFO - PROCESS | 28510 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:55:22 INFO - TEST-INFO | 28510: exit 0 13:55:22 INFO - Browser initialized. 13:55:22 INFO - Running cycle 1/1 for dromaeo_css test... 13:55:22 INFO - TEST-INFO | started process 28596 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWIWMMx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:55:34 INFO - PROCESS | 28596 | RSS: Main: 145575936 13:55:34 INFO - PROCESS | 28596 | 13:56:25 INFO - PROCESS | 28596 | 1472849785935 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:58:34 INFO - PROCESS | 28596 | Cycle 1(1): loaded http://localhost:58139/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58139/tests/dromaeo/cssquery-ext.html) 13:58:35 INFO - PROCESS | 28596 | RSS: Main: 252891136 13:58:35 INFO - PROCESS | 28596 | 14:01:37 INFO - PROCESS | 28596 | Cycle 1(1): loaded http://localhost:58139/tests/dromaeo/cssquery-ext.html (next: http://localhost:58139/tests/dromaeo/cssquery-jquery.html) 14:01:38 INFO - PROCESS | 28596 | RSS: Main: 234147840 14:01:38 INFO - PROCESS | 28596 | 14:04:40 INFO - PROCESS | 28596 | Cycle 1(1): loaded http://localhost:58139/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58139/tests/dromaeo/cssquery-mootools.html) 14:04:40 INFO - PROCESS | 28596 | RSS: Main: 175173632 14:04:40 INFO - PROCESS | 28596 | 14:07:42 INFO - PROCESS | 28596 | Cycle 1(1): loaded http://localhost:58139/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58139/tests/dromaeo/cssquery-prototype.html) 14:07:43 INFO - PROCESS | 28596 | RSS: Main: 173989888 14:07:43 INFO - PROCESS | 28596 | 14:10:46 INFO - PROCESS | 28596 | Cycle 1(1): loaded http://localhost:58139/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58139/tests/dromaeo/cssquery-yui.html) 14:10:46 INFO - PROCESS | 28596 | RSS: Main: 175542272 14:10:46 INFO - PROCESS | 28596 | 14:13:49 INFO - PROCESS | 28596 | Cycle 1(1): loaded http://localhost:58139/tests/dromaeo/cssquery-yui.html (next: http://localhost:58139/tests/dromaeo/cssquery-dojo.html) 14:13:49 INFO - PROCESS | 28596 | RSS: Main: 260132864 14:13:49 INFO - PROCESS | 28596 | 14:13:49 INFO - PROCESS | 28596 | __start_tp_report 14:13:49 INFO - PROCESS | 28596 | _x_x_mozilla_page_load 14:13:49 INFO - PROCESS | 28596 | _x_x_mozilla_page_load_details 14:13:49 INFO - PROCESS | 28596 | |i|pagename|runs| 14:13:49 INFO - PROCESS | 28596 | |0;dojo.html;3197.728193103586;3205.759568032398;3210.9518357224647;3221.404040252552;3246.1722260823503;5522.171674248864;5608.551315894729;5628.043232650449;5658.688772117532;5673.2908386451745;5578.191162281467;5623.606347555673;5643.492085712285;5646.6894320812335;5674.177244299574;5054.444011158788;5101.38783345998;5107.46371630979;5127.692338459686;5182.3262975813095;5030;5077.923831142536;5116.948830511703;5157.020166168416;5158.252053452247;5106.412762532301;5201.0378080055125;5202.583793296527;5208.270842082112;5213.009528189632;5131.923021154686;5137.152369858969;5198.584113270929;5205.115130427836;5227.5295223429885;4795.400574928134;4961.925571116436;5099.36257967754;5121.590272778206;5185.403678576974;5677;5681.34664513578;5692.800751973702;5694.088945768657;5697.287839020122;6081.847953801146;6101.617272147843;6103.908441373383;6109.14471973924;6113.755449782004;5823.941760582382;5939.643621382731;5987.880242395127;6014.909776353358;6045.72794224261;5632.042552765999;5647.661140331593;5654.378018418012;5657.1514272858985;5660.943390566083;6175.598586091979;6182.938170618237;6184.041473571604;6184.443400093923;6184.536159788034;5718.7426565804635;5812.7674893003805;5819.330776960704;5870.26621672291;5964.015937370372;6007;6010.338862725097;6037.09443583466;6047.365026672136;6050.30421501533;5929.495992840571;5993.61041532299;5997.670128142907;6002.759889604367;6003.789867354709;5818.389069147764;5881.059030555176;5889.9692553802915;5907.61600495975;5979.820605381846;4525.818967241339;4612.654050946126;4615.538446155425;4616.215243408628;4616.469106052779;4089.7750623715983;4111.835526578963;4126.442930204384;4136.751794892316;4151.46031015966;5642.435756424325;5679.659220446786;5693.60144789869;5694.743736531866;5699.259096317535;5883.470487656132;6032.095185722089;6040.6677632730625;6131.540134489931;6135.386461353829;5760.6255593386295;5843.1235314703135;5847.064469684915;5868.559858010666;5875.29496460419;5320.414754377015;5330.147176451749;5331.333583302087;5332.33345831771;5335.1730481775385;6024.3975602439405;6191.535634827496;6392.7123279452535;6399.744010239631;6401.487880969511;6086.269647642312;6139.216166136355;6150.138980542638;6151;6153.384661533811;6298.433141017147;6351.777687780906;6354.52341074412;6361.045843123568;6363.140975968185;5772.163036359789;5845.736941837543;6038.184845046038;6042.305134909366;6042.667653279112;5934.7922822701;6088.269407671108;6096.237970253718;6132.325444201045;6135.355787642412;5937.762489500372;5941.792037278675;5942.851428714143;5947.464728174485;5950.31571369298;5692.7722891083895;5818.73815678304;5856.180134781049;5865.208196893364;5919.023361145363;5845.09401042839;5933.199018132497;5933.821985340447;5959.255093113361;5959.67221802805;5958.731857066356;5964.224650795369;5994.130851026664;6003.429674180946;6007.699615019144;5597.944020559742;5690.231818704422;5805.796797112081;5812.883742325214;5818.709064546841 14:13:49 INFO - PROCESS | 28596 | |1;ext.html;615.2770494668766;635.7774778827411;667.7362441835473;669.7287598522597;669.8928171492562;35804;47031.76484117579;48213;48401.75799121004;52037.21944170831;49503;49950;50098.248526272044;50137;50209;8429.494230346168;9316.95341523293;9472.336936414455;9486.62053517862;9493.240540756757;8586.87119693205;8650.610722517502;8653.524056176908;10829.566817327297;11082.445877706123;11270.154738394613;11330.206885518017;11340.206185567014;11350.545978160904;11367.431628418588;11219.326840389562;11267.21129520934;11276.94361528187;11288.322700637988;11292.322460652387;11044.171687123498;11169;11182;11183.664490065312;11192.776144477146;46158.76920615409;46545.76727116343;46562.30156547655;46585;46602;3903.434002069684;4049.068714195722;4266.2320782259185;4363.607275345203;4665.300204969248;35749.64250357489;36103.81948090269;36820.07949801276;36854.26291474156;37093.62906370956;4486.147631949952;4530.139273538017;4553.658475614342;4602.677812553123;4797.904041919177;269847;270450;270807;271182;272561.999999998;38446.80776596099;64890;65130.674346627966;65196;65358.67320663366;39232.41151382731;41395.79302103531;41435.58564414378;41682.583174168474;41717.37423938643;15777.974431661905;15914.68170636604;15928.601784955468;15935;15962.60093497672;13133.015023873057;13162.934185329012;13216.603501894959;13221.405036773176;13241.668958276121;8652.394332396798;8858.29133669305;8880.689175878942;8993.775155621162;9088.863667045;3529.36471435139;3538.876139335111;3555.1289933966345;3604.333198358312;3624.456331550236;1095.6548687163677;1111.2332490581634;1149.8792626774236;1175.5650409348425;1189.7679952409196;34863.30273394518;35647;35733.46399804005;35734;36047.639523604426;1208.5407545132794;1224.8958838498827;1254.9184303020274;1256.0830593666674;1261.142423152247;18441.81558184428;18550;18617.627647446974;18680.995801855563;18859.905700471685;634050;635483;641993;642515;643350;45069.54930450719;45213.32180017302;45354.092918141454;45356;45557.772211139396;1472.1902953375816;1477.637978695205;1478.667299857545;1494.3499577683742;1496.341609691754;34196.82901585476;35420.291594168484;35540.11149721174;35639.465408019416;35662.465063024596;57307.42692573021;57914.13128803155;57936.130958036505;57942.4205757937;57985.71007144937;372284;373133;373415;373846;373883;31326.530102048942;31619.84190079035;41241.793791030854;47306.76346618245;47955;44177.55822441734;44286.81740783294;52392.73803630957;52783.47216527785;58454.707726462795;45767.084658307314;46994.29508557306;47434.28848567343;48507;48537;46875.765621171675;47145;47500.7624961873;47872.281915771986;48077;46566.76716616531;47391.52608474009;47439.762801185774;47630;47658.761706192636;38671.22657546777;40069;40432.79783601063;40912.1817563653;41335.586644133175;30546.389072217986;30744.625898994927;30997;31009.379812404077;31035.379292414476 14:13:49 INFO - PROCESS | 28596 | |2;jquery.html;395.33188910740864;414.34326592351124;426.7503510446389;435.74508912286313;439.10203633569347;15897.761533577006;21391.57216855662;21501.24745633903;21526.461838454026;22214.222502212386;20343.59312813743;20501.48746281344;20501.48746281344;20566.280180193695;20815.583688326224;5551.306086739158;5762.366139724627;5970.850728731773;6015.3383127855905;6021.759129634831;5809.709514524278;5902.232709747726;5963.701814909259;5989.850253743648;5990.071538911476;5898.675572843514;5903.114532820068;5917.585768996172;5957.404259574051;5960.433758792908;5849.064149736065;5898.521655151937;5915.112733090028;5950.851228719273;5962.463378295953;5321.840344789663;5341.225522299244;5348.277982472356;5349.3045904032615;5351.892962140774;20505.79494205054;23838.284851454486;24113.758862411327;24260.636090458658;24825.62761558578;2468.3458883395915;2752.477029364434;2759.958600620972;2794.3712664650293;3022.909312720622;15426.460073897471;15544.533663990098;15633.296501657453;15708.293126809322;16069.598260043476;2114.7250857388594;2116.9788302116935;2166.9566608667737;2195.3633446300555;2314.837961342707;86032.56983715041;88177.55911220404;88410;89853;90516.54741726315;11743.295402275891;11791;11947.641570752892;11993.64019079429;12150.088743344284;16676.58308542296;16703.331866725195;16710.08094554811;16713.91643041777;16723.49829505117;8548.700795472128;9051.637934482678;9064.410813297114;9077.546122693839;9095.181433671001;8896.42173258749;8904.554772261361;8921.330900182513;9062.456252624863;9155.13026262504;5022.171341728574;5077.746112694351;5139.383274007143;5149.845504634867;5153.871153221125;2067;2091.6444204485274;2106.7577228618907;2115.027087539749;2147.957040859174;989.8762654668167;1012.2155329619603;1019.887812340642;1103.2608152537819;1107.7618312062946;20490.487737806674;20688.17247310091;20771.792282076985;20849.68725469119;21143.154273828874;1027.9743006424899;1043.5773511727762;1054.947252637365;1055.0451841083832;1063.6170978447753;10810.837837432446;11435.885641143648;11738.354390508433;11823.881761182278;11833.290002599872;497358;504839;512292;512310;512422;94625.5268723652;96626;98245.5087724571;98860;99768.50115749375;971.5579411367952;980.5097451274505;993.8707967964118;996.0686757881259;1006.4213077480478;17731.202095905457;18364.540886477946;18374.173162208;18442.815571844647;18564.44306670802;44782.3282650767;45174.177053371735;45286.77356613196;45911.08177836358;47122.29316560323;60905.69547152236;61598.69200653968;62341.688291560065;62703.6864815673;63840.68079659572;7802.843943121219;8078.111407745026;8135.511869287861;8513.233808957224;8724.262576638179;12664.74670506603;13360.866391335961;13737.381817818423;13891.72216555643;14066;12000.279983201035;12058.216215945935;12354.135210535536;12368.505259789868;12472.87527124717;11731.824022639497;12039.578614748441;12148.944292137812;12168.209066410654;12614.306213158365;12228.266304021787;12367.010639149026;12648;12711.364431778558;13040.282784446947;8288.96387951506;8332.916670833214;8398.328133749408;8471.279941205066;8729.083446238054;11337.489812958602;12343.444544995677;12499.500019999097;12551.93724031374;12665.620031399072 14:13:49 INFO - PROCESS | 28596 | |3;mootools.html;1411.1674112273756;1554.6035760880977;1603.711331960247;1815.927362905484;1938.8545859060573;9136.543172841357;9584.56869440875;9604.807903841927;9883.061109194632;9909.801803963916;9664.178544823699;9776.853347199798;9786.168175705074;9793.216542676586;9804.362716423446;2515.71069327027;2561.5773397389503;2561.7822485088695;2567.6148577713398;2568.961465578018;2453.0800949643885;2578.329634295078;2584.961225581618;2589.5468293048734;2590.080521414899;2489.5643262429094;2493.9750602494014;2552.553303171947;2552.8340657857275;2555.3611597100726;2419.5402873454077;2420.7579242075826;2443.4135807406183;2522.7351128131468;2525.722170561237;2437.585610446228;2465.3713303107593;2470.5923522618746;2482.304954612693;2482.515909397668;9582.13760761528;9799.363041402357;9864.457454839981;9864.95067524665;9872.40765554069;5787.971060144672;5951.4048595140575;5956.821295361146;5964.433378829004;5970.910436343458;3567.8216089195694;3574.0528759878653;3575.910602234939;3595.209054008114;3691.984704206338;1169.1582060916128;1179.257068047125;1182.0779791762352;1205.2406983600363;1212.9514819407214;13990.790138147935;14057.64855878601;14066.36701348452;14080.577582672537;14087.154770713687;4884.731339776346;4889.902201955941;4975.701457912464;5055.545000949932;5062.038212739568;10803.081738052197;10820.78358432827;10826.350418974887;10827.566897324177;10831.512581933832;5730.283714535683;5779.075347944308;5793.420657934173;5795.884082318415;5806.65160090396;4579.175748365326;4747.477777444478;5152.948470515322;5168.405633352138;5389.51494365509;4195.727277726938;4204.51648060477;4302.075053863435;4332.588404101605;4342.001339691826;1298.0718786067819;1320.7226482438796;1324.9006324525699;1327.2567362277155;1330.481112366178;950.7575568230195;994.9502524873727;1020.6172685243034;1027.4759872464947;1036.3781730961364;6412.743490260431;6440.645764482999;6537.477001839841;6750;6856.211535673362;485.0347807862399;485.61636307316826;493.00167161497535;493.80494704591484;503.23257033024197;4374.606285434326;4413.073254616503;4463.776811159429;4569.840055598038;4588.632909367243;11713.941430292794;12229.449674764657;12251.632451026484;12258;12258.570950016885;11685.357305348287;12120.454579543766;12146;12148.149629526026;12149;9148.542572871329;9358.672446464341;9366.203872670763;9369.25045996305;9372.765680857896;6562.671866406565;6761.430656350555;6900.171979362509;6938.687759050956;7028;11027.22809403334;11320.37737924422;11489.712757181138;11506.367149806842;11609.767804644029;10092.899071009197;10653.733656658645;11335.206535542433;11687.240329378563;11751.88248117542;8571.22858942698;8933.374663773473;8970.192682658591;9046.728598142066;9048.683296084606;9494.430334179971;9576.138147566753;9652.806943861224;9660.95169524148;9661.71014869555;8530.061693213618;8617.310615150898;8625.309975201842;8731.869021964549;8876;8472.957635211784;8491.915080849112;8496.532690701824;8524.318054555744;8652.26455751243;7757.883631745415;7800.765977020698;7938.880916786368;8026.277635012877;8202.09776924526;5868.09044598088;5879.559033072622;5923.111533270044;6030.6080104793045;6047.516198704181;5829.300483941955;5857.707114644336;5869.4717475425705;5947.31605865331;5961.2846458425265 14:13:49 INFO - PROCESS | 28596 | |4;prototype.html;342.3888359278688;346.2020043799042;350.64584769382924;352.86238367036844;357.3585414181544;5521.447855214482;5591.2731344925105;5596;5623.834823592915;5632.943670563298;5445.774700692342;5501.587380946425;5545.197810711515;5565.693886836222;5571.916421253684;3913.882583522499;4130.215259100776;4312.460942382202;4350.499692535352;4362.454693163355;4326.7668714416395;4342.457192850894;4343.04453020335;4351.71713838601;4402.911941761163;4364.869053928387;4401.339799030139;4401.581849724271;4403.6917415780745;4416.116776644668;4160.230357383879;4287.742735435868;4350.978245108786;4372.978135109304;4394.4946331172005;3875.341191997339;3883.2815929053218;3887.1642596841816;3890.4164375343644;3917.157811070605;5394.109971854639;5432.402435732066;5433.945660543423;5444.863878403032;5469.234307196995;4813.374261346037;4959.082569724576;4960.355153829979;4978.581104385269;5051.52010559;5705.885882282331;5744.626599271076;5760.711964401763;5779.566532510036;5785;4258.850940217078;4287.164003019412;4317.676174286941;4320.1143765527995;4331.891702707426;5693.658380497183;5702.287214098238;5879.089295978824;6020.5785595008365;6033.969830150865;4219.38818871262;4227.619514243733;4232;4290.978545107317;4297.763623000765;4346.087321662423;4347.217500849877;4347.500037495665;4371.213181627338;4374.168907907488;4829.468758436569;4910.288008238786;4990.875228119254;5003.574696150868;5017.54842064216;5764.308283006066;5771.163181338682;5774.527495488624;5806.506446951972;5816.592838501264;1464.5386703188462;1476.2249818845191;1485.6434455730546;1489.9942538786277;1501.9474318398804;971.6696323249987;996.14829320931;996.3772642098688;1001.4241810958728;1024.6516184497307;4919;4955.860152165023;4986.276989836453;4995.6056248002615;5001.224810154432;5211.664534697797;5221.738913054332;5228.686278823283;5236.790528378899;5239.607029472729;4964.230544265644;5000.424951130593;5030.220315851049;5292.603054770908;5760.654360738369;3159.273367125574;3174.349258402022;3193.121891479815;3202.151429871086;3209.7111259986714;6112.786052488231;6209.720562574695;6223.844403889849;6278.183836101278;6285.654289014148;6048.45764330093;6096.451319381188;6098.512119030467;6115.694215289218;6127.4068742127365;6165.753369865155;6179.208029671435;6189.96905015472;6198.969005154945;6211.391215689511;5778.739956702043;5951.256092988377;5968.313643931004;5983.34183239852;6057.636541807506;5845.941540584539;5859.59756499833;5911.556633252438;5926.140709596998;5929.407059294209;5856.94143058564;5986.341502434641;6126.846828829315;6128.203333566607;6174.567780255339;4235.470566179228;4256.914861702687;4270.530241673351;4303.763293018788;4313.956860431355;5827.475527202572;6037;6051.394860513913;6067.787627433018;6084.939150608614;5810.373095533258;5852.707364631837;5867.794627188028;5868.38381969887;5901.380355062656;5809.622374545641;5819.098039803837;5835.737391817293;5848.397463128867;5898.616589921641;5529.834104976857;5791.623544469596;5796.82609521715;5815.82552523425;5850.268716410449;5853.151293062568;5862.954561352653;5877.823665290048;5880.911786323294;5883.852903677443;5542.307211598551;5739.364281179844;5753.194552762533;5790.4788569027305;5815.709214539341 14:13:49 INFO - PROCESS | 28596 | |5;yui.html;796.2197046894045;812.3582369927753;828.6643909216765;848.9787755306121;929.7907970706592;15388.846111538882;16615.41846035386;16627.251773670185;16668.166591670397;16676.082815445145;15956;16064.83935160651;16105.27526261318;16134.596635084128;16140;710.346481237263;852.86780549015;876.369014309696;889.1508609278123;897.0939351255214;897.3763234551989;897.8653202019684;898.4833720610642;899.8380291547519;900.8108297257584;953.1278879824952;954.2366107114316;954.4273435938451;954.8472244440891;955.6559638530124;763.6525380951654;878.1876763993313;919.0671468459519;933.09955892564;937.2829785214317;695.2699665351412;696.0533674203131;696.3419568507762;696.6098984568607;698.2877464985686;15917;15962.680746385007;15992.280347384396;15995.600109997344;16053.117078560674;14945.626359340995;15041.623959400993;15265.694686106326;15326.846731532654;15442.613934651723;12033.879661203451;12117.575884843987;14176;14381.424743010268;14827.258637068104;4882.5605695487575;4899.755012249373;4975;4980.427250866161;5106.66806657566;71328;71749.28250717478;73371.63314183448;77025.61487192585;83939.16060839375;12875.871241287467;13636.181829090287;13637.931810341082;13807.309634518233;13826.723465530633;24203.757962420503;24721.134760283305;24751.133710320053;25353.74646253514;25649;9489.572969216402;9533.332666713402;9633.807323853624;9888.110070093726;10145.543450544743;5041.395032596039;5147.0992576298995;5150.6137039721425;5209.218617207373;5229.189475631283;3857.9035524112123;3986.9202615948097;3995.08113133975;3996.360582306817;3997.960020399817;540.3218960204917;629.288903538998;659.2517492645785;667.3326673326674;668.3249917583275;681.7511608263047;727.5380133615087;778.1090651204339;825.4964471672275;847.6863560482661;9731.367461115147;10089.495525223709;10108.646197383203;10778.838317425245;10802.405867677353;800.6157044618616;813.2680587471323;817.0318172965056;822.6462621072997;826.5701835045743;5082.288479612857;5341.839744807662;5447.809326673548;5481.698506582176;5503.697296648723;76245.6187719069;76326.61836690782;77299.61350193214;110441;128028.35985820198;113509.43245283885;114291;114965.4251728736;115406;115575.42212288885;94466.52766736122;96115;99242;100787;102885.48557257166;10232.283740138118;10585.206109541661;10730.463476826284;10846.29499082557;11342.886571134182;53032.73483632557;55219.72390138024;55386;55493.445065548825;55971;77021;77572;78083.60958195172;79529.60235198787;81163;12464.252144870974;13098.2141071536;13367.866321337051;13376.531821386205;13690.794638080635;16453.83546164523;16584.58538536498;16656.167191640612;16854.57863553372;17119.22963466672;10021.348612340174;11522.942385288356;14148;15036.849631503545;15517.76723349128;14380.712385752435;14428.567142985727;14936.551903442914;14994.850051499345;15241.771373429186;15736.685266294839;15768.448104316294;15793.36826526972;15936.681266374839;16132.516024519284;12539.24764514132;12874.420651070537;12893.355332233163;13697.246651434267;14131.64670883195;10689.30519516229;11549.249298795552;13220.933895330461;13480.93259533696;13929 14:13:49 INFO - PROCESS | 28596 | __end_tp_report 14:13:49 INFO - PROCESS | 28596 | __start_cc_report 14:13:49 INFO - PROCESS | 28596 | _x_x_mozilla_cycle_collect,969 14:13:49 INFO - PROCESS | 28596 | __end_cc_report 14:13:49 INFO - PROCESS | 28596 | __startTimestamp1472850829528__endTimestamp 14:13:49 INFO - PROCESS | 28596 | 14:13:49 INFO - PROCESS | 28596 | ------- Summary: start ------- 14:13:49 INFO - PROCESS | 28596 | Number of tests: 6 14:13:49 INFO - PROCESS | 28596 | 14:13:49 INFO - PROCESS | 28596 | [#0] dojo.html Cycles:165 Average:5613.95 Median:5819.33 stddev:648.12 (11.1%) stddev-sans-first:621.77 14:13:49 INFO - PROCESS | 28596 | Values: 3197.7 3205.8 3211.0 3221.4 3246.2 5522.2 5608.6 5628.0 5658.7 5673.3 5578.2 5623.6 5643.5 5646.7 5674.2 5054.4 5101.4 5107.5 5127.7 5182.3 5030.0 5077.9 5116.9 5157.0 5158.3 5106.4 5201.0 5202.6 5208.3 5213.0 5131.9 5137.2 5198.6 5205.1 5227.5 4795.4 4961.9 5099.4 5121.6 5185.4 5677.0 5681.3 5692.8 5694.1 5697.3 6081.8 6101.6 6103.9 6109.1 6113.8 5823.9 5939.6 5987.9 6014.9 6045.7 5632.0 5647.7 5654.4 5657.2 5660.9 6175.6 6182.9 6184.0 6184.4 6184.5 5718.7 5812.8 5819.3 5870.3 5964.0 6007.0 6010.3 6037.1 6047.4 6050.3 5929.5 5993.6 5997.7 6002.8 6003.8 5818.4 5881.1 5890.0 5907.6 5979.8 4525.8 4612.7 4615.5 4616.2 4616.5 4089.8 4111.8 4126.4 4136.8 4151.5 5642.4 5679.7 5693.6 5694.7 5699.3 5883.5 6032.1 6040.7 6131.5 6135.4 5760.6 5843.1 5847.1 5868.6 5875.3 5320.4 5330.1 5331.3 5332.3 5335.2 6024.4 6191.5 6392.7 6399.7 6401.5 6086.3 6139.2 6150.1 6151.0 6153.4 6298.4 6351.8 6354.5 6361.0 6363.1 5772.2 5845.7 6038.2 6042.3 6042.7 5934.8 6088.3 6096.2 6132.3 6135.4 5937.8 5941.8 5942.9 5947.5 5950.3 5692.8 5818.7 5856.2 5865.2 5919.0 5845.1 5933.2 5933.8 5959.3 5959.7 5958.7 5964.2 5994.1 6003.4 6007.7 5597.9 5690.2 5805.8 5812.9 5818.7 14:13:49 INFO - PROCESS | 28596 | 14:13:49 INFO - PROCESS | 28596 | [#1] ext.html Cycles:180 Average:60217.41 Median:22240.93 stddev:121404.98 (545.9%) stddev-sans-first:121663.08 14:13:49 INFO - PROCESS | 28596 | Values: 615.3 635.8 667.7 669.7 669.9 35804.0 47031.8 48213.0 48401.8 52037.2 49503.0 49950.0 50098.2 50137.0 50209.0 8429.5 9317.0 9472.3 9486.6 9493.2 8586.9 8650.6 8653.5 10829.6 11082.4 11270.2 11330.2 11340.2 11350.5 11367.4 11219.3 11267.2 11276.9 11288.3 11292.3 11044.2 11169.0 11182.0 11183.7 11192.8 46158.8 46545.8 46562.3 46585.0 46602.0 3903.4 4049.1 4266.2 4363.6 4665.3 35749.6 36103.8 36820.1 36854.3 37093.6 4486.1 4530.1 4553.7 4602.7 4797.9 269847.0 270450.0 270807.0 271182.0 272562.0 38446.8 64890.0 65130.7 65196.0 65358.7 39232.4 41395.8 41435.6 41682.6 41717.4 15778.0 15914.7 15928.6 15935.0 15962.6 13133.0 13162.9 13216.6 13221.4 13241.7 8652.4 8858.3 8880.7 8993.8 9088.9 3529.4 3538.9 3555.1 3604.3 3624.5 1095.7 1111.2 1149.9 1175.6 1189.8 34863.3 35647.0 35733.5 35734.0 36047.6 1208.5 1224.9 1254.9 1256.1 1261.1 18441.8 18550.0 18617.6 18681.0 18859.9 634050.0 635483.0 641993.0 642515.0 643350.0 45069.5 45213.3 45354.1 45356.0 45557.8 1472.2 1477.6 1478.7 1494.3 1496.3 34196.8 35420.3 35540.1 35639.5 35662.5 57307.4 57914.1 57936.1 57942.4 57985.7 372284.0 373133.0 373415.0 373846.0 373883.0 31326.5 31619.8 41241.8 47306.8 47955.0 44177.6 44286.8 52392.7 52783.5 58454.7 45767.1 46994.3 47434.3 48507.0 48537.0 46875.8 47145.0 47500.8 47872.3 48077.0 46566.8 47391.5 47439.8 47630.0 47658.8 38671.2 40069.0 40432.8 40912.2 41335.6 30546.4 30744.6 30997.0 31009.4 31035.4 14:13:49 INFO - PROCESS | 28596 | 14:13:49 INFO - PROCESS | 28596 | [#2] jquery.html Cycles:180 Average:30655.26 Median:13789.05 stddev:83855.78 (608.1%) stddev-sans-first:84060.24 14:13:49 INFO - PROCESS | 28596 | Values: 395.3 414.3 426.8 435.7 439.1 15897.8 21391.6 21501.2 21526.5 22214.2 20343.6 20501.5 20501.5 20566.3 20815.6 5551.3 5762.4 5970.9 6015.3 6021.8 5809.7 5902.2 5963.7 5989.9 5990.1 5898.7 5903.1 5917.6 5957.4 5960.4 5849.1 5898.5 5915.1 5950.9 5962.5 5321.8 5341.2 5348.3 5349.3 5351.9 20505.8 23838.3 24113.8 24260.6 24825.6 2468.3 2752.5 2760.0 2794.4 3022.9 15426.5 15544.5 15633.3 15708.3 16069.6 2114.7 2117.0 2167.0 2195.4 2314.8 86032.6 88177.6 88410.0 89853.0 90516.5 11743.3 11791.0 11947.6 11993.6 12150.1 16676.6 16703.3 16710.1 16713.9 16723.5 8548.7 9051.6 9064.4 9077.5 9095.2 8896.4 8904.6 8921.3 9062.5 9155.1 5022.2 5077.7 5139.4 5149.8 5153.9 2067.0 2091.6 2106.8 2115.0 2148.0 989.9 1012.2 1019.9 1103.3 1107.8 20490.5 20688.2 20771.8 20849.7 21143.2 1028.0 1043.6 1054.9 1055.0 1063.6 10810.8 11435.9 11738.4 11823.9 11833.3 497358.0 504839.0 512292.0 512310.0 512422.0 94625.5 96626.0 98245.5 98860.0 99768.5 971.6 980.5 993.9 996.1 1006.4 17731.2 18364.5 18374.2 18442.8 18564.4 44782.3 45174.2 45286.8 45911.1 47122.3 60905.7 61598.7 62341.7 62703.7 63840.7 7802.8 8078.1 8135.5 8513.2 8724.3 12664.7 13360.9 13737.4 13891.7 14066.0 12000.3 12058.2 12354.1 12368.5 12472.9 11731.8 12039.6 12148.9 12168.2 12614.3 12228.3 12367.0 12648.0 12711.4 13040.3 8289.0 8332.9 8398.3 8471.3 8729.1 11337.5 12343.4 12499.5 12551.9 12665.6 14:13:49 INFO - PROCESS | 28596 | 14:13:49 INFO - PROCESS | 28596 | [#3] mootools.html Cycles:180 Average:6425.65 Median:485.33 stddev:3728.39 (768.2%) stddev-sans-first:3719.80 14:13:49 INFO - PROCESS | 28596 | Values: 1411.2 1554.6 1603.7 1815.9 1938.9 9136.5 9584.6 9604.8 9883.1 9909.8 9664.2 9776.9 9786.2 9793.2 9804.4 2515.7 2561.6 2561.8 2567.6 2569.0 2453.1 2578.3 2585.0 2589.5 2590.1 2489.6 2494.0 2552.6 2552.8 2555.4 2419.5 2420.8 2443.4 2522.7 2525.7 2437.6 2465.4 2470.6 2482.3 2482.5 9582.1 9799.4 9864.5 9865.0 9872.4 5788.0 5951.4 5956.8 5964.4 5970.9 3567.8 3574.1 3575.9 3595.2 3692.0 1169.2 1179.3 1182.1 1205.2 1213.0 13990.8 14057.6 14066.4 14080.6 14087.2 4884.7 4889.9 4975.7 5055.5 5062.0 10803.1 10820.8 10826.4 10827.6 10831.5 5730.3 5779.1 5793.4 5795.9 5806.7 4579.2 4747.5 5152.9 5168.4 5389.5 4195.7 4204.5 4302.1 4332.6 4342.0 1298.1 1320.7 1324.9 1327.3 1330.5 950.8 995.0 1020.6 1027.5 1036.4 6412.7 6440.6 6537.5 6750.0 6856.2 485.0 485.6 493.0 493.8 503.2 4374.6 4413.1 4463.8 4569.8 4588.6 11713.9 12229.4 12251.6 12258.0 12258.6 11685.4 12120.5 12146.0 12148.1 12149.0 9148.5 9358.7 9366.2 9369.3 9372.8 6562.7 6761.4 6900.2 6938.7 7028.0 11027.2 11320.4 11489.7 11506.4 11609.8 10092.9 10653.7 11335.2 11687.2 11751.9 8571.2 8933.4 8970.2 9046.7 9048.7 9494.4 9576.1 9652.8 9661.0 9661.7 8530.1 8617.3 8625.3 8731.9 8876.0 8473.0 8491.9 8496.5 8524.3 8652.3 7757.9 7800.8 7938.9 8026.3 8202.1 5868.1 5879.6 5923.1 6030.6 6047.5 5829.3 5857.7 5869.5 5947.3 5961.3 14:13:49 INFO - PROCESS | 28596 | 14:13:49 INFO - PROCESS | 28596 | [#4] prototype.html Cycles:180 Average:4864.72 Median:5457.50 stddev:1418.77 (26.0%) stddev-sans-first:1381.55 14:13:49 INFO - PROCESS | 28596 | Values: 342.4 346.2 350.6 352.9 357.4 5521.4 5591.3 5596.0 5623.8 5632.9 5445.8 5501.6 5545.2 5565.7 5571.9 3913.9 4130.2 4312.5 4350.5 4362.5 4326.8 4342.5 4343.0 4351.7 4402.9 4364.9 4401.3 4401.6 4403.7 4416.1 4160.2 4287.7 4351.0 4373.0 4394.5 3875.3 3883.3 3887.2 3890.4 3917.2 5394.1 5432.4 5433.9 5444.9 5469.2 4813.4 4959.1 4960.4 4978.6 5051.5 5705.9 5744.6 5760.7 5779.6 5785.0 4258.9 4287.2 4317.7 4320.1 4331.9 5693.7 5702.3 5879.1 6020.6 6034.0 4219.4 4227.6 4232.0 4291.0 4297.8 4346.1 4347.2 4347.5 4371.2 4374.2 4829.5 4910.3 4990.9 5003.6 5017.5 5764.3 5771.2 5774.5 5806.5 5816.6 1464.5 1476.2 1485.6 1490.0 1501.9 971.7 996.1 996.4 1001.4 1024.7 4919.0 4955.9 4986.3 4995.6 5001.2 5211.7 5221.7 5228.7 5236.8 5239.6 4964.2 5000.4 5030.2 5292.6 5760.7 3159.3 3174.3 3193.1 3202.2 3209.7 6112.8 6209.7 6223.8 6278.2 6285.7 6048.5 6096.5 6098.5 6115.7 6127.4 6165.8 6179.2 6190.0 6199.0 6211.4 5778.7 5951.3 5968.3 5983.3 6057.6 5845.9 5859.6 5911.6 5926.1 5929.4 5856.9 5986.3 6126.8 6128.2 6174.6 4235.5 4256.9 4270.5 4303.8 4314.0 5827.5 6037.0 6051.4 6067.8 6084.9 5810.4 5852.7 5867.8 5868.4 5901.4 5809.6 5819.1 5835.7 5848.4 5898.6 5529.8 5791.6 5796.8 5815.8 5850.3 5853.2 5863.0 5877.8 5880.9 5883.9 5542.3 5739.4 5753.2 5790.5 5815.7 14:13:49 INFO - PROCESS | 28596 | 14:13:49 INFO - PROCESS | 28596 | [#5] yui.html Cycles:180 Average:21922.43 Median:3991.00 stddev:30663.66 (768.3%) stddev-sans-first:30708.65 14:13:49 INFO - PROCESS | 28596 | Values: 796.2 812.4 828.7 849.0 929.8 15388.8 16615.4 16627.3 16668.2 16676.1 15956.0 16064.8 16105.3 16134.6 16140.0 710.3 852.9 876.4 889.2 897.1 897.4 897.9 898.5 899.8 900.8 953.1 954.2 954.4 954.8 955.7 763.7 878.2 919.1 933.1 937.3 695.3 696.1 696.3 696.6 698.3 15917.0 15962.7 15992.3 15995.6 16053.1 14945.6 15041.6 15265.7 15326.8 15442.6 12033.9 12117.6 14176.0 14381.4 14827.3 4882.6 4899.8 4975.0 4980.4 5106.7 71328.0 71749.3 73371.6 77025.6 83939.2 12875.9 13636.2 13637.9 13807.3 13826.7 24203.8 24721.1 24751.1 25353.7 25649.0 9489.6 9533.3 9633.8 9888.1 10145.5 5041.4 5147.1 5150.6 5209.2 5229.2 3857.9 3986.9 3995.1 3996.4 3998.0 540.3 629.3 659.3 667.3 668.3 681.8 727.5 778.1 825.5 847.7 9731.4 10089.5 10108.6 10778.8 10802.4 800.6 813.3 817.0 822.6 826.6 5082.3 5341.8 5447.8 5481.7 5503.7 76245.6 76326.6 77299.6 110441.0 128028.4 113509.4 114291.0 114965.4 115406.0 115575.4 94466.5 96115.0 99242.0 100787.0 102885.5 10232.3 10585.2 10730.5 10846.3 11342.9 53032.7 55219.7 55386.0 55493.4 55971.0 77021.0 77572.0 78083.6 79529.6 81163.0 12464.3 13098.2 13367.9 13376.5 13690.8 16453.8 16584.6 16656.2 16854.6 17119.2 10021.3 11522.9 14148.0 15036.8 15517.8 14380.7 14428.6 14936.6 14994.9 15241.8 15736.7 15768.4 15793.4 15936.7 16132.5 12539.2 12874.4 12893.4 13697.2 14131.6 10689.3 11549.2 13220.9 13480.9 13929.0 14:13:49 INFO - PROCESS | 28596 | -------- Summary: end -------- 14:13:49 INFO - PROCESS | 28596 | 14:13:50 INFO - TEST-INFO | 28596: exit 0 14:13:50 INFO - TEST-OK | dromaeo_css | took 1113128ms 14:13:50 INFO - TEST-START | kraken 14:13:50 INFO - operating with platform_type : linux_ 14:13:50 INFO - Initialising browser for kraken test... 14:13:50 INFO - TEST-INFO | started process 28798 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0Vp6LU/profile http://localhost:58139/getInfo.html) 14:13:54 INFO - PROCESS | 28798 | __metrics Screen width/height:1600/1200 14:13:54 INFO - PROCESS | 28798 | colorDepth:24 14:13:54 INFO - PROCESS | 28798 | Browser inner width/height: 1024/697 14:13:54 INFO - PROCESS | 28798 | __metrics 14:13:55 INFO - PROCESS | 28798 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:13:55 INFO - PROCESS | 28798 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:13:56 INFO - TEST-INFO | 28798: exit 0 14:13:56 INFO - Browser initialized. 14:13:56 INFO - Running cycle 1/1 for kraken test... 14:13:56 INFO - TEST-INFO | started process 28889 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0Vp6LU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:14:07 INFO - PROCESS | 28889 | RSS: Main: 145694720 14:14:07 INFO - PROCESS | 28889 | 14:14:10 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58139/tests/kraken/driver.html?testName=audio-beat-detection) 14:14:11 INFO - PROCESS | 28889 | RSS: Main: 191643648 14:14:11 INFO - PROCESS | 28889 | 14:14:13 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58139/tests/kraken/driver.html?testName=audio-dft) 14:14:14 INFO - PROCESS | 28889 | RSS: Main: 181760000 14:14:14 INFO - PROCESS | 28889 | 14:14:17 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58139/tests/kraken/driver.html?testName=audio-fft) 14:14:17 INFO - PROCESS | 28889 | RSS: Main: 269578240 14:14:17 INFO - PROCESS | 28889 | 14:14:19 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58139/tests/kraken/driver.html?testName=audio-oscillator) 14:14:20 INFO - PROCESS | 28889 | RSS: Main: 175607808 14:14:20 INFO - PROCESS | 28889 | 14:14:22 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58139/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:14:23 INFO - PROCESS | 28889 | RSS: Main: 177070080 14:14:23 INFO - PROCESS | 28889 | 14:14:26 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58139/tests/kraken/driver.html?testName=imaging-darkroom) 14:14:26 INFO - PROCESS | 28889 | RSS: Main: 213135360 14:14:26 INFO - PROCESS | 28889 | 14:14:30 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58139/tests/kraken/driver.html?testName=imaging-desaturate) 14:14:30 INFO - PROCESS | 28889 | RSS: Main: 211550208 14:14:30 INFO - PROCESS | 28889 | 14:14:33 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58139/tests/kraken/driver.html?testName=json-parse-financial) 14:14:33 INFO - PROCESS | 28889 | RSS: Main: 211943424 14:14:33 INFO - PROCESS | 28889 | 14:14:35 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58139/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:14:36 INFO - PROCESS | 28889 | RSS: Main: 176041984 14:14:36 INFO - PROCESS | 28889 | 14:14:37 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58139/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:14:38 INFO - PROCESS | 28889 | RSS: Main: 181198848 14:14:38 INFO - PROCESS | 28889 | 14:14:40 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58139/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:14:41 INFO - PROCESS | 28889 | RSS: Main: 186265600 14:14:41 INFO - PROCESS | 28889 | 14:14:43 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58139/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:14:44 INFO - PROCESS | 28889 | RSS: Main: 180948992 14:14:44 INFO - PROCESS | 28889 | 14:14:46 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58139/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:14:47 INFO - PROCESS | 28889 | RSS: Main: 184909824 14:14:47 INFO - PROCESS | 28889 | 14:14:48 INFO - PROCESS | 28889 | Cycle 1(1): loaded http://localhost:58139/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58139/tests/kraken/driver.html?testName=ai-astar) 14:14:49 INFO - PROCESS | 28889 | RSS: Main: 235175936 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | __start_tp_report 14:14:49 INFO - PROCESS | 28889 | _x_x_mozilla_page_load 14:14:49 INFO - PROCESS | 28889 | _x_x_mozilla_page_load_details 14:14:49 INFO - PROCESS | 28889 | |i|pagename|runs| 14:14:49 INFO - PROCESS | 28889 | |0;ai-astar;104;110;109;111;113;109;110;113;108;110 14:14:49 INFO - PROCESS | 28889 | |1;audio-beat-detection;137;116;116;173;116;119;138;116;152;117 14:14:49 INFO - PROCESS | 28889 | |2;audio-dft;162;162;161;160;160;162;162;161;161;161 14:14:49 INFO - PROCESS | 28889 | |3;audio-fft;74;74;75;110;75;76;75;74;100;75 14:14:49 INFO - PROCESS | 28889 | |4;audio-oscillator;82;83;132;84;83;84;129;84;83;126 14:14:49 INFO - PROCESS | 28889 | |5;imaging-gaussian-blur;116;117;116;116;117;117;117;116;116;117 14:14:49 INFO - PROCESS | 28889 | |6;imaging-darkroom;145;146;146;146;147;146;146;146;146;146 14:14:49 INFO - PROCESS | 28889 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;94 14:14:49 INFO - PROCESS | 28889 | |8;json-parse-financial;53;51;51;50;51;50;51;51;51;51 14:14:49 INFO - PROCESS | 28889 | |9;json-stringify-tinderbox;59;60;58;60;59;59;58;59;58;58 14:14:49 INFO - PROCESS | 28889 | |10;stanford-crypto-aes;72;77;72;71;77;72;72;79;77;71 14:14:49 INFO - PROCESS | 28889 | |11;stanford-crypto-ccm;117;124;122;121;114;114;114;127;164;137 14:14:49 INFO - PROCESS | 28889 | |12;stanford-crypto-pbkdf2;149;164;151;149;149;149;149;153;151;149 14:14:49 INFO - PROCESS | 28889 | |13;stanford-crypto-sha256-iterative;66;61;65;61;60;61;60;62;60;61 14:14:49 INFO - PROCESS | 28889 | __end_tp_report 14:14:49 INFO - PROCESS | 28889 | __start_cc_report 14:14:49 INFO - PROCESS | 28889 | _x_x_mozilla_cycle_collect,1622 14:14:49 INFO - PROCESS | 28889 | __end_cc_report 14:14:49 INFO - PROCESS | 28889 | __startTimestamp1472850889208__endTimestamp 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | ------- Summary: start ------- 14:14:49 INFO - PROCESS | 28889 | Number of tests: 14 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.00 stddev:2.58 (2.3%) stddev-sans-first:1.73 14:14:49 INFO - PROCESS | 28889 | Values: 104.0 110.0 109.0 111.0 113.0 109.0 110.0 113.0 108.0 110.0 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:128.00 stddev:19.78 (15.5%) stddev-sans-first:20.81 14:14:49 INFO - PROCESS | 28889 | Values: 137.0 116.0 116.0 173.0 116.0 119.0 138.0 116.0 152.0 117.0 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:0.79 (0.5%) stddev-sans-first:0.78 14:14:49 INFO - PROCESS | 28889 | Values: 162.0 162.0 161.0 160.0 160.0 162.0 162.0 161.0 161.0 161.0 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#3] audio-fft Cycles:10 Average:80.80 Median:75.00 stddev:12.99 (17.3%) stddev-sans-first:13.54 14:14:49 INFO - PROCESS | 28889 | Values: 74.0 74.0 75.0 110.0 75.0 76.0 75.0 74.0 100.0 75.0 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:83.00 stddev:22.14 (26.7%) stddev-sans-first:22.80 14:14:49 INFO - PROCESS | 28889 | Values: 82.0 83.0 132.0 84.0 83.0 84.0 129.0 84.0 83.0 126.0 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 14:14:49 INFO - PROCESS | 28889 | Values: 116.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 116.0 117.0 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.33 14:14:49 INFO - PROCESS | 28889 | Values: 145.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 14:14:49 INFO - PROCESS | 28889 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:0.82 (1.6%) stddev-sans-first:0.44 14:14:49 INFO - PROCESS | 28889 | Values: 53.0 51.0 51.0 50.0 51.0 50.0 51.0 51.0 51.0 51.0 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:0.79 (1.3%) stddev-sans-first:0.83 14:14:49 INFO - PROCESS | 28889 | Values: 59.0 60.0 58.0 60.0 59.0 59.0 58.0 59.0 58.0 58.0 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:74.50 stddev:3.09 (4.1%) stddev-sans-first:3.19 14:14:49 INFO - PROCESS | 28889 | Values: 72.0 77.0 72.0 71.0 77.0 72.0 72.0 79.0 77.0 71.0 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#11] stanford-crypto-ccm Cycles:10 Average:125.40 Median:123.00 stddev:15.35 (12.5%) stddev-sans-first:15.98 14:14:49 INFO - PROCESS | 28889 | Values: 117.0 124.0 122.0 121.0 114.0 114.0 114.0 127.0 164.0 137.0 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.30 Median:150.00 stddev:4.67 (3.1%) stddev-sans-first:4.88 14:14:49 INFO - PROCESS | 28889 | Values: 149.0 164.0 151.0 149.0 149.0 149.0 149.0 153.0 151.0 149.0 14:14:49 INFO - PROCESS | 28889 | 14:14:49 INFO - PROCESS | 28889 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:61.00 stddev:2.11 (3.5%) stddev-sans-first:1.56 14:14:49 INFO - PROCESS | 28889 | Values: 66.0 61.0 65.0 61.0 60.0 61.0 60.0 62.0 60.0 61.0 14:14:49 INFO - PROCESS | 28889 | -------- Summary: end -------- 14:14:49 INFO - PROCESS | 28889 | 14:14:50 INFO - TEST-INFO | 28889: exit 0 14:14:50 INFO - TEST-OK | kraken | took 59973ms 14:14:50 INFO - SUITE-END | took 1173s 14:14:50 INFO - Completed test suite (00:19:33) 14:14:50 INFO - javascript benchmark 14:14:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3197.728193103586, 3205.759568032398, 3210.9518357224647, 3221.404040252552, 3246.1722260823503, 5522.171674248864, 5608.551315894729, 5628.043232650449, 5658.688772117532, 5673.2908386451745, 5578.191162281467, 5623.606347555673, 5643.492085712285, 5646.6894320812335, 5674.177244299574, 5054.444011158788, 5101.38783345998, 5107.46371630979, 5127.692338459686, 5182.3262975813095, 5030.0, 5077.923831142536, 5116.948830511703, 5157.020166168416, 5158.252053452247, 5106.412762532301, 5201.0378080055125, 5202.583793296527, 5208.270842082112, 5213.009528189632, 5131.923021154686, 5137.152369858969, 5198.584113270929, 5205.115130427836, 5227.5295223429885, 4795.400574928134, 4961.925571116436, 5099.36257967754, 5121.590272778206, 5185.403678576974, 5677.0, 5681.34664513578, 5692.800751973702, 5694.088945768657, 5697.287839020122, 6081.847953801146, 6101.617272147843, 6103.908441373383, 6109.14471973924, 6113.755449782004, 5823.941760582382, 5939.643621382731, 5987.880242395127, 6014.909776353358, 6045.72794224261, 5632.042552765999, 5647.661140331593, 5654.378018418012, 5657.1514272858985, 5660.943390566083, 6175.598586091979, 6182.938170618237, 6184.041473571604, 6184.443400093923, 6184.536159788034, 5718.7426565804635, 5812.7674893003805, 5819.330776960704, 5870.26621672291, 5964.015937370372, 6007.0, 6010.338862725097, 6037.09443583466, 6047.365026672136, 6050.30421501533, 5929.495992840571, 5993.61041532299, 5997.670128142907, 6002.759889604367, 6003.789867354709, 5818.389069147764, 5881.059030555176, 5889.9692553802915, 5907.61600495975, 5979.820605381846, 4525.818967241339, 4612.654050946126, 4615.538446155425, 4616.215243408628, 4616.469106052779, 4089.7750623715983, 4111.835526578963, 4126.442930204384, 4136.751794892316, 4151.46031015966, 5642.435756424325, 5679.659220446786, 5693.60144789869, 5694.743736531866, 5699.259096317535, 5883.470487656132, 6032.095185722089, 6040.6677632730625, 6131.540134489931, 6135.386461353829, 5760.6255593386295, 5843.1235314703135, 5847.064469684915, 5868.559858010666, 5875.29496460419, 5320.414754377015, 5330.147176451749, 5331.333583302087, 5332.33345831771, 5335.1730481775385, 6024.3975602439405, 6191.535634827496, 6392.7123279452535, 6399.744010239631, 6401.487880969511, 6086.269647642312, 6139.216166136355, 6150.138980542638, 6151.0, 6153.384661533811, 6298.433141017147, 6351.777687780906, 6354.52341074412, 6361.045843123568, 6363.140975968185, 5772.163036359789, 5845.736941837543, 6038.184845046038, 6042.305134909366, 6042.667653279112, 5934.7922822701, 6088.269407671108, 6096.237970253718, 6132.325444201045, 6135.355787642412, 5937.762489500372, 5941.792037278675, 5942.851428714143, 5947.464728174485, 5950.31571369298, 5692.7722891083895, 5818.73815678304, 5856.180134781049, 5865.208196893364, 5919.023361145363, 5845.09401042839, 5933.199018132497, 5933.821985340447, 5959.255093113361, 5959.67221802805, 5958.731857066356, 5964.224650795369, 5994.130851026664, 6003.429674180946, 6007.699615019144, 5597.944020559742, 5690.231818704422, 5805.796797112081, 5812.883742325214, 5818.709064546841], "value": 5569.248958727265, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [615.2770494668766, 635.7774778827411, 667.7362441835473, 669.7287598522597, 669.8928171492562, 35804.0, 47031.76484117579, 48213.0, 48401.75799121004, 52037.21944170831, 49503.0, 49950.0, 50098.248526272044, 50137.0, 50209.0, 8429.494230346168, 9316.95341523293, 9472.336936414455, 9486.62053517862, 9493.240540756757, 8586.87119693205, 8650.610722517502, 8653.524056176908, 10829.566817327297, 11082.445877706123, 11270.154738394613, 11330.206885518017, 11340.206185567014, 11350.545978160904, 11367.431628418588, 11219.326840389562, 11267.21129520934, 11276.94361528187, 11288.322700637988, 11292.322460652387, 11044.171687123498, 11169.0, 11182.0, 11183.664490065312, 11192.776144477146, 46158.76920615409, 46545.76727116343, 46562.30156547655, 46585.0, 46602.0, 3903.434002069684, 4049.068714195722, 4266.2320782259185, 4363.607275345203, 4665.300204969248, 35749.64250357489, 36103.81948090269, 36820.07949801276, 36854.26291474156, 37093.62906370956, 4486.147631949952, 4530.139273538017, 4553.658475614342, 4602.677812553123, 4797.904041919177, 269847.0, 270450.0, 270807.0, 271182.0, 272561.999999998, 38446.80776596099, 64890.0, 65130.674346627966, 65196.0, 65358.67320663366, 39232.41151382731, 41395.79302103531, 41435.58564414378, 41682.583174168474, 41717.37423938643, 15777.974431661905, 15914.68170636604, 15928.601784955468, 15935.0, 15962.60093497672, 13133.015023873057, 13162.934185329012, 13216.603501894959, 13221.405036773176, 13241.668958276121, 8652.394332396798, 8858.29133669305, 8880.689175878942, 8993.775155621162, 9088.863667045, 3529.36471435139, 3538.876139335111, 3555.1289933966345, 3604.333198358312, 3624.456331550236, 1095.6548687163677, 1111.2332490581634, 1149.8792626774236, 1175.5650409348425, 1189.7679952409196, 34863.30273394518, 35647.0, 35733.46399804005, 35734.0, 36047.639523604426, 1208.5407545132794, 1224.8958838498827, 1254.9184303020274, 1256.0830593666674, 1261.142423152247, 18441.81558184428, 18550.0, 18617.627647446974, 18680.995801855563, 18859.905700471685, 634050.0, 635483.0, 641993.0, 642515.0, 643350.0, 45069.54930450719, 45213.32180017302, 45354.092918141454, 45356.0, 45557.772211139396, 1472.1902953375816, 1477.637978695205, 1478.667299857545, 1494.3499577683742, 1496.341609691754, 34196.82901585476, 35420.291594168484, 35540.11149721174, 35639.465408019416, 35662.465063024596, 57307.42692573021, 57914.13128803155, 57936.130958036505, 57942.4205757937, 57985.71007144937, 372284.0, 373133.0, 373415.0, 373846.0, 373883.0, 31326.530102048942, 31619.84190079035, 41241.793791030854, 47306.76346618245, 47955.0, 44177.55822441734, 44286.81740783294, 52392.73803630957, 52783.47216527785, 58454.707726462795, 45767.084658307314, 46994.29508557306, 47434.28848567343, 48507.0, 48537.0, 46875.765621171675, 47145.0, 47500.7624961873, 47872.281915771986, 48077.0, 46566.76716616531, 47391.52608474009, 47439.762801185774, 47630.0, 47658.761706192636, 38671.22657546777, 40069.0, 40432.79783601063, 40912.1817563653, 41335.586644133175, 30546.389072217986, 30744.625898994927, 30997.0, 31009.379812404077, 31035.379292414476], "value": 20684.299861787786, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.33188910740864, 414.34326592351124, 426.7503510446389, 435.74508912286313, 439.10203633569347, 15897.761533577006, 21391.57216855662, 21501.24745633903, 21526.461838454026, 22214.222502212386, 20343.59312813743, 20501.48746281344, 20501.48746281344, 20566.280180193695, 20815.583688326224, 5551.306086739158, 5762.366139724627, 5970.850728731773, 6015.3383127855905, 6021.759129634831, 5809.709514524278, 5902.232709747726, 5963.701814909259, 5989.850253743648, 5990.071538911476, 5898.675572843514, 5903.114532820068, 5917.585768996172, 5957.404259574051, 5960.433758792908, 5849.064149736065, 5898.521655151937, 5915.112733090028, 5950.851228719273, 5962.463378295953, 5321.840344789663, 5341.225522299244, 5348.277982472356, 5349.3045904032615, 5351.892962140774, 20505.79494205054, 23838.284851454486, 24113.758862411327, 24260.636090458658, 24825.62761558578, 2468.3458883395915, 2752.477029364434, 2759.958600620972, 2794.3712664650293, 3022.909312720622, 15426.460073897471, 15544.533663990098, 15633.296501657453, 15708.293126809322, 16069.598260043476, 2114.7250857388594, 2116.9788302116935, 2166.9566608667737, 2195.3633446300555, 2314.837961342707, 86032.56983715041, 88177.55911220404, 88410.0, 89853.0, 90516.54741726315, 11743.295402275891, 11791.0, 11947.641570752892, 11993.64019079429, 12150.088743344284, 16676.58308542296, 16703.331866725195, 16710.08094554811, 16713.91643041777, 16723.49829505117, 8548.700795472128, 9051.637934482678, 9064.410813297114, 9077.546122693839, 9095.181433671001, 8896.42173258749, 8904.554772261361, 8921.330900182513, 9062.456252624863, 9155.13026262504, 5022.171341728574, 5077.746112694351, 5139.383274007143, 5149.845504634867, 5153.871153221125, 2067.0, 2091.6444204485274, 2106.7577228618907, 2115.027087539749, 2147.957040859174, 989.8762654668167, 1012.2155329619603, 1019.887812340642, 1103.2608152537819, 1107.7618312062946, 20490.487737806674, 20688.17247310091, 20771.792282076985, 20849.68725469119, 21143.154273828874, 1027.9743006424899, 1043.5773511727762, 1054.947252637365, 1055.0451841083832, 1063.6170978447753, 10810.837837432446, 11435.885641143648, 11738.354390508433, 11823.881761182278, 11833.290002599872, 497358.0, 504839.0, 512292.0, 512310.0, 512422.0, 94625.5268723652, 96626.0, 98245.5087724571, 98860.0, 99768.50115749375, 971.5579411367952, 980.5097451274505, 993.8707967964118, 996.0686757881259, 1006.4213077480478, 17731.202095905457, 18364.540886477946, 18374.173162208, 18442.815571844647, 18564.44306670802, 44782.3282650767, 45174.177053371735, 45286.77356613196, 45911.08177836358, 47122.29316560323, 60905.69547152236, 61598.69200653968, 62341.688291560065, 62703.6864815673, 63840.68079659572, 7802.843943121219, 8078.111407745026, 8135.511869287861, 8513.233808957224, 8724.262576638179, 12664.74670506603, 13360.866391335961, 13737.381817818423, 13891.72216555643, 14066.0, 12000.279983201035, 12058.216215945935, 12354.135210535536, 12368.505259789868, 12472.87527124717, 11731.824022639497, 12039.578614748441, 12148.944292137812, 12168.209066410654, 12614.306213158365, 12228.266304021787, 12367.010639149026, 12648.0, 12711.364431778558, 13040.282784446947, 8288.96387951506, 8332.916670833214, 8398.328133749408, 8471.279941205066, 8729.083446238054, 11337.489812958602, 12343.444544995677, 12499.500019999097, 12551.93724031374, 12665.620031399072], "value": 9848.448054980174, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.1674112273756, 1554.6035760880977, 1603.711331960247, 1815.927362905484, 1938.8545859060573, 9136.543172841357, 9584.56869440875, 9604.807903841927, 9883.061109194632, 9909.801803963916, 9664.178544823699, 9776.853347199798, 9786.168175705074, 9793.216542676586, 9804.362716423446, 2515.71069327027, 2561.5773397389503, 2561.7822485088695, 2567.6148577713398, 2568.961465578018, 2453.0800949643885, 2578.329634295078, 2584.961225581618, 2589.5468293048734, 2590.080521414899, 2489.5643262429094, 2493.9750602494014, 2552.553303171947, 2552.8340657857275, 2555.3611597100726, 2419.5402873454077, 2420.7579242075826, 2443.4135807406183, 2522.7351128131468, 2525.722170561237, 2437.585610446228, 2465.3713303107593, 2470.5923522618746, 2482.304954612693, 2482.515909397668, 9582.13760761528, 9799.363041402357, 9864.457454839981, 9864.95067524665, 9872.40765554069, 5787.971060144672, 5951.4048595140575, 5956.821295361146, 5964.433378829004, 5970.910436343458, 3567.8216089195694, 3574.0528759878653, 3575.910602234939, 3595.209054008114, 3691.984704206338, 1169.1582060916128, 1179.257068047125, 1182.0779791762352, 1205.2406983600363, 1212.9514819407214, 13990.790138147935, 14057.64855878601, 14066.36701348452, 14080.577582672537, 14087.154770713687, 4884.731339776346, 4889.902201955941, 4975.701457912464, 5055.545000949932, 5062.038212739568, 10803.081738052197, 10820.78358432827, 10826.350418974887, 10827.566897324177, 10831.512581933832, 5730.283714535683, 5779.075347944308, 5793.420657934173, 5795.884082318415, 5806.65160090396, 4579.175748365326, 4747.477777444478, 5152.948470515322, 5168.405633352138, 5389.51494365509, 4195.727277726938, 4204.51648060477, 4302.075053863435, 4332.588404101605, 4342.001339691826, 1298.0718786067819, 1320.7226482438796, 1324.9006324525699, 1327.2567362277155, 1330.481112366178, 950.7575568230195, 994.9502524873727, 1020.6172685243034, 1027.4759872464947, 1036.3781730961364, 6412.743490260431, 6440.645764482999, 6537.477001839841, 6750.0, 6856.211535673362, 485.0347807862399, 485.61636307316826, 493.00167161497535, 493.80494704591484, 503.23257033024197, 4374.606285434326, 4413.073254616503, 4463.776811159429, 4569.840055598038, 4588.632909367243, 11713.941430292794, 12229.449674764657, 12251.632451026484, 12258.0, 12258.570950016885, 11685.357305348287, 12120.454579543766, 12146.0, 12148.149629526026, 12149.0, 9148.542572871329, 9358.672446464341, 9366.203872670763, 9369.25045996305, 9372.765680857896, 6562.671866406565, 6761.430656350555, 6900.171979362509, 6938.687759050956, 7028.0, 11027.22809403334, 11320.37737924422, 11489.712757181138, 11506.367149806842, 11609.767804644029, 10092.899071009197, 10653.733656658645, 11335.206535542433, 11687.240329378563, 11751.88248117542, 8571.22858942698, 8933.374663773473, 8970.192682658591, 9046.728598142066, 9048.683296084606, 9494.430334179971, 9576.138147566753, 9652.806943861224, 9660.95169524148, 9661.71014869555, 8530.061693213618, 8617.310615150898, 8625.309975201842, 8731.869021964549, 8876.0, 8472.957635211784, 8491.915080849112, 8496.532690701824, 8524.318054555744, 8652.26455751243, 7757.883631745415, 7800.765977020698, 7938.880916786368, 8026.277635012877, 8202.09776924526, 5868.09044598088, 5879.559033072622, 5923.111533270044, 6030.6080104793045, 6047.516198704181, 5829.300483941955, 5857.707114644336, 5869.4717475425705, 5947.31605865331, 5961.2846458425265], "value": 5002.575526059859, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.3888359278688, 346.2020043799042, 350.64584769382924, 352.86238367036844, 357.3585414181544, 5521.447855214482, 5591.2731344925105, 5596.0, 5623.834823592915, 5632.943670563298, 5445.774700692342, 5501.587380946425, 5545.197810711515, 5565.693886836222, 5571.916421253684, 3913.882583522499, 4130.215259100776, 4312.460942382202, 4350.499692535352, 4362.454693163355, 4326.7668714416395, 4342.457192850894, 4343.04453020335, 4351.71713838601, 4402.911941761163, 4364.869053928387, 4401.339799030139, 4401.581849724271, 4403.6917415780745, 4416.116776644668, 4160.230357383879, 4287.742735435868, 4350.978245108786, 4372.978135109304, 4394.4946331172005, 3875.341191997339, 3883.2815929053218, 3887.1642596841816, 3890.4164375343644, 3917.157811070605, 5394.109971854639, 5432.402435732066, 5433.945660543423, 5444.863878403032, 5469.234307196995, 4813.374261346037, 4959.082569724576, 4960.355153829979, 4978.581104385269, 5051.52010559, 5705.885882282331, 5744.626599271076, 5760.711964401763, 5779.566532510036, 5785.0, 4258.850940217078, 4287.164003019412, 4317.676174286941, 4320.1143765527995, 4331.891702707426, 5693.658380497183, 5702.287214098238, 5879.089295978824, 6020.5785595008365, 6033.969830150865, 4219.38818871262, 4227.619514243733, 4232.0, 4290.978545107317, 4297.763623000765, 4346.087321662423, 4347.217500849877, 4347.500037495665, 4371.213181627338, 4374.168907907488, 4829.468758436569, 4910.288008238786, 4990.875228119254, 5003.574696150868, 5017.54842064216, 5764.308283006066, 5771.163181338682, 5774.527495488624, 5806.506446951972, 5816.592838501264, 1464.5386703188462, 1476.2249818845191, 1485.6434455730546, 1489.9942538786277, 1501.9474318398804, 971.6696323249987, 996.14829320931, 996.3772642098688, 1001.4241810958728, 1024.6516184497307, 4919.0, 4955.860152165023, 4986.276989836453, 4995.6056248002615, 5001.224810154432, 5211.664534697797, 5221.738913054332, 5228.686278823283, 5236.790528378899, 5239.607029472729, 4964.230544265644, 5000.424951130593, 5030.220315851049, 5292.603054770908, 5760.654360738369, 3159.273367125574, 3174.349258402022, 3193.121891479815, 3202.151429871086, 3209.7111259986714, 6112.786052488231, 6209.720562574695, 6223.844403889849, 6278.183836101278, 6285.654289014148, 6048.45764330093, 6096.451319381188, 6098.512119030467, 6115.694215289218, 6127.4068742127365, 6165.753369865155, 6179.208029671435, 6189.96905015472, 6198.969005154945, 6211.391215689511, 5778.739956702043, 5951.256092988377, 5968.313643931004, 5983.34183239852, 6057.636541807506, 5845.941540584539, 5859.59756499833, 5911.556633252438, 5926.140709596998, 5929.407059294209, 5856.94143058564, 5986.341502434641, 6126.846828829315, 6128.203333566607, 6174.567780255339, 4235.470566179228, 4256.914861702687, 4270.530241673351, 4303.763293018788, 4313.956860431355, 5827.475527202572, 6037.0, 6051.394860513913, 6067.787627433018, 6084.939150608614, 5810.373095533258, 5852.707364631837, 5867.794627188028, 5868.38381969887, 5901.380355062656, 5809.622374545641, 5819.098039803837, 5835.737391817293, 5848.397463128867, 5898.616589921641, 5529.834104976857, 5791.623544469596, 5796.82609521715, 5815.82552523425, 5850.268716410449, 5853.151293062568, 5862.954561352653, 5877.823665290048, 5880.911786323294, 5883.852903677443, 5542.307211598551, 5739.364281179844, 5753.194552762533, 5790.4788569027305, 5815.709214539341], "value": 4415.380718595471, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.2197046894045, 812.3582369927753, 828.6643909216765, 848.9787755306121, 929.7907970706592, 15388.846111538882, 16615.41846035386, 16627.251773670185, 16668.166591670397, 16676.082815445145, 15956.0, 16064.83935160651, 16105.27526261318, 16134.596635084128, 16140.0, 710.346481237263, 852.86780549015, 876.369014309696, 889.1508609278123, 897.0939351255214, 897.3763234551989, 897.8653202019684, 898.4833720610642, 899.8380291547519, 900.8108297257584, 953.1278879824952, 954.2366107114316, 954.4273435938451, 954.8472244440891, 955.6559638530124, 763.6525380951654, 878.1876763993313, 919.0671468459519, 933.09955892564, 937.2829785214317, 695.2699665351412, 696.0533674203131, 696.3419568507762, 696.6098984568607, 698.2877464985686, 15917.0, 15962.680746385007, 15992.280347384396, 15995.600109997344, 16053.117078560674, 14945.626359340995, 15041.623959400993, 15265.694686106326, 15326.846731532654, 15442.613934651723, 12033.879661203451, 12117.575884843987, 14176.0, 14381.424743010268, 14827.258637068104, 4882.5605695487575, 4899.755012249373, 4975.0, 4980.427250866161, 5106.66806657566, 71328.0, 71749.28250717478, 73371.63314183448, 77025.61487192585, 83939.16060839375, 12875.871241287467, 13636.181829090287, 13637.931810341082, 13807.309634518233, 13826.723465530633, 24203.757962420503, 24721.134760283305, 24751.133710320053, 25353.74646253514, 25649.0, 9489.572969216402, 9533.332666713402, 9633.807323853624, 9888.110070093726, 10145.543450544743, 5041.395032596039, 5147.0992576298995, 5150.6137039721425, 5209.218617207373, 5229.189475631283, 3857.9035524112123, 3986.9202615948097, 3995.08113133975, 3996.360582306817, 3997.960020399817, 540.3218960204917, 629.288903538998, 659.2517492645785, 667.3326673326674, 668.3249917583275, 681.7511608263047, 727.5380133615087, 778.1090651204339, 825.4964471672275, 847.6863560482661, 9731.367461115147, 10089.495525223709, 10108.646197383203, 10778.838317425245, 10802.405867677353, 800.6157044618616, 813.2680587471323, 817.0318172965056, 822.6462621072997, 826.5701835045743, 5082.288479612857, 5341.839744807662, 5447.809326673548, 5481.698506582176, 5503.697296648723, 76245.6187719069, 76326.61836690782, 77299.61350193214, 110441.0, 128028.35985820198, 113509.43245283885, 114291.0, 114965.4251728736, 115406.0, 115575.42212288885, 94466.52766736122, 96115.0, 99242.0, 100787.0, 102885.48557257166, 10232.283740138118, 10585.206109541661, 10730.463476826284, 10846.29499082557, 11342.886571134182, 53032.73483632557, 55219.72390138024, 55386.0, 55493.445065548825, 55971.0, 77021.0, 77572.0, 78083.60958195172, 79529.60235198787, 81163.0, 12464.252144870974, 13098.2141071536, 13367.866321337051, 13376.531821386205, 13690.794638080635, 16453.83546164523, 16584.58538536498, 16656.167191640612, 16854.57863553372, 17119.22963466672, 10021.348612340174, 11522.942385288356, 14148.0, 15036.849631503545, 15517.76723349128, 14380.712385752435, 14428.567142985727, 14936.551903442914, 14994.850051499345, 15241.771373429186, 15736.685266294839, 15768.448104316294, 15793.36826526972, 15936.681266374839, 16132.516024519284, 12539.24764514132, 12874.420651070537, 12893.355332233163, 13697.246651434267, 14131.64670883195, 10689.30519516229, 11549.249298795552, 13220.933895330461, 13480.93259533696, 13929.0], "value": 8262.175042639346, "unit": "score"}], "name": "dromaeo_css", "value": 7691.609846075404, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 110.0, 109.0, 111.0, 113.0, 109.0, 110.0, 113.0, 108.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 116.0, 116.0, 173.0, 116.0, 119.0, 138.0, 116.0, 152.0, 117.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 160.0, 160.0, 162.0, 162.0, 161.0, 161.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 110.0, 75.0, 76.0, 75.0, 74.0, 100.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 132.0, 84.0, 83.0, 84.0, 129.0, 84.0, 83.0, 126.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 51.0, 50.0, 51.0, 50.0, 51.0, 51.0, 51.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 58.0, 60.0, 59.0, 59.0, 58.0, 59.0, 58.0, 58.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 77.0, 72.0, 71.0, 77.0, 72.0, 72.0, 79.0, 77.0, 71.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 124.0, 122.0, 121.0, 114.0, 114.0, 114.0, 127.0, 164.0, 137.0], "value": 125.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 164.0, 151.0, 149.0, 149.0, 149.0, 149.0, 153.0, 151.0, 149.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 61.0, 65.0, 61.0, 60.0, 61.0, 60.0, 62.0, 60.0, 61.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1458.4, "alertThreshold": 2.0}]} 14:14:50 INFO - Return code: 0 14:14:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:14:50 INFO - # TBPL SUCCESS # 14:14:50 INFO - Running post-action listener: _resource_record_post_action 14:14:50 INFO - [mozharness: 2016-09-02 21:14:50.506634Z] Finished run-tests step (success) 14:14:50 INFO - Running post-run listener: _resource_record_post_run 14:14:50 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328544256; Read time: 16; Write time: 245112 14:14:50 INFO - TinderboxPrint: CPU usage
12.9% 14:14:50 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 14:14:50 INFO - TinderboxPrint: I/O write bytes / time
328,544,256 / 245,112 14:14:50 INFO - TinderboxPrint: CPU idle
8,266.9 (87.0%) 14:14:50 INFO - TinderboxPrint: CPU user
1,185.7 (12.5%) 14:14:50 INFO - TinderboxPrint: Swap in / out
0 / 0 14:14:50 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78618624; Read time: 0; Write time: 53452 14:14:50 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 248836096; Read time: 0; Write time: 191488 14:14:51 INFO - Running post-run listener: _upload_blobber_files 14:14:51 INFO - Blob upload gear active. 14:14:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:14:51 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:14:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:14:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:14:51 INFO - (blobuploader) - INFO - Open directory for files ... 14:14:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:14:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:14:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:52 INFO - (blobuploader) - INFO - Done attempting. 14:14:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:14:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:14:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:53 INFO - (blobuploader) - INFO - Done attempting. 14:14:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:14:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:14:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:57 INFO - (blobuploader) - INFO - Done attempting. 14:14:57 INFO - (blobuploader) - INFO - Iteration through files over. 14:14:57 INFO - Return code: 0 14:14:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:14:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:14:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7bedb789796cb4c958747e5e231ba042be09bc3cebeedab3b3f77bdb4fd80b1eae60bba1a4cdabdc7ca89aa6c7995e0968e91b8bddb6fa5f1555fcd5ef364aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e950c98da04eb230c4d6eb1644d0193a024ace918e32ef4797696271ceb8abebd6caa4ffded8bdde8cf1bb21ed8501adb457b68febb236fdf5b6d3755b313fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38c0cb9d23e1b50e65a555b36c60c8077801d51a71f37e2b10baa6e5572341a8feffd2ee4f03da6e16c0ca023f4e5a6e6a56c0ffd4467daee4dfa145ba3587ea"} 14:14:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:14:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:14:57 INFO - Contents: 14:14:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7bedb789796cb4c958747e5e231ba042be09bc3cebeedab3b3f77bdb4fd80b1eae60bba1a4cdabdc7ca89aa6c7995e0968e91b8bddb6fa5f1555fcd5ef364aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e950c98da04eb230c4d6eb1644d0193a024ace918e32ef4797696271ceb8abebd6caa4ffded8bdde8cf1bb21ed8501adb457b68febb236fdf5b6d3755b313fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38c0cb9d23e1b50e65a555b36c60c8077801d51a71f37e2b10baa6e5572341a8feffd2ee4f03da6e16c0ca023f4e5a6e6a56c0ffd4467daee4dfa145ba3587ea"} 14:14:57 INFO - Running post-run listener: copy_logs_to_upload_dir 14:14:57 INFO - Copying logs to upload dir... 14:14:57 INFO - mkdir: /builds/slave/test/build/upload/logs 14:14:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.245170 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-09-02 14:14:57.233271) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 14:14:57.236664) ========= 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=652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7bedb789796cb4c958747e5e231ba042be09bc3cebeedab3b3f77bdb4fd80b1eae60bba1a4cdabdc7ca89aa6c7995e0968e91b8bddb6fa5f1555fcd5ef364aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e950c98da04eb230c4d6eb1644d0193a024ace918e32ef4797696271ceb8abebd6caa4ffded8bdde8cf1bb21ed8501adb457b68febb236fdf5b6d3755b313fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38c0cb9d23e1b50e65a555b36c60c8077801d51a71f37e2b10baa6e5572341a8feffd2ee4f03da6e16c0ca023f4e5a6e6a56c0ffd4467daee4dfa145ba3587ea"} build_url:https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011451 build_url: 'https://queue.taskcluster.net/v1/task/SHVR_C8YQjGQ6C2Q7nLjCQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7bedb789796cb4c958747e5e231ba042be09bc3cebeedab3b3f77bdb4fd80b1eae60bba1a4cdabdc7ca89aa6c7995e0968e91b8bddb6fa5f1555fcd5ef364aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e950c98da04eb230c4d6eb1644d0193a024ace918e32ef4797696271ceb8abebd6caa4ffded8bdde8cf1bb21ed8501adb457b68febb236fdf5b6d3755b313fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38c0cb9d23e1b50e65a555b36c60c8077801d51a71f37e2b10baa6e5572341a8feffd2ee4f03da6e16c0ca023f4e5a6e6a56c0ffd4467daee4dfa145ba3587ea"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 14:14:57.263096) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 14:14:57.263503) ========= 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=652eb03db935cfc1c15ebda0000001ad-1472849216.763221-1643375790 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004466 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 14:14:57.345256) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 14:14:57.345616) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 14:14:57.345950) ========= ========= Total master_lag: 0.18 =========