builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-065 starttime: 1472920250.84 results: success (0) buildid: 20160903064749 builduid: 412a419a5c4642fe9a81f2791ac162a1 revision: ddd6e3e541f1e406dca4a51fb257e68d3c968644 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:50.838239) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:50.838851) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:50.839132) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:50.868852) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:50.869196) ========= 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=523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-03 09:30:51-- 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% 34.9M=0s 2016-09-03 09:30:51 (34.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089333 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:50.987946) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:50.988232) ========= 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=523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023181 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:51.053382) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:51.053680) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ddd6e3e541f1e406dca4a51fb257e68d3c968644 --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 ddd6e3e541f1e406dca4a51fb257e68d3c968644 --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=523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-03 09:30:51,378 truncating revision to first 12 chars 2016-09-03 09:30:51,378 Setting DEBUG logging. 2016-09-03 09:30:51,378 attempt 1/10 2016-09-03 09:30:51,378 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ddd6e3e541f1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-03 09:30:51,971 unpacking tar archive at: mozilla-aurora-ddd6e3e541f1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.970108 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:52.041821) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:52.042131) ========= script_repo_revision: ddd6e3e541f1e406dca4a51fb257e68d3c968644 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:52.042529) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:52.042791) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 09:30:52.052237) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-03 09:30:52.052643) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:30:52 INFO - MultiFileLogger online at 20160903 09:30:52 in /builds/slave/test 09:30:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:30:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:30:52 INFO - {'append_to_log': False, 09:30:52 INFO - 'base_work_dir': '/builds/slave/test', 09:30:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:30:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:30:52 INFO - 'branch': 'Mozilla-Aurora', 09:30:52 INFO - 'buildbot_json_path': 'buildprops.json', 09:30:52 INFO - 'config_files': ('talos/linux_config.py',), 09:30:52 INFO - 'default_actions': ('clobber', 09:30:52 INFO - 'read-buildbot-config', 09:30:52 INFO - 'download-and-extract', 09:30:52 INFO - 'populate-webroot', 09:30:52 INFO - 'create-virtualenv', 09:30:52 INFO - 'install', 09:30:52 INFO - 'run-tests'), 09:30:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:30:52 INFO - 'download_minidump_stackwalk': True, 09:30:52 INFO - 'download_symbols': 'ondemand', 09:30:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:30:52 INFO - 'tooltool.py': '/tools/tooltool.py', 09:30:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:30:52 INFO - '/tools/misc-python/virtualenv.py')}, 09:30:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:30:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:30:52 INFO - 'installer_path': 'installer.exe', 09:30:52 INFO - 'log_level': 'info', 09:30:52 INFO - 'log_name': 'talos', 09:30:52 INFO - 'log_to_console': True, 09:30:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:30:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:30:52 INFO - 'opt_config_files': (), 09:30:52 INFO - 'pip_index': False, 09:30:52 INFO - 'sps_profile': False, 09:30:52 INFO - 'sps_profile_interval': 0, 09:30:52 INFO - 'suite': 'dromaeojs-e10s', 09:30:52 INFO - 'system_bits': '32', 09:30:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:30:52 INFO - 'title': 'talos-linux64-ix-065', 09:30:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:30:52 INFO - 'use_talos_json': True, 09:30:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:30:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:30:52 INFO - 'work_dir': 'build'} 09:30:52 INFO - [mozharness: 2016-09-03 16:30:52.471038Z] Running clobber step. 09:30:52 INFO - Running pre-action listener: _resource_record_pre_action 09:30:52 INFO - Running main action method: clobber 09:30:52 INFO - rmtree: /builds/slave/test/build 09:30:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:30:52 INFO - Running post-action listener: _resource_record_post_action 09:30:52 INFO - [mozharness: 2016-09-03 16:30:52.924930Z] Finished clobber step (success) 09:30:52 INFO - [mozharness: 2016-09-03 16:30:52.925030Z] Running read-buildbot-config step. 09:30:52 INFO - Running pre-action listener: _resource_record_pre_action 09:30:52 INFO - Running main action method: read_buildbot_config 09:30:52 INFO - Using buildbot properties: 09:30:52 INFO - { 09:30:52 INFO - "project": "", 09:30:52 INFO - "product": "firefox", 09:30:52 INFO - "script_repo_revision": "production", 09:30:52 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 09:30:52 INFO - "repository": "", 09:30:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 09:30:52 INFO - "buildid": "20160903064749", 09:30:52 INFO - "pgo_build": "True", 09:30:52 INFO - "basedir": "/builds/slave/test", 09:30:52 INFO - "buildnumber": 541, 09:30:52 INFO - "slavename": "talos-linux64-ix-065", 09:30:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:30:52 INFO - "platform": "ubuntu64_hw", 09:30:52 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:30:52 INFO - "branch": "mozilla-aurora", 09:30:52 INFO - "repo_path": "releases/mozilla-aurora", 09:30:52 INFO - "revision": "ddd6e3e541f1e406dca4a51fb257e68d3c968644", 09:30:52 INFO - "stage_platform": "linux64-pgo", 09:30:52 INFO - "builduid": "412a419a5c4642fe9a81f2791ac162a1", 09:30:52 INFO - "slavebuilddir": "test" 09:30:52 INFO - } 09:30:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 09:30:52 INFO - Running post-action listener: _resource_record_post_action 09:30:52 INFO - [mozharness: 2016-09-03 16:30:52.927472Z] Finished read-buildbot-config step (success) 09:30:52 INFO - [mozharness: 2016-09-03 16:30:52.927576Z] Running download-and-extract step. 09:30:52 INFO - Running pre-action listener: _resource_record_pre_action 09:30:52 INFO - Running main action method: download_and_extract 09:30:52 INFO - mkdir: /builds/slave/test/build/tests 09:30: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')]} 09:30:52 INFO - https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:30:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:30:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:30:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:30:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:30:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:30:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:30:52 INFO - retry: Failed, sleeping 30 seconds before retrying 09:31:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:31:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:31:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:31:22 INFO - retry: Failed, sleeping 60 seconds before retrying 09:32:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:32:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:32:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:32:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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! 09:32:23 INFO - Caught exception: HTTP Error 404: Not Found 09:32:23 INFO - Caught exception: HTTP Error 404: Not Found 09:32:23 INFO - Caught exception: HTTP Error 404: Not Found 09:32:23 INFO - trying https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:32:23 INFO - Downloading https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:32:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:32:23 INFO - Downloaded 1361 bytes. 09:32:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:32:23 INFO - Using the following test package requirements: 09:32:23 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 09:32:23 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:32:23 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:32:23 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:32:23 INFO - u'jsshell-linux-x86_64.zip'], 09:32:23 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:32:23 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 09:32:23 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:32:23 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:32:23 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:32:23 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:32:23 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:32: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')]} 09:32:23 INFO - https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:32:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:32:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:32:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:32:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:32:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:32:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:32:23 INFO - retry: Failed, sleeping 30 seconds before retrying 09:32:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:32:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:32:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:32:53 INFO - retry: Failed, sleeping 60 seconds before retrying 09:33:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:33:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:33:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:33:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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! 09:33:53 INFO - Caught exception: HTTP Error 404: Not Found 09:33:53 INFO - Caught exception: HTTP Error 404: Not Found 09:33:53 INFO - Caught exception: HTTP Error 404: Not Found 09:33:53 INFO - trying https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:33:53 INFO - Downloading https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:33:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:33:54 INFO - Downloaded 21770170 bytes. 09:33:54 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'] 09:33:54 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 09:33:55 INFO - Return code: 0 09:33:55 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 09:33:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:55 INFO - https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:33:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:33:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:33:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:33:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:33:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:33:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:33:55 INFO - retry: Failed, sleeping 30 seconds before retrying 09:34:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:34:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:34:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:34:25 INFO - retry: Failed, sleeping 60 seconds before retrying 09:35:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:35:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:35:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:35:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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! 09:35:25 INFO - Caught exception: HTTP Error 404: Not Found 09:35:25 INFO - Caught exception: HTTP Error 404: Not Found 09:35:25 INFO - Caught exception: HTTP Error 404: Not Found 09:35:25 INFO - trying https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:35:25 INFO - Downloading https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:35:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:35:26 INFO - Downloaded 21770170 bytes. 09:35:26 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'] 09:35:26 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 09:35:28 INFO - Return code: 0 09:35: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')]} 09:35:28 INFO - https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:35:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 09:35:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 09:35:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:35:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:35:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 09:35:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:35:28 INFO - retry: Failed, sleeping 30 seconds before retrying 09:35:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:35:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 09:35:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:35:58 INFO - retry: Failed, sleeping 60 seconds before retrying 09:36:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:36:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 09:36:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:36:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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! 09:36:58 INFO - Caught exception: HTTP Error 404: Not Found 09:36:58 INFO - Caught exception: HTTP Error 404: Not Found 09:36:58 INFO - Caught exception: HTTP Error 404: Not Found 09:36:58 INFO - trying https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 09:36:58 INFO - Downloading https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:36:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:36:59 INFO - Downloaded 14413740 bytes. 09:36:59 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'] 09:36:59 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 09:36:59 INFO - Return code: 0 09:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:59 INFO - https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:36:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:36:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:36:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:36:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:36:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:36:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:36:59 INFO - retry: Failed, sleeping 30 seconds before retrying 09:37:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:37:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:37:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:37:29 INFO - retry: Failed, sleeping 60 seconds before retrying 09:38:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:38:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:38:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:38:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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! 09:38:29 INFO - Caught exception: HTTP Error 404: Not Found 09:38:29 INFO - Caught exception: HTTP Error 404: Not Found 09:38:29 INFO - Caught exception: HTTP Error 404: Not Found 09:38:29 INFO - trying https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:38:29 INFO - Downloading https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:38:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/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 09:38:31 INFO - Downloaded 63885204 bytes. 09:38:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:38:31 INFO - mkdir: /builds/slave/test/properties 09:38:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:38:31 INFO - Writing to file /builds/slave/test/properties/build_url 09:38:31 INFO - Contents: 09:38:31 INFO - build_url:https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:38:31 INFO - Running post-action listener: _resource_record_post_action 09:38:31 INFO - Running post-action listener: set_extra_try_arguments 09:38:31 INFO - [mozharness: 2016-09-03 16:38:31.758871Z] Finished download-and-extract step (success) 09:38:31 INFO - [mozharness: 2016-09-03 16:38:31.759104Z] Running populate-webroot step. 09:38:31 INFO - Running pre-action listener: _resource_record_pre_action 09:38:31 INFO - Running main action method: populate_webroot 09:38:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:38:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:38:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:38:31 INFO - '--tppagecycles', 09:38:31 INFO - '7'], 09:38:31 INFO - 'tests': ['tsvgm']}}, 09:38:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:38:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:38:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:38:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:38:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:38:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:38:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:31 INFO - 'talos_options': ['--disable-e10s'], 09:38:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:38:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:31 INFO - 'talos_options': ['--disable-e10s'], 09:38:31 INFO - 'tests': ['damp', 'tps']}, 09:38:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:31 INFO - 'tests': ['damp', 'tps']}, 09:38:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:38:31 INFO - 'tests': ['dromaeo_dom']}, 09:38:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:38:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:38:31 INFO - 'tests': ['basic_compositor_video']}, 09:38:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:38:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:38:31 INFO - 'tests': ['a11yr', 09:38:31 INFO - 'ts_paint', 09:38:31 INFO - 'tpaint', 09:38:31 INFO - 'sessionrestore', 09:38:31 INFO - 'sessionrestore_no_auto_restore', 09:38:31 INFO - 'tabpaint']}, 09:38:31 INFO - 'other-e10s': {'tests': ['a11yr', 09:38:31 INFO - 'ts_paint', 09:38:31 INFO - 'tpaint', 09:38:31 INFO - 'sessionrestore', 09:38:31 INFO - 'sessionrestore_no_auto_restore', 09:38:31 INFO - 'tabpaint']}, 09:38:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:38:31 INFO - 'ts_paint', 09:38:31 INFO - 'tpaint', 09:38:31 INFO - 'sessionrestore', 09:38:31 INFO - 'sessionrestore_no_auto_restore', 09:38:31 INFO - 'tabpaint']}, 09:38:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:38:31 INFO - 'ts_paint', 09:38:31 INFO - 'tpaint', 09:38:31 INFO - 'sessionrestore', 09:38:31 INFO - 'sessionrestore_no_auto_restore', 09:38:31 INFO - 'tabpaint']}, 09:38:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:38:31 INFO - 'tests': ['a11yr', 09:38:31 INFO - 'ts_paint', 09:38:31 INFO - 'tpaint', 09:38:31 INFO - 'sessionrestore', 09:38:31 INFO - 'sessionrestore_no_auto_restore', 09:38:31 INFO - 'tabpaint']}, 09:38:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:38:31 INFO - 'tests': ['a11yr', 09:38:31 INFO - 'ts_paint', 09:38:31 INFO - 'tpaint', 09:38:31 INFO - 'sessionrestore', 09:38:31 INFO - 'sessionrestore_no_auto_restore', 09:38:31 INFO - 'tabpaint']}, 09:38:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:38:31 INFO - 'tests': ['tsvgx', 09:38:31 INFO - 'tsvgr_opacity', 09:38:31 INFO - 'tart', 09:38:31 INFO - 'tscrollx', 09:38:31 INFO - 'cart']}, 09:38:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:38:31 INFO - 'tsvgr_opacity', 09:38:31 INFO - 'tart', 09:38:31 INFO - 'tscrollx', 09:38:31 INFO - 'cart']}, 09:38:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:31 INFO - 'talos_options': ['--disable-e10s'], 09:38:31 INFO - 'tests': ['tp5o']}, 09:38:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:31 INFO - 'tests': ['tp5o']}, 09:38:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:31 INFO - 'talos_options': ['--disable-e10s', 09:38:31 INFO - '--xperf_path', 09:38:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:31 INFO - 'tests': ['tp5n']}, 09:38:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:38:31 INFO - 'talos_options': ['--xperf_path', 09:38:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:38:31 INFO - 'tests': ['tp5n']}}, 09:38:31 INFO - 'talos.zip': {'path': '', 09:38:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:38:31 INFO - Running post-action listener: _resource_record_post_action 09:38:31 INFO - [mozharness: 2016-09-03 16:38:31.780429Z] Finished populate-webroot step (success) 09:38:31 INFO - [mozharness: 2016-09-03 16:38:31.780518Z] Running create-virtualenv step. 09:38:31 INFO - Running pre-action listener: _resource_record_pre_action 09:38:31 INFO - Running main action method: create_virtualenv 09:38:31 INFO - Creating virtualenv /builds/slave/test/build/venv 09:38:31 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 09:38:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:38:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:38:31 INFO - Using real prefix '/usr' 09:38:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:38:32 INFO - Installing distribute.............................................................................................................................................................................................done. 09:38:34 INFO - Installing pip.................done. 09:38:34 INFO - Return code: 0 09:38:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:38:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x176a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1999400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a28570>, '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 0x1a29470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a29900>, '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': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', '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 09:38:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:38:34 INFO - Using env: {'DISPLAY': ':0', 09:38:34 INFO - 'HOME': '/home/cltbld', 09:38:34 INFO - 'LANG': 'en_US.UTF-8', 09:38:34 INFO - 'LANGUAGE': 'en_US:en', 09:38:34 INFO - 'LOGNAME': 'cltbld', 09:38:34 INFO - 'MAIL': '/var/mail/cltbld', 09:38:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:34 INFO - 'MOZ_NO_REMOTE': '1', 09:38:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:34 INFO - 'NO_EM_RESTART': '1', 09:38:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:34 INFO - 'PWD': '/builds/slave/test', 09:38:34 INFO - 'SHELL': '/bin/bash', 09:38:34 INFO - 'SHLVL': '1', 09:38:34 INFO - 'TERM': 'linux', 09:38:34 INFO - 'TMOUT': '86400', 09:38:34 INFO - 'USER': 'cltbld', 09:38:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', 09:38:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:34 INFO - '_': '/tools/buildbot/bin/python'} 09:38:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:34 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 09:38:34 INFO - Cleaning up... 09:38:34 INFO - Return code: 0 09:38:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:38:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x176a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1999400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a28570>, '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 0x1a29470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a29900>, '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': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', '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 09:38:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:38:34 INFO - Using env: {'DISPLAY': ':0', 09:38:34 INFO - 'HOME': '/home/cltbld', 09:38:34 INFO - 'LANG': 'en_US.UTF-8', 09:38:34 INFO - 'LANGUAGE': 'en_US:en', 09:38:34 INFO - 'LOGNAME': 'cltbld', 09:38:34 INFO - 'MAIL': '/var/mail/cltbld', 09:38:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:34 INFO - 'MOZ_NO_REMOTE': '1', 09:38:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:34 INFO - 'NO_EM_RESTART': '1', 09:38:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:34 INFO - 'PWD': '/builds/slave/test', 09:38:34 INFO - 'SHELL': '/bin/bash', 09:38:34 INFO - 'SHLVL': '1', 09:38:34 INFO - 'TERM': 'linux', 09:38:34 INFO - 'TMOUT': '86400', 09:38:34 INFO - 'USER': 'cltbld', 09:38:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', 09:38:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:34 INFO - '_': '/tools/buildbot/bin/python'} 09:38:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:34 INFO - Downloading/unpacking psutil>=3.1.1 09:38:34 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 09:38:34 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 09:38:34 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 09:38:34 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 09:38:34 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 09:38:34 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 09:38:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:38:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:36 INFO - Installing collected packages: psutil 09:38:36 INFO - Running setup.py install for psutil 09:38:36 INFO - building 'psutil._psutil_linux' extension 09:38:36 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 09:38:37 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 09:38:37 INFO - building 'psutil._psutil_posix' extension 09:38:37 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 09:38:37 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 09:38:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:37 INFO - Successfully installed psutil 09:38:37 INFO - Cleaning up... 09:38:37 INFO - Return code: 0 09:38:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:38:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:37 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 0x176a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1999400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a28570>, '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 0x1a29470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a29900>, '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': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', '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 09:38:37 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 09:38:37 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 09:38:37 INFO - Using env: {'DISPLAY': ':0', 09:38:37 INFO - 'HOME': '/home/cltbld', 09:38:37 INFO - 'LANG': 'en_US.UTF-8', 09:38:37 INFO - 'LANGUAGE': 'en_US:en', 09:38:37 INFO - 'LOGNAME': 'cltbld', 09:38:37 INFO - 'MAIL': '/var/mail/cltbld', 09:38:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:37 INFO - 'MOZ_NO_REMOTE': '1', 09:38:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:37 INFO - 'NO_EM_RESTART': '1', 09:38:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:37 INFO - 'PWD': '/builds/slave/test', 09:38:37 INFO - 'SHELL': '/bin/bash', 09:38:37 INFO - 'SHLVL': '1', 09:38:37 INFO - 'TERM': 'linux', 09:38:37 INFO - 'TMOUT': '86400', 09:38:37 INFO - 'USER': 'cltbld', 09:38:37 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', 09:38:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:37 INFO - '_': '/tools/buildbot/bin/python'} 09:38:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:38:37 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 09:38:37 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 09:38:37 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 09:38:37 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 09:38:37 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 09:38:37 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 09:38:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:38:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:38:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:38:39 INFO - Installing collected packages: mozsystemmonitor 09:38:39 INFO - Running setup.py install for mozsystemmonitor 09:38:39 INFO - Successfully installed mozsystemmonitor 09:38:39 INFO - Cleaning up... 09:38:39 INFO - Return code: 0 09:38:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:38:39 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')]} 09:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:39 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')]} 09:38:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:39 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 0x176a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1999400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a28570>, '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 0x1a29470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a29900>, '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': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', '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 09:38:39 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 09:38:39 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 09:38:39 INFO - Using env: {'DISPLAY': ':0', 09:38:39 INFO - 'HOME': '/home/cltbld', 09:38:39 INFO - 'LANG': 'en_US.UTF-8', 09:38:39 INFO - 'LANGUAGE': 'en_US:en', 09:38:39 INFO - 'LOGNAME': 'cltbld', 09:38:39 INFO - 'MAIL': '/var/mail/cltbld', 09:38:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:39 INFO - 'MOZ_NO_REMOTE': '1', 09:38:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:39 INFO - 'NO_EM_RESTART': '1', 09:38:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:39 INFO - 'PWD': '/builds/slave/test', 09:38:39 INFO - 'SHELL': '/bin/bash', 09:38:39 INFO - 'SHLVL': '1', 09:38:39 INFO - 'TERM': 'linux', 09:38:39 INFO - 'TMOUT': '86400', 09:38:39 INFO - 'USER': 'cltbld', 09:38:39 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', 09:38:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:39 INFO - '_': '/tools/buildbot/bin/python'} 09:38:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:39 INFO - Downloading/unpacking jsonschema==2.5.1 09:38:39 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 09:38:39 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 09:38:39 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 09:38:39 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 09:38:39 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 09:38:39 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 09:38:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:38:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:42 INFO - Downloading functools32-3.2.3-2.tar.gz 09:38:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:38:42 INFO - warning: no files found matching '*.txt' 09:38:42 INFO - no previously-included directories found matching 'build' 09:38:42 INFO - no previously-included directories found matching 'dist' 09:38:42 INFO - no previously-included directories found matching '.git*' 09:38:42 INFO - Installing collected packages: jsonschema, functools32 09:38:42 INFO - Running setup.py install for functools32 09:38:42 INFO - warning: no files found matching '*.txt' 09:38:42 INFO - no previously-included directories found matching 'build' 09:38:42 INFO - no previously-included directories found matching 'dist' 09:38:42 INFO - no previously-included directories found matching '.git*' 09:38:42 INFO - Successfully installed jsonschema functools32 09:38:42 INFO - Cleaning up... 09:38:42 INFO - Return code: 0 09:38:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:38:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x176a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1999400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a28570>, '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 0x1a29470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a29900>, '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': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', '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 09:38:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:38:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:38:42 INFO - Using env: {'DISPLAY': ':0', 09:38:42 INFO - 'HOME': '/home/cltbld', 09:38:42 INFO - 'LANG': 'en_US.UTF-8', 09:38:42 INFO - 'LANGUAGE': 'en_US:en', 09:38:42 INFO - 'LOGNAME': 'cltbld', 09:38:42 INFO - 'MAIL': '/var/mail/cltbld', 09:38:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:42 INFO - 'MOZ_NO_REMOTE': '1', 09:38:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:42 INFO - 'NO_EM_RESTART': '1', 09:38:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:42 INFO - 'PWD': '/builds/slave/test', 09:38:42 INFO - 'SHELL': '/bin/bash', 09:38:42 INFO - 'SHLVL': '1', 09:38:42 INFO - 'TERM': 'linux', 09:38:42 INFO - 'TMOUT': '86400', 09:38:42 INFO - 'USER': 'cltbld', 09:38:42 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', 09:38:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:42 INFO - '_': '/tools/buildbot/bin/python'} 09:38:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:38:42 INFO - Cleaning up... 09:38:42 INFO - Return code: 0 09:38:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:38:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x176a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1999400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a28570>, '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 0x1a29470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a29900>, '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': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', '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 09:38:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:38:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:38:42 INFO - Using env: {'DISPLAY': ':0', 09:38:42 INFO - 'HOME': '/home/cltbld', 09:38:42 INFO - 'LANG': 'en_US.UTF-8', 09:38:42 INFO - 'LANGUAGE': 'en_US:en', 09:38:42 INFO - 'LOGNAME': 'cltbld', 09:38:42 INFO - 'MAIL': '/var/mail/cltbld', 09:38:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:42 INFO - 'MOZ_NO_REMOTE': '1', 09:38:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:42 INFO - 'NO_EM_RESTART': '1', 09:38:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:42 INFO - 'PWD': '/builds/slave/test', 09:38:42 INFO - 'SHELL': '/bin/bash', 09:38:42 INFO - 'SHLVL': '1', 09:38:42 INFO - 'TERM': 'linux', 09:38:42 INFO - 'TMOUT': '86400', 09:38:42 INFO - 'USER': 'cltbld', 09:38:42 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', 09:38:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:42 INFO - '_': '/tools/buildbot/bin/python'} 09:38:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:42 INFO - Downloading/unpacking blobuploader==1.2.4 09:38:42 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 09:38:42 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 09:38:42 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 09:38:42 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 09:38:42 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 09:38:42 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 09:38:44 INFO - Downloading blobuploader-1.2.4.tar.gz 09:38:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:38:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:38:44 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 09:38:44 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 09:38:44 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 09:38:44 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 09:38:44 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 09:38:44 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 09:38:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:38:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:45 INFO - Downloading docopt-0.6.1.tar.gz 09:38:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:38:45 INFO - Installing collected packages: blobuploader, requests, docopt 09:38:45 INFO - Running setup.py install for blobuploader 09:38:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:38:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:38:45 INFO - Running setup.py install for requests 09:38:45 INFO - Running setup.py install for docopt 09:38:45 INFO - Successfully installed blobuploader requests docopt 09:38:45 INFO - Cleaning up... 09:38:46 INFO - Return code: 0 09:38:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:46 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')]} 09:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:46 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')]} 09:38:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:46 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 0x176a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1999400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a28570>, '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 0x1a29470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a29900>, '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': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', '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 09:38:46 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 09:38:46 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 09:38:46 INFO - Using env: {'DISPLAY': ':0', 09:38:46 INFO - 'HOME': '/home/cltbld', 09:38:46 INFO - 'LANG': 'en_US.UTF-8', 09:38:46 INFO - 'LANGUAGE': 'en_US:en', 09:38:46 INFO - 'LOGNAME': 'cltbld', 09:38:46 INFO - 'MAIL': '/var/mail/cltbld', 09:38:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:46 INFO - 'MOZ_NO_REMOTE': '1', 09:38:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:46 INFO - 'NO_EM_RESTART': '1', 09:38:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:46 INFO - 'PWD': '/builds/slave/test', 09:38:46 INFO - 'SHELL': '/bin/bash', 09:38:46 INFO - 'SHLVL': '1', 09:38:46 INFO - 'TERM': 'linux', 09:38:46 INFO - 'TMOUT': '86400', 09:38:46 INFO - 'USER': 'cltbld', 09:38:46 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', 09:38:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:46 INFO - '_': '/tools/buildbot/bin/python'} 09:38:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:46 INFO - Running setup.py (path:/tmp/pip-FFzxLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:46 INFO - Running setup.py (path:/tmp/pip-k98a0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:46 INFO - Running setup.py (path:/tmp/pip-D3P8hF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:46 INFO - Running setup.py (path:/tmp/pip-rjIAtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:46 INFO - Running setup.py (path:/tmp/pip-EFx5Nd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:46 INFO - Running setup.py (path:/tmp/pip-MdSOfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:46 INFO - Running setup.py (path:/tmp/pip-zQBK86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:46 INFO - Running setup.py (path:/tmp/pip-NyKYgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:46 INFO - Running setup.py (path:/tmp/pip-RIE0xy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:46 INFO - Running setup.py (path:/tmp/pip-BzCGyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:46 INFO - Running setup.py (path:/tmp/pip-ONdZdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:47 INFO - Running setup.py (path:/tmp/pip-yAHP2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:47 INFO - Running setup.py (path:/tmp/pip-JvWea4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:47 INFO - Running setup.py (path:/tmp/pip-7GIto_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:47 INFO - Running setup.py (path:/tmp/pip-j0ll2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:47 INFO - Running setup.py (path:/tmp/pip-VcO10B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:47 INFO - Running setup.py (path:/tmp/pip-wAawhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:38:47 INFO - Running setup.py install for manifestparser 09:38:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:38:47 INFO - Running setup.py install for mozcrash 09:38:47 INFO - Running setup.py install for mozdebug 09:38:47 INFO - Running setup.py install for mozdevice 09:38:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:38:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:38:47 INFO - Running setup.py install for mozfile 09:38:47 INFO - Running setup.py install for mozhttpd 09:38:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:38:48 INFO - Running setup.py install for mozinfo 09:38:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:38:48 INFO - Running setup.py install for mozInstall 09:38:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:38:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:38:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:38:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:38:48 INFO - Running setup.py install for mozleak 09:38:48 INFO - Running setup.py install for mozlog 09:38:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:38:48 INFO - Running setup.py install for moznetwork 09:38:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:38:48 INFO - Running setup.py install for mozprocess 09:38:48 INFO - Running setup.py install for mozprofile 09:38:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:38:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:38:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:38:48 INFO - Running setup.py install for mozrunner 09:38:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:38:48 INFO - Running setup.py install for mozscreenshot 09:38:49 INFO - Running setup.py install for moztest 09:38:49 INFO - Running setup.py install for mozversion 09:38:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:38:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:38:49 INFO - Cleaning up... 09:38:49 INFO - Return code: 0 09:38:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:49 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')]} 09:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:49 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')]} 09:38:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:49 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 0x176a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1999400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a28570>, '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 0x1a29470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a29900>, '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': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', '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 09:38:49 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 09:38:49 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 09:38:49 INFO - Using env: {'DISPLAY': ':0', 09:38:49 INFO - 'HOME': '/home/cltbld', 09:38:49 INFO - 'LANG': 'en_US.UTF-8', 09:38:49 INFO - 'LANGUAGE': 'en_US:en', 09:38:49 INFO - 'LOGNAME': 'cltbld', 09:38:49 INFO - 'MAIL': '/var/mail/cltbld', 09:38:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:49 INFO - 'MOZ_NO_REMOTE': '1', 09:38:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:49 INFO - 'NO_EM_RESTART': '1', 09:38:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:49 INFO - 'PWD': '/builds/slave/test', 09:38:49 INFO - 'SHELL': '/bin/bash', 09:38:49 INFO - 'SHLVL': '1', 09:38:49 INFO - 'TERM': 'linux', 09:38:49 INFO - 'TMOUT': '86400', 09:38:49 INFO - 'USER': 'cltbld', 09:38:49 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', 09:38:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:49 INFO - '_': '/tools/buildbot/bin/python'} 09:38:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:49 INFO - Running setup.py (path:/tmp/pip-mgd3V9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:49 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)) 09:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:49 INFO - Running setup.py (path:/tmp/pip-mPyvWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:49 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)) 09:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:49 INFO - Running setup.py (path:/tmp/pip-MfAniN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:49 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)) 09:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:49 INFO - Running setup.py (path:/tmp/pip-iMyaNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:49 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)) 09:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:49 INFO - Running setup.py (path:/tmp/pip-ZOGMcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:49 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)) 09:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:49 INFO - Running setup.py (path:/tmp/pip-7y4av6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:49 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)) 09:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:49 INFO - Running setup.py (path:/tmp/pip-JQ_1rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:49 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)) 09:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:49 INFO - Running setup.py (path:/tmp/pip-Gw7mlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:50 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)) 09:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:50 INFO - Running setup.py (path:/tmp/pip-7QqLSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:50 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)) 09:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:50 INFO - Running setup.py (path:/tmp/pip-IiR_ED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:50 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)) 09:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:50 INFO - Running setup.py (path:/tmp/pip-mHwGBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:50 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)) 09:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:50 INFO - Running setup.py (path:/tmp/pip-cdTb11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:50 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)) 09:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:50 INFO - Running setup.py (path:/tmp/pip-PN71jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:50 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)) 09:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:50 INFO - Running setup.py (path:/tmp/pip-CIdg5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:50 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)) 09:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:50 INFO - Running setup.py (path:/tmp/pip-Y2tYlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:50 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)) 09:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:50 INFO - Running setup.py (path:/tmp/pip-YG0UL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:50 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)) 09:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:50 INFO - Running setup.py (path:/tmp/pip-HmTlv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:50 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)) 09:38:50 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)) 09:38:50 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)) 09:38:50 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)) 09:38:50 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)) 09:38:50 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)) 09:38:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:52 INFO - Downloading blessings-1.6.tar.gz 09:38:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:38:52 INFO - Installing collected packages: blessings 09:38:52 INFO - Running setup.py install for blessings 09:38:52 INFO - Successfully installed blessings 09:38:52 INFO - Cleaning up... 09:38:52 INFO - Return code: 0 09:38:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:38:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:53 INFO - Reading from file tmpfile_stdout 09:38:53 INFO - Current package versions: 09:38:53 INFO - argparse == 1.2.1 09:38:53 INFO - blessings == 1.6 09:38:53 INFO - blobuploader == 1.2.4 09:38:53 INFO - docopt == 0.6.1 09:38:53 INFO - functools32 == 3.2.3-2 09:38:53 INFO - jsonschema == 2.5.1 09:38:53 INFO - manifestparser == 1.1 09:38:53 INFO - mozInstall == 1.12 09:38:53 INFO - mozcrash == 0.17 09:38:53 INFO - mozdebug == 0.1 09:38:53 INFO - mozdevice == 0.48 09:38:53 INFO - mozfile == 1.2 09:38:53 INFO - mozhttpd == 0.7 09:38:53 INFO - mozinfo == 0.9 09:38:53 INFO - mozleak == 0.1 09:38:53 INFO - mozlog == 3.3 09:38:53 INFO - moznetwork == 0.27 09:38:53 INFO - mozprocess == 0.23 09:38:53 INFO - mozprofile == 0.28 09:38:53 INFO - mozrunner == 6.12 09:38:53 INFO - mozscreenshot == 0.1 09:38:53 INFO - mozsystemmonitor == 0.3 09:38:53 INFO - moztest == 0.7 09:38:53 INFO - mozversion == 1.4 09:38:53 INFO - psutil == 3.1.1 09:38:53 INFO - requests == 1.2.3 09:38:53 INFO - wsgiref == 0.1.2 09:38:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:53 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')]} 09:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:53 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')]} 09:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:53 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 0x176a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1999400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a28570>, '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 0x1a29470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a29900>, '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': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', '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 09:38:53 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 09:38:53 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 09:38:53 INFO - Using env: {'DISPLAY': ':0', 09:38:53 INFO - 'HOME': '/home/cltbld', 09:38:53 INFO - 'LANG': 'en_US.UTF-8', 09:38:53 INFO - 'LANGUAGE': 'en_US:en', 09:38:53 INFO - 'LOGNAME': 'cltbld', 09:38:53 INFO - 'MAIL': '/var/mail/cltbld', 09:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:53 INFO - 'MOZ_NO_REMOTE': '1', 09:38:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:53 INFO - 'NO_EM_RESTART': '1', 09:38:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:53 INFO - 'PWD': '/builds/slave/test', 09:38:53 INFO - 'SHELL': '/bin/bash', 09:38:53 INFO - 'SHLVL': '1', 09:38:53 INFO - 'TERM': 'linux', 09:38:53 INFO - 'TMOUT': '86400', 09:38:53 INFO - 'USER': 'cltbld', 09:38:53 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', 09:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:53 INFO - '_': '/tools/buildbot/bin/python'} 09:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:53 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)) 09:38:53 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)) 09:38:53 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)) 09:38:53 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)) 09:38:53 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)) 09:38:53 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)) 09:38:53 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)) 09:38:53 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)) 09:38:53 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)) 09:38:53 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)) 09:38:53 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)) 09:38:53 INFO - Cleaning up... 09:38:53 INFO - Return code: 0 09:38:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:38:53 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')]} 09:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:53 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')]} 09:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:53 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 0x176a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18cd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1999400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a28570>, '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 0x1a29470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a29900>, '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': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', '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 09:38:53 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 09:38:53 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 09:38:53 INFO - Using env: {'DISPLAY': ':0', 09:38:53 INFO - 'HOME': '/home/cltbld', 09:38:53 INFO - 'LANG': 'en_US.UTF-8', 09:38:53 INFO - 'LANGUAGE': 'en_US:en', 09:38:53 INFO - 'LOGNAME': 'cltbld', 09:38:53 INFO - 'MAIL': '/var/mail/cltbld', 09:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:53 INFO - 'MOZ_NO_REMOTE': '1', 09:38:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:53 INFO - 'NO_EM_RESTART': '1', 09:38:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:53 INFO - 'PWD': '/builds/slave/test', 09:38:53 INFO - 'SHELL': '/bin/bash', 09:38:53 INFO - 'SHLVL': '1', 09:38:53 INFO - 'TERM': 'linux', 09:38:53 INFO - 'TMOUT': '86400', 09:38:53 INFO - 'USER': 'cltbld', 09:38:53 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', 09:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:53 INFO - '_': '/tools/buildbot/bin/python'} 09:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:38:53 INFO - Cleaning up... 09:38:53 INFO - Return code: 0 09:38:53 INFO - Running post-action listener: _resource_record_post_action 09:38:53 INFO - Running post-action listener: _start_resource_monitoring 09:38:53 INFO - Starting resource monitoring. 09:38:53 INFO - [mozharness: 2016-09-03 16:38:53.518956Z] Finished create-virtualenv step (success) 09:38:53 INFO - [mozharness: 2016-09-03 16:38:53.519380Z] Running install step. 09:38:53 INFO - Running pre-action listener: _resource_record_pre_action 09:38:53 INFO - Running main action method: install 09:38:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:53 INFO - Reading from file tmpfile_stdout 09:38:53 INFO - Detecting whether we're running mozinstall >=1.0... 09:38:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:38:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:38:53 INFO - Reading from file tmpfile_stdout 09:38:53 INFO - Output received: 09:38:53 INFO - Usage: mozinstall [options] installer 09:38:53 INFO - Options: 09:38:53 INFO - -h, --help show this help message and exit 09:38:53 INFO - -d DEST, --destination=DEST 09:38:53 INFO - Directory to install application into. [default: 09:38:53 INFO - "/builds/slave/test"] 09:38:53 INFO - --app=APP Application being installed. [default: firefox] 09:38:53 INFO - mkdir: /builds/slave/test/build/application 09:38:53 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'] 09:38:53 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 09:39:10 INFO - Reading from file tmpfile_stdout 09:39:10 INFO - Output received: 09:39:10 INFO - /builds/slave/test/build/application/firefox/firefox 09:39:10 INFO - Running post-action listener: _resource_record_post_action 09:39:10 INFO - [mozharness: 2016-09-03 16:39:10.384994Z] Finished install step (success) 09:39:10 INFO - [mozharness: 2016-09-03 16:39:10.385270Z] Running run-tests step. 09:39:10 INFO - Running pre-action listener: _resource_record_pre_action 09:39:10 INFO - Running main action method: run_tests 09:39:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:39:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:39:10 INFO - Python 2.7.3 09:39:10 INFO - Return code: 0 09:39:10 INFO - grabbing minidump binary from tooltool 09:39:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:10 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 0x1a28570>, '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 0x1a29470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a29900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:39:10 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 09:39:10 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 09:39:10 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 09:39:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:39:10 INFO - Return code: 0 09:39:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:39:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:39:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:39:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:39:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:39:10 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:39:10 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:39:10 INFO - Using env: {'DISPLAY': ':0', 09:39:10 INFO - 'HOME': '/home/cltbld', 09:39:10 INFO - 'LANG': 'en_US.UTF-8', 09:39:10 INFO - 'LANGUAGE': 'en_US:en', 09:39:10 INFO - 'LOGNAME': 'cltbld', 09:39:10 INFO - 'MAIL': '/var/mail/cltbld', 09:39:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:39:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:39:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:10 INFO - 'MOZ_NO_REMOTE': '1', 09:39:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:39:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:39:10 INFO - 'NO_EM_RESTART': '1', 09:39:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:39:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:10 INFO - 'PWD': '/builds/slave/test', 09:39:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:39:10 INFO - 'SHELL': '/bin/bash', 09:39:10 INFO - 'SHLVL': '1', 09:39:10 INFO - 'TERM': 'linux', 09:39:10 INFO - 'TMOUT': '86400', 09:39:10 INFO - 'USER': 'cltbld', 09:39:10 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295', 09:39:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:10 INFO - '_': '/tools/buildbot/bin/python'} 09:39:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:39:10 INFO - mozversion application_buildid: 20160903064749 09:39:10 INFO - mozversion application_changeset: ddd6e3e541f1e406dca4a51fb257e68d3c968644 09:39:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:39:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:39:10 INFO - mozversion application_name: Firefox 09:39:10 INFO - mozversion application_remotingname: firefox-dev 09:39:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:39:10 INFO - mozversion application_vendor: Mozilla 09:39:10 INFO - mozversion application_version: 50.0a2 09:39:10 INFO - mozversion platform_buildid: 20160903064749 09:39:10 INFO - mozversion platform_changeset: ddd6e3e541f1e406dca4a51fb257e68d3c968644 09:39:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:39:10 INFO - mozversion platform_version: 50.0a2 09:39:10 INFO - using testdate: 1472920750 09:39:10 INFO - actual date: 1472920750 09:39:10 INFO - starting webserver on 'localhost:33879' 09:39:10 INFO - SUITE-START | Running 2 tests 09:39:10 INFO - TEST-START | dromaeo_css 09:39:10 INFO - operating with platform_type : linux_ 09:39:10 INFO - Initialising browser for dromaeo_css test... 09:39:10 INFO - TEST-INFO | started process 8258 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpry_N7k/profile http://localhost:33879/getInfo.html) 09:39:15 INFO - PROCESS | 8258 | __metrics Screen width/height:1600/1200 09:39:15 INFO - PROCESS | 8258 | colorDepth:24 09:39:15 INFO - PROCESS | 8258 | Browser inner width/height: 1024/697 09:39:15 INFO - PROCESS | 8258 | __metrics 09:39:15 ERROR - PROCESS | 8258 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 09:39:16 INFO - TEST-INFO | 8258: exit 0 09:39:16 INFO - Browser initialized. 09:39:16 INFO - Running cycle 1/1 for dromaeo_css test... 09:39:16 INFO - TEST-INFO | started process 8386 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpry_N7k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:28 INFO - PROCESS | 8386 | RSS: Main: 152780800 09:39:28 INFO - PROCESS | 8386 | 09:40:17 INFO - PROCESS | 8386 | 1472920817982 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] 09:42:26 INFO - PROCESS | 8386 | Cycle 1(1): loaded http://localhost:33879/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33879/tests/dromaeo/cssquery-ext.html) 09:42:27 INFO - PROCESS | 8386 | RSS: Main: 158986240 09:42:27 INFO - PROCESS | 8386 | 09:45:29 INFO - PROCESS | 8386 | Cycle 1(1): loaded http://localhost:33879/tests/dromaeo/cssquery-ext.html (next: http://localhost:33879/tests/dromaeo/cssquery-jquery.html) 09:45:30 INFO - PROCESS | 8386 | RSS: Main: 153997312 09:45:30 INFO - PROCESS | 8386 | 09:48:32 INFO - PROCESS | 8386 | Cycle 1(1): loaded http://localhost:33879/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33879/tests/dromaeo/cssquery-mootools.html) 09:48:32 INFO - PROCESS | 8386 | RSS: Main: 154382336 09:48:32 INFO - PROCESS | 8386 | 09:51:34 INFO - PROCESS | 8386 | Cycle 1(1): loaded http://localhost:33879/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33879/tests/dromaeo/cssquery-prototype.html) 09:51:35 INFO - PROCESS | 8386 | RSS: Main: 152813568 09:51:35 INFO - PROCESS | 8386 | 09:54:37 INFO - PROCESS | 8386 | Cycle 1(1): loaded http://localhost:33879/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33879/tests/dromaeo/cssquery-yui.html) 09:54:38 INFO - PROCESS | 8386 | RSS: Main: 155193344 09:54:38 INFO - PROCESS | 8386 | 09:57:40 INFO - PROCESS | 8386 | Cycle 1(1): loaded http://localhost:33879/tests/dromaeo/cssquery-yui.html (next: http://localhost:33879/tests/dromaeo/cssquery-dojo.html) 09:57:40 INFO - PROCESS | 8386 | RSS: Main: 156217344 09:57:40 INFO - PROCESS | 8386 | 09:57:40 INFO - PROCESS | 8386 | __start_tp_report 09:57:40 INFO - PROCESS | 8386 | _x_x_mozilla_page_load 09:57:40 INFO - PROCESS | 8386 | _x_x_mozilla_page_load_details 09:57:40 INFO - PROCESS | 8386 | |i|pagename|runs| 09:57:40 INFO - PROCESS | 8386 | |0;dojo.html;3252.934941301174;3277.868885244589;3287.408266512028;3303.2237424205323;3303.719183869371;5686.943130568693;5690.57320700947;5721.399253078429;5764.510016648579;5772.220750198722;5711.9718450678865;5751.367349591552;5751.946081722996;5760.62555933864;5763.942360576403;5191.143461328886;5204.089284375238;5252.054630166568;5276.366835979668;5312.707801070939;5129.666571672829;5141.691498510101;5193.63644544882;5238.00477909198;5275.366955965271;5195.090859099661;5239.5546378557865;5256.316678831766;5258;5304.522592966632;5312.707801070939;5319.308489896327;5325.893482130355;5335.33308336458;5364.436734142898;4980.178270585349;5080.339555857752;5111.028904508132;5202.817901373433;5230.398504172031;5666.150077488369;5700.7149642518125;5713.285839270091;5721.34204566476;5768.480836724715;6139.201903752483;6143.78496752616;6144.109104179869;6184.6598437086395;6189.319174890758;5942.405759424067;6130.785422510191;6144.740328232701;6220.1913751212505;6227.501799856001;5813.215215945877;5815.505682017034;5818.709064546798;5825.883482330372;5845.970770146165;6356.523260755462;6359.300476947532;6366.363363663597;6375.649339286384;6394.136791533177;5852.590318677737;5873.794417195378;5881.676507792027;6078.969605151946;6098.14625952367;6010.459058684739;6043.153958445821;6053.303870054912;6059.848503787441;6074.848128796727;6027.276726792726;6028.457438830526;6047.9395206047375;6051.939480605226;6059.515238780887;5847.912281315784;5966.671833049224;6048.818535443944;6048.879022419527;6068.575199735976;4725.10223057618;4728.4562275338085;4748.952510474851;4752.049590081998;4767.141914455431;4198.895027624334;4215.051613386963;4217.261979153636;4240.936385954213;4242.8727138185905;5724.914126288109;5738.139279108083;5776.306843178845;5972.641641501524;5989.131575921554;6055.303640081359;6068.33248342682;6069.2716873975405;6101.542384321194;6108.480779133734;5835.183074369592;5838.095095260242;5842.036064049385;5851.707414629252;5865.970670146622;5151.2530683050745;5166.870828229325;5167.534921857051;5249.238860365225;5269.104252277122;6251.468625166912;6274.121622972787;6275.905861412082;6276;6291.622502649856;6028.4272994065495;6041.335453100068;6051.878962420815;6058.3335833057445;6062.939370606414;6123.191113103653;6191.380861913773;6215.192025036717;6225.750969961151;6262.718177681924;5981.3420523743225;6021;6025.427584379477;6051.485623721856;6055.515558755377;6117.265928088658;6135.570510064253;6148.200733904564;6161.476274516537;6173;5798.913016304675;5935.258092738408;5967.791127310524;6027.126066720389;6033.758649653964;5975.55183361241;5997.280326360865;6017.578769486094;6023.45788879003;6052.90920636182;5902.675352855634;5950.464458198783;5952.523798096228;5959.67221802805;5991;5793.681347525927;5956.8808623828145;5975.880482390415;6038.8188354347685;6039.818805435668;5963.373845746134;5977.820665380045;5978.222831031938;5991.281046274475;6010.87978240424 09:57:40 INFO - PROCESS | 8386 | |1;ext.html;580.2369883603062;638.3361304243587;658.5456035335618;671.9563228390151;711.2283172757558;42730;47502.287465688;47638.76180619097;47697.52302476974;47906;46330;46720.299195512096;46870.76564617181;46976.530234697726;47293.52706472943;9056.230220431255;9173.853268341458;9205;9206.631734730636;9228.354015218938;9097.909020909805;9181.58682859273;9225.077492250788;9235.538223088852;9257;9162.816743665157;9174.816503669923;9197.908020919773;9218.078192180761;9268.629254829799;9048.185663290302;9132.908670913272;9147.22248608869;9183.540822958825;9197.540122993856;9053.864192037125;9103.36276460648;9106.86339704905;9125.817483650357;9179.036201198845;45043.09913801739;45287.54712452866;45490.54509454929;45502.77248613769;45616.77191614054;3091.90724278272;3583.068402215443;3642.5993140754495;3669.9633003669887;3797.544294684628;34901.30197396063;35310.29379412397;36349.818250908844;36561.45157822635;36588.63411365879;4651.534846515321;4684.976575117103;4688.203005489075;4732.337472753818;5009.499050094998;391070;392658;394007;396653;397503;62412.37587624157;63213.36786632167;63256.68371658204;63401.68299158567;63651.68174159192;41037;41486.1702765943;41587;44256.557434425886;74888.62555687295;20091.698624520643;20394.490137746674;20453.59092818165;20488.69266960997;20505.692414613794;13846.515371961934;13850.09974351664;13932.512362067482;13967.580972570839;14022;22740.317790466313;22743.772562274164;22853.971571279333;22936.197233096762;22942.541149176923;7943.364530837519;7999.720009799629;8161.469504482189;8180.67277308901;8247.340212782963;1042.4631314872695;1086;1109.450821843193;1130.9830352544718;1262.1921969939062;35911;35959;36554.8172259137;36623.4506482403;36787.816060919526;1168.053876360151;1180.8486725442624;1197.7724232395817;1205.0720944872398;1214.3988725580718;18837.434876953714;18964.525886852938;19025.809741902405;19026.42920712381;19108;653380;655721;656968;660915;662888;66435;67221.66389168023;67264;67353;67413.66293168503;1429.963276624439;1442.1563385419647;1449.9275036248146;1458.3947661720479;1459.9635009124856;32630.83684581562;34555.827220863735;34800.65199347974;34808;35328.823355883054;57659;57924.42075579359;58283.708581458515;58293;58500.707496462244;531767;537093;541472;543142;544917;31552.52671209888;32276.35447290994;32356;32509;32847.83576082104;54340;54522.45477545174;54699.45300547103;55068.44931550793;55602.72198639143;47383.28925066058;48236.75881620569;48883;49323.75338123286;49795.751021244665;47931.28103078526;48940.75529622329;48985;49023;49808.501914980385;46432;46890.06219875652;47096;47936.7603161982;49840.252396213364;40439;40548.391774124;40945.385819213334;41256.79371603123;42070.57929420667;31413.8429307852;31791.205219869687;32099.839500802347;32201.677983219866;32325.191870202492 09:57:40 INFO - PROCESS | 8386 | |2;jquery.html;382.48364707644663;386.38178913738005;402.17554014270746;416.26736942980347;434.63056402058254;18482.630347393046;19445.70831437528;20269.797302026975;21244.044018019187;21340.03969821358;16432.67134657306;16549.66900661989;20880.06039728212;21215.893920530416;21236.57526849466;5628.802991895294;5905.616134951236;5934.673592952386;5938.495227905633;5952.940470595304;5562.666240025591;5786.132080187985;5786.768529258825;5821.417858214187;5826.941730582704;5843.79546715865;5901.911471327933;5916.171735956969;5916.763329466816;5917.112433135043;5821.912671309934;5835.795747148851;5961.731722072517;6133.264008318985;6136.2636483622255;5839.299284085937;5904.321003084614;5950.285965684146;5967.850803729942;6036.15493830864;14825.481108161162;15071.773923391158;15177.544673659697;21007.579848402947;24722.13472528453;2492.962605560918;2497.463045445238;2666.0668765931964;2842.9857850710823;2894.6815850256453;10621.84067238992;10896.346219226793;10963.725906852313;11035.889641103648;11296.435178241056;2056.208359781495;2095.8218551422997;2106.852520323578;2119.395972147946;2128.9041993110345;80428;88743.11256887413;89163.55418222932;89921;91278.5436072822;11068.778624427467;11406.714832129097;11556.075513958864;11736;11800.70498237551;13181.275029873257;13949.721005579831;16562.83437165637;16607.086610236554;16672.416465423652;8463.915360846437;8608.311335093304;8730.56347182651;8731.781705457415;8957.731268061969;8920;8937.46375217489;9055.090896523101;9126.908730912606;9304.581293841793;4263.253930562139;4336.371226172188;4366.388705581221;4447.2217361962175;4486.416765820488;2047.897605119738;2070.6272870883386;2124.968125478119;2135.7330333708287;2155.148716257101;944.7260294514651;1014.5586669798661;1027.337367398039;1107.092184408777;1110.7001109700336;21129.89435052815;21321.68017479739;21337.466563336042;21651.67522487164;21826.454338641663;1018.9643362482426;1050.894910508943;1066.5946940162844;1072.4155335342257;1114.7603265298;10944.945275273732;11682.182247242781;11684.766304673858;11770.470328835223;12012.279263244232;486644;514816;525525;538952;553907;95022;96911;98191.50904245433;99560.50219748855;99791.50104249577;1077.5851297250615;1080.7460246841986;1115.983260251097;1116.0234794554765;1119.6473110970019;18867.245310187987;19212.519687007938;19520.511987200432;19541.413757587296;19574.902125489283;45035.54964450314;45459.31811022903;46020;46066.078678426915;46980.7650961743;63915.68042159759;64353;64746.352536474034;65119.34880651133;67061.66469167623;8852.955735221283;8925.866112008196;9033.864492032493;9088.863667044867;9131.817363652823;13101.541446049343;13541.1198272112;13615.79576306376;14266.572002839932;14390.568282951528;11529.308241505536;11553.458239187365;12097;12276.754464910831;12321.753564928473;11635.069194464253;11801.940990294994;11897.167198296036;12006.39968001579;12426.440810163384;11935.403229838646;11951;12030.15788894769;12243.87756122463;12292.508299667914;8953;8992.415492992934;9017.188453038996;9028.683996060106;9238;11766.882331176921;11877.881221187678;11927.701807454883;11949.22330048544;12105.063189889575 09:57:40 INFO - PROCESS | 8386 | |3;mootools.html;1541.0060510970425;1546.2887071946905;1721.655668866227;1843.2811203630579;1849.0384999800112;7506.361959233458;8286.544240066794;8318.83362332753;8917.420367676104;8971.551422428885;8293.626786794608;8503.404761666687;8730.869036964436;8736.301095912328;8751.256143227818;2423.5637585234695;2460.2250291158316;2481.7270100289043;2483.341914392687;2484.068474322129;2376.6791483149727;2452.4849781545895;2456.4104614892476;2467.7902378297777;2469.296250568588;2441.24321460347;2450.80393568515;2454.6686197363397;2458.815588830836;2466.3710753757855;2325.790678838904;2326.034695601332;2329.3827135809024;2368.7868091871815;2397.2688330059327;2343.437574981992;2344;2355.1286024170995;2358.9646155307682;2370.182287110944;8577.957110214471;8624.396292259544;8689.17452841979;8761.386702930797;8765.912340876574;5422.186671999192;5473.425290344497;5538.806141785019;5539.086050801613;5549.6115271930985;3469.1500582357485;3484.756067075306;3548.361294966905;3563.2873425314783;3570.589382221052;1134.3647557367817;1153.892263427111;1229.9754004919942;1230.544698461566;1254.7239607286383;11748.647540573797;11882.22765520247;11897.167198296123;11925.761484770343;11974;4615.653825963066;4659.207934651117;4800.903981920342;4856.344393506902;4865.294532292798;10132.493375331203;10153.39079655223;10177.084062434416;10198.133158681596;10210.948945255375;5441.891162176814;5474.178873168977;5479.643823151482;5496.093144631171;5511.338639363302;4224.324108142682;4938.728369939688;5041.269015992661;5052.747362631853;5065.873353166126;4187.832486700559;4268.338407546835;4280.636145927569;4324.394584758136;4324.675649326313;1192.2071822238288;1208.4863932828512;1223.2782658231508;1232.3345393487416;1250.2311078686723;938.4088024544493;961.3318743473219;1011.8077565262577;1026.8459731040255;1036.1658864614058;5942.673152976541;5945.821625351246;6127.479164270998;6133.233345831771;6184.938150618526;485.6964397251718;492.50995259716814;493.7235148316954;508.0271280497879;508.59312549959884;4190.1200747843295;4195.601417865298;4276.273033584298;4296.183725092223;4314.741115533078;10927.726806829893;11087.279326843729;11155.27490713101;11167.994880460798;11180.832287515694;11023;11034.944825275823;11046.834297485544;11055.944720276348;11056.944715276371;7848.332891704269;8133.796655083552;8437.409381343246;8464.703735369232;8476.576171191417;6094.299155597163;6201.534884883742;6282.900492413901;6304.022876454157;6383.553151279366;10142.746431339276;10194.388336699822;10206.948965255126;10222.48887555634;10224.846627300447;9552.331336806357;9925.305228634215;9948.552315145691;10048.045435683598;10109.848352274868;6952.687129079305;7556.131044929683;7734.845303093794;7781.805454863447;7935.920640793749;8613.397062205586;8635.266002389868;8684.78288042804;8686.218240358145;8731.170538798804;7590.127135379502;7686.615669216405;7842.411819113703;7859.921400785919;8011.599420029092;7453.403727701662;7517.849643007218;7531.171571127062;7589.1272503662785;7677.692892284246;7276.272372762682;7381.630918454163;7422.035135432359;7426.4430167736555;7427.25727427253;5503.064479038493;5532.446755324436;5548.778048878;5573.470520300565;5626.38109807929;5419.458054194707;5486.890262194654;5538.501534861882;5577.247071645276;5598.944010559842 09:57:40 INFO - PROCESS | 8386 | |4;prototype.html;348.3190362840647;352.0617554218009;356.7930600251854;357.0716138041092;363.17559140750495;5742.196092547041;5782.53739700824;5803.506701930331;5824.4565190224575;5828.766849326027;5725.6278341907855;5727.169560413738;5750.971245143768;5785.363610002896;5795.551112221944;4300.376445415405;4410.31640095784;4447.510773814878;4454.732716037032;4522.954770452303;4453.710508816933;4455.933161002587;4464.308032255006;4484.641228701712;4506.932396014063;4452.332150177467;4475.776211189427;4491.079328737617;4503.594676479116;4517.4804897436725;4387.846425375096;4389.758563279019;4422.756748378838;4449.599536041772;4450.577195166454;3869;3869.496965394509;3883.3980732986433;3893.364786789555;3908.237893610747;5600.635958662674;5627.915581266284;5628.521575666074;5663.122216056518;5695.572832037614;4968.677035992649;5083.720395378253;5131.307273518065;5142.768575414116;5143.614228932845;5971.761129554812;5987.221661184061;6017.217761690952;6029.517638588946;6061.605995610315;4331;4359.171757366122;4370.847020354304;4384.473863136413;4396.978015109904;6030.040236923018;6078.756849726005;6238.785231242889;6280.403361680679;6280.968595157041;4291.206126866539;4294.57054294568;4308.67684923632;4309.202797482476;4337.067530480898;4382.145481631115;4390.363397307373;4401.405810215644;4401.603855652943;4406.228909940747;4926.3842019747535;5069.670471419346;5076.898462030813;5084;5106.591472682177;6024.138534196221;6042;6061.121137435047;6070.025687413224;6099.664518451439;1501.8873584481207;1539.7151526967548;1554.4326320892792;1555.9066456012677;1558.2364641325669;907.6142639378238;949.943003419797;959.5490119643756;961.9663311784054;964.2478866484154;5140.614453915971;5191.740412979336;5192.221166825007;5197.506236907488;5232.2674825524455;5430.56555475561;5521.907731670278;5543.085390910535;5548.8624831909365;5571.220029195916;5008.719288865799;5193.870153246124;5239.214117882349;5255.527002569787;5258.553022993012;2905.6513218413925;2922.933129407751;2928.0089539113605;2931.1792698044583;2938.4416960777385;5490.129458731584;5613.354464236665;5617.859553511195;5666.773329066791;5686.161840757555;5237.826302084668;5483.835484935458;5528.44715528452;5581.358143813513;5585.413531579207;5570.579502226906;5610.260819145996;5661.49970257882;5664.971675141598;5673.489385955284;5138.614603904647;5429.809956651498;5469.699166545878;5516.365617953827;5528;5329.387120481194;5361.4265152727685;5396.001739678172;5411;5413.160960051198;5506.80726174598;6033.818985430268;6154.323024467216;6287.0510141856175;6378.425941665272;4383.978080109579;4408.228560001922;4455.108978204307;4466.151431228186;4466.173757854807;5940.108983652314;6230.470410015199;6253.624782513064;6270.247570291412;6311.27420346666;6092.329843717099;6116.908246376219;6140.846478838065;6141.815745527641;6153.2000839890525;6042.516598672008;6106.236720409949;6118.938810611837;6141.877162456815;6157.784477543264;5740.655560666373;6031.396860313934;6058.27300723916;6075.696215189135;6137.7851775187655;6151.446369826737;6175.84560385976;6179.28938172116;6184.443400094013;6197.03945888388;5946.524278057658;5991;5998.670073146019;6006.849828754316;6024.6503863494145 09:57:40 INFO - PROCESS | 8386 | |5;yui.html;764.6597264217426;807.6365635464039;857.3655494933743;900.8198360327934;919.1479169473512;16032.83967160328;16954.491365259062;17024.48926532203;17060.488185354443;17107.230174642125;14417.423303067864;14579.19814410207;14702.852971470282;14732.484363047295;14753.557393278192;875.0418291970315;892.8348255572707;893.1291990309461;893.7631527645146;898.0884402331639;878.0604752914384;878.9296856251517;880.4981160738382;882.2853488674162;884.385352180235;935.3546053223288;935.4200395754642;935.4247138010109;937.6858752317947;940.0364626258106;924.4776701163821;933.09955892564;933.2347475070411;933.2953620016912;933.3093510802025;689.5965859971873;691.022203581931;692.0933577014127;694.2952902803659;695.3498478922224;16301.51095467138;16304.021758694516;16307.836921630751;16342.509724708278;16382.098984555845;15776.763348549666;16473.588160295967;16520;16551.66896662072;17306.22122004513;13783.310834458238;13828.101173423694;13851;13869.167849929037;13978.440862365595;4104.8152833122285;4128.731632443912;4136.152088821785;4159.376093585956;4200.894977625553;53378.466215337736;78182;79853.60073199598;87817.99999999936;95430.52284738602;12032.81950770739;12383.133180677267;12456.81314780279;12458.439370228361;12461;22036.228731994303;22150.667739983914;22619.773802262094;22732.20437284687;23152.768472315398;8295.751127466187;9086.909130908738;9217.44695318283;9316.627334906529;9536.28477864163;4983.352164218701;5018.172001619765;5028.698278103326;5038.34501514808;5079.288899554065;3720.2001569662652;3810.676092532166;3827.13889374894;3849.480320156743;3859.9807000964815;664.1963224498411;664.6875968294894;671.9630420326831;708.288170388756;710.7121615745631;665.3911670821241;818.8689809630431;833.9624716887633;839.3872473094675;852.6078004118051;10311.742206444898;10698.62554810578;11054.354813792253;11067.169962252703;11270.380129092815;767.6046835879526;809.401043228007;812.4212842429109;820.5445977482435;831.8170002599539;5001.699898006132;5228.215767634885;5274.314339135888;5281.234221037928;5324.733763311819;73463;80607;101659;127869.72130278764;139480;119030.40484797521;120651;121692;122149.38925305495;122843;62449.37550624526;92837.11648790223;103871;104441.95558044474;108274.45862770636;9966.152877005536;10030.398176109165;10084.54619542137;10088.69733907984;10212;61799;61845.38154618396;62063.37936620757;62654.68672656608;62933;81019.18980810116;84782.57608711917;88880.5555972216;92058;92499.26561013966;12918.160319579198;13366;13472.5284615038;13697;14042.297885105909;14749.778753318495;15162.696746064796;15247.542573722805;15263.084214946695;15274.389024438899;14073.718525629634;14184.574462766133;14812.481563145238;14855.40578376896;14958.850411495745;13470.79793803074;13944.442222310994;14037.578872633838;14628.707425851637;14635.634109147357;14160.362783674553;14215.573532794033;14305.21321327337;14514.491992780202;14594;10907.454627268764;11327.660170194908;11341.659750207507;11469.598564050219;12193.878061219275;12121.543066773273;12356.938215308866;12557;12598.370081496072;12608.74782504363 09:57:40 INFO - PROCESS | 8386 | __end_tp_report 09:57:40 INFO - PROCESS | 8386 | __start_cc_report 09:57:40 INFO - PROCESS | 8386 | _x_x_mozilla_cycle_collect,354 09:57:40 INFO - PROCESS | 8386 | __end_cc_report 09:57:40 INFO - PROCESS | 8386 | __startTimestamp1472921860874__endTimestamp 09:57:40 INFO - PROCESS | 8386 | 09:57:40 INFO - PROCESS | 8386 | ------- Summary: start ------- 09:57:40 INFO - PROCESS | 8386 | Number of tests: 6 09:57:40 INFO - PROCESS | 8386 | 09:57:40 INFO - PROCESS | 8386 | [#0] dojo.html Cycles:165 Average:5686.38 Median:5950.46 stddev:634.85 (10.7%) stddev-sans-first:607.42 09:57:40 INFO - PROCESS | 8386 | Values: 3252.9 3277.9 3287.4 3303.2 3303.7 5686.9 5690.6 5721.4 5764.5 5772.2 5712.0 5751.4 5751.9 5760.6 5763.9 5191.1 5204.1 5252.1 5276.4 5312.7 5129.7 5141.7 5193.6 5238.0 5275.4 5195.1 5239.6 5256.3 5258.0 5304.5 5312.7 5319.3 5325.9 5335.3 5364.4 4980.2 5080.3 5111.0 5202.8 5230.4 5666.2 5700.7 5713.3 5721.3 5768.5 6139.2 6143.8 6144.1 6184.7 6189.3 5942.4 6130.8 6144.7 6220.2 6227.5 5813.2 5815.5 5818.7 5825.9 5846.0 6356.5 6359.3 6366.4 6375.6 6394.1 5852.6 5873.8 5881.7 6079.0 6098.1 6010.5 6043.2 6053.3 6059.8 6074.8 6027.3 6028.5 6047.9 6051.9 6059.5 5847.9 5966.7 6048.8 6048.9 6068.6 4725.1 4728.5 4749.0 4752.0 4767.1 4198.9 4215.1 4217.3 4240.9 4242.9 5724.9 5738.1 5776.3 5972.6 5989.1 6055.3 6068.3 6069.3 6101.5 6108.5 5835.2 5838.1 5842.0 5851.7 5866.0 5151.3 5166.9 5167.5 5249.2 5269.1 6251.5 6274.1 6275.9 6276.0 6291.6 6028.4 6041.3 6051.9 6058.3 6062.9 6123.2 6191.4 6215.2 6225.8 6262.7 5981.3 6021.0 6025.4 6051.5 6055.5 6117.3 6135.6 6148.2 6161.5 6173.0 5798.9 5935.3 5967.8 6027.1 6033.8 5975.6 5997.3 6017.6 6023.5 6052.9 5902.7 5950.5 5952.5 5959.7 5991.0 5793.7 5956.9 5975.9 6038.8 6039.8 5963.4 5977.8 5978.2 5991.3 6010.9 09:57:40 INFO - PROCESS | 8386 | 09:57:40 INFO - PROCESS | 8386 | [#1] ext.html Cycles:180 Average:70085.70 Median:25779.48 stddev:144102.30 (559.0%) stddev-sans-first:144412.05 09:57:40 INFO - PROCESS | 8386 | Values: 580.2 638.3 658.5 672.0 711.2 42730.0 47502.3 47638.8 47697.5 47906.0 46330.0 46720.3 46870.8 46976.5 47293.5 9056.2 9173.9 9205.0 9206.6 9228.4 9097.9 9181.6 9225.1 9235.5 9257.0 9162.8 9174.8 9197.9 9218.1 9268.6 9048.2 9132.9 9147.2 9183.5 9197.5 9053.9 9103.4 9106.9 9125.8 9179.0 45043.1 45287.5 45490.5 45502.8 45616.8 3091.9 3583.1 3642.6 3670.0 3797.5 34901.3 35310.3 36349.8 36561.5 36588.6 4651.5 4685.0 4688.2 4732.3 5009.5 391070.0 392658.0 394007.0 396653.0 397503.0 62412.4 63213.4 63256.7 63401.7 63651.7 41037.0 41486.2 41587.0 44256.6 74888.6 20091.7 20394.5 20453.6 20488.7 20505.7 13846.5 13850.1 13932.5 13967.6 14022.0 22740.3 22743.8 22854.0 22936.2 22942.5 7943.4 7999.7 8161.5 8180.7 8247.3 1042.5 1086.0 1109.5 1131.0 1262.2 35911.0 35959.0 36554.8 36623.5 36787.8 1168.1 1180.8 1197.8 1205.1 1214.4 18837.4 18964.5 19025.8 19026.4 19108.0 653380.0 655721.0 656968.0 660915.0 662888.0 66435.0 67221.7 67264.0 67353.0 67413.7 1430.0 1442.2 1449.9 1458.4 1460.0 32630.8 34555.8 34800.7 34808.0 35328.8 57659.0 57924.4 58283.7 58293.0 58500.7 531767.0 537093.0 541472.0 543142.0 544917.0 31552.5 32276.4 32356.0 32509.0 32847.8 54340.0 54522.5 54699.5 55068.4 55602.7 47383.3 48236.8 48883.0 49323.8 49795.8 47931.3 48940.8 48985.0 49023.0 49808.5 46432.0 46890.1 47096.0 47936.8 49840.3 40439.0 40548.4 40945.4 41256.8 42070.6 31413.8 31791.2 32099.8 32201.7 32325.2 09:57:40 INFO - PROCESS | 8386 | 09:57:40 INFO - PROCESS | 8386 | [#2] jquery.html Cycles:180 Average:30845.61 Median:11903.41 stddev:86581.42 (727.4%) stddev-sans-first:86794.09 09:57:40 INFO - PROCESS | 8386 | Values: 382.5 386.4 402.2 416.3 434.6 18482.6 19445.7 20269.8 21244.0 21340.0 16432.7 16549.7 20880.1 21215.9 21236.6 5628.8 5905.6 5934.7 5938.5 5952.9 5562.7 5786.1 5786.8 5821.4 5826.9 5843.8 5901.9 5916.2 5916.8 5917.1 5821.9 5835.8 5961.7 6133.3 6136.3 5839.3 5904.3 5950.3 5967.9 6036.2 14825.5 15071.8 15177.5 21007.6 24722.1 2493.0 2497.5 2666.1 2843.0 2894.7 10621.8 10896.3 10963.7 11035.9 11296.4 2056.2 2095.8 2106.9 2119.4 2128.9 80428.0 88743.1 89163.6 89921.0 91278.5 11068.8 11406.7 11556.1 11736.0 11800.7 13181.3 13949.7 16562.8 16607.1 16672.4 8463.9 8608.3 8730.6 8731.8 8957.7 8920.0 8937.5 9055.1 9126.9 9304.6 4263.3 4336.4 4366.4 4447.2 4486.4 2047.9 2070.6 2125.0 2135.7 2155.1 944.7 1014.6 1027.3 1107.1 1110.7 21129.9 21321.7 21337.5 21651.7 21826.5 1019.0 1050.9 1066.6 1072.4 1114.8 10944.9 11682.2 11684.8 11770.5 12012.3 486644.0 514816.0 525525.0 538952.0 553907.0 95022.0 96911.0 98191.5 99560.5 99791.5 1077.6 1080.7 1116.0 1116.0 1119.6 18867.2 19212.5 19520.5 19541.4 19574.9 45035.5 45459.3 46020.0 46066.1 46980.8 63915.7 64353.0 64746.4 65119.3 67061.7 8853.0 8925.9 9033.9 9088.9 9131.8 13101.5 13541.1 13615.8 14266.6 14390.6 11529.3 11553.5 12097.0 12276.8 12321.8 11635.1 11801.9 11897.2 12006.4 12426.4 11935.4 11951.0 12030.2 12243.9 12292.5 8953.0 8992.4 9017.2 9028.7 9238.0 11766.9 11877.9 11927.7 11949.2 12105.1 09:57:40 INFO - PROCESS | 8386 | 09:57:40 INFO - PROCESS | 8386 | [#3] mootools.html Cycles:180 Average:5862.12 Median:4860.82 stddev:3252.40 (66.9%) stddev-sans-first:3245.31 09:57:40 INFO - PROCESS | 8386 | Values: 1541.0 1546.3 1721.7 1843.3 1849.0 7506.4 8286.5 8318.8 8917.4 8971.6 8293.6 8503.4 8730.9 8736.3 8751.3 2423.6 2460.2 2481.7 2483.3 2484.1 2376.7 2452.5 2456.4 2467.8 2469.3 2441.2 2450.8 2454.7 2458.8 2466.4 2325.8 2326.0 2329.4 2368.8 2397.3 2343.4 2344.0 2355.1 2359.0 2370.2 8578.0 8624.4 8689.2 8761.4 8765.9 5422.2 5473.4 5538.8 5539.1 5549.6 3469.2 3484.8 3548.4 3563.3 3570.6 1134.4 1153.9 1230.0 1230.5 1254.7 11748.6 11882.2 11897.2 11925.8 11974.0 4615.7 4659.2 4800.9 4856.3 4865.3 10132.5 10153.4 10177.1 10198.1 10210.9 5441.9 5474.2 5479.6 5496.1 5511.3 4224.3 4938.7 5041.3 5052.7 5065.9 4187.8 4268.3 4280.6 4324.4 4324.7 1192.2 1208.5 1223.3 1232.3 1250.2 938.4 961.3 1011.8 1026.8 1036.2 5942.7 5945.8 6127.5 6133.2 6184.9 485.7 492.5 493.7 508.0 508.6 4190.1 4195.6 4276.3 4296.2 4314.7 10927.7 11087.3 11155.3 11168.0 11180.8 11023.0 11034.9 11046.8 11055.9 11056.9 7848.3 8133.8 8437.4 8464.7 8476.6 6094.3 6201.5 6282.9 6304.0 6383.6 10142.7 10194.4 10206.9 10222.5 10224.8 9552.3 9925.3 9948.6 10048.0 10109.8 6952.7 7556.1 7734.8 7781.8 7935.9 8613.4 8635.3 8684.8 8686.2 8731.2 7590.1 7686.6 7842.4 7859.9 8011.6 7453.4 7517.8 7531.2 7589.1 7677.7 7276.3 7381.6 7422.0 7426.4 7427.3 5503.1 5532.4 5548.8 5573.5 5626.4 5419.5 5486.9 5538.5 5577.2 5598.9 09:57:40 INFO - PROCESS | 8386 | 09:57:40 INFO - PROCESS | 8386 | [#4] prototype.html Cycles:180 Average:4908.48 Median:5486.98 stddev:1433.22 (26.1%) stddev-sans-first:1395.77 09:57:40 INFO - PROCESS | 8386 | Values: 348.3 352.1 356.8 357.1 363.2 5742.2 5782.5 5803.5 5824.5 5828.8 5725.6 5727.2 5751.0 5785.4 5795.6 4300.4 4410.3 4447.5 4454.7 4523.0 4453.7 4455.9 4464.3 4484.6 4506.9 4452.3 4475.8 4491.1 4503.6 4517.5 4387.8 4389.8 4422.8 4449.6 4450.6 3869.0 3869.5 3883.4 3893.4 3908.2 5600.6 5627.9 5628.5 5663.1 5695.6 4968.7 5083.7 5131.3 5142.8 5143.6 5971.8 5987.2 6017.2 6029.5 6061.6 4331.0 4359.2 4370.8 4384.5 4397.0 6030.0 6078.8 6238.8 6280.4 6281.0 4291.2 4294.6 4308.7 4309.2 4337.1 4382.1 4390.4 4401.4 4401.6 4406.2 4926.4 5069.7 5076.9 5084.0 5106.6 6024.1 6042.0 6061.1 6070.0 6099.7 1501.9 1539.7 1554.4 1555.9 1558.2 907.6 949.9 959.5 962.0 964.2 5140.6 5191.7 5192.2 5197.5 5232.3 5430.6 5521.9 5543.1 5548.9 5571.2 5008.7 5193.9 5239.2 5255.5 5258.6 2905.7 2922.9 2928.0 2931.2 2938.4 5490.1 5613.4 5617.9 5666.8 5686.2 5237.8 5483.8 5528.4 5581.4 5585.4 5570.6 5610.3 5661.5 5665.0 5673.5 5138.6 5429.8 5469.7 5516.4 5528.0 5329.4 5361.4 5396.0 5411.0 5413.2 5506.8 6033.8 6154.3 6287.1 6378.4 4384.0 4408.2 4455.1 4466.2 4466.2 5940.1 6230.5 6253.6 6270.2 6311.3 6092.3 6116.9 6140.8 6141.8 6153.2 6042.5 6106.2 6118.9 6141.9 6157.8 5740.7 6031.4 6058.3 6075.7 6137.8 6151.4 6175.8 6179.3 6184.4 6197.0 5946.5 5991.0 5998.7 6006.8 6024.7 09:57:40 INFO - PROCESS | 8386 | 09:57:40 INFO - PROCESS | 8386 | [#5] yui.html Cycles:180 Average:22603.80 Median:3765.44 stddev:32902.30 (873.8%) stddev-sans-first:32953.74 09:57:40 INFO - PROCESS | 8386 | Values: 764.7 807.6 857.4 900.8 919.1 16032.8 16954.5 17024.5 17060.5 17107.2 14417.4 14579.2 14702.9 14732.5 14753.6 875.0 892.8 893.1 893.8 898.1 878.1 878.9 880.5 882.3 884.4 935.4 935.4 935.4 937.7 940.0 924.5 933.1 933.2 933.3 933.3 689.6 691.0 692.1 694.3 695.3 16301.5 16304.0 16307.8 16342.5 16382.1 15776.8 16473.6 16520.0 16551.7 17306.2 13783.3 13828.1 13851.0 13869.2 13978.4 4104.8 4128.7 4136.2 4159.4 4200.9 53378.5 78182.0 79853.6 87818.0 95430.5 12032.8 12383.1 12456.8 12458.4 12461.0 22036.2 22150.7 22619.8 22732.2 23152.8 8295.8 9086.9 9217.4 9316.6 9536.3 4983.4 5018.2 5028.7 5038.3 5079.3 3720.2 3810.7 3827.1 3849.5 3860.0 664.2 664.7 672.0 708.3 710.7 665.4 818.9 834.0 839.4 852.6 10311.7 10698.6 11054.4 11067.2 11270.4 767.6 809.4 812.4 820.5 831.8 5001.7 5228.2 5274.3 5281.2 5324.7 73463.0 80607.0 101659.0 127869.7 139480.0 119030.4 120651.0 121692.0 122149.4 122843.0 62449.4 92837.1 103871.0 104442.0 108274.5 9966.2 10030.4 10084.5 10088.7 10212.0 61799.0 61845.4 62063.4 62654.7 62933.0 81019.2 84782.6 88880.6 92058.0 92499.3 12918.2 13366.0 13472.5 13697.0 14042.3 14749.8 15162.7 15247.5 15263.1 15274.4 14073.7 14184.6 14812.5 14855.4 14958.9 13470.8 13944.4 14037.6 14628.7 14635.6 14160.4 14215.6 14305.2 14514.5 14594.0 10907.5 11327.7 11341.7 11469.6 12193.9 12121.5 12356.9 12557.0 12598.4 12608.7 09:57:40 INFO - PROCESS | 8386 | -------- Summary: end -------- 09:57:40 INFO - PROCESS | 8386 | 09:57:41 INFO - TEST-INFO | 8386: exit 0 09:57:41 INFO - TEST-OK | dromaeo_css | took 1110969ms 09:57:41 INFO - TEST-START | kraken 09:57:41 INFO - operating with platform_type : linux_ 09:57:41 INFO - Initialising browser for kraken test... 09:57:41 INFO - TEST-INFO | started process 8649 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJw4YN_/profile http://localhost:33879/getInfo.html) 09:57:45 INFO - PROCESS | 8649 | __metrics Screen width/height:1600/1200 09:57:45 INFO - PROCESS | 8649 | colorDepth:24 09:57:45 INFO - PROCESS | 8649 | Browser inner width/height: 1024/697 09:57:45 INFO - PROCESS | 8649 | __metrics 09:57:46 ERROR - PROCESS | 8649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 09:57:47 INFO - TEST-INFO | 8649: exit 0 09:57:47 INFO - Browser initialized. 09:57:47 INFO - Running cycle 1/1 for kraken test... 09:57:47 INFO - TEST-INFO | started process 8774 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJw4YN_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:59 INFO - PROCESS | 8774 | RSS: Main: 153059328 09:57:59 INFO - PROCESS | 8774 | 09:58:01 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33879/tests/kraken/driver.html?testName=audio-beat-detection) 09:58:02 INFO - PROCESS | 8774 | RSS: Main: 158187520 09:58:02 INFO - PROCESS | 8774 | 09:58:05 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33879/tests/kraken/driver.html?testName=audio-dft) 09:58:05 INFO - PROCESS | 8774 | RSS: Main: 156811264 09:58:05 INFO - PROCESS | 8774 | 09:58:08 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33879/tests/kraken/driver.html?testName=audio-fft) 09:58:09 INFO - PROCESS | 8774 | RSS: Main: 157818880 09:58:09 INFO - PROCESS | 8774 | 09:58:11 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33879/tests/kraken/driver.html?testName=audio-oscillator) 09:58:11 INFO - PROCESS | 8774 | RSS: Main: 157028352 09:58:11 INFO - PROCESS | 8774 | 09:58:14 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33879/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:58:14 INFO - PROCESS | 8774 | RSS: Main: 157302784 09:58:14 INFO - PROCESS | 8774 | 09:58:17 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33879/tests/kraken/driver.html?testName=imaging-darkroom) 09:58:18 INFO - PROCESS | 8774 | RSS: Main: 156119040 09:58:18 INFO - PROCESS | 8774 | 09:58:21 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33879/tests/kraken/driver.html?testName=imaging-desaturate) 09:58:22 INFO - PROCESS | 8774 | RSS: Main: 154853376 09:58:22 INFO - PROCESS | 8774 | 09:58:25 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33879/tests/kraken/driver.html?testName=json-parse-financial) 09:58:25 INFO - PROCESS | 8774 | RSS: Main: 156807168 09:58:25 INFO - PROCESS | 8774 | 09:58:27 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33879/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:58:28 INFO - PROCESS | 8774 | RSS: Main: 156868608 09:58:28 INFO - PROCESS | 8774 | 09:58:29 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33879/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:58:30 INFO - PROCESS | 8774 | RSS: Main: 156536832 09:58:30 INFO - PROCESS | 8774 | 09:58:32 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33879/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:58:33 INFO - PROCESS | 8774 | RSS: Main: 157032448 09:58:33 INFO - PROCESS | 8774 | 09:58:35 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33879/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:58:36 INFO - PROCESS | 8774 | RSS: Main: 157319168 09:58:36 INFO - PROCESS | 8774 | 09:58:39 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33879/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:58:39 INFO - PROCESS | 8774 | RSS: Main: 158068736 09:58:39 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | Cycle 1(1): loaded http://localhost:33879/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33879/tests/kraken/driver.html?testName=ai-astar) 09:58:41 INFO - PROCESS | 8774 | RSS: Main: 158707712 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | __start_tp_report 09:58:41 INFO - PROCESS | 8774 | _x_x_mozilla_page_load 09:58:41 INFO - PROCESS | 8774 | _x_x_mozilla_page_load_details 09:58:41 INFO - PROCESS | 8774 | |i|pagename|runs| 09:58:41 INFO - PROCESS | 8774 | |0;ai-astar;101;112;105;111;112;111;112;111;112;112 09:58:41 INFO - PROCESS | 8774 | |1;audio-beat-detection;142;117;144;166;118;154;141;116;162;116 09:58:41 INFO - PROCESS | 8774 | |2;audio-dft;162;162;162;162;164;164;161;162;162;162 09:58:41 INFO - PROCESS | 8774 | |3;audio-fft;75;75;76;167;77;78;77;75;173;75 09:58:41 INFO - PROCESS | 8774 | |4;audio-oscillator;83;82;153;84;84;83;152;83;84;142 09:58:41 INFO - PROCESS | 8774 | |5;imaging-gaussian-blur;118;117;117;122;121;117;118;117;118;118 09:58:41 INFO - PROCESS | 8774 | |6;imaging-darkroom;148;147;146;146;147;147;148;147;147;148 09:58:41 INFO - PROCESS | 8774 | |7;imaging-desaturate;96;97;97;95;96;96;95;95;95;95 09:58:41 INFO - PROCESS | 8774 | |8;json-parse-financial;52;51;52;51;52;53;51;51;51;51 09:58:41 INFO - PROCESS | 8774 | |9;json-stringify-tinderbox;65;62;60;62;61;60;60;61;61;60 09:58:41 INFO - PROCESS | 8774 | |10;stanford-crypto-aes;76;72;73;73;73;72;77;81;77;72 09:58:41 INFO - PROCESS | 8774 | |11;stanford-crypto-ccm;120;118;122;132;118;128;119;117;160;136 09:58:41 INFO - PROCESS | 8774 | |12;stanford-crypto-pbkdf2;150;154;160;151;152;149;152;153;148;150 09:58:41 INFO - PROCESS | 8774 | |13;stanford-crypto-sha256-iterative;62;66;61;62;62;60;61;62;61;62 09:58:41 INFO - PROCESS | 8774 | __end_tp_report 09:58:41 INFO - PROCESS | 8774 | __start_cc_report 09:58:41 INFO - PROCESS | 8774 | _x_x_mozilla_cycle_collect,875 09:58:41 INFO - PROCESS | 8774 | __end_cc_report 09:58:41 INFO - PROCESS | 8774 | __startTimestamp1472921921553__endTimestamp 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | ------- Summary: start ------- 09:58:41 INFO - PROCESS | 8774 | Number of tests: 14 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#0] ai-astar Cycles:10 Average:109.90 Median:112.00 stddev:3.78 (3.4%) stddev-sans-first:2.26 09:58:41 INFO - PROCESS | 8774 | Values: 101.0 112.0 105.0 111.0 112.0 111.0 112.0 111.0 112.0 112.0 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#1] audio-beat-detection Cycles:10 Average:137.60 Median:143.00 stddev:19.68 (13.8%) stddev-sans-first:20.81 09:58:41 INFO - PROCESS | 8774 | Values: 142.0 117.0 144.0 166.0 118.0 154.0 141.0 116.0 162.0 116.0 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 09:58:41 INFO - PROCESS | 8774 | Values: 162.0 162.0 162.0 162.0 164.0 164.0 161.0 162.0 162.0 162.0 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#3] audio-fft Cycles:10 Average:94.80 Median:75.50 stddev:39.67 (52.5%) stddev-sans-first:41.43 09:58:41 INFO - PROCESS | 8774 | Values: 75.0 75.0 76.0 167.0 77.0 78.0 77.0 75.0 173.0 75.0 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#4] audio-oscillator Cycles:10 Average:103.00 Median:83.00 stddev:31.88 (38.4%) stddev-sans-first:32.98 09:58:41 INFO - PROCESS | 8774 | Values: 83.0 82.0 153.0 84.0 84.0 83.0 152.0 83.0 84.0 142.0 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#5] imaging-gaussian-blur Cycles:10 Average:118.30 Median:118.00 stddev:1.77 (1.5%) stddev-sans-first:1.87 09:58:41 INFO - PROCESS | 8774 | Values: 118.0 117.0 117.0 122.0 121.0 117.0 118.0 117.0 118.0 118.0 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 09:58:41 INFO - PROCESS | 8774 | Values: 148.0 147.0 146.0 146.0 147.0 147.0 148.0 147.0 147.0 148.0 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 09:58:41 INFO - PROCESS | 8774 | Values: 96.0 97.0 97.0 95.0 96.0 96.0 95.0 95.0 95.0 95.0 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.50 stddev:0.71 (1.4%) stddev-sans-first:0.73 09:58:41 INFO - PROCESS | 8774 | Values: 52.0 51.0 52.0 51.0 52.0 53.0 51.0 51.0 51.0 51.0 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.00 stddev:1.55 (2.5%) stddev-sans-first:0.83 09:58:41 INFO - PROCESS | 8774 | Values: 65.0 62.0 60.0 62.0 61.0 60.0 60.0 61.0 61.0 60.0 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:74.50 stddev:3.03 (4.1%) stddev-sans-first:3.17 09:58:41 INFO - PROCESS | 8774 | Values: 76.0 72.0 73.0 73.0 73.0 72.0 77.0 81.0 77.0 72.0 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:125.00 stddev:13.32 (10.7%) stddev-sans-first:13.88 09:58:41 INFO - PROCESS | 8774 | Values: 120.0 118.0 122.0 132.0 118.0 128.0 119.0 117.0 160.0 136.0 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.90 Median:152.00 stddev:3.38 (2.2%) stddev-sans-first:3.52 09:58:41 INFO - PROCESS | 8774 | Values: 150.0 154.0 160.0 151.0 152.0 149.0 152.0 153.0 148.0 150.0 09:58:41 INFO - PROCESS | 8774 | 09:58:41 INFO - PROCESS | 8774 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:1.60 (2.6%) stddev-sans-first:1.69 09:58:41 INFO - PROCESS | 8774 | Values: 62.0 66.0 61.0 62.0 62.0 60.0 61.0 62.0 61.0 62.0 09:58:41 INFO - PROCESS | 8774 | -------- Summary: end -------- 09:58:41 INFO - PROCESS | 8774 | 09:58:42 INFO - TEST-INFO | 8774: exit 0 09:58:42 INFO - TEST-OK | kraken | took 60870ms 09:58:42 INFO - SUITE-END | took 1171s 09:58:42 INFO - Completed test suite (00:19:32) 09:58:42 INFO - javascript benchmark 09:58:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7686.545175674973, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3252.934941301174, 3277.868885244589, 3287.408266512028, 3303.2237424205323, 3303.719183869371, 5686.943130568693, 5690.57320700947, 5721.399253078429, 5764.510016648579, 5772.220750198722, 5711.9718450678865, 5751.367349591552, 5751.946081722996, 5760.62555933864, 5763.942360576403, 5191.143461328886, 5204.089284375238, 5252.054630166568, 5276.366835979668, 5312.707801070939, 5129.666571672829, 5141.691498510101, 5193.63644544882, 5238.00477909198, 5275.366955965271, 5195.090859099661, 5239.5546378557865, 5256.316678831766, 5258.0, 5304.522592966632, 5312.707801070939, 5319.308489896327, 5325.893482130355, 5335.33308336458, 5364.436734142898, 4980.178270585349, 5080.339555857752, 5111.028904508132, 5202.817901373433, 5230.398504172031, 5666.150077488369, 5700.7149642518125, 5713.285839270091, 5721.34204566476, 5768.480836724715, 6139.201903752483, 6143.78496752616, 6144.109104179869, 6184.6598437086395, 6189.319174890758, 5942.405759424067, 6130.785422510191, 6144.740328232701, 6220.1913751212505, 6227.501799856001, 5813.215215945877, 5815.505682017034, 5818.709064546798, 5825.883482330372, 5845.970770146165, 6356.523260755462, 6359.300476947532, 6366.363363663597, 6375.649339286384, 6394.136791533177, 5852.590318677737, 5873.794417195378, 5881.676507792027, 6078.969605151946, 6098.14625952367, 6010.459058684739, 6043.153958445821, 6053.303870054912, 6059.848503787441, 6074.848128796727, 6027.276726792726, 6028.457438830526, 6047.9395206047375, 6051.939480605226, 6059.515238780887, 5847.912281315784, 5966.671833049224, 6048.818535443944, 6048.879022419527, 6068.575199735976, 4725.10223057618, 4728.4562275338085, 4748.952510474851, 4752.049590081998, 4767.141914455431, 4198.895027624334, 4215.051613386963, 4217.261979153636, 4240.936385954213, 4242.8727138185905, 5724.914126288109, 5738.139279108083, 5776.306843178845, 5972.641641501524, 5989.131575921554, 6055.303640081359, 6068.33248342682, 6069.2716873975405, 6101.542384321194, 6108.480779133734, 5835.183074369592, 5838.095095260242, 5842.036064049385, 5851.707414629252, 5865.970670146622, 5151.2530683050745, 5166.870828229325, 5167.534921857051, 5249.238860365225, 5269.104252277122, 6251.468625166912, 6274.121622972787, 6275.905861412082, 6276.0, 6291.622502649856, 6028.4272994065495, 6041.335453100068, 6051.878962420815, 6058.3335833057445, 6062.939370606414, 6123.191113103653, 6191.380861913773, 6215.192025036717, 6225.750969961151, 6262.718177681924, 5981.3420523743225, 6021.0, 6025.427584379477, 6051.485623721856, 6055.515558755377, 6117.265928088658, 6135.570510064253, 6148.200733904564, 6161.476274516537, 6173.0, 5798.913016304675, 5935.258092738408, 5967.791127310524, 6027.126066720389, 6033.758649653964, 5975.55183361241, 5997.280326360865, 6017.578769486094, 6023.45788879003, 6052.90920636182, 5902.675352855634, 5950.464458198783, 5952.523798096228, 5959.67221802805, 5991.0, 5793.681347525927, 5956.8808623828145, 5975.880482390415, 6038.8188354347685, 6039.818805435668, 5963.373845746134, 5977.820665380045, 5978.222831031938, 5991.281046274475, 6010.87978240424], "value": 5643.818937440375, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.2369883603062, 638.3361304243587, 658.5456035335618, 671.9563228390151, 711.2283172757558, 42730.0, 47502.287465688, 47638.76180619097, 47697.52302476974, 47906.0, 46330.0, 46720.299195512096, 46870.76564617181, 46976.530234697726, 47293.52706472943, 9056.230220431255, 9173.853268341458, 9205.0, 9206.631734730636, 9228.354015218938, 9097.909020909805, 9181.58682859273, 9225.077492250788, 9235.538223088852, 9257.0, 9162.816743665157, 9174.816503669923, 9197.908020919773, 9218.078192180761, 9268.629254829799, 9048.185663290302, 9132.908670913272, 9147.22248608869, 9183.540822958825, 9197.540122993856, 9053.864192037125, 9103.36276460648, 9106.86339704905, 9125.817483650357, 9179.036201198845, 45043.09913801739, 45287.54712452866, 45490.54509454929, 45502.77248613769, 45616.77191614054, 3091.90724278272, 3583.068402215443, 3642.5993140754495, 3669.9633003669887, 3797.544294684628, 34901.30197396063, 35310.29379412397, 36349.818250908844, 36561.45157822635, 36588.63411365879, 4651.534846515321, 4684.976575117103, 4688.203005489075, 4732.337472753818, 5009.499050094998, 391070.0, 392658.0, 394007.0, 396653.0, 397503.0, 62412.37587624157, 63213.36786632167, 63256.68371658204, 63401.68299158567, 63651.68174159192, 41037.0, 41486.1702765943, 41587.0, 44256.557434425886, 74888.62555687295, 20091.698624520643, 20394.490137746674, 20453.59092818165, 20488.69266960997, 20505.692414613794, 13846.515371961934, 13850.09974351664, 13932.512362067482, 13967.580972570839, 14022.0, 22740.317790466313, 22743.772562274164, 22853.971571279333, 22936.197233096762, 22942.541149176923, 7943.364530837519, 7999.720009799629, 8161.469504482189, 8180.67277308901, 8247.340212782963, 1042.4631314872695, 1086.0, 1109.450821843193, 1130.9830352544718, 1262.1921969939062, 35911.0, 35959.0, 36554.8172259137, 36623.4506482403, 36787.816060919526, 1168.053876360151, 1180.8486725442624, 1197.7724232395817, 1205.0720944872398, 1214.3988725580718, 18837.434876953714, 18964.525886852938, 19025.809741902405, 19026.42920712381, 19108.0, 653380.0, 655721.0, 656968.0, 660915.0, 662888.0, 66435.0, 67221.66389168023, 67264.0, 67353.0, 67413.66293168503, 1429.963276624439, 1442.1563385419647, 1449.9275036248146, 1458.3947661720479, 1459.9635009124856, 32630.83684581562, 34555.827220863735, 34800.65199347974, 34808.0, 35328.823355883054, 57659.0, 57924.42075579359, 58283.708581458515, 58293.0, 58500.707496462244, 531767.0, 537093.0, 541472.0, 543142.0, 544917.0, 31552.52671209888, 32276.35447290994, 32356.0, 32509.0, 32847.83576082104, 54340.0, 54522.45477545174, 54699.45300547103, 55068.44931550793, 55602.72198639143, 47383.28925066058, 48236.75881620569, 48883.0, 49323.75338123286, 49795.751021244665, 47931.28103078526, 48940.75529622329, 48985.0, 49023.0, 49808.501914980385, 46432.0, 46890.06219875652, 47096.0, 47936.7603161982, 49840.252396213364, 40439.0, 40548.391774124, 40945.385819213334, 41256.79371603123, 42070.57929420667, 31413.8429307852, 31791.205219869687, 32099.839500802347, 32201.677983219866, 32325.191870202492], "value": 22160.44418434623, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.48364707644663, 386.38178913738005, 402.17554014270746, 416.26736942980347, 434.63056402058254, 18482.630347393046, 19445.70831437528, 20269.797302026975, 21244.044018019187, 21340.03969821358, 16432.67134657306, 16549.66900661989, 20880.06039728212, 21215.893920530416, 21236.57526849466, 5628.802991895294, 5905.616134951236, 5934.673592952386, 5938.495227905633, 5952.940470595304, 5562.666240025591, 5786.132080187985, 5786.768529258825, 5821.417858214187, 5826.941730582704, 5843.79546715865, 5901.911471327933, 5916.171735956969, 5916.763329466816, 5917.112433135043, 5821.912671309934, 5835.795747148851, 5961.731722072517, 6133.264008318985, 6136.2636483622255, 5839.299284085937, 5904.321003084614, 5950.285965684146, 5967.850803729942, 6036.15493830864, 14825.481108161162, 15071.773923391158, 15177.544673659697, 21007.579848402947, 24722.13472528453, 2492.962605560918, 2497.463045445238, 2666.0668765931964, 2842.9857850710823, 2894.6815850256453, 10621.84067238992, 10896.346219226793, 10963.725906852313, 11035.889641103648, 11296.435178241056, 2056.208359781495, 2095.8218551422997, 2106.852520323578, 2119.395972147946, 2128.9041993110345, 80428.0, 88743.11256887413, 89163.55418222932, 89921.0, 91278.5436072822, 11068.778624427467, 11406.714832129097, 11556.075513958864, 11736.0, 11800.70498237551, 13181.275029873257, 13949.721005579831, 16562.83437165637, 16607.086610236554, 16672.416465423652, 8463.915360846437, 8608.311335093304, 8730.56347182651, 8731.781705457415, 8957.731268061969, 8920.0, 8937.46375217489, 9055.090896523101, 9126.908730912606, 9304.581293841793, 4263.253930562139, 4336.371226172188, 4366.388705581221, 4447.2217361962175, 4486.416765820488, 2047.897605119738, 2070.6272870883386, 2124.968125478119, 2135.7330333708287, 2155.148716257101, 944.7260294514651, 1014.5586669798661, 1027.337367398039, 1107.092184408777, 1110.7001109700336, 21129.89435052815, 21321.68017479739, 21337.466563336042, 21651.67522487164, 21826.454338641663, 1018.9643362482426, 1050.894910508943, 1066.5946940162844, 1072.4155335342257, 1114.7603265298, 10944.945275273732, 11682.182247242781, 11684.766304673858, 11770.470328835223, 12012.279263244232, 486644.0, 514816.0, 525525.0, 538952.0, 553907.0, 95022.0, 96911.0, 98191.50904245433, 99560.50219748855, 99791.50104249577, 1077.5851297250615, 1080.7460246841986, 1115.983260251097, 1116.0234794554765, 1119.6473110970019, 18867.245310187987, 19212.519687007938, 19520.511987200432, 19541.413757587296, 19574.902125489283, 45035.54964450314, 45459.31811022903, 46020.0, 46066.078678426915, 46980.7650961743, 63915.68042159759, 64353.0, 64746.352536474034, 65119.34880651133, 67061.66469167623, 8852.955735221283, 8925.866112008196, 9033.864492032493, 9088.863667044867, 9131.817363652823, 13101.541446049343, 13541.1198272112, 13615.79576306376, 14266.572002839932, 14390.568282951528, 11529.308241505536, 11553.458239187365, 12097.0, 12276.754464910831, 12321.753564928473, 11635.069194464253, 11801.940990294994, 11897.167198296036, 12006.39968001579, 12426.440810163384, 11935.403229838646, 11951.0, 12030.15788894769, 12243.87756122463, 12292.508299667914, 8953.0, 8992.415492992934, 9017.188453038996, 9028.683996060106, 9238.0, 11766.882331176921, 11877.881221187678, 11927.701807454883, 11949.22330048544, 12105.063189889575], "value": 9668.001513166804, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1541.0060510970425, 1546.2887071946905, 1721.655668866227, 1843.2811203630579, 1849.0384999800112, 7506.361959233458, 8286.544240066794, 8318.83362332753, 8917.420367676104, 8971.551422428885, 8293.626786794608, 8503.404761666687, 8730.869036964436, 8736.301095912328, 8751.256143227818, 2423.5637585234695, 2460.2250291158316, 2481.7270100289043, 2483.341914392687, 2484.068474322129, 2376.6791483149727, 2452.4849781545895, 2456.4104614892476, 2467.7902378297777, 2469.296250568588, 2441.24321460347, 2450.80393568515, 2454.6686197363397, 2458.815588830836, 2466.3710753757855, 2325.790678838904, 2326.034695601332, 2329.3827135809024, 2368.7868091871815, 2397.2688330059327, 2343.437574981992, 2344.0, 2355.1286024170995, 2358.9646155307682, 2370.182287110944, 8577.957110214471, 8624.396292259544, 8689.17452841979, 8761.386702930797, 8765.912340876574, 5422.186671999192, 5473.425290344497, 5538.806141785019, 5539.086050801613, 5549.6115271930985, 3469.1500582357485, 3484.756067075306, 3548.361294966905, 3563.2873425314783, 3570.589382221052, 1134.3647557367817, 1153.892263427111, 1229.9754004919942, 1230.544698461566, 1254.7239607286383, 11748.647540573797, 11882.22765520247, 11897.167198296123, 11925.761484770343, 11974.0, 4615.653825963066, 4659.207934651117, 4800.903981920342, 4856.344393506902, 4865.294532292798, 10132.493375331203, 10153.39079655223, 10177.084062434416, 10198.133158681596, 10210.948945255375, 5441.891162176814, 5474.178873168977, 5479.643823151482, 5496.093144631171, 5511.338639363302, 4224.324108142682, 4938.728369939688, 5041.269015992661, 5052.747362631853, 5065.873353166126, 4187.832486700559, 4268.338407546835, 4280.636145927569, 4324.394584758136, 4324.675649326313, 1192.2071822238288, 1208.4863932828512, 1223.2782658231508, 1232.3345393487416, 1250.2311078686723, 938.4088024544493, 961.3318743473219, 1011.8077565262577, 1026.8459731040255, 1036.1658864614058, 5942.673152976541, 5945.821625351246, 6127.479164270998, 6133.233345831771, 6184.938150618526, 485.6964397251718, 492.50995259716814, 493.7235148316954, 508.0271280497879, 508.59312549959884, 4190.1200747843295, 4195.601417865298, 4276.273033584298, 4296.183725092223, 4314.741115533078, 10927.726806829893, 11087.279326843729, 11155.27490713101, 11167.994880460798, 11180.832287515694, 11023.0, 11034.944825275823, 11046.834297485544, 11055.944720276348, 11056.944715276371, 7848.332891704269, 8133.796655083552, 8437.409381343246, 8464.703735369232, 8476.576171191417, 6094.299155597163, 6201.534884883742, 6282.900492413901, 6304.022876454157, 6383.553151279366, 10142.746431339276, 10194.388336699822, 10206.948965255126, 10222.48887555634, 10224.846627300447, 9552.331336806357, 9925.305228634215, 9948.552315145691, 10048.045435683598, 10109.848352274868, 6952.687129079305, 7556.131044929683, 7734.845303093794, 7781.805454863447, 7935.920640793749, 8613.397062205586, 8635.266002389868, 8684.78288042804, 8686.218240358145, 8731.170538798804, 7590.127135379502, 7686.615669216405, 7842.411819113703, 7859.921400785919, 8011.599420029092, 7453.403727701662, 7517.849643007218, 7531.171571127062, 7589.1272503662785, 7677.692892284246, 7276.272372762682, 7381.630918454163, 7422.035135432359, 7426.4430167736555, 7427.25727427253, 5503.064479038493, 5532.446755324436, 5548.778048878, 5573.470520300565, 5626.38109807929, 5419.458054194707, 5486.890262194654, 5538.501534861882, 5577.247071645276, 5598.944010559842], "value": 4663.069575898686, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.3190362840647, 352.0617554218009, 356.7930600251854, 357.0716138041092, 363.17559140750495, 5742.196092547041, 5782.53739700824, 5803.506701930331, 5824.4565190224575, 5828.766849326027, 5725.6278341907855, 5727.169560413738, 5750.971245143768, 5785.363610002896, 5795.551112221944, 4300.376445415405, 4410.31640095784, 4447.510773814878, 4454.732716037032, 4522.954770452303, 4453.710508816933, 4455.933161002587, 4464.308032255006, 4484.641228701712, 4506.932396014063, 4452.332150177467, 4475.776211189427, 4491.079328737617, 4503.594676479116, 4517.4804897436725, 4387.846425375096, 4389.758563279019, 4422.756748378838, 4449.599536041772, 4450.577195166454, 3869.0, 3869.496965394509, 3883.3980732986433, 3893.364786789555, 3908.237893610747, 5600.635958662674, 5627.915581266284, 5628.521575666074, 5663.122216056518, 5695.572832037614, 4968.677035992649, 5083.720395378253, 5131.307273518065, 5142.768575414116, 5143.614228932845, 5971.761129554812, 5987.221661184061, 6017.217761690952, 6029.517638588946, 6061.605995610315, 4331.0, 4359.171757366122, 4370.847020354304, 4384.473863136413, 4396.978015109904, 6030.040236923018, 6078.756849726005, 6238.785231242889, 6280.403361680679, 6280.968595157041, 4291.206126866539, 4294.57054294568, 4308.67684923632, 4309.202797482476, 4337.067530480898, 4382.145481631115, 4390.363397307373, 4401.405810215644, 4401.603855652943, 4406.228909940747, 4926.3842019747535, 5069.670471419346, 5076.898462030813, 5084.0, 5106.591472682177, 6024.138534196221, 6042.0, 6061.121137435047, 6070.025687413224, 6099.664518451439, 1501.8873584481207, 1539.7151526967548, 1554.4326320892792, 1555.9066456012677, 1558.2364641325669, 907.6142639378238, 949.943003419797, 959.5490119643756, 961.9663311784054, 964.2478866484154, 5140.614453915971, 5191.740412979336, 5192.221166825007, 5197.506236907488, 5232.2674825524455, 5430.56555475561, 5521.907731670278, 5543.085390910535, 5548.8624831909365, 5571.220029195916, 5008.719288865799, 5193.870153246124, 5239.214117882349, 5255.527002569787, 5258.553022993012, 2905.6513218413925, 2922.933129407751, 2928.0089539113605, 2931.1792698044583, 2938.4416960777385, 5490.129458731584, 5613.354464236665, 5617.859553511195, 5666.773329066791, 5686.161840757555, 5237.826302084668, 5483.835484935458, 5528.44715528452, 5581.358143813513, 5585.413531579207, 5570.579502226906, 5610.260819145996, 5661.49970257882, 5664.971675141598, 5673.489385955284, 5138.614603904647, 5429.809956651498, 5469.699166545878, 5516.365617953827, 5528.0, 5329.387120481194, 5361.4265152727685, 5396.001739678172, 5411.0, 5413.160960051198, 5506.80726174598, 6033.818985430268, 6154.323024467216, 6287.0510141856175, 6378.425941665272, 4383.978080109579, 4408.228560001922, 4455.108978204307, 4466.151431228186, 4466.173757854807, 5940.108983652314, 6230.470410015199, 6253.624782513064, 6270.247570291412, 6311.27420346666, 6092.329843717099, 6116.908246376219, 6140.846478838065, 6141.815745527641, 6153.2000839890525, 6042.516598672008, 6106.236720409949, 6118.938810611837, 6141.877162456815, 6157.784477543264, 5740.655560666373, 6031.396860313934, 6058.27300723916, 6075.696215189135, 6137.7851775187655, 6151.446369826737, 6175.84560385976, 6179.28938172116, 6184.443400094013, 6197.03945888388, 5946.524278057658, 5991.0, 5998.670073146019, 6006.849828754316, 6024.6503863494145], "value": 4450.680356629078, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.6597264217426, 807.6365635464039, 857.3655494933743, 900.8198360327934, 919.1479169473512, 16032.83967160328, 16954.491365259062, 17024.48926532203, 17060.488185354443, 17107.230174642125, 14417.423303067864, 14579.19814410207, 14702.852971470282, 14732.484363047295, 14753.557393278192, 875.0418291970315, 892.8348255572707, 893.1291990309461, 893.7631527645146, 898.0884402331639, 878.0604752914384, 878.9296856251517, 880.4981160738382, 882.2853488674162, 884.385352180235, 935.3546053223288, 935.4200395754642, 935.4247138010109, 937.6858752317947, 940.0364626258106, 924.4776701163821, 933.09955892564, 933.2347475070411, 933.2953620016912, 933.3093510802025, 689.5965859971873, 691.022203581931, 692.0933577014127, 694.2952902803659, 695.3498478922224, 16301.51095467138, 16304.021758694516, 16307.836921630751, 16342.509724708278, 16382.098984555845, 15776.763348549666, 16473.588160295967, 16520.0, 16551.66896662072, 17306.22122004513, 13783.310834458238, 13828.101173423694, 13851.0, 13869.167849929037, 13978.440862365595, 4104.8152833122285, 4128.731632443912, 4136.152088821785, 4159.376093585956, 4200.894977625553, 53378.466215337736, 78182.0, 79853.60073199598, 87817.99999999936, 95430.52284738602, 12032.81950770739, 12383.133180677267, 12456.81314780279, 12458.439370228361, 12461.0, 22036.228731994303, 22150.667739983914, 22619.773802262094, 22732.20437284687, 23152.768472315398, 8295.751127466187, 9086.909130908738, 9217.44695318283, 9316.627334906529, 9536.28477864163, 4983.352164218701, 5018.172001619765, 5028.698278103326, 5038.34501514808, 5079.288899554065, 3720.2001569662652, 3810.676092532166, 3827.13889374894, 3849.480320156743, 3859.9807000964815, 664.1963224498411, 664.6875968294894, 671.9630420326831, 708.288170388756, 710.7121615745631, 665.3911670821241, 818.8689809630431, 833.9624716887633, 839.3872473094675, 852.6078004118051, 10311.742206444898, 10698.62554810578, 11054.354813792253, 11067.169962252703, 11270.380129092815, 767.6046835879526, 809.401043228007, 812.4212842429109, 820.5445977482435, 831.8170002599539, 5001.699898006132, 5228.215767634885, 5274.314339135888, 5281.234221037928, 5324.733763311819, 73463.0, 80607.0, 101659.0, 127869.72130278764, 139480.0, 119030.40484797521, 120651.0, 121692.0, 122149.38925305495, 122843.0, 62449.37550624526, 92837.11648790223, 103871.0, 104441.95558044474, 108274.45862770636, 9966.152877005536, 10030.398176109165, 10084.54619542137, 10088.69733907984, 10212.0, 61799.0, 61845.38154618396, 62063.37936620757, 62654.68672656608, 62933.0, 81019.18980810116, 84782.57608711917, 88880.5555972216, 92058.0, 92499.26561013966, 12918.160319579198, 13366.0, 13472.5284615038, 13697.0, 14042.297885105909, 14749.778753318495, 15162.696746064796, 15247.542573722805, 15263.084214946695, 15274.389024438899, 14073.718525629634, 14184.574462766133, 14812.481563145238, 14855.40578376896, 14958.850411495745, 13470.79793803074, 13944.442222310994, 14037.578872633838, 14628.707425851637, 14635.634109147357, 14160.362783674553, 14215.573532794033, 14305.21321327337, 14514.491992780202, 14594.0, 10907.454627268764, 11327.660170194908, 11341.659750207507, 11469.598564050219, 12193.878061219275, 12121.543066773273, 12356.938215308866, 12557.0, 12598.370081496072, 12608.74782504363], "value": 8217.780638757366, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1496.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 105.0, 111.0, 112.0, 111.0, 112.0, 111.0, 112.0, 112.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 117.0, 144.0, 166.0, 118.0, 154.0, 141.0, 116.0, 162.0, 116.0], "value": 137.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 162.0, 164.0, 164.0, 161.0, 162.0, 162.0, 162.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 167.0, 77.0, 78.0, 77.0, 75.0, 173.0, 75.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 153.0, 84.0, 84.0, 83.0, 152.0, 83.0, 84.0, 142.0], "value": 103.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 122.0, 121.0, 117.0, 118.0, 117.0, 118.0, 118.0], "value": 118.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 146.0, 146.0, 147.0, 147.0, 148.0, 147.0, 147.0, 148.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 51.0, 52.0, 53.0, 51.0, 51.0, 51.0, 51.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 60.0, 62.0, 61.0, 60.0, 60.0, 61.0, 61.0, 60.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 72.0, 73.0, 73.0, 73.0, 72.0, 77.0, 81.0, 77.0, 72.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 118.0, 122.0, 132.0, 118.0, 128.0, 119.0, 117.0, 160.0, 136.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 154.0, 160.0, 151.0, 152.0, 149.0, 152.0, 153.0, 148.0, 150.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 61.0, 62.0, 62.0, 60.0, 61.0, 62.0, 61.0, 62.0], "value": 61.9, "unit": "score"}]}]} 09:58:42 INFO - Return code: 0 09:58:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:58:42 ERROR - # TBPL SUCCESS # 09:58:42 INFO - Running post-action listener: _resource_record_post_action 09:58:42 INFO - [mozharness: 2016-09-03 16:58:42.873210Z] Finished run-tests step (success) 09:58:42 INFO - Running post-run listener: _resource_record_post_run 09:58:42 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 270336; Write bytes: 329216000; Read time: 88; Write time: 206032 09:58:42 INFO - TinderboxPrint: CPU usage
13.3% 09:58:42 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 88 09:58:42 INFO - TinderboxPrint: I/O write bytes / time
329,216,000 / 206,032 09:58:42 INFO - TinderboxPrint: CPU idle
8,226.4 (86.7%) 09:58:42 INFO - TinderboxPrint: CPU user
1,203.4 (12.7%) 09:58:42 INFO - TinderboxPrint: Swap in / out
0 / 0 09:58:42 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78381056; Read time: 0; Write time: 40708 09:58:42 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 270336; Write bytes: 249937920; Read time: 88; Write time: 165296 09:58:43 INFO - Running post-run listener: _upload_blobber_files 09:58:43 INFO - Blob upload gear active. 09:58:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:58:43 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 09:58:43 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'] 09:58:43 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 09:58:43 INFO - (blobuploader) - INFO - Open directory for files ... 09:58:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:58:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:58:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:44 INFO - (blobuploader) - INFO - Done attempting. 09:58:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:58:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:58:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:45 INFO - (blobuploader) - INFO - Done attempting. 09:58:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:58:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:58:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:49 INFO - (blobuploader) - INFO - Done attempting. 09:58:49 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:49 INFO - Return code: 0 09:58:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3bef9fba4463edec2120ed4a4172328e245bada9c76bd5bf452a9f162c04f94c621f05b07f898a4dad6f00c18b7dfb2003472c251a54ec1c90ecd35ae4647e55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9531af70de294b8580067129f6b6c45a5e9828e46eb3602a92afc2519fedfb2f65875fc4be7e49499359ed477bc7a1563e40b7d1aaeb0d5c41a63734388a2fb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f93d4285c1c7ef745278fd291046ffcb75558d7e378f5e2e9b7021802b2475f8683a067aeddd5e3310962cf59344e62221d43e10e54d499b5927f6108b9f1b60"} 09:58:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:49 INFO - Contents: 09:58:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3bef9fba4463edec2120ed4a4172328e245bada9c76bd5bf452a9f162c04f94c621f05b07f898a4dad6f00c18b7dfb2003472c251a54ec1c90ecd35ae4647e55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9531af70de294b8580067129f6b6c45a5e9828e46eb3602a92afc2519fedfb2f65875fc4be7e49499359ed477bc7a1563e40b7d1aaeb0d5c41a63734388a2fb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f93d4285c1c7ef745278fd291046ffcb75558d7e378f5e2e9b7021802b2475f8683a067aeddd5e3310962cf59344e62221d43e10e54d499b5927f6108b9f1b60"} 09:58:49 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:49 INFO - Copying logs to upload dir... 09:58:49 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.972283 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-03 09:58:49.018761) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 09:58:49.022350) ========= 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=523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3bef9fba4463edec2120ed4a4172328e245bada9c76bd5bf452a9f162c04f94c621f05b07f898a4dad6f00c18b7dfb2003472c251a54ec1c90ecd35ae4647e55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9531af70de294b8580067129f6b6c45a5e9828e46eb3602a92afc2519fedfb2f65875fc4be7e49499359ed477bc7a1563e40b7d1aaeb0d5c41a63734388a2fb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f93d4285c1c7ef745278fd291046ffcb75558d7e378f5e2e9b7021802b2475f8683a067aeddd5e3310962cf59344e62221d43e10e54d499b5927f6108b9f1b60"} build_url:https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/VwFlFQBxSRmxbIZNXabyQQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3bef9fba4463edec2120ed4a4172328e245bada9c76bd5bf452a9f162c04f94c621f05b07f898a4dad6f00c18b7dfb2003472c251a54ec1c90ecd35ae4647e55", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9531af70de294b8580067129f6b6c45a5e9828e46eb3602a92afc2519fedfb2f65875fc4be7e49499359ed477bc7a1563e40b7d1aaeb0d5c41a63734388a2fb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f93d4285c1c7ef745278fd291046ffcb75558d7e378f5e2e9b7021802b2475f8683a067aeddd5e3310962cf59344e62221d43e10e54d499b5927f6108b9f1b60"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 09:58:49.046966) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:58:49.047260) ========= 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=523ca41ca1e0623de333ad52000001bf-1472919067.568223-705145295 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004409 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:58:49.132839) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 09:58:49.135297) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 09:58:49.135632) ========= ========= Total master_lag: 0.18 =========