builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1472850320.06 results: success (0) buildid: 20160902110447 builduid: 0684f3cd04564a4da15011e6a010e5df revision: 3cb50e5df49728e19c6f5bcb4c5b3fe34a9db53c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.057249) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.057715) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.058003) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.072765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.073178) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-02 14:05:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-09-02 14:05:20 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089266 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.176429) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.176741) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016722 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.225903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.226262) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3cb50e5df49728e19c6f5bcb4c5b3fe34a9db53c --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 3cb50e5df49728e19c6f5bcb4c5b3fe34a9db53c --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=d6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-02 14:05:21,078 truncating revision to first 12 chars 2016-09-02 14:05:21,078 Setting DEBUG logging. 2016-09-02 14:05:21,078 attempt 1/10 2016-09-02 14:05:21,078 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3cb50e5df497?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-02 14:05:21,380 unpacking tar archive at: mozilla-aurora-3cb50e5df497/testing/mozharness/ program finished with exit code 0 elapsedTime=0.625826 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.865763) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.866133) ========= script_repo_revision: 3cb50e5df49728e19c6f5bcb4c5b3fe34a9db53c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.866542) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.866808) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 14:05:20.875031) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-02 14:05:20.875316) ========= /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=d6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:05:21 INFO - MultiFileLogger online at 20160902 14:05:21 in /builds/slave/test 14:05:21 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 14:05:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:05:21 INFO - {'append_to_log': False, 14:05:21 INFO - 'base_work_dir': '/builds/slave/test', 14:05:21 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:05:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:05:21 INFO - 'branch': 'Mozilla-Aurora', 14:05:21 INFO - 'buildbot_json_path': 'buildprops.json', 14:05:21 INFO - 'config_files': ('talos/linux_config.py',), 14:05:21 INFO - 'default_actions': ('clobber', 14:05:21 INFO - 'read-buildbot-config', 14:05:21 INFO - 'download-and-extract', 14:05:21 INFO - 'populate-webroot', 14:05:21 INFO - 'create-virtualenv', 14:05:21 INFO - 'install', 14:05:21 INFO - 'run-tests'), 14:05:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:05:21 INFO - 'download_minidump_stackwalk': True, 14:05:21 INFO - 'download_symbols': 'ondemand', 14:05:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:05:21 INFO - 'tooltool.py': '/tools/tooltool.py', 14:05:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:05:21 INFO - '/tools/misc-python/virtualenv.py')}, 14:05:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:05:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:05:21 INFO - 'installer_path': 'installer.exe', 14:05:21 INFO - 'log_level': 'info', 14:05:21 INFO - 'log_name': 'talos', 14:05:21 INFO - 'log_to_console': True, 14:05:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:05:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:05:21 INFO - 'opt_config_files': (), 14:05:21 INFO - 'pip_index': False, 14:05:21 INFO - 'sps_profile': False, 14:05:21 INFO - 'sps_profile_interval': 0, 14:05:21 INFO - 'suite': 'dromaeojs', 14:05:21 INFO - 'system_bits': '32', 14:05:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:05:21 INFO - 'title': 'talos-linux64-ix-034', 14:05:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:05:21 INFO - 'use_talos_json': True, 14:05:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:05:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:05:21 INFO - 'work_dir': 'build'} 14:05:21 INFO - [mozharness: 2016-09-02 21:05:21.814311Z] Running clobber step. 14:05:21 INFO - Running pre-action listener: _resource_record_pre_action 14:05:21 INFO - Running main action method: clobber 14:05:21 INFO - rmtree: /builds/slave/test/build 14:05:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:05:22 INFO - Running post-action listener: _resource_record_post_action 14:05:22 INFO - [mozharness: 2016-09-02 21:05:22.312559Z] Finished clobber step (success) 14:05:22 INFO - [mozharness: 2016-09-02 21:05:22.312659Z] Running read-buildbot-config step. 14:05:22 INFO - Running pre-action listener: _resource_record_pre_action 14:05:22 INFO - Running main action method: read_buildbot_config 14:05:22 INFO - Using buildbot properties: 14:05:22 INFO - { 14:05:22 INFO - "project": "", 14:05:22 INFO - "product": "firefox", 14:05:22 INFO - "script_repo_revision": "production", 14:05:22 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 14:05:22 INFO - "repository": "", 14:05:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 14:05:22 INFO - "buildid": "20160902110447", 14:05:22 INFO - "pgo_build": "True", 14:05:22 INFO - "basedir": "/builds/slave/test", 14:05:22 INFO - "buildnumber": 504, 14:05:22 INFO - "slavename": "talos-linux64-ix-034", 14:05:22 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:05:22 INFO - "platform": "ubuntu64_hw", 14:05:22 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 14:05:22 INFO - "branch": "mozilla-aurora", 14:05:22 INFO - "repo_path": "releases/mozilla-aurora", 14:05:22 INFO - "revision": "3cb50e5df49728e19c6f5bcb4c5b3fe34a9db53c", 14:05:22 INFO - "stage_platform": "linux64-pgo", 14:05:22 INFO - "builduid": "0684f3cd04564a4da15011e6a010e5df", 14:05:22 INFO - "slavebuilddir": "test" 14:05:22 INFO - } 14:05:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 14:05:22 INFO - Running post-action listener: _resource_record_post_action 14:05:22 INFO - [mozharness: 2016-09-02 21:05:22.315090Z] Finished read-buildbot-config step (success) 14:05:22 INFO - [mozharness: 2016-09-02 21:05:22.315178Z] Running download-and-extract step. 14:05:22 INFO - Running pre-action listener: _resource_record_pre_action 14:05:22 INFO - Running main action method: download_and_extract 14:05:22 INFO - mkdir: /builds/slave/test/build/tests 14:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:22 INFO - https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:05:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:05:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:05:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/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 14:05:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/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 14:05:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:05:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:05:22 INFO - retry: Failed, sleeping 30 seconds before retrying 14:05:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/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 14:05:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:05:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:05:52 INFO - retry: Failed, sleeping 60 seconds before retrying 14:06:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/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 14:06:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:06:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:06:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/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! 14:06:52 INFO - Caught exception: HTTP Error 404: Not Found 14:06:52 INFO - Caught exception: HTTP Error 404: Not Found 14:06:52 INFO - Caught exception: HTTP Error 404: Not Found 14:06:52 INFO - trying https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:06:52 INFO - Downloading https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/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 14:06:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/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 14:06:52 INFO - Downloaded 1361 bytes. 14:06:52 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:06:52 INFO - Using the following test package requirements: 14:06:52 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:06:52 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:06:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 14:06:52 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:06:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 14:06:52 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:06:52 INFO - u'jsshell-linux-x86_64.zip'], 14:06:52 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:06:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 14:06:52 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:06:52 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:06:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 14:06:52 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:06:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 14:06:52 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:06:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 14:06:52 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:06:52 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 14:06:52 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:06:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:06:52 INFO - https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:06:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:06:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:06:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:06:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:06:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:06:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:06:53 INFO - retry: Failed, sleeping 30 seconds before retrying 14:07:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 14:07:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:07:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:07:23 INFO - retry: Failed, sleeping 60 seconds before retrying 14:08:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:08:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:08:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:08:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 14:08:23 INFO - Caught exception: HTTP Error 404: Not Found 14:08:23 INFO - Caught exception: HTTP Error 404: Not Found 14:08:23 INFO - Caught exception: HTTP Error 404: Not Found 14:08:23 INFO - trying https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:08:23 INFO - Downloading https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:08:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:08:23 INFO - Downloaded 21769878 bytes. 14:08:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:08:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:08:25 INFO - Return code: 0 14:08:25 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:08:25 INFO - https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:08:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:08:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:08:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:08:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:08:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:08:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:08:25 INFO - retry: Failed, sleeping 30 seconds before retrying 14:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 14:08:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:08:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:08:55 INFO - retry: Failed, sleeping 60 seconds before retrying 14:09:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:09:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:09:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:09:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 14:09:55 INFO - Caught exception: HTTP Error 404: Not Found 14:09:55 INFO - Caught exception: HTTP Error 404: Not Found 14:09:55 INFO - Caught exception: HTTP Error 404: Not Found 14:09:55 INFO - trying https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:09:55 INFO - Downloading https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:09:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:09:56 INFO - Downloaded 21769878 bytes. 14:09:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:09:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:09:57 INFO - Return code: 0 14:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:57 INFO - https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:09:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:09:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:09:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:09:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:09:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:09:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:09:57 INFO - retry: Failed, sleeping 30 seconds before retrying 14:10:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 14:10:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:10:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:10:27 INFO - retry: Failed, sleeping 60 seconds before retrying 14:11:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 14:11:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:11:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:11:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 14:11:27 INFO - Caught exception: HTTP Error 404: Not Found 14:11:27 INFO - Caught exception: HTTP Error 404: Not Found 14:11:27 INFO - Caught exception: HTTP Error 404: Not Found 14:11:27 INFO - trying https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:11:27 INFO - Downloading https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:11:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:11:28 INFO - Downloaded 14413740 bytes. 14:11:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:11:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:11:28 INFO - Return code: 0 14:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:11:28 INFO - https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:11:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:11:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:11:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:11:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:11:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:11:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:11:28 INFO - retry: Failed, sleeping 30 seconds before retrying 14:11:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 14:11:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:11:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:11:58 INFO - retry: Failed, sleeping 60 seconds before retrying 14:12:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 14:12:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:12:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:12:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 14:12:58 INFO - Caught exception: HTTP Error 404: Not Found 14:12:58 INFO - Caught exception: HTTP Error 404: Not Found 14:12:58 INFO - Caught exception: HTTP Error 404: Not Found 14:12:58 INFO - trying https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:12:58 INFO - Downloading https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:12:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:13:00 INFO - Downloaded 63876320 bytes. 14:13:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:13:00 INFO - mkdir: /builds/slave/test/properties 14:13:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:13:00 INFO - Writing to file /builds/slave/test/properties/build_url 14:13:00 INFO - Contents: 14:13:00 INFO - build_url:https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:13:01 INFO - Running post-action listener: _resource_record_post_action 14:13:01 INFO - Running post-action listener: set_extra_try_arguments 14:13:01 INFO - [mozharness: 2016-09-02 21:13:01.616541Z] Finished download-and-extract step (success) 14:13:01 INFO - [mozharness: 2016-09-02 21:13:01.616771Z] Running populate-webroot step. 14:13:01 INFO - Running pre-action listener: _resource_record_pre_action 14:13:01 INFO - Running main action method: populate_webroot 14:13:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:13:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:13:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:13:01 INFO - '--tppagecycles', 14:13:01 INFO - '7'], 14:13:01 INFO - 'tests': ['tsvgm']}}, 14:13:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:13:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:13:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:13:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:13:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:13:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:13:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:13:01 INFO - 'talos_options': ['--disable-e10s'], 14:13:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:13:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:13:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:13:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:13:01 INFO - 'talos_options': ['--disable-e10s'], 14:13:01 INFO - 'tests': ['damp', 'tps']}, 14:13:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:13:01 INFO - 'tests': ['damp', 'tps']}, 14:13:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:13:01 INFO - 'tests': ['dromaeo_dom']}, 14:13:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:13:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:13:01 INFO - 'tests': ['basic_compositor_video']}, 14:13:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:13:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:13:01 INFO - 'tests': ['a11yr', 14:13:01 INFO - 'ts_paint', 14:13:01 INFO - 'tpaint', 14:13:01 INFO - 'sessionrestore', 14:13:01 INFO - 'sessionrestore_no_auto_restore', 14:13:01 INFO - 'tabpaint']}, 14:13:01 INFO - 'other-e10s': {'tests': ['a11yr', 14:13:01 INFO - 'ts_paint', 14:13:01 INFO - 'tpaint', 14:13:01 INFO - 'sessionrestore', 14:13:01 INFO - 'sessionrestore_no_auto_restore', 14:13:01 INFO - 'tabpaint']}, 14:13:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:13:01 INFO - 'ts_paint', 14:13:01 INFO - 'tpaint', 14:13:01 INFO - 'sessionrestore', 14:13:01 INFO - 'sessionrestore_no_auto_restore', 14:13:01 INFO - 'tabpaint']}, 14:13:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:13:01 INFO - 'ts_paint', 14:13:01 INFO - 'tpaint', 14:13:01 INFO - 'sessionrestore', 14:13:01 INFO - 'sessionrestore_no_auto_restore', 14:13:01 INFO - 'tabpaint']}, 14:13:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:13:01 INFO - 'tests': ['a11yr', 14:13:01 INFO - 'ts_paint', 14:13:01 INFO - 'tpaint', 14:13:01 INFO - 'sessionrestore', 14:13:01 INFO - 'sessionrestore_no_auto_restore', 14:13:01 INFO - 'tabpaint']}, 14:13:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:13:01 INFO - 'tests': ['a11yr', 14:13:01 INFO - 'ts_paint', 14:13:01 INFO - 'tpaint', 14:13:01 INFO - 'sessionrestore', 14:13:01 INFO - 'sessionrestore_no_auto_restore', 14:13:01 INFO - 'tabpaint']}, 14:13:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:13:01 INFO - 'tests': ['tsvgx', 14:13:01 INFO - 'tsvgr_opacity', 14:13:01 INFO - 'tart', 14:13:01 INFO - 'tscrollx', 14:13:01 INFO - 'cart']}, 14:13:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:13:01 INFO - 'tsvgr_opacity', 14:13:01 INFO - 'tart', 14:13:01 INFO - 'tscrollx', 14:13:01 INFO - 'cart']}, 14:13:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:13:01 INFO - 'talos_options': ['--disable-e10s'], 14:13:01 INFO - 'tests': ['tp5o']}, 14:13:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:13:01 INFO - 'tests': ['tp5o']}, 14:13:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:13:01 INFO - 'talos_options': ['--disable-e10s', 14:13:01 INFO - '--xperf_path', 14:13:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:13:01 INFO - 'tests': ['tp5n']}, 14:13:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:13:01 INFO - 'talos_options': ['--xperf_path', 14:13:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:13:01 INFO - 'tests': ['tp5n']}}, 14:13:01 INFO - 'talos.zip': {'path': '', 14:13:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:13:01 INFO - Running post-action listener: _resource_record_post_action 14:13:01 INFO - [mozharness: 2016-09-02 21:13:01.637823Z] Finished populate-webroot step (success) 14:13:01 INFO - [mozharness: 2016-09-02 21:13:01.637911Z] Running create-virtualenv step. 14:13:01 INFO - Running pre-action listener: _resource_record_pre_action 14:13:01 INFO - Running main action method: create_virtualenv 14:13:01 INFO - Creating virtualenv /builds/slave/test/build/venv 14:13:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:13:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:13:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:13:01 INFO - Using real prefix '/usr' 14:13:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:13:02 INFO - Installing distribute.............................................................................................................................................................................................done. 14:13:04 INFO - Installing pip.................done. 14:13:04 INFO - Return code: 0 14:13:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2409030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c83b0>, '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 0x26c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c9740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:13:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:13:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:13:04 INFO - Using env: {'DISPLAY': ':0', 14:13:04 INFO - 'HOME': '/home/cltbld', 14:13:04 INFO - 'LANG': 'en_US.UTF-8', 14:13:04 INFO - 'LANGUAGE': 'en_US:en', 14:13:04 INFO - 'LOGNAME': 'cltbld', 14:13:04 INFO - 'MAIL': '/var/mail/cltbld', 14:13:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:04 INFO - 'MOZ_NO_REMOTE': '1', 14:13:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:04 INFO - 'NO_EM_RESTART': '1', 14:13:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:04 INFO - 'PWD': '/builds/slave/test', 14:13:04 INFO - 'SHELL': '/bin/bash', 14:13:04 INFO - 'SHLVL': '1', 14:13:04 INFO - 'TERM': 'linux', 14:13:04 INFO - 'TMOUT': '86400', 14:13:04 INFO - 'USER': 'cltbld', 14:13:04 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 14:13:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:04 INFO - '_': '/tools/buildbot/bin/python'} 14:13:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:13:04 INFO - Cleaning up... 14:13:04 INFO - Return code: 0 14:13:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2409030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c83b0>, '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 0x26c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c9740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:13:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:13:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:13:04 INFO - Using env: {'DISPLAY': ':0', 14:13:04 INFO - 'HOME': '/home/cltbld', 14:13:04 INFO - 'LANG': 'en_US.UTF-8', 14:13:04 INFO - 'LANGUAGE': 'en_US:en', 14:13:04 INFO - 'LOGNAME': 'cltbld', 14:13:04 INFO - 'MAIL': '/var/mail/cltbld', 14:13:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:04 INFO - 'MOZ_NO_REMOTE': '1', 14:13:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:04 INFO - 'NO_EM_RESTART': '1', 14:13:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:04 INFO - 'PWD': '/builds/slave/test', 14:13:04 INFO - 'SHELL': '/bin/bash', 14:13:04 INFO - 'SHLVL': '1', 14:13:04 INFO - 'TERM': 'linux', 14:13:04 INFO - 'TMOUT': '86400', 14:13:04 INFO - 'USER': 'cltbld', 14:13:04 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 14:13:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:04 INFO - '_': '/tools/buildbot/bin/python'} 14:13:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:04 INFO - Downloading/unpacking psutil>=3.1.1 14:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:13:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:13:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:13:06 INFO - Installing collected packages: psutil 14:13:06 INFO - Running setup.py install for psutil 14:13:06 INFO - building 'psutil._psutil_linux' extension 14:13:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:13:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:13:06 INFO - building 'psutil._psutil_posix' extension 14:13:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:13:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:13:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:13:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:13:07 INFO - Successfully installed psutil 14:13:07 INFO - Cleaning up... 14:13:07 INFO - Return code: 0 14:13:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:07 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 0x2409030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c83b0>, '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 0x26c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c9740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:13:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:13:07 INFO - Using env: {'DISPLAY': ':0', 14:13:07 INFO - 'HOME': '/home/cltbld', 14:13:07 INFO - 'LANG': 'en_US.UTF-8', 14:13:07 INFO - 'LANGUAGE': 'en_US:en', 14:13:07 INFO - 'LOGNAME': 'cltbld', 14:13:07 INFO - 'MAIL': '/var/mail/cltbld', 14:13:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:07 INFO - 'MOZ_NO_REMOTE': '1', 14:13:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:07 INFO - 'NO_EM_RESTART': '1', 14:13:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:07 INFO - 'PWD': '/builds/slave/test', 14:13:07 INFO - 'SHELL': '/bin/bash', 14:13:07 INFO - 'SHLVL': '1', 14:13:07 INFO - 'TERM': 'linux', 14:13:07 INFO - 'TMOUT': '86400', 14:13:07 INFO - 'USER': 'cltbld', 14:13:07 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 14:13:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:07 INFO - '_': '/tools/buildbot/bin/python'} 14:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:13:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:13:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:13:09 INFO - Installing collected packages: mozsystemmonitor 14:13:09 INFO - Running setup.py install for mozsystemmonitor 14:13:09 INFO - Successfully installed mozsystemmonitor 14:13:09 INFO - Cleaning up... 14:13:09 INFO - Return code: 0 14:13:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:13:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:09 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 0x2409030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c83b0>, '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 0x26c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c9740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:13:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:13:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:13:09 INFO - Using env: {'DISPLAY': ':0', 14:13:09 INFO - 'HOME': '/home/cltbld', 14:13:09 INFO - 'LANG': 'en_US.UTF-8', 14:13:09 INFO - 'LANGUAGE': 'en_US:en', 14:13:09 INFO - 'LOGNAME': 'cltbld', 14:13:09 INFO - 'MAIL': '/var/mail/cltbld', 14:13:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:09 INFO - 'MOZ_NO_REMOTE': '1', 14:13:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:09 INFO - 'NO_EM_RESTART': '1', 14:13:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:09 INFO - 'PWD': '/builds/slave/test', 14:13:09 INFO - 'SHELL': '/bin/bash', 14:13:09 INFO - 'SHLVL': '1', 14:13:09 INFO - 'TERM': 'linux', 14:13:09 INFO - 'TMOUT': '86400', 14:13:09 INFO - 'USER': 'cltbld', 14:13:09 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 14:13:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:09 INFO - '_': '/tools/buildbot/bin/python'} 14:13:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:09 INFO - Downloading/unpacking jsonschema==2.5.1 14:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:13:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:11 INFO - Downloading functools32-3.2.3-2.tar.gz 14:13:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:13:11 INFO - warning: no files found matching '*.txt' 14:13:11 INFO - no previously-included directories found matching 'build' 14:13:11 INFO - no previously-included directories found matching 'dist' 14:13:11 INFO - no previously-included directories found matching '.git*' 14:13:11 INFO - Installing collected packages: jsonschema, functools32 14:13:11 INFO - Running setup.py install for functools32 14:13:11 INFO - warning: no files found matching '*.txt' 14:13:11 INFO - no previously-included directories found matching 'build' 14:13:11 INFO - no previously-included directories found matching 'dist' 14:13:11 INFO - no previously-included directories found matching '.git*' 14:13:11 INFO - Successfully installed jsonschema functools32 14:13:11 INFO - Cleaning up... 14:13:12 INFO - Return code: 0 14:13:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:12 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 0x2409030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c83b0>, '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 0x26c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c9740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:13:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:13:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:13:12 INFO - Using env: {'DISPLAY': ':0', 14:13:12 INFO - 'HOME': '/home/cltbld', 14:13:12 INFO - 'LANG': 'en_US.UTF-8', 14:13:12 INFO - 'LANGUAGE': 'en_US:en', 14:13:12 INFO - 'LOGNAME': 'cltbld', 14:13:12 INFO - 'MAIL': '/var/mail/cltbld', 14:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:12 INFO - 'MOZ_NO_REMOTE': '1', 14:13:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:12 INFO - 'NO_EM_RESTART': '1', 14:13:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:12 INFO - 'PWD': '/builds/slave/test', 14:13:12 INFO - 'SHELL': '/bin/bash', 14:13:12 INFO - 'SHLVL': '1', 14:13:12 INFO - 'TERM': 'linux', 14:13:12 INFO - 'TMOUT': '86400', 14:13:12 INFO - 'USER': 'cltbld', 14:13:12 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 14:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:12 INFO - '_': '/tools/buildbot/bin/python'} 14:13:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:13:12 INFO - Cleaning up... 14:13:12 INFO - Return code: 0 14:13:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:12 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 0x2409030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c83b0>, '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 0x26c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c9740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:13:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:13:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:13:12 INFO - Using env: {'DISPLAY': ':0', 14:13:12 INFO - 'HOME': '/home/cltbld', 14:13:12 INFO - 'LANG': 'en_US.UTF-8', 14:13:12 INFO - 'LANGUAGE': 'en_US:en', 14:13:12 INFO - 'LOGNAME': 'cltbld', 14:13:12 INFO - 'MAIL': '/var/mail/cltbld', 14:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:12 INFO - 'MOZ_NO_REMOTE': '1', 14:13:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:12 INFO - 'NO_EM_RESTART': '1', 14:13:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:12 INFO - 'PWD': '/builds/slave/test', 14:13:12 INFO - 'SHELL': '/bin/bash', 14:13:12 INFO - 'SHLVL': '1', 14:13:12 INFO - 'TERM': 'linux', 14:13:12 INFO - 'TMOUT': '86400', 14:13:12 INFO - 'USER': 'cltbld', 14:13:12 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 14:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:12 INFO - '_': '/tools/buildbot/bin/python'} 14:13:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:12 INFO - Downloading/unpacking blobuploader==1.2.4 14:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:14 INFO - Downloading blobuploader-1.2.4.tar.gz 14:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:13:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:13:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:15 INFO - Downloading docopt-0.6.1.tar.gz 14:13:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:13:15 INFO - Installing collected packages: blobuploader, requests, docopt 14:13:15 INFO - Running setup.py install for blobuploader 14:13:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:13:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:13:15 INFO - Running setup.py install for requests 14:13:15 INFO - Running setup.py install for docopt 14:13:15 INFO - Successfully installed blobuploader requests docopt 14:13:15 INFO - Cleaning up... 14:13:15 INFO - Return code: 0 14:13:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:15 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 0x2409030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c83b0>, '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 0x26c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c9740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:13:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:13:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:13:15 INFO - Using env: {'DISPLAY': ':0', 14:13:15 INFO - 'HOME': '/home/cltbld', 14:13:15 INFO - 'LANG': 'en_US.UTF-8', 14:13:15 INFO - 'LANGUAGE': 'en_US:en', 14:13:15 INFO - 'LOGNAME': 'cltbld', 14:13:15 INFO - 'MAIL': '/var/mail/cltbld', 14:13:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:15 INFO - 'MOZ_NO_REMOTE': '1', 14:13:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:15 INFO - 'NO_EM_RESTART': '1', 14:13:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:15 INFO - 'PWD': '/builds/slave/test', 14:13:15 INFO - 'SHELL': '/bin/bash', 14:13:15 INFO - 'SHLVL': '1', 14:13:15 INFO - 'TERM': 'linux', 14:13:15 INFO - 'TMOUT': '86400', 14:13:15 INFO - 'USER': 'cltbld', 14:13:15 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 14:13:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:15 INFO - '_': '/tools/buildbot/bin/python'} 14:13:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:13:15 INFO - Running setup.py (path:/tmp/pip-Tzk5ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:13:16 INFO - Running setup.py (path:/tmp/pip-d8noLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:13:16 INFO - Running setup.py (path:/tmp/pip-JD6JgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:13:16 INFO - Running setup.py (path:/tmp/pip-9risEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:13:16 INFO - Running setup.py (path:/tmp/pip-v6iKBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:13:16 INFO - Running setup.py (path:/tmp/pip-SyqeEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:13:16 INFO - Running setup.py (path:/tmp/pip-d6lkmc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:13:16 INFO - Running setup.py (path:/tmp/pip-OW9RRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:13:16 INFO - Running setup.py (path:/tmp/pip-zba_6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:13:16 INFO - Running setup.py (path:/tmp/pip-6I52A8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:13:16 INFO - Running setup.py (path:/tmp/pip-ChBz4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:13:16 INFO - Running setup.py (path:/tmp/pip-N1hoKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:13:16 INFO - Running setup.py (path:/tmp/pip-8n_NV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:13:16 INFO - Running setup.py (path:/tmp/pip-DiO1FF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:16 INFO - Running setup.py (path:/tmp/pip-1kIVOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:13:17 INFO - Running setup.py (path:/tmp/pip-mtG2Kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:13:17 INFO - Running setup.py (path:/tmp/pip-Zr6Jp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:13:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:13:17 INFO - Running setup.py install for manifestparser 14:13:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:13:17 INFO - Running setup.py install for mozcrash 14:13:17 INFO - Running setup.py install for mozdebug 14:13:17 INFO - Running setup.py install for mozdevice 14:13:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:13:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:13:17 INFO - Running setup.py install for mozfile 14:13:17 INFO - Running setup.py install for mozhttpd 14:13:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:13:17 INFO - Running setup.py install for mozinfo 14:13:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:13:17 INFO - Running setup.py install for mozInstall 14:13:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:13:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:13:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:13:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:13:18 INFO - Running setup.py install for mozleak 14:13:18 INFO - Running setup.py install for mozlog 14:13:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:13:18 INFO - Running setup.py install for moznetwork 14:13:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:13:18 INFO - Running setup.py install for mozprocess 14:13:18 INFO - Running setup.py install for mozprofile 14:13:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:13:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:13:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:13:18 INFO - Running setup.py install for mozrunner 14:13:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:13:18 INFO - Running setup.py install for mozscreenshot 14:13:18 INFO - Running setup.py install for moztest 14:13:18 INFO - Running setup.py install for mozversion 14:13:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:13:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:13:18 INFO - Cleaning up... 14:13:19 INFO - Return code: 0 14:13:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:19 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 0x2409030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c83b0>, '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 0x26c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c9740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:13:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:13:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:13:19 INFO - Using env: {'DISPLAY': ':0', 14:13:19 INFO - 'HOME': '/home/cltbld', 14:13:19 INFO - 'LANG': 'en_US.UTF-8', 14:13:19 INFO - 'LANGUAGE': 'en_US:en', 14:13:19 INFO - 'LOGNAME': 'cltbld', 14:13:19 INFO - 'MAIL': '/var/mail/cltbld', 14:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:19 INFO - 'MOZ_NO_REMOTE': '1', 14:13:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:19 INFO - 'NO_EM_RESTART': '1', 14:13:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:19 INFO - 'PWD': '/builds/slave/test', 14:13:19 INFO - 'SHELL': '/bin/bash', 14:13:19 INFO - 'SHLVL': '1', 14:13:19 INFO - 'TERM': 'linux', 14:13:19 INFO - 'TMOUT': '86400', 14:13:19 INFO - 'USER': 'cltbld', 14:13:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 14:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:19 INFO - '_': '/tools/buildbot/bin/python'} 14:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:13:19 INFO - Running setup.py (path:/tmp/pip-R0eUli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:13:19 INFO - Running setup.py (path:/tmp/pip-rfN37A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:13:19 INFO - Running setup.py (path:/tmp/pip-tuFPkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:13:19 INFO - Running setup.py (path:/tmp/pip-GIqhUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:13:19 INFO - Running setup.py (path:/tmp/pip-5sBw6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:13:19 INFO - Running setup.py (path:/tmp/pip-aOhHIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:13:19 INFO - Running setup.py (path:/tmp/pip-6zIWaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:13:19 INFO - Running setup.py (path:/tmp/pip-rY9KOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:13:19 INFO - Running setup.py (path:/tmp/pip-rKVzhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:13:19 INFO - Running setup.py (path:/tmp/pip-cmcdwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:13:19 INFO - Running setup.py (path:/tmp/pip-5gFC2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:13:20 INFO - Running setup.py (path:/tmp/pip-KvnDy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:13:20 INFO - Running setup.py (path:/tmp/pip-KedhvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:13:20 INFO - Running setup.py (path:/tmp/pip-EKWv4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:20 INFO - Running setup.py (path:/tmp/pip-LSsB1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:13:20 INFO - Running setup.py (path:/tmp/pip-S8kKOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:13:20 INFO - Running setup.py (path:/tmp/pip-kc3AZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:13:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:13:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:22 INFO - Downloading blessings-1.6.tar.gz 14:13:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:13:22 INFO - Installing collected packages: blessings 14:13:22 INFO - Running setup.py install for blessings 14:13:22 INFO - Successfully installed blessings 14:13:22 INFO - Cleaning up... 14:13:22 INFO - Return code: 0 14:13:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:13:22 INFO - Reading from file tmpfile_stdout 14:13:22 INFO - Current package versions: 14:13:22 INFO - argparse == 1.2.1 14:13:22 INFO - blessings == 1.6 14:13:22 INFO - blobuploader == 1.2.4 14:13:22 INFO - docopt == 0.6.1 14:13:22 INFO - functools32 == 3.2.3-2 14:13:22 INFO - jsonschema == 2.5.1 14:13:22 INFO - manifestparser == 1.1 14:13:22 INFO - mozInstall == 1.12 14:13:22 INFO - mozcrash == 0.17 14:13:22 INFO - mozdebug == 0.1 14:13:22 INFO - mozdevice == 0.48 14:13:22 INFO - mozfile == 1.2 14:13:22 INFO - mozhttpd == 0.7 14:13:22 INFO - mozinfo == 0.9 14:13:22 INFO - mozleak == 0.1 14:13:22 INFO - mozlog == 3.3 14:13:22 INFO - moznetwork == 0.27 14:13:22 INFO - mozprocess == 0.23 14:13:22 INFO - mozprofile == 0.28 14:13:22 INFO - mozrunner == 6.12 14:13:22 INFO - mozscreenshot == 0.1 14:13:22 INFO - mozsystemmonitor == 0.3 14:13:22 INFO - moztest == 0.7 14:13:22 INFO - mozversion == 1.4 14:13:22 INFO - psutil == 3.1.1 14:13:22 INFO - requests == 1.2.3 14:13:22 INFO - wsgiref == 0.1.2 14:13:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:22 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 0x2409030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c83b0>, '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 0x26c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c9740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:13:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:13:22 INFO - Using env: {'DISPLAY': ':0', 14:13:22 INFO - 'HOME': '/home/cltbld', 14:13:22 INFO - 'LANG': 'en_US.UTF-8', 14:13:22 INFO - 'LANGUAGE': 'en_US:en', 14:13:22 INFO - 'LOGNAME': 'cltbld', 14:13:22 INFO - 'MAIL': '/var/mail/cltbld', 14:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:22 INFO - 'MOZ_NO_REMOTE': '1', 14:13:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:22 INFO - 'NO_EM_RESTART': '1', 14:13:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:22 INFO - 'PWD': '/builds/slave/test', 14:13:22 INFO - 'SHELL': '/bin/bash', 14:13:22 INFO - 'SHLVL': '1', 14:13:22 INFO - 'TERM': 'linux', 14:13:22 INFO - 'TMOUT': '86400', 14:13:22 INFO - 'USER': 'cltbld', 14:13:22 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 14:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:22 INFO - '_': '/tools/buildbot/bin/python'} 14:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:13:23 INFO - Cleaning up... 14:13:23 INFO - Return code: 0 14:13:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:13:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2409030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26ca180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c83b0>, '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 0x26c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c9740>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:13:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:13:23 INFO - Using env: {'DISPLAY': ':0', 14:13:23 INFO - 'HOME': '/home/cltbld', 14:13:23 INFO - 'LANG': 'en_US.UTF-8', 14:13:23 INFO - 'LANGUAGE': 'en_US:en', 14:13:23 INFO - 'LOGNAME': 'cltbld', 14:13:23 INFO - 'MAIL': '/var/mail/cltbld', 14:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:23 INFO - 'MOZ_NO_REMOTE': '1', 14:13:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:23 INFO - 'NO_EM_RESTART': '1', 14:13:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:23 INFO - 'PWD': '/builds/slave/test', 14:13:23 INFO - 'SHELL': '/bin/bash', 14:13:23 INFO - 'SHLVL': '1', 14:13:23 INFO - 'TERM': 'linux', 14:13:23 INFO - 'TMOUT': '86400', 14:13:23 INFO - 'USER': 'cltbld', 14:13:23 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 14:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:23 INFO - '_': '/tools/buildbot/bin/python'} 14:13:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:13:23 INFO - Cleaning up... 14:13:23 INFO - Return code: 0 14:13:23 INFO - Running post-action listener: _resource_record_post_action 14:13:23 INFO - Running post-action listener: _start_resource_monitoring 14:13:23 INFO - Starting resource monitoring. 14:13:23 INFO - [mozharness: 2016-09-02 21:13:23.273321Z] Finished create-virtualenv step (success) 14:13:23 INFO - [mozharness: 2016-09-02 21:13:23.273749Z] Running install step. 14:13:23 INFO - Running pre-action listener: _resource_record_pre_action 14:13:23 INFO - Running main action method: install 14:13:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:13:23 INFO - Reading from file tmpfile_stdout 14:13:23 INFO - Detecting whether we're running mozinstall >=1.0... 14:13:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:13:23 INFO - Reading from file tmpfile_stdout 14:13:23 INFO - Output received: 14:13:23 INFO - Usage: mozinstall [options] installer 14:13:23 INFO - Options: 14:13:23 INFO - -h, --help show this help message and exit 14:13:23 INFO - -d DEST, --destination=DEST 14:13:23 INFO - Directory to install application into. [default: 14:13:23 INFO - "/builds/slave/test"] 14:13:23 INFO - --app=APP Application being installed. [default: firefox] 14:13:23 INFO - mkdir: /builds/slave/test/build/application 14:13:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:13:40 INFO - Reading from file tmpfile_stdout 14:13:40 INFO - Output received: 14:13:40 INFO - /builds/slave/test/build/application/firefox/firefox 14:13:40 INFO - Running post-action listener: _resource_record_post_action 14:13:40 INFO - [mozharness: 2016-09-02 21:13:40.128972Z] Finished install step (success) 14:13:40 INFO - [mozharness: 2016-09-02 21:13:40.129302Z] Running run-tests step. 14:13:40 INFO - Running pre-action listener: _resource_record_pre_action 14:13:40 INFO - Running main action method: run_tests 14:13:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:13:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:13:40 INFO - Python 2.7.3 14:13:40 INFO - Return code: 0 14:13:40 INFO - grabbing minidump binary from tooltool 14:13: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')]} 14:13:40 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 0x26c83b0>, '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 0x26c92b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c9740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:13:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:13:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:13:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:13:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:13:40 INFO - Return code: 0 14:13:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:13:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:13:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:13:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:13:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:13:40 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/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:13:40 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/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:13:40 INFO - Using env: {'DISPLAY': ':0', 14:13:40 INFO - 'HOME': '/home/cltbld', 14:13:40 INFO - 'LANG': 'en_US.UTF-8', 14:13:40 INFO - 'LANGUAGE': 'en_US:en', 14:13:40 INFO - 'LOGNAME': 'cltbld', 14:13:40 INFO - 'MAIL': '/var/mail/cltbld', 14:13:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:13:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:13:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:40 INFO - 'MOZ_NO_REMOTE': '1', 14:13:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:13:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:40 INFO - 'NO_EM_RESTART': '1', 14:13:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:40 INFO - 'PWD': '/builds/slave/test', 14:13:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:13:40 INFO - 'SHELL': '/bin/bash', 14:13:40 INFO - 'SHLVL': '1', 14:13:40 INFO - 'TERM': 'linux', 14:13:40 INFO - 'TMOUT': '86400', 14:13:40 INFO - 'USER': 'cltbld', 14:13:40 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819', 14:13:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:40 INFO - '_': '/tools/buildbot/bin/python'} 14:13:40 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/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:13:40 INFO - mozversion application_buildid: 20160902110447 14:13:40 INFO - mozversion application_changeset: 3cb50e5df49728e19c6f5bcb4c5b3fe34a9db53c 14:13:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:13:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:13:40 INFO - mozversion application_name: Firefox 14:13:40 INFO - mozversion application_remotingname: firefox-dev 14:13:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:13:40 INFO - mozversion application_vendor: Mozilla 14:13:40 INFO - mozversion application_version: 50.0a2 14:13:40 INFO - mozversion platform_buildid: 20160902110447 14:13:40 INFO - mozversion platform_changeset: 3cb50e5df49728e19c6f5bcb4c5b3fe34a9db53c 14:13:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:13:40 INFO - mozversion platform_version: 50.0a2 14:13:40 INFO - using testdate: 1472850820 14:13:40 INFO - actual date: 1472850820 14:13:40 INFO - starting webserver on 'localhost:57666' 14:13:40 INFO - SUITE-START | Running 2 tests 14:13:40 INFO - TEST-START | dromaeo_css 14:13:40 INFO - operating with platform_type : linux_ 14:13:40 INFO - Initialising browser for dromaeo_css test... 14:13:40 INFO - TEST-INFO | started process 841 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp57wPx1/profile http://localhost:57666/getInfo.html) 14:13:44 INFO - PROCESS | 841 | __metrics Screen width/height:1600/1200 14:13:44 INFO - PROCESS | 841 | colorDepth:24 14:13:44 INFO - PROCESS | 841 | Browser inner width/height: 1024/697 14:13:44 INFO - PROCESS | 841 | __metrics 14:13:45 INFO - PROCESS | 841 | 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:45 INFO - PROCESS | 841 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:13:45 INFO - TEST-INFO | 841: exit 0 14:13:45 INFO - Browser initialized. 14:13:45 INFO - Running cycle 1/1 for dromaeo_css test... 14:13:45 INFO - TEST-INFO | started process 929 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp57wPx1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:13:57 INFO - PROCESS | 929 | RSS: Main: 145911808 14:13:57 INFO - PROCESS | 929 | 14:14:48 INFO - PROCESS | 929 | 1472850888752 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:16:57 INFO - PROCESS | 929 | Cycle 1(1): loaded http://localhost:57666/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57666/tests/dromaeo/cssquery-ext.html) 14:16:58 INFO - PROCESS | 929 | RSS: Main: 272695296 14:16:58 INFO - PROCESS | 929 | 14:20:01 INFO - PROCESS | 929 | Cycle 1(1): loaded http://localhost:57666/tests/dromaeo/cssquery-ext.html (next: http://localhost:57666/tests/dromaeo/cssquery-jquery.html) 14:20:01 INFO - PROCESS | 929 | RSS: Main: 222416896 14:20:01 INFO - PROCESS | 929 | 14:23:03 INFO - PROCESS | 929 | Cycle 1(1): loaded http://localhost:57666/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57666/tests/dromaeo/cssquery-mootools.html) 14:23:04 INFO - PROCESS | 929 | RSS: Main: 204673024 14:23:04 INFO - PROCESS | 929 | 14:26:06 INFO - PROCESS | 929 | Cycle 1(1): loaded http://localhost:57666/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57666/tests/dromaeo/cssquery-prototype.html) 14:26:07 INFO - PROCESS | 929 | RSS: Main: 171913216 14:26:07 INFO - PROCESS | 929 | 14:29:09 INFO - PROCESS | 929 | Cycle 1(1): loaded http://localhost:57666/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57666/tests/dromaeo/cssquery-yui.html) 14:29:10 INFO - PROCESS | 929 | RSS: Main: 175312896 14:29:10 INFO - PROCESS | 929 | 14:32:12 INFO - PROCESS | 929 | Cycle 1(1): loaded http://localhost:57666/tests/dromaeo/cssquery-yui.html (next: http://localhost:57666/tests/dromaeo/cssquery-dojo.html) 14:32:13 INFO - PROCESS | 929 | RSS: Main: 263532544 14:32:13 INFO - PROCESS | 929 | 14:32:13 INFO - PROCESS | 929 | __start_tp_report 14:32:13 INFO - PROCESS | 929 | _x_x_mozilla_page_load 14:32:13 INFO - PROCESS | 929 | _x_x_mozilla_page_load_details 14:32:13 INFO - PROCESS | 929 | |i|pagename|runs| 14:32:13 INFO - PROCESS | 929 | |0;dojo.html;3200.5839240898686;3228.6287076986123;3234.7897386669856;3246.5617141685866;3272.2800983783573;5578.832635020951;5635.718214089299;5658.179563963228;5663.490285874275;5670.68811215383;5605.327360716709;5672.914906276409;5693.146028095788;5700.458456446641;5723.341815691197;4925.694690906895;5001.374828146481;5078.59371250301;5112.795488180487;5159.303494028314;5015.0972824891505;5037.974810125944;5040.6471546991725;5094.083065048289;5136.203888397305;5105.795768169269;5134.717590532519;5148.099082660538;5159.458256883029;5249.947500524984;5198.428172900997;5200.843974680728;5208.973955130238;5210.296609957683;5239.056969745444;4897.975510122426;4946.802127914879;4957.330760347343;5108.386993560796;5114.514121158484;5513.558915286808;5566.081596536607;5566.944330556683;5575.804846830382;5588.944110558883;5960.284765828085;5968.313643930916;5987.1318658794235;5997.22036135304;6000.609960352563;5811.418858114154;5964.075568286922;5966.552508561876;6020.397960203988;6070.332263451017;5663.122216056518;5665.348484924204;5665.886682266331;5668.31980162379;5669.574781891375;6176.227971503562;6181.443670069625;6182.072689096672;6183.041628547582;6186.659733714689;5709.42905709434;5815.912761308584;5876.794312199053;5877.647341159544;5967.492763115184;5993.4905533029305;6025.879482410415;6028.186194863725;6030.728617212236;6034.758609655652;5926.170336152942;5933.436323549256;5992.101184822312;5995.61028533144;5995.790147344822;5782.508486778587;5847.736851841677;5895;5942.257217847769;5970.223870896755;4612.238980568236;4616.030633566988;4618.49196588375;4632.328312394684;4645.2103142465185;4110.034141976633;4113.91772164555;4115.9588404116175;4124.360724087771;4135.793210339471;5570.220169176318;5615.943840561624;5675.943240567624;5740.913886291709;5741.224934633855;6015.0977353396465;6021.909671354933;6022.066579680157;6044.546659000504;6064.272287325549;5657.2928383952;5701.914471282934;5703.258576385043;5704.857378565486;5710.771569137271;4913.70517768935;4926.310316555685;4974.1543937530705;5030.446650868402;5041.697498150123;6203.875922481525;6220.72006759706;6228.875422491434;6229.252489701174;6231;5900.7049647518315;5950;5952.970235148797;5964.612300200473;5997.950358687125;6009.978303688471;6119.357467465852;6142.1304578115505;6217.502599792097;6228.5951413158;5925.377835327228;5936.732847021981;5938.8218353449465;5966.880662386815;5972.58191926561;5983.730732117152;6046.697665116814;6051.636901785907;6056.182415373868;6061.454469097626;5726.570507211893;5779.653220806765;5835.241418615553;5923.7926672566255;5933.821985340447;5863.149843272787;5867.354590995073;5870.55970802183;5897.144913987534;5912.349641539513;5818.592698511064;5830.096335068071;5832.300123984979;5833.416658334133;5835.299764028174;5675.489205971482;5748.252727145445;5817.185594016756;5837.562182836219;5954.10688396744;5763.884722305615;5764.164196191613;5777.248957635648;5798.623089499169;5806.390329015392 14:32:13 INFO - PROCESS | 929 | |1;ext.html;594.0732457366508;628.4187126907609;643.6942452335142;653.5817077070674;654.8559316950269;37024;44585.55414445855;45268.32097518536;45295.77352113243;48436.51563484373;48853.26720099201;49071.26393104097;49145.50854491463;49207.50792492074;49385;8252.546109963949;9138.131877471646;9232.67685631003;9286.674966376178;10752.354858708502;10778.245522813406;11211.159163062763;11211.831822522669;11218.158638102133;11219.15856310776;11202.215844890861;11262.380569068739;11269.154813388988;11271.549138034468;11273.54905803771;11191.384473853936;11222.66332010041;11248.55005799767;11250.887491125066;11258.380789056599;11033.337999720043;11085.113190944705;11097.3896435696;11107.944460277648;11110.666679999613;46577.30134047992;47146.76426617879;47151.528484715054;47208.76395618;47219.76390118062;3824.9235015299814;3827.9043023924346;4008.2785098682225;4307.310830267143;4556.248219043854;34942;35312.47031294533;35460.29079418423;36842.631573684455;36889;4558.22510173268;4643.907121857544;4653.720776753372;4671.85984420468;4804.567588917014;371372;371938;373536;373555;374463.99999999726;37616;38070.809645951595;65287.67356163284;65579;65705;40065.1986960265;40384.79807601002;40459.1908161841;40487.392689109685;40519.18961620751;17079;17267.136643167792;17293.913530432517;17307.826921730622;17330.82669173292;12655.746885062246;12857.807132893015;12905.35473226335;12911.096223264281;14705.41178352854;8932.821343573221;9070.410423322463;9180.678676246433;9192.218661413855;9212.263018958467;3483.9303213935577;3484.3205574913154;3519.8064106473876;3565.358235517632;3611.2957973194975;1063.4629512096342;1075.419273592267;1087.429099722652;1139.9031082357926;1152.6196355202762;34735.82632086823;34874.12814679653;35140;35395.64604353924;35440.822795885855;1186.4364426897155;1223.7552489501957;1224.9326287054118;1243.5771837575087;1256.2274201366095;17705.694542292982;18427.81572184261;18580.44258672242;18718.438446846616;18758.1558829853;677008;684127;684999;685109;685370;66438.66780666065;67039.66480167568;67101;67106.32893671098;67117;1311.6392991927296;1428.8071110399962;1429.6140042188551;1481.7110663420528;1488.895777295579;33175.33649327048;35691.46462803008;35946.4608030885;36114.27771444609;36234.637653623126;56317.155242670575;56452.43547564472;56544.71727641336;56826.7158664204;56888;449902;453299;453349;454405;456167;30296.242593935327;31262.37475250437;31430.528542072345;32686.182845429055;32706.83646581752;53290;53323.733381332844;53382.199267011805;53527.19709204287;53559.19661205007;43689.27216007403;44951.550484494735;45066.77466612646;46017.53982460133;46823.76588117037;44417;45786;45802.54197458116;46608.766956165004;46812.29781553211;44522;44679.32981005222;45148.548514514434;45787.77106114448;45946.31080533728;38068.42897356486;39816.800915995234;39901;40316.59683403128;40753.79623101984;29464.26339341448;31761.682383176798;31802;31819.363612727153;32434.35131297408 14:32:13 INFO - PROCESS | 929 | |2;jquery.html;392.8664254153587;407.594443528689;420.9663226941845;423.0861339506665;431.58352190136515;15065.472708455207;20396.79603203971;21038.57922841544;21042.579148417022;21917.780822191773;19625.803741962576;19684.90157549214;19696.212151513922;19704.40886773399;19904.50238744032;5788.102844059177;5809.186713860063;5944.970275148618;5978.8804223915495;5992.131140984555;5897.4692277695;5970.373110823366;5978.163057171978;5981.371955944628;5982.072778719284;5740.253767010283;5869.178315035899;5918.082697181943;5937.346891841894;5954.821355359346;5747.827565173052;5837.124431335291;5916.940830591703;5953.047512398017;5959.851003724898;5290.285811415448;5326.493983071602;5331.306930099062;5333.599980001477;5335.1197052486295;22547.21084762042;23230.186943457065;23351.88324058386;24125;24670.13654522083;2546.745325467457;3092.597962264891;3148.7481001520055;3152.7950683205518;3189.40995915754;10440.791184176274;10576.153907687367;10750.784984300348;10752.247342686014;12230.571929982409;1963.803619638039;2006.4783156379447;2010.1657812008;2047.5085979364844;2101.7688054314012;64988.67505662489;74643.62678186574;80528.59735701284;84098;88087;11208.775824483628;11553.826692599618;11579.421028948518;11643.76712465746;11699.181057325906;16137.354505819636;16227.594310142342;16256.43102491407;16257.024578525326;16260.51218463448;8651.394402391772;8935.273200090603;8985.415947963362;9022.413543119676;9052.502112383881;8636.870446943176;8863.2466240369;8872.334574906909;9064.50145242018;9076.818463630823;4987.501249874984;5069.898602027939;5093.185090385595;5098.413682426494;5205.375354957429;2011.9295824646067;2050.6718924971933;2053.0248132137117;2082.5314304281414;2091.309867743641;964.8456247000446;1017.2574020964737;1040.3549799124592;1073.8335557988523;1116.1760745314368;20581.58836823255;20841.79158208429;21009.474763131046;21031.474213144793;21138.682919756215;1036.3574583757968;1058.629479682105;1058.9735256618646;1086.6631344283298;1086.7772106718103;10964.616238431616;11619.709507262218;11680.240784348991;11840.111991600665;11914.582989595323;541926;544661;545627;546918;569043;90394;92523.53738231266;93345.53327233456;94003;94100;1034.4155552113182;1055.4617145255975;1070.3898777696793;1074.8656417947757;1075.4353964168874;18087.547811304823;18401.447956561326;18425.447236582924;18558.536036599195;18809.811901880807;44184.33723494215;44691.32963005623;45207.54792452033;45299.547004530854;46022.309665354376;61892.38107618866;62542;63059.684701576196;64227.35772642401;64284.35715642971;8079.83840323202;8436.578171091543;8673.30613550927;8682.305415566612;8735.126487351214;13259.337033148497;13624.18254904709;14223.928880355532;14447.133172009713;14472.27638618086;11642.417879105842;11957.402129893297;12222.388880556115;12327.445264962918;12538.435770390537;11885.643430697093;12092.214006089576;12098;12444.626661200178;12728.745425091261;12070;12099.939500302795;12386.75226495483;12634.810477842655;12869.133975573677;8252.21603947624;8544.2310192083;8658.740237792876;8744.562771861254;8745.169208925143;8968.192862642394;11353.148513861328;11368.317900925971;11474.77050459003;11566.826497602375 14:32:13 INFO - PROCESS | 929 | |3;mootools.html;1391.276536201176;1466.4061055272614;1536.5236776599254;1670.640812225372;1834.7614810074688;8617.698380556682;9147.588358523866;9199.95400022999;9450.480223587718;9466.621335146603;9238.722838314845;9355.111264429868;9368.06319368063;9380.155785979261;9388.295877809158;2396.209250947183;2434.9513009739794;2435.57377458944;2436.561418944589;2440.8413453125577;2394.341556072077;2416.5891798394314;2436.8172387070954;2443.9144629937955;2445.205308274818;2379.6668466414717;2386.3914701751028;2391.4021494626345;2433.2092070077206;2435.561598912195;2337.544178885118;2356.493353928905;2366.3492539551676;2368.218487898998;2388.9402764931015;2295.563842869866;2306.4695120122465;2311.6763653088583;2313.6298192289155;2314.9189778357845;8935.106489351012;9235.584398702007;9295.07049295072;9352.485613291266;9400.24798016157;5249.396319423239;5457.426970168154;5458.263134476834;5461.42655021221;5463.31708536433;3442.2599141184764;3486.058764133695;3499.1951851074396;3561.679448849616;3565.6434356564137;1152.4698638626242;1158.594491927827;1161.1102805601254;1163.7265242667966;1166.5042356998326;13072.150310229805;13113.934430327787;13174.802377964339;13186.67033324165;13190.142640728322;4386.407834942306;4586.334981427674;4613.584777369986;4722.929156062662;4734.834280800155;10260.179185665129;10264;10335.276530642783;10340.172786177087;10346.172306215485;5406.594505412031;5406.756695948692;5410.621256512007;5413.891722165535;5415.539679127318;4469.329600559877;4576.633869290449;4771.141194585008;4804.8318308859025;5071;4092.8362865485046;4095.201435720006;4209.284421648327;4231.11146659205;4232.470941132358;1269.6127681057367;1284.3642397013352;1287.7553264879643;1295.0481396173805;1306.3272414706244;965.5896244096231;1017.872765904262;1022.5909636145601;1044.216837371971;1058.9576416943237;6337.397947195102;6488.124103246096;6550.737970481223;6584.703688334036;6764.966175169191;439.12833026442485;446.9620082292976;455.78805855277454;1066.1790421375497;1140.0651465798132;4479.372887795711;4507.188706032945;4584.931226031612;4737.076270127292;4799.544043315879;11465.770684586261;11481.966623003802;11642.94178529102;11790.410479475991;11829.526818927146;11608.825867611993;11726.237794543327;11808.114391420679;11846.940765296118;11850.407479625985;8431.114732952952;8587.484750914966;8663;8668.523231222218;8689.391742577958;6294.716737746904;6414.903776443451;6457;6474.514411419256;6479.7408103675325;10236.232282578687;10465.372077675363;10567;10623.415712135598;10735.838962415413;10599.682009539727;10658;10725.141988640735;10855.348679079281;10917.672469825919;7492.250774922464;8053.31546818527;8089.8382032360205;8272.627731752205;8285.378596605158;9113.863292050757;9129.99570047281;9192.586333615138;9196.12636799503;9209.355345125778;7933.801654958672;7965.442419030613;7969.043714754266;8095.473794203358;8186.877196841933;8019.278264956182;8034.598270086589;8096.230858068474;8150.551719655238;8190.754277371689;7548.056492938383;7571.659275332513;7615.390768738377;7624.504407213513;7703.229677032252;5229.529342359206;5306.867328316823;5396.730163491889;5397.487238712316;5508.063629182969;5555.30558680165;5595;5741.368449470639;5743.10981797822;5812.3025236971835 14:32:13 INFO - PROCESS | 929 | |4;prototype.html;349.35892637011085;354.0970525160841;355.5040718198114;359.25096174476215;361.4289422712113;5425.321834770653;5484.588655850807;5504.4770746778995;5504.917426238605;5514.944850551493;5374.973125134378;5406.8918621627545;5408.8377348679605;5417.160340147274;5441.918371224425;3818.2363527294515;4029.4761680981433;4109.568495307994;4247.87256382309;4292.570742925714;4266.466691663542;4270.97864510677;4286.849960251392;4291.6566674665955;4293.141371725667;4270.295401258789;4278.6363159131515;4281.207976524337;4283.293256612671;4283.67872409569;4051.736637118578;4079.469668943048;4080.571539988303;4191.874243772691;4280.3793449949735;3809.4857194278693;3811.866584669523;3821.178446633959;3824.9426258606145;3834.2523207974455;5225.059489291926;5253.947460525423;5259.86850328741;5262.289590905217;5290.788368465295;4789.952100478986;4829.517048295177;4866.075445665348;4888.364512613373;4892.755362231874;5608.859778505529;5615.101583746622;5617.466340697627;5631.971840140773;5636.971815140898;4187.413762073312;4188.832446702128;4193.454850869398;4194.391813187101;4197.91604167918;5534.1975413565215;5640.351359593658;5687.203791469197;5842.211301474289;5900.350961394243;4243.257429949746;4248.596383343577;4256.638185754246;4264.808083636244;4268.957310426856;4318.935215971763;4322.351647252937;4322.589354011364;4327.870163895088;4328.523862375137;4596.25342369904;4864.686534635629;4871.196252618349;4871.293662419001;4877.219644856806;5651.971740141273;5661.631993920382;5672.5745569081655;5686.601937864392;5714.79998200061;1456.6139972907188;1461.883049356049;1478.9260536973109;1481.8518148185094;1490.2250829568563;907.6433896390308;947.1617618407621;948.8244674735196;952.828490871636;956.2875657635107;4847.830325938575;4884.192848646024;4929.507049295042;4932.432770231398;4953.356063711694;5102.285680004802;5157.716325602053;5160.870978225575;5170.97414512925;5226.346706661668;4653.694110895061;4849.903001940012;4875.829345972874;4915.48387419323;4937.7531123444405;2730.904418345388;2732.8360298381763;2736.2748871549056;2742.3281296082587;2743.2593199835933;5301.257823904656;5318.946810531923;5335.199720041947;5338.519533241949;5350.555350055463;5231.843044708665;5244.475552444725;5543.889122217533;5963.43572263922;6009.45914867664;6063.878722425527;6093.817185484442;6099.634021958696;6106.236720409949;6108.6866323740605;5554.028045092174;5861.501772349398;5876.706164691834;5896.616719913192;5899.528037756883;5753.395893431297;5817.93463320532;5832.104566015977;5832.475077243069;5851.268591426072;5831.4168583141345;5950.672713000827;6026.819195424145;6057.545684073624;6089.817305480843;4202.348635961431;4240.109576988867;4241.172971270573;4241.38499917504;4243.130158317599;5661.716914154358;5851.561132914963;5855.473007429352;5857.238558987305;5876.676782776817;5701.372848986691;5704.3725190228215;5723.484886360247;5726.226959360433;5732.484076433141;5636.746346414339;5665.745041473061;5680.233168522362;5691.459311365414;5696.8006119785605;5481.972590137024;5603.747831347518;5619.241402410786;5641.351244606923;5643.633163844337;5680.573956953162;5699.430056994267;5700.173474846207;5702.885942281048;5705.771769129188;5535.169724541351;5551.8889622206525;5582.246396736551;5583.74873130702;5585.245991791056 14:32:13 INFO - PROCESS | 929 | |5;yui.html;814.1126172472008;817.6647574494458;838.9412741108125;883.2227639677085;903.0743487924879;15581.688366232669;16301.918490407532;16306.429274975379;16315.999999999985;16332.101734404603;15370.615734606612;15523.379064837392;15568.68862622747;15881.126538040404;16148.273327700252;730.1894896664697;865.2602025268368;892.2282225874604;893.8703887936246;898.5731777405713;885.340421386065;888.5290795878195;889.1997202517754;889.3152272749978;889.5285498685707;946.432140715572;949.0841338108723;949.3402085550545;950.2493030505892;950.5294879034888;773.5822655765879;794.4161041634394;797.3860127701695;922.0733163171037;944.36255527519;680.8365992161895;682.3244987462426;683.0676127086491;685.3044160177424;685.468761709674;16656.58358541034;16714.08072556009;16797.328106875833;16822.411215607393;16847.157642117967;16542.917285413616;16734.079625620587;16758.916205419016;16870.915645421817;17160.39938602143;12976.15654982423;12999.285039322833;13180.34098295091;13470.86529134706;13475.66310842227;4152.231837110145;4158.313878210122;4163.646090082347;4203.957960420387;4259.5740425957465;75846.96612135509;79158.20841791508;83858;84295;84624.57687711583;12832.422540985492;12917.870821291666;12989.41547630359;13147.408366623526;13260.071794974452;23109.53780924372;24218.152364667152;24301.87849060779;24509.63235551468;24591.87704061504;9468.526573671288;9544.761380965392;9545.809083818423;9620.182284505756;9813.051498080547;4613.331066995267;5035.244713292962;5156.767945442464;5181.171012637959;5378.569714422837;3721.5720192177705;3815.9427608585893;3875.980620096938;3878.5733569307354;3881.029742564359;609.9054646529795;612.6844674992383;618.5979113576211;627.9717412716439;630.0737915264554;639.2265358915671;657.4773055420952;673.5722816011868;680.9717326836413;692.5013989927244;9842.65550705722;10211.540480678239;10375.792484150275;10522.684319470429;10869.184811139196;700.7967689370128;717.9497435179487;723.8226634474482;740.2227660955974;760.4296777416937;4653.395058642423;4911.066897289575;4920.335754673073;4951.3810773653295;4978.950210497848;101406;103718.481407594;107704.46147769211;138464;141022;88933.55533222292;101844.49077754564;102639.03384523603;104214.47892760488;105018;54462.72768636211;54916.45083549193;58457;84254.15745842586;86778;10018.699439016842;10384.584616615251;10708.946455267675;10728.302660327054;10987.725306867393;52003;52138;53055.734721326146;53468.46531534635;53813;72661;74069;79754;81328.59335703283;81655.18344816475;13882.305884705522;13955.441782328593;14129.364178612193;14352.990581318832;14562.053466525065;16173.67652646964;16300.184990750653;16303.59241018984;16331.918340408698;17129.400470983455;14435.278236088363;14745.26273686333;15263.23683815827;15455.768163477333;15663.608409789846;14775.261236938326;15203;15662.37350505967;15741.68516629638;16245.918770406071;14855.182964937032;14940.327685253973;14951.850481495047;15171;15472.53582392485;12303.692407689521;12895.16181448203;13158.934205328913;13230.279834981704;13256.469741210629;12782.552610658582;12798.68003299925;12808.74382512326;12941.223526588436;13173.93413032967 14:32:13 INFO - PROCESS | 929 | __end_tp_report 14:32:13 INFO - PROCESS | 929 | __start_cc_report 14:32:13 INFO - PROCESS | 929 | _x_x_mozilla_cycle_collect,1114 14:32:13 INFO - PROCESS | 929 | __end_cc_report 14:32:13 INFO - PROCESS | 929 | __startTimestamp1472851933127__endTimestamp 14:32:13 INFO - PROCESS | 929 | 14:32:13 INFO - PROCESS | 929 | ------- Summary: start ------- 14:32:13 INFO - PROCESS | 929 | Number of tests: 6 14:32:13 INFO - PROCESS | 929 | 14:32:13 INFO - PROCESS | 929 | [#0] dojo.html Cycles:165 Average:5566.96 Median:5779.65 stddev:632.46 (10.9%) stddev-sans-first:606.55 14:32:13 INFO - PROCESS | 929 | Values: 3200.6 3228.6 3234.8 3246.6 3272.3 5578.8 5635.7 5658.2 5663.5 5670.7 5605.3 5672.9 5693.1 5700.5 5723.3 4925.7 5001.4 5078.6 5112.8 5159.3 5015.1 5038.0 5040.6 5094.1 5136.2 5105.8 5134.7 5148.1 5159.5 5249.9 5198.4 5200.8 5209.0 5210.3 5239.1 4898.0 4946.8 4957.3 5108.4 5114.5 5513.6 5566.1 5566.9 5575.8 5588.9 5960.3 5968.3 5987.1 5997.2 6000.6 5811.4 5964.1 5966.6 6020.4 6070.3 5663.1 5665.3 5665.9 5668.3 5669.6 6176.2 6181.4 6182.1 6183.0 6186.7 5709.4 5815.9 5876.8 5877.6 5967.5 5993.5 6025.9 6028.2 6030.7 6034.8 5926.2 5933.4 5992.1 5995.6 5995.8 5782.5 5847.7 5895.0 5942.3 5970.2 4612.2 4616.0 4618.5 4632.3 4645.2 4110.0 4113.9 4116.0 4124.4 4135.8 5570.2 5615.9 5675.9 5740.9 5741.2 6015.1 6021.9 6022.1 6044.5 6064.3 5657.3 5701.9 5703.3 5704.9 5710.8 4913.7 4926.3 4974.2 5030.4 5041.7 6203.9 6220.7 6228.9 6229.3 6231.0 5900.7 5950.0 5953.0 5964.6 5998.0 6010.0 6119.4 6142.1 6217.5 6228.6 5925.4 5936.7 5938.8 5966.9 5972.6 5983.7 6046.7 6051.6 6056.2 6061.5 5726.6 5779.7 5835.2 5923.8 5933.8 5863.1 5867.4 5870.6 5897.1 5912.3 5818.6 5830.1 5832.3 5833.4 5835.3 5675.5 5748.3 5817.2 5837.6 5954.1 5763.9 5764.2 5777.2 5798.6 5806.4 14:32:13 INFO - PROCESS | 929 | 14:32:13 INFO - PROCESS | 929 | [#1] ext.html Cycles:180 Average:66565.61 Median:3826.41 stddev:138743.85 (3625.9%) stddev-sans-first:139044.65 14:32:13 INFO - PROCESS | 929 | Values: 594.1 628.4 643.7 653.6 654.9 37024.0 44585.6 45268.3 45295.8 48436.5 48853.3 49071.3 49145.5 49207.5 49385.0 8252.5 9138.1 9232.7 9286.7 10752.4 10778.2 11211.2 11211.8 11218.2 11219.2 11202.2 11262.4 11269.2 11271.5 11273.5 11191.4 11222.7 11248.6 11250.9 11258.4 11033.3 11085.1 11097.4 11107.9 11110.7 46577.3 47146.8 47151.5 47208.8 47219.8 3824.9 3827.9 4008.3 4307.3 4556.2 34942.0 35312.5 35460.3 36842.6 36889.0 4558.2 4643.9 4653.7 4671.9 4804.6 371372.0 371938.0 373536.0 373555.0 374464.0 37616.0 38070.8 65287.7 65579.0 65705.0 40065.2 40384.8 40459.2 40487.4 40519.2 17079.0 17267.1 17293.9 17307.8 17330.8 12655.7 12857.8 12905.4 12911.1 14705.4 8932.8 9070.4 9180.7 9192.2 9212.3 3483.9 3484.3 3519.8 3565.4 3611.3 1063.5 1075.4 1087.4 1139.9 1152.6 34735.8 34874.1 35140.0 35395.6 35440.8 1186.4 1223.8 1224.9 1243.6 1256.2 17705.7 18427.8 18580.4 18718.4 18758.2 677008.0 684127.0 684999.0 685109.0 685370.0 66438.7 67039.7 67101.0 67106.3 67117.0 1311.6 1428.8 1429.6 1481.7 1488.9 33175.3 35691.5 35946.5 36114.3 36234.6 56317.2 56452.4 56544.7 56826.7 56888.0 449902.0 453299.0 453349.0 454405.0 456167.0 30296.2 31262.4 31430.5 32686.2 32706.8 53290.0 53323.7 53382.2 53527.2 53559.2 43689.3 44951.6 45066.8 46017.5 46823.8 44417.0 45786.0 45802.5 46608.8 46812.3 44522.0 44679.3 45148.5 45787.8 45946.3 38068.4 39816.8 39901.0 40316.6 40753.8 29464.3 31761.7 31802.0 31819.4 32434.4 14:32:13 INFO - PROCESS | 929 | 14:32:13 INFO - PROCESS | 929 | [#2] jquery.html Cycles:180 Average:31210.84 Median:23738.44 stddev:90324.03 (380.5%) stddev-sans-first:90547.77 14:32:13 INFO - PROCESS | 929 | Values: 392.9 407.6 421.0 423.1 431.6 15065.5 20396.8 21038.6 21042.6 21917.8 19625.8 19684.9 19696.2 19704.4 19904.5 5788.1 5809.2 5945.0 5978.9 5992.1 5897.5 5970.4 5978.2 5981.4 5982.1 5740.3 5869.2 5918.1 5937.3 5954.8 5747.8 5837.1 5916.9 5953.0 5959.9 5290.3 5326.5 5331.3 5333.6 5335.1 22547.2 23230.2 23351.9 24125.0 24670.1 2546.7 3092.6 3148.7 3152.8 3189.4 10440.8 10576.2 10750.8 10752.2 12230.6 1963.8 2006.5 2010.2 2047.5 2101.8 64988.7 74643.6 80528.6 84098.0 88087.0 11208.8 11553.8 11579.4 11643.8 11699.2 16137.4 16227.6 16256.4 16257.0 16260.5 8651.4 8935.3 8985.4 9022.4 9052.5 8636.9 8863.2 8872.3 9064.5 9076.8 4987.5 5069.9 5093.2 5098.4 5205.4 2011.9 2050.7 2053.0 2082.5 2091.3 964.8 1017.3 1040.4 1073.8 1116.2 20581.6 20841.8 21009.5 21031.5 21138.7 1036.4 1058.6 1059.0 1086.7 1086.8 10964.6 11619.7 11680.2 11840.1 11914.6 541926.0 544661.0 545627.0 546918.0 569043.0 90394.0 92523.5 93345.5 94003.0 94100.0 1034.4 1055.5 1070.4 1074.9 1075.4 18087.5 18401.4 18425.4 18558.5 18809.8 44184.3 44691.3 45207.5 45299.5 46022.3 61892.4 62542.0 63059.7 64227.4 64284.4 8079.8 8436.6 8673.3 8682.3 8735.1 13259.3 13624.2 14223.9 14447.1 14472.3 11642.4 11957.4 12222.4 12327.4 12538.4 11885.6 12092.2 12098.0 12444.6 12728.7 12070.0 12099.9 12386.8 12634.8 12869.1 8252.2 8544.2 8658.7 8744.6 8745.2 8968.2 11353.1 11368.3 11474.8 11566.8 14:32:13 INFO - PROCESS | 929 | 14:32:13 INFO - PROCESS | 929 | [#3] mootools.html Cycles:180 Average:6101.86 Median:4493.28 stddev:3512.87 (78.2%) stddev-sans-first:3504.89 14:32:13 INFO - PROCESS | 929 | Values: 1391.3 1466.4 1536.5 1670.6 1834.8 8617.7 9147.6 9200.0 9450.5 9466.6 9238.7 9355.1 9368.1 9380.2 9388.3 2396.2 2435.0 2435.6 2436.6 2440.8 2394.3 2416.6 2436.8 2443.9 2445.2 2379.7 2386.4 2391.4 2433.2 2435.6 2337.5 2356.5 2366.3 2368.2 2388.9 2295.6 2306.5 2311.7 2313.6 2314.9 8935.1 9235.6 9295.1 9352.5 9400.2 5249.4 5457.4 5458.3 5461.4 5463.3 3442.3 3486.1 3499.2 3561.7 3565.6 1152.5 1158.6 1161.1 1163.7 1166.5 13072.2 13113.9 13174.8 13186.7 13190.1 4386.4 4586.3 4613.6 4722.9 4734.8 10260.2 10264.0 10335.3 10340.2 10346.2 5406.6 5406.8 5410.6 5413.9 5415.5 4469.3 4576.6 4771.1 4804.8 5071.0 4092.8 4095.2 4209.3 4231.1 4232.5 1269.6 1284.4 1287.8 1295.0 1306.3 965.6 1017.9 1022.6 1044.2 1059.0 6337.4 6488.1 6550.7 6584.7 6765.0 439.1 447.0 455.8 1066.2 1140.1 4479.4 4507.2 4584.9 4737.1 4799.5 11465.8 11482.0 11642.9 11790.4 11829.5 11608.8 11726.2 11808.1 11846.9 11850.4 8431.1 8587.5 8663.0 8668.5 8689.4 6294.7 6414.9 6457.0 6474.5 6479.7 10236.2 10465.4 10567.0 10623.4 10735.8 10599.7 10658.0 10725.1 10855.3 10917.7 7492.3 8053.3 8089.8 8272.6 8285.4 9113.9 9130.0 9192.6 9196.1 9209.4 7933.8 7965.4 7969.0 8095.5 8186.9 8019.3 8034.6 8096.2 8150.6 8190.8 7548.1 7571.7 7615.4 7624.5 7703.2 5229.5 5306.9 5396.7 5397.5 5508.1 5555.3 5595.0 5741.4 5743.1 5812.3 14:32:13 INFO - PROCESS | 929 | 14:32:13 INFO - PROCESS | 929 | [#4] prototype.html Cycles:180 Average:4711.74 Median:5276.54 stddev:1376.23 (26.1%) stddev-sans-first:1340.58 14:32:13 INFO - PROCESS | 929 | Values: 349.4 354.1 355.5 359.3 361.4 5425.3 5484.6 5504.5 5504.9 5514.9 5375.0 5406.9 5408.8 5417.2 5441.9 3818.2 4029.5 4109.6 4247.9 4292.6 4266.5 4271.0 4286.8 4291.7 4293.1 4270.3 4278.6 4281.2 4283.3 4283.7 4051.7 4079.5 4080.6 4191.9 4280.4 3809.5 3811.9 3821.2 3824.9 3834.3 5225.1 5253.9 5259.9 5262.3 5290.8 4790.0 4829.5 4866.1 4888.4 4892.8 5608.9 5615.1 5617.5 5632.0 5637.0 4187.4 4188.8 4193.5 4194.4 4197.9 5534.2 5640.4 5687.2 5842.2 5900.4 4243.3 4248.6 4256.6 4264.8 4269.0 4318.9 4322.4 4322.6 4327.9 4328.5 4596.3 4864.7 4871.2 4871.3 4877.2 5652.0 5661.6 5672.6 5686.6 5714.8 1456.6 1461.9 1478.9 1481.9 1490.2 907.6 947.2 948.8 952.8 956.3 4847.8 4884.2 4929.5 4932.4 4953.4 5102.3 5157.7 5160.9 5171.0 5226.3 4653.7 4849.9 4875.8 4915.5 4937.8 2730.9 2732.8 2736.3 2742.3 2743.3 5301.3 5318.9 5335.2 5338.5 5350.6 5231.8 5244.5 5543.9 5963.4 6009.5 6063.9 6093.8 6099.6 6106.2 6108.7 5554.0 5861.5 5876.7 5896.6 5899.5 5753.4 5817.9 5832.1 5832.5 5851.3 5831.4 5950.7 6026.8 6057.5 6089.8 4202.3 4240.1 4241.2 4241.4 4243.1 5661.7 5851.6 5855.5 5857.2 5876.7 5701.4 5704.4 5723.5 5726.2 5732.5 5636.7 5665.7 5680.2 5691.5 5696.8 5482.0 5603.7 5619.2 5641.4 5643.6 5680.6 5699.4 5700.2 5702.9 5705.8 5535.2 5551.9 5582.2 5583.7 5585.2 14:32:13 INFO - PROCESS | 929 | 14:32:13 INFO - PROCESS | 929 | [#5] yui.html Cycles:180 Average:21465.68 Median:3877.28 stddev:29965.22 (772.8%) stddev-sans-first:30009.16 14:32:13 INFO - PROCESS | 929 | Values: 814.1 817.7 838.9 883.2 903.1 15581.7 16301.9 16306.4 16316.0 16332.1 15370.6 15523.4 15568.7 15881.1 16148.3 730.2 865.3 892.2 893.9 898.6 885.3 888.5 889.2 889.3 889.5 946.4 949.1 949.3 950.2 950.5 773.6 794.4 797.4 922.1 944.4 680.8 682.3 683.1 685.3 685.5 16656.6 16714.1 16797.3 16822.4 16847.2 16542.9 16734.1 16758.9 16870.9 17160.4 12976.2 12999.3 13180.3 13470.9 13475.7 4152.2 4158.3 4163.6 4204.0 4259.6 75847.0 79158.2 83858.0 84295.0 84624.6 12832.4 12917.9 12989.4 13147.4 13260.1 23109.5 24218.2 24301.9 24509.6 24591.9 9468.5 9544.8 9545.8 9620.2 9813.1 4613.3 5035.2 5156.8 5181.2 5378.6 3721.6 3815.9 3876.0 3878.6 3881.0 609.9 612.7 618.6 628.0 630.1 639.2 657.5 673.6 681.0 692.5 9842.7 10211.5 10375.8 10522.7 10869.2 700.8 717.9 723.8 740.2 760.4 4653.4 4911.1 4920.3 4951.4 4979.0 101406.0 103718.5 107704.5 138464.0 141022.0 88933.6 101844.5 102639.0 104214.5 105018.0 54462.7 54916.5 58457.0 84254.2 86778.0 10018.7 10384.6 10708.9 10728.3 10987.7 52003.0 52138.0 53055.7 53468.5 53813.0 72661.0 74069.0 79754.0 81328.6 81655.2 13882.3 13955.4 14129.4 14353.0 14562.1 16173.7 16300.2 16303.6 16331.9 17129.4 14435.3 14745.3 15263.2 15455.8 15663.6 14775.3 15203.0 15662.4 15741.7 16245.9 14855.2 14940.3 14951.9 15171.0 15472.5 12303.7 12895.2 13158.9 13230.3 13256.5 12782.6 12798.7 12808.7 12941.2 13173.9 14:32:13 INFO - PROCESS | 929 | -------- Summary: end -------- 14:32:13 INFO - PROCESS | 929 | 14:32:13 INFO - TEST-INFO | 929: exit 0 14:32:13 INFO - TEST-OK | dromaeo_css | took 1113452ms 14:32:13 INFO - TEST-START | kraken 14:32:13 INFO - operating with platform_type : linux_ 14:32:13 INFO - Initialising browser for kraken test... 14:32:13 INFO - TEST-INFO | started process 1208 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDuKqWV/profile http://localhost:57666/getInfo.html) 14:32:17 INFO - PROCESS | 1208 | __metrics Screen width/height:1600/1200 14:32:17 INFO - PROCESS | 1208 | colorDepth:24 14:32:17 INFO - PROCESS | 1208 | Browser inner width/height: 1024/697 14:32:17 INFO - PROCESS | 1208 | __metrics 14:32:18 INFO - PROCESS | 1208 | 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:32:18 INFO - PROCESS | 1208 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:32:19 INFO - TEST-INFO | 1208: exit 0 14:32:19 INFO - Browser initialized. 14:32:19 INFO - Running cycle 1/1 for kraken test... 14:32:19 INFO - TEST-INFO | started process 1308 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDuKqWV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:32:31 INFO - PROCESS | 1308 | RSS: Main: 145444864 14:32:31 INFO - PROCESS | 1308 | 14:32:33 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57666/tests/kraken/driver.html?testName=audio-beat-detection) 14:32:34 INFO - PROCESS | 1308 | RSS: Main: 191148032 14:32:34 INFO - PROCESS | 1308 | 14:32:37 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57666/tests/kraken/driver.html?testName=audio-dft) 14:32:37 INFO - PROCESS | 1308 | RSS: Main: 181268480 14:32:37 INFO - PROCESS | 1308 | 14:32:40 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57666/tests/kraken/driver.html?testName=audio-fft) 14:32:41 INFO - PROCESS | 1308 | RSS: Main: 269983744 14:32:41 INFO - PROCESS | 1308 | 14:32:43 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57666/tests/kraken/driver.html?testName=audio-oscillator) 14:32:43 INFO - PROCESS | 1308 | RSS: Main: 175312896 14:32:43 INFO - PROCESS | 1308 | 14:32:45 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57666/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:32:46 INFO - PROCESS | 1308 | RSS: Main: 177192960 14:32:46 INFO - PROCESS | 1308 | 14:32:49 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57666/tests/kraken/driver.html?testName=imaging-darkroom) 14:32:50 INFO - PROCESS | 1308 | RSS: Main: 212647936 14:32:50 INFO - PROCESS | 1308 | 14:32:53 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57666/tests/kraken/driver.html?testName=imaging-desaturate) 14:32:54 INFO - PROCESS | 1308 | RSS: Main: 212074496 14:32:54 INFO - PROCESS | 1308 | 14:32:56 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57666/tests/kraken/driver.html?testName=json-parse-financial) 14:32:57 INFO - PROCESS | 1308 | RSS: Main: 211394560 14:32:57 INFO - PROCESS | 1308 | 14:32:58 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57666/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:32:59 INFO - PROCESS | 1308 | RSS: Main: 175771648 14:32:59 INFO - PROCESS | 1308 | 14:33:01 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57666/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:33:01 INFO - PROCESS | 1308 | RSS: Main: 182423552 14:33:01 INFO - PROCESS | 1308 | 14:33:03 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57666/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:33:04 INFO - PROCESS | 1308 | RSS: Main: 186650624 14:33:04 INFO - PROCESS | 1308 | 14:33:07 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57666/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:33:07 INFO - PROCESS | 1308 | RSS: Main: 180604928 14:33:07 INFO - PROCESS | 1308 | 14:33:10 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57666/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:33:10 INFO - PROCESS | 1308 | RSS: Main: 184238080 14:33:10 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | Cycle 1(1): loaded http://localhost:57666/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57666/tests/kraken/driver.html?testName=ai-astar) 14:33:12 INFO - PROCESS | 1308 | RSS: Main: 235352064 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | __start_tp_report 14:33:12 INFO - PROCESS | 1308 | _x_x_mozilla_page_load 14:33:12 INFO - PROCESS | 1308 | _x_x_mozilla_page_load_details 14:33:12 INFO - PROCESS | 1308 | |i|pagename|runs| 14:33:12 INFO - PROCESS | 1308 | |0;ai-astar;103;111;108;110;110;109;108;111;110;108 14:33:12 INFO - PROCESS | 1308 | |1;audio-beat-detection;148;116;117;179;120;118;149;116;163;115 14:33:12 INFO - PROCESS | 1308 | |2;audio-dft;161;161;162;160;163;162;160;160;160;160 14:33:12 INFO - PROCESS | 1308 | |3;audio-fft;75;75;75;114;74;75;75;76;101;76 14:33:12 INFO - PROCESS | 1308 | |4;audio-oscillator;85;84;132;84;85;85;119;83;84;120 14:33:12 INFO - PROCESS | 1308 | |5;imaging-gaussian-blur;116;117;116;117;118;117;117;117;116;116 14:33:12 INFO - PROCESS | 1308 | |6;imaging-darkroom;146;146;146;145;146;146;146;146;147;146 14:33:12 INFO - PROCESS | 1308 | |7;imaging-desaturate;95;95;94;95;95;95;96;95;96;96 14:33:12 INFO - PROCESS | 1308 | |8;json-parse-financial;52;49;49;50;49;48;51;50;51;50 14:33:12 INFO - PROCESS | 1308 | |9;json-stringify-tinderbox;61;60;60;60;60;59;60;60;61;59 14:33:12 INFO - PROCESS | 1308 | |10;stanford-crypto-aes;73;87;73;73;72;82;72;72;74;72 14:33:12 INFO - PROCESS | 1308 | |11;stanford-crypto-ccm;117;129;116;119;116;125;117;118;190;130 14:33:12 INFO - PROCESS | 1308 | |12;stanford-crypto-pbkdf2;152;150;150;149;149;152;155;155;151;154 14:33:12 INFO - PROCESS | 1308 | |13;stanford-crypto-sha256-iterative;62;61;61;62;62;61;62;61;61;59 14:33:12 INFO - PROCESS | 1308 | __end_tp_report 14:33:12 INFO - PROCESS | 1308 | __start_cc_report 14:33:12 INFO - PROCESS | 1308 | _x_x_mozilla_cycle_collect,1629 14:33:12 INFO - PROCESS | 1308 | __end_cc_report 14:33:12 INFO - PROCESS | 1308 | __startTimestamp1472851992795__endTimestamp 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | ------- Summary: start ------- 14:33:12 INFO - PROCESS | 1308 | Number of tests: 14 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#0] ai-astar Cycles:10 Average:108.80 Median:110.00 stddev:2.35 (2.1%) stddev-sans-first:1.24 14:33:12 INFO - PROCESS | 1308 | Values: 103.0 111.0 108.0 110.0 110.0 109.0 108.0 111.0 110.0 108.0 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:134.00 stddev:23.66 (17.7%) stddev-sans-first:24.55 14:33:12 INFO - PROCESS | 1308 | Values: 148.0 116.0 117.0 179.0 120.0 118.0 149.0 116.0 163.0 115.0 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 14:33:12 INFO - PROCESS | 1308 | Values: 161.0 161.0 162.0 160.0 163.0 162.0 160.0 160.0 160.0 160.0 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#3] audio-fft Cycles:10 Average:81.60 Median:75.00 stddev:14.00 (18.7%) stddev-sans-first:14.65 14:33:12 INFO - PROCESS | 1308 | Values: 75.0 75.0 75.0 114.0 74.0 75.0 75.0 76.0 101.0 76.0 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:84.00 stddev:19.34 (23.0%) stddev-sans-first:20.09 14:33:12 INFO - PROCESS | 1308 | Values: 85.0 84.0 132.0 84.0 85.0 85.0 119.0 83.0 84.0 120.0 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 14:33:12 INFO - PROCESS | 1308 | Values: 116.0 117.0 116.0 117.0 118.0 117.0 117.0 117.0 116.0 116.0 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 14:33:12 INFO - PROCESS | 1308 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 147.0 146.0 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 14:33:12 INFO - PROCESS | 1308 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 96.0 95.0 96.0 96.0 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#8] json-parse-financial Cycles:10 Average:49.90 Median:50.00 stddev:1.20 (2.4%) stddev-sans-first:1.00 14:33:12 INFO - PROCESS | 1308 | Values: 52.0 49.0 49.0 50.0 49.0 48.0 51.0 50.0 51.0 50.0 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.67 (1.1%) stddev-sans-first:0.60 14:33:12 INFO - PROCESS | 1308 | Values: 61.0 60.0 60.0 60.0 60.0 59.0 60.0 60.0 61.0 59.0 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:73.00 stddev:5.19 (7.1%) stddev-sans-first:5.45 14:33:12 INFO - PROCESS | 1308 | Values: 73.0 87.0 73.0 73.0 72.0 82.0 72.0 72.0 74.0 72.0 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:122.00 stddev:22.53 (18.5%) stddev-sans-first:23.56 14:33:12 INFO - PROCESS | 1308 | Values: 117.0 129.0 116.0 119.0 116.0 125.0 117.0 118.0 190.0 130.0 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.70 Median:152.00 stddev:2.31 (1.5%) stddev-sans-first:2.45 14:33:12 INFO - PROCESS | 1308 | Values: 152.0 150.0 150.0 149.0 149.0 152.0 155.0 155.0 151.0 154.0 14:33:12 INFO - PROCESS | 1308 | 14:33:12 INFO - PROCESS | 1308 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.50 stddev:0.92 (1.5%) stddev-sans-first:0.93 14:33:12 INFO - PROCESS | 1308 | Values: 62.0 61.0 61.0 62.0 62.0 61.0 62.0 61.0 61.0 59.0 14:33:12 INFO - PROCESS | 1308 | -------- Summary: end -------- 14:33:12 INFO - PROCESS | 1308 | 14:33:13 INFO - TEST-INFO | 1308: exit 0 14:33:13 INFO - TEST-OK | kraken | took 59912ms 14:33:13 INFO - SUITE-END | took 1173s 14:33:13 INFO - Completed test suite (00:19:33) 14:33:13 INFO - javascript benchmark 14:33:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3200.5839240898686, 3228.6287076986123, 3234.7897386669856, 3246.5617141685866, 3272.2800983783573, 5578.832635020951, 5635.718214089299, 5658.179563963228, 5663.490285874275, 5670.68811215383, 5605.327360716709, 5672.914906276409, 5693.146028095788, 5700.458456446641, 5723.341815691197, 4925.694690906895, 5001.374828146481, 5078.59371250301, 5112.795488180487, 5159.303494028314, 5015.0972824891505, 5037.974810125944, 5040.6471546991725, 5094.083065048289, 5136.203888397305, 5105.795768169269, 5134.717590532519, 5148.099082660538, 5159.458256883029, 5249.947500524984, 5198.428172900997, 5200.843974680728, 5208.973955130238, 5210.296609957683, 5239.056969745444, 4897.975510122426, 4946.802127914879, 4957.330760347343, 5108.386993560796, 5114.514121158484, 5513.558915286808, 5566.081596536607, 5566.944330556683, 5575.804846830382, 5588.944110558883, 5960.284765828085, 5968.313643930916, 5987.1318658794235, 5997.22036135304, 6000.609960352563, 5811.418858114154, 5964.075568286922, 5966.552508561876, 6020.397960203988, 6070.332263451017, 5663.122216056518, 5665.348484924204, 5665.886682266331, 5668.31980162379, 5669.574781891375, 6176.227971503562, 6181.443670069625, 6182.072689096672, 6183.041628547582, 6186.659733714689, 5709.42905709434, 5815.912761308584, 5876.794312199053, 5877.647341159544, 5967.492763115184, 5993.4905533029305, 6025.879482410415, 6028.186194863725, 6030.728617212236, 6034.758609655652, 5926.170336152942, 5933.436323549256, 5992.101184822312, 5995.61028533144, 5995.790147344822, 5782.508486778587, 5847.736851841677, 5895.0, 5942.257217847769, 5970.223870896755, 4612.238980568236, 4616.030633566988, 4618.49196588375, 4632.328312394684, 4645.2103142465185, 4110.034141976633, 4113.91772164555, 4115.9588404116175, 4124.360724087771, 4135.793210339471, 5570.220169176318, 5615.943840561624, 5675.943240567624, 5740.913886291709, 5741.224934633855, 6015.0977353396465, 6021.909671354933, 6022.066579680157, 6044.546659000504, 6064.272287325549, 5657.2928383952, 5701.914471282934, 5703.258576385043, 5704.857378565486, 5710.771569137271, 4913.70517768935, 4926.310316555685, 4974.1543937530705, 5030.446650868402, 5041.697498150123, 6203.875922481525, 6220.72006759706, 6228.875422491434, 6229.252489701174, 6231.0, 5900.7049647518315, 5950.0, 5952.970235148797, 5964.612300200473, 5997.950358687125, 6009.978303688471, 6119.357467465852, 6142.1304578115505, 6217.502599792097, 6228.5951413158, 5925.377835327228, 5936.732847021981, 5938.8218353449465, 5966.880662386815, 5972.58191926561, 5983.730732117152, 6046.697665116814, 6051.636901785907, 6056.182415373868, 6061.454469097626, 5726.570507211893, 5779.653220806765, 5835.241418615553, 5923.7926672566255, 5933.821985340447, 5863.149843272787, 5867.354590995073, 5870.55970802183, 5897.144913987534, 5912.349641539513, 5818.592698511064, 5830.096335068071, 5832.300123984979, 5833.416658334133, 5835.299764028174, 5675.489205971482, 5748.252727145445, 5817.185594016756, 5837.562182836219, 5954.10688396744, 5763.884722305615, 5764.164196191613, 5777.248957635648, 5798.623089499169, 5806.390329015392], "value": 5524.186927066407, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.0732457366508, 628.4187126907609, 643.6942452335142, 653.5817077070674, 654.8559316950269, 37024.0, 44585.55414445855, 45268.32097518536, 45295.77352113243, 48436.51563484373, 48853.26720099201, 49071.26393104097, 49145.50854491463, 49207.50792492074, 49385.0, 8252.546109963949, 9138.131877471646, 9232.67685631003, 9286.674966376178, 10752.354858708502, 10778.245522813406, 11211.159163062763, 11211.831822522669, 11218.158638102133, 11219.15856310776, 11202.215844890861, 11262.380569068739, 11269.154813388988, 11271.549138034468, 11273.54905803771, 11191.384473853936, 11222.66332010041, 11248.55005799767, 11250.887491125066, 11258.380789056599, 11033.337999720043, 11085.113190944705, 11097.3896435696, 11107.944460277648, 11110.666679999613, 46577.30134047992, 47146.76426617879, 47151.528484715054, 47208.76395618, 47219.76390118062, 3824.9235015299814, 3827.9043023924346, 4008.2785098682225, 4307.310830267143, 4556.248219043854, 34942.0, 35312.47031294533, 35460.29079418423, 36842.631573684455, 36889.0, 4558.22510173268, 4643.907121857544, 4653.720776753372, 4671.85984420468, 4804.567588917014, 371372.0, 371938.0, 373536.0, 373555.0, 374463.99999999726, 37616.0, 38070.809645951595, 65287.67356163284, 65579.0, 65705.0, 40065.1986960265, 40384.79807601002, 40459.1908161841, 40487.392689109685, 40519.18961620751, 17079.0, 17267.136643167792, 17293.913530432517, 17307.826921730622, 17330.82669173292, 12655.746885062246, 12857.807132893015, 12905.35473226335, 12911.096223264281, 14705.41178352854, 8932.821343573221, 9070.410423322463, 9180.678676246433, 9192.218661413855, 9212.263018958467, 3483.9303213935577, 3484.3205574913154, 3519.8064106473876, 3565.358235517632, 3611.2957973194975, 1063.4629512096342, 1075.419273592267, 1087.429099722652, 1139.9031082357926, 1152.6196355202762, 34735.82632086823, 34874.12814679653, 35140.0, 35395.64604353924, 35440.822795885855, 1186.4364426897155, 1223.7552489501957, 1224.9326287054118, 1243.5771837575087, 1256.2274201366095, 17705.694542292982, 18427.81572184261, 18580.44258672242, 18718.438446846616, 18758.1558829853, 677008.0, 684127.0, 684999.0, 685109.0, 685370.0, 66438.66780666065, 67039.66480167568, 67101.0, 67106.32893671098, 67117.0, 1311.6392991927296, 1428.8071110399962, 1429.6140042188551, 1481.7110663420528, 1488.895777295579, 33175.33649327048, 35691.46462803008, 35946.4608030885, 36114.27771444609, 36234.637653623126, 56317.155242670575, 56452.43547564472, 56544.71727641336, 56826.7158664204, 56888.0, 449902.0, 453299.0, 453349.0, 454405.0, 456167.0, 30296.242593935327, 31262.37475250437, 31430.528542072345, 32686.182845429055, 32706.83646581752, 53290.0, 53323.733381332844, 53382.199267011805, 53527.19709204287, 53559.19661205007, 43689.27216007403, 44951.550484494735, 45066.77466612646, 46017.53982460133, 46823.76588117037, 44417.0, 45786.0, 45802.54197458116, 46608.766956165004, 46812.29781553211, 44522.0, 44679.32981005222, 45148.548514514434, 45787.77106114448, 45946.31080533728, 38068.42897356486, 39816.800915995234, 39901.0, 40316.59683403128, 40753.79623101984, 29464.26339341448, 31761.682383176798, 31802.0, 31819.363612727153, 32434.35131297408], "value": 21012.313942191686, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.8664254153587, 407.594443528689, 420.9663226941845, 423.0861339506665, 431.58352190136515, 15065.472708455207, 20396.79603203971, 21038.57922841544, 21042.579148417022, 21917.780822191773, 19625.803741962576, 19684.90157549214, 19696.212151513922, 19704.40886773399, 19904.50238744032, 5788.102844059177, 5809.186713860063, 5944.970275148618, 5978.8804223915495, 5992.131140984555, 5897.4692277695, 5970.373110823366, 5978.163057171978, 5981.371955944628, 5982.072778719284, 5740.253767010283, 5869.178315035899, 5918.082697181943, 5937.346891841894, 5954.821355359346, 5747.827565173052, 5837.124431335291, 5916.940830591703, 5953.047512398017, 5959.851003724898, 5290.285811415448, 5326.493983071602, 5331.306930099062, 5333.599980001477, 5335.1197052486295, 22547.21084762042, 23230.186943457065, 23351.88324058386, 24125.0, 24670.13654522083, 2546.745325467457, 3092.597962264891, 3148.7481001520055, 3152.7950683205518, 3189.40995915754, 10440.791184176274, 10576.153907687367, 10750.784984300348, 10752.247342686014, 12230.571929982409, 1963.803619638039, 2006.4783156379447, 2010.1657812008, 2047.5085979364844, 2101.7688054314012, 64988.67505662489, 74643.62678186574, 80528.59735701284, 84098.0, 88087.0, 11208.775824483628, 11553.826692599618, 11579.421028948518, 11643.76712465746, 11699.181057325906, 16137.354505819636, 16227.594310142342, 16256.43102491407, 16257.024578525326, 16260.51218463448, 8651.394402391772, 8935.273200090603, 8985.415947963362, 9022.413543119676, 9052.502112383881, 8636.870446943176, 8863.2466240369, 8872.334574906909, 9064.50145242018, 9076.818463630823, 4987.501249874984, 5069.898602027939, 5093.185090385595, 5098.413682426494, 5205.375354957429, 2011.9295824646067, 2050.6718924971933, 2053.0248132137117, 2082.5314304281414, 2091.309867743641, 964.8456247000446, 1017.2574020964737, 1040.3549799124592, 1073.8335557988523, 1116.1760745314368, 20581.58836823255, 20841.79158208429, 21009.474763131046, 21031.474213144793, 21138.682919756215, 1036.3574583757968, 1058.629479682105, 1058.9735256618646, 1086.6631344283298, 1086.7772106718103, 10964.616238431616, 11619.709507262218, 11680.240784348991, 11840.111991600665, 11914.582989595323, 541926.0, 544661.0, 545627.0, 546918.0, 569043.0, 90394.0, 92523.53738231266, 93345.53327233456, 94003.0, 94100.0, 1034.4155552113182, 1055.4617145255975, 1070.3898777696793, 1074.8656417947757, 1075.4353964168874, 18087.547811304823, 18401.447956561326, 18425.447236582924, 18558.536036599195, 18809.811901880807, 44184.33723494215, 44691.32963005623, 45207.54792452033, 45299.547004530854, 46022.309665354376, 61892.38107618866, 62542.0, 63059.684701576196, 64227.35772642401, 64284.35715642971, 8079.83840323202, 8436.578171091543, 8673.30613550927, 8682.305415566612, 8735.126487351214, 13259.337033148497, 13624.18254904709, 14223.928880355532, 14447.133172009713, 14472.27638618086, 11642.417879105842, 11957.402129893297, 12222.388880556115, 12327.445264962918, 12538.435770390537, 11885.643430697093, 12092.214006089576, 12098.0, 12444.626661200178, 12728.745425091261, 12070.0, 12099.939500302795, 12386.75226495483, 12634.810477842655, 12869.133975573677, 8252.21603947624, 8544.2310192083, 8658.740237792876, 8744.562771861254, 8745.169208925143, 8968.192862642394, 11353.148513861328, 11368.317900925971, 11474.77050459003, 11566.826497602375], "value": 9694.713921523138, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.276536201176, 1466.4061055272614, 1536.5236776599254, 1670.640812225372, 1834.7614810074688, 8617.698380556682, 9147.588358523866, 9199.95400022999, 9450.480223587718, 9466.621335146603, 9238.722838314845, 9355.111264429868, 9368.06319368063, 9380.155785979261, 9388.295877809158, 2396.209250947183, 2434.9513009739794, 2435.57377458944, 2436.561418944589, 2440.8413453125577, 2394.341556072077, 2416.5891798394314, 2436.8172387070954, 2443.9144629937955, 2445.205308274818, 2379.6668466414717, 2386.3914701751028, 2391.4021494626345, 2433.2092070077206, 2435.561598912195, 2337.544178885118, 2356.493353928905, 2366.3492539551676, 2368.218487898998, 2388.9402764931015, 2295.563842869866, 2306.4695120122465, 2311.6763653088583, 2313.6298192289155, 2314.9189778357845, 8935.106489351012, 9235.584398702007, 9295.07049295072, 9352.485613291266, 9400.24798016157, 5249.396319423239, 5457.426970168154, 5458.263134476834, 5461.42655021221, 5463.31708536433, 3442.2599141184764, 3486.058764133695, 3499.1951851074396, 3561.679448849616, 3565.6434356564137, 1152.4698638626242, 1158.594491927827, 1161.1102805601254, 1163.7265242667966, 1166.5042356998326, 13072.150310229805, 13113.934430327787, 13174.802377964339, 13186.67033324165, 13190.142640728322, 4386.407834942306, 4586.334981427674, 4613.584777369986, 4722.929156062662, 4734.834280800155, 10260.179185665129, 10264.0, 10335.276530642783, 10340.172786177087, 10346.172306215485, 5406.594505412031, 5406.756695948692, 5410.621256512007, 5413.891722165535, 5415.539679127318, 4469.329600559877, 4576.633869290449, 4771.141194585008, 4804.8318308859025, 5071.0, 4092.8362865485046, 4095.201435720006, 4209.284421648327, 4231.11146659205, 4232.470941132358, 1269.6127681057367, 1284.3642397013352, 1287.7553264879643, 1295.0481396173805, 1306.3272414706244, 965.5896244096231, 1017.872765904262, 1022.5909636145601, 1044.216837371971, 1058.9576416943237, 6337.397947195102, 6488.124103246096, 6550.737970481223, 6584.703688334036, 6764.966175169191, 439.12833026442485, 446.9620082292976, 455.78805855277454, 1066.1790421375497, 1140.0651465798132, 4479.372887795711, 4507.188706032945, 4584.931226031612, 4737.076270127292, 4799.544043315879, 11465.770684586261, 11481.966623003802, 11642.94178529102, 11790.410479475991, 11829.526818927146, 11608.825867611993, 11726.237794543327, 11808.114391420679, 11846.940765296118, 11850.407479625985, 8431.114732952952, 8587.484750914966, 8663.0, 8668.523231222218, 8689.391742577958, 6294.716737746904, 6414.903776443451, 6457.0, 6474.514411419256, 6479.7408103675325, 10236.232282578687, 10465.372077675363, 10567.0, 10623.415712135598, 10735.838962415413, 10599.682009539727, 10658.0, 10725.141988640735, 10855.348679079281, 10917.672469825919, 7492.250774922464, 8053.31546818527, 8089.8382032360205, 8272.627731752205, 8285.378596605158, 9113.863292050757, 9129.99570047281, 9192.586333615138, 9196.12636799503, 9209.355345125778, 7933.801654958672, 7965.442419030613, 7969.043714754266, 8095.473794203358, 8186.877196841933, 8019.278264956182, 8034.598270086589, 8096.230858068474, 8150.551719655238, 8190.754277371689, 7548.056492938383, 7571.659275332513, 7615.390768738377, 7624.504407213513, 7703.229677032252, 5229.529342359206, 5306.867328316823, 5396.730163491889, 5397.487238712316, 5508.063629182969, 5555.30558680165, 5595.0, 5741.368449470639, 5743.10981797822, 5812.3025236971835], "value": 4819.449924179816, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.35892637011085, 354.0970525160841, 355.5040718198114, 359.25096174476215, 361.4289422712113, 5425.321834770653, 5484.588655850807, 5504.4770746778995, 5504.917426238605, 5514.944850551493, 5374.973125134378, 5406.8918621627545, 5408.8377348679605, 5417.160340147274, 5441.918371224425, 3818.2363527294515, 4029.4761680981433, 4109.568495307994, 4247.87256382309, 4292.570742925714, 4266.466691663542, 4270.97864510677, 4286.849960251392, 4291.6566674665955, 4293.141371725667, 4270.295401258789, 4278.6363159131515, 4281.207976524337, 4283.293256612671, 4283.67872409569, 4051.736637118578, 4079.469668943048, 4080.571539988303, 4191.874243772691, 4280.3793449949735, 3809.4857194278693, 3811.866584669523, 3821.178446633959, 3824.9426258606145, 3834.2523207974455, 5225.059489291926, 5253.947460525423, 5259.86850328741, 5262.289590905217, 5290.788368465295, 4789.952100478986, 4829.517048295177, 4866.075445665348, 4888.364512613373, 4892.755362231874, 5608.859778505529, 5615.101583746622, 5617.466340697627, 5631.971840140773, 5636.971815140898, 4187.413762073312, 4188.832446702128, 4193.454850869398, 4194.391813187101, 4197.91604167918, 5534.1975413565215, 5640.351359593658, 5687.203791469197, 5842.211301474289, 5900.350961394243, 4243.257429949746, 4248.596383343577, 4256.638185754246, 4264.808083636244, 4268.957310426856, 4318.935215971763, 4322.351647252937, 4322.589354011364, 4327.870163895088, 4328.523862375137, 4596.25342369904, 4864.686534635629, 4871.196252618349, 4871.293662419001, 4877.219644856806, 5651.971740141273, 5661.631993920382, 5672.5745569081655, 5686.601937864392, 5714.79998200061, 1456.6139972907188, 1461.883049356049, 1478.9260536973109, 1481.8518148185094, 1490.2250829568563, 907.6433896390308, 947.1617618407621, 948.8244674735196, 952.828490871636, 956.2875657635107, 4847.830325938575, 4884.192848646024, 4929.507049295042, 4932.432770231398, 4953.356063711694, 5102.285680004802, 5157.716325602053, 5160.870978225575, 5170.97414512925, 5226.346706661668, 4653.694110895061, 4849.903001940012, 4875.829345972874, 4915.48387419323, 4937.7531123444405, 2730.904418345388, 2732.8360298381763, 2736.2748871549056, 2742.3281296082587, 2743.2593199835933, 5301.257823904656, 5318.946810531923, 5335.199720041947, 5338.519533241949, 5350.555350055463, 5231.843044708665, 5244.475552444725, 5543.889122217533, 5963.43572263922, 6009.45914867664, 6063.878722425527, 6093.817185484442, 6099.634021958696, 6106.236720409949, 6108.6866323740605, 5554.028045092174, 5861.501772349398, 5876.706164691834, 5896.616719913192, 5899.528037756883, 5753.395893431297, 5817.93463320532, 5832.104566015977, 5832.475077243069, 5851.268591426072, 5831.4168583141345, 5950.672713000827, 6026.819195424145, 6057.545684073624, 6089.817305480843, 4202.348635961431, 4240.109576988867, 4241.172971270573, 4241.38499917504, 4243.130158317599, 5661.716914154358, 5851.561132914963, 5855.473007429352, 5857.238558987305, 5876.676782776817, 5701.372848986691, 5704.3725190228215, 5723.484886360247, 5726.226959360433, 5732.484076433141, 5636.746346414339, 5665.745041473061, 5680.233168522362, 5691.459311365414, 5696.8006119785605, 5481.972590137024, 5603.747831347518, 5619.241402410786, 5641.351244606923, 5643.633163844337, 5680.573956953162, 5699.430056994267, 5700.173474846207, 5702.885942281048, 5705.771769129188, 5535.169724541351, 5551.8889622206525, 5582.246396736551, 5583.74873130702, 5585.245991791056], "value": 4278.497183863756, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [814.1126172472008, 817.6647574494458, 838.9412741108125, 883.2227639677085, 903.0743487924879, 15581.688366232669, 16301.918490407532, 16306.429274975379, 16315.999999999985, 16332.101734404603, 15370.615734606612, 15523.379064837392, 15568.68862622747, 15881.126538040404, 16148.273327700252, 730.1894896664697, 865.2602025268368, 892.2282225874604, 893.8703887936246, 898.5731777405713, 885.340421386065, 888.5290795878195, 889.1997202517754, 889.3152272749978, 889.5285498685707, 946.432140715572, 949.0841338108723, 949.3402085550545, 950.2493030505892, 950.5294879034888, 773.5822655765879, 794.4161041634394, 797.3860127701695, 922.0733163171037, 944.36255527519, 680.8365992161895, 682.3244987462426, 683.0676127086491, 685.3044160177424, 685.468761709674, 16656.58358541034, 16714.08072556009, 16797.328106875833, 16822.411215607393, 16847.157642117967, 16542.917285413616, 16734.079625620587, 16758.916205419016, 16870.915645421817, 17160.39938602143, 12976.15654982423, 12999.285039322833, 13180.34098295091, 13470.86529134706, 13475.66310842227, 4152.231837110145, 4158.313878210122, 4163.646090082347, 4203.957960420387, 4259.5740425957465, 75846.96612135509, 79158.20841791508, 83858.0, 84295.0, 84624.57687711583, 12832.422540985492, 12917.870821291666, 12989.41547630359, 13147.408366623526, 13260.071794974452, 23109.53780924372, 24218.152364667152, 24301.87849060779, 24509.63235551468, 24591.87704061504, 9468.526573671288, 9544.761380965392, 9545.809083818423, 9620.182284505756, 9813.051498080547, 4613.331066995267, 5035.244713292962, 5156.767945442464, 5181.171012637959, 5378.569714422837, 3721.5720192177705, 3815.9427608585893, 3875.980620096938, 3878.5733569307354, 3881.029742564359, 609.9054646529795, 612.6844674992383, 618.5979113576211, 627.9717412716439, 630.0737915264554, 639.2265358915671, 657.4773055420952, 673.5722816011868, 680.9717326836413, 692.5013989927244, 9842.65550705722, 10211.540480678239, 10375.792484150275, 10522.684319470429, 10869.184811139196, 700.7967689370128, 717.9497435179487, 723.8226634474482, 740.2227660955974, 760.4296777416937, 4653.395058642423, 4911.066897289575, 4920.335754673073, 4951.3810773653295, 4978.950210497848, 101406.0, 103718.481407594, 107704.46147769211, 138464.0, 141022.0, 88933.55533222292, 101844.49077754564, 102639.03384523603, 104214.47892760488, 105018.0, 54462.72768636211, 54916.45083549193, 58457.0, 84254.15745842586, 86778.0, 10018.699439016842, 10384.584616615251, 10708.946455267675, 10728.302660327054, 10987.725306867393, 52003.0, 52138.0, 53055.734721326146, 53468.46531534635, 53813.0, 72661.0, 74069.0, 79754.0, 81328.59335703283, 81655.18344816475, 13882.305884705522, 13955.441782328593, 14129.364178612193, 14352.990581318832, 14562.053466525065, 16173.67652646964, 16300.184990750653, 16303.59241018984, 16331.918340408698, 17129.400470983455, 14435.278236088363, 14745.26273686333, 15263.23683815827, 15455.768163477333, 15663.608409789846, 14775.261236938326, 15203.0, 15662.37350505967, 15741.68516629638, 16245.918770406071, 14855.182964937032, 14940.327685253973, 14951.850481495047, 15171.0, 15472.53582392485, 12303.692407689521, 12895.16181448203, 13158.934205328913, 13230.279834981704, 13256.469741210629, 12782.552610658582, 12798.68003299925, 12808.74382512326, 12941.223526588436, 13173.93413032967], "value": 8098.780254694583, "unit": "score"}], "name": "dromaeo_css", "value": 7568.417834863433, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 108.0, 110.0, 110.0, 109.0, 108.0, 111.0, 110.0, 108.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 117.0, 179.0, 120.0, 118.0, 149.0, 116.0, 163.0, 115.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 160.0, 163.0, 162.0, 160.0, 160.0, 160.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 114.0, 74.0, 75.0, 75.0, 76.0, 101.0, 76.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 132.0, 84.0, 85.0, 85.0, 119.0, 83.0, 84.0, 120.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 118.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 49.0, 50.0, 49.0, 48.0, 51.0, 50.0, 51.0, 50.0], "value": 49.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 60.0, 60.0, 60.0, 59.0, 60.0, 60.0, 61.0, 59.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 87.0, 73.0, 73.0, 72.0, 82.0, 72.0, 72.0, 74.0, 72.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 129.0, 116.0, 119.0, 116.0, 125.0, 117.0, 118.0, 190.0, 130.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 150.0, 150.0, 149.0, 149.0, 152.0, 155.0, 155.0, 151.0, 154.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 62.0, 62.0, 61.0, 62.0, 61.0, 61.0, 59.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1464.9000000000003, "alertThreshold": 2.0}]} 14:33:14 INFO - Return code: 0 14:33:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:33:14 INFO - # TBPL SUCCESS # 14:33:14 INFO - Running post-action listener: _resource_record_post_action 14:33:14 INFO - [mozharness: 2016-09-02 21:33:14.108766Z] Finished run-tests step (success) 14:33:14 INFO - Running post-run listener: _resource_record_post_run 14:33:14 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 329265152; Read time: 12; Write time: 200968 14:33:14 INFO - TinderboxPrint: CPU usage
12.9% 14:33:14 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 14:33:14 INFO - TinderboxPrint: I/O write bytes / time
329,265,152 / 200,968 14:33:14 INFO - TinderboxPrint: CPU idle
8,267.3 (87.0%) 14:33:14 INFO - TinderboxPrint: CPU user
1,185.3 (12.5%) 14:33:14 INFO - TinderboxPrint: Swap in / out
0 / 0 14:33:14 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78774272; Read time: 0; Write time: 45828 14:33:14 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 249954304; Read time: 12; Write time: 155140 14:33:14 INFO - Running post-run listener: _upload_blobber_files 14:33:14 INFO - Blob upload gear active. 14:33:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:33:14 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:33:14 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:33:14 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:33:15 INFO - (blobuploader) - INFO - Open directory for files ... 14:33:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:33:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:33:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:15 INFO - (blobuploader) - INFO - Done attempting. 14:33:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:33:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:33:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:16 INFO - (blobuploader) - INFO - Done attempting. 14:33:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:33:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:33:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:21 INFO - (blobuploader) - INFO - Done attempting. 14:33:21 INFO - (blobuploader) - INFO - Iteration through files over. 14:33:21 INFO - Return code: 0 14:33:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:33:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:33:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d84f0f3b43888d3f34361dcf0752ae4fa968c43bc4f6ab54234bed8a52a00e69f221aa8c018af2123abec7e1e1d4d24e0c7d09cd8c541b6fa3d921c9574172a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d5cf935729e553b4ec047c121df11bbffa4433bb8307307585ed735a8ddfeccf4b0ab6844adbeca7ebd2eeef341459a9849bc9d1760c906187c3dbbb630b6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2b7a22179493d08c74e4e6f56e1be81256b67a36ad0f8e5ca2515043270bf2be5df346663924d1f81ea6dedf245d8be0a2fa66be3d077d11d48a075bc7e7fdf3"} 14:33:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:33:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:33:21 INFO - Contents: 14:33:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d84f0f3b43888d3f34361dcf0752ae4fa968c43bc4f6ab54234bed8a52a00e69f221aa8c018af2123abec7e1e1d4d24e0c7d09cd8c541b6fa3d921c9574172a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d5cf935729e553b4ec047c121df11bbffa4433bb8307307585ed735a8ddfeccf4b0ab6844adbeca7ebd2eeef341459a9849bc9d1760c906187c3dbbb630b6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2b7a22179493d08c74e4e6f56e1be81256b67a36ad0f8e5ca2515043270bf2be5df346663924d1f81ea6dedf245d8be0a2fa66be3d077d11d48a075bc7e7fdf3"} 14:33:21 INFO - Running post-run listener: copy_logs_to_upload_dir 14:33:21 INFO - Copying logs to upload dir... 14:33:21 INFO - mkdir: /builds/slave/test/build/upload/logs 14:33:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.881143 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-02 14:33:20.767317) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 14:33:20.770487) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819 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/6d84f0f3b43888d3f34361dcf0752ae4fa968c43bc4f6ab54234bed8a52a00e69f221aa8c018af2123abec7e1e1d4d24e0c7d09cd8c541b6fa3d921c9574172a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d5cf935729e553b4ec047c121df11bbffa4433bb8307307585ed735a8ddfeccf4b0ab6844adbeca7ebd2eeef341459a9849bc9d1760c906187c3dbbb630b6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2b7a22179493d08c74e4e6f56e1be81256b67a36ad0f8e5ca2515043270bf2be5df346663924d1f81ea6dedf245d8be0a2fa66be3d077d11d48a075bc7e7fdf3"} build_url:https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011344 build_url: 'https://queue.taskcluster.net/v1/task/IU2O5TwdSXGE77QTrRrFYw/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/6d84f0f3b43888d3f34361dcf0752ae4fa968c43bc4f6ab54234bed8a52a00e69f221aa8c018af2123abec7e1e1d4d24e0c7d09cd8c541b6fa3d921c9574172a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d5cf935729e553b4ec047c121df11bbffa4433bb8307307585ed735a8ddfeccf4b0ab6844adbeca7ebd2eeef341459a9849bc9d1760c906187c3dbbb630b6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2b7a22179493d08c74e4e6f56e1be81256b67a36ad0f8e5ca2515043270bf2be5df346663924d1f81ea6dedf245d8be0a2fa66be3d077d11d48a075bc7e7fdf3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 14:33:20.796083) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 14:33:20.796428) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1472850320.165100-1122626819 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004428 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 14:33:20.878928) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 14:33:20.879274) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 14:33:20.879583) ========= ========= Total master_lag: 0.16 =========