builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-006 starttime: 1473447009.56 results: success (0) buildid: 20160909090454 builduid: e6d7915ae0d54378b103cd8a49f0b257 revision: 2997ae20bac0d7cebeb238dc919b63d86100e9da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.558621) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.559046) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.559326) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.610259) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.610519) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-09 11:50:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.3M=0s 2016-09-09 11:50:09 (43.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080101 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.712697) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.713045) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018517 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.766985) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:09.767322) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2997ae20bac0d7cebeb238dc919b63d86100e9da --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 2997ae20bac0d7cebeb238dc919b63d86100e9da --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=e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-09 11:50:09,954 truncating revision to first 12 chars 2016-09-09 11:50:09,954 Setting DEBUG logging. 2016-09-09 11:50:09,954 attempt 1/10 2016-09-09 11:50:09,954 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2997ae20bac0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-09 11:50:10,260 unpacking tar archive at: mozilla-aurora-2997ae20bac0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.658565 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:10.440215) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:10.440548) ========= script_repo_revision: 2997ae20bac0d7cebeb238dc919b63d86100e9da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:10.440987) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:10.441306) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 11:50:10.452570) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-09-09 11:50:10.452929) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:50:10 INFO - MultiFileLogger online at 20160909 11:50:10 in /builds/slave/test 11:50:10 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 11:50:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:50:10 INFO - {'append_to_log': False, 11:50:10 INFO - 'base_work_dir': '/builds/slave/test', 11:50:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:50:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:50:10 INFO - 'branch': 'Mozilla-Aurora', 11:50:10 INFO - 'buildbot_json_path': 'buildprops.json', 11:50:10 INFO - 'config_files': ('talos/linux_config.py',), 11:50:10 INFO - 'default_actions': ('clobber', 11:50:10 INFO - 'read-buildbot-config', 11:50:10 INFO - 'download-and-extract', 11:50:10 INFO - 'populate-webroot', 11:50:10 INFO - 'create-virtualenv', 11:50:10 INFO - 'install', 11:50:10 INFO - 'run-tests'), 11:50:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:50:10 INFO - 'download_minidump_stackwalk': True, 11:50:10 INFO - 'download_symbols': 'ondemand', 11:50:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:50:10 INFO - 'tooltool.py': '/tools/tooltool.py', 11:50:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:50:10 INFO - '/tools/misc-python/virtualenv.py')}, 11:50:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:50:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:50:10 INFO - 'installer_path': 'installer.exe', 11:50:10 INFO - 'log_level': 'info', 11:50:10 INFO - 'log_name': 'talos', 11:50:10 INFO - 'log_to_console': True, 11:50:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:50:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:50:10 INFO - 'opt_config_files': (), 11:50:10 INFO - 'pip_index': False, 11:50:10 INFO - 'sps_profile': False, 11:50:10 INFO - 'sps_profile_interval': 0, 11:50:10 INFO - 'suite': 'dromaeojs-e10s', 11:50:10 INFO - 'system_bits': '32', 11:50:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:50:10 INFO - 'title': 'talos-linux64-ix-006', 11:50:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:50:10 INFO - 'use_talos_json': True, 11:50:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:50:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:50:10 INFO - 'work_dir': 'build'} 11:50:10 INFO - [mozharness: 2016-09-09 18:50:10.729589Z] Running clobber step. 11:50:10 INFO - Running pre-action listener: _resource_record_pre_action 11:50:10 INFO - Running main action method: clobber 11:50:10 INFO - rmtree: /builds/slave/test/build 11:50:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:50:11 INFO - Running post-action listener: _resource_record_post_action 11:50:11 INFO - [mozharness: 2016-09-09 18:50:11.188481Z] Finished clobber step (success) 11:50:11 INFO - [mozharness: 2016-09-09 18:50:11.188583Z] Running read-buildbot-config step. 11:50:11 INFO - Running pre-action listener: _resource_record_pre_action 11:50:11 INFO - Running main action method: read_buildbot_config 11:50:11 INFO - Using buildbot properties: 11:50:11 INFO - { 11:50:11 INFO - "project": "", 11:50:11 INFO - "product": "firefox", 11:50:11 INFO - "script_repo_revision": "production", 11:50:11 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 11:50:11 INFO - "repository": "", 11:50:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 11:50:11 INFO - "buildid": "20160909090454", 11:50:11 INFO - "pgo_build": "True", 11:50:11 INFO - "basedir": "/builds/slave/test", 11:50:11 INFO - "buildnumber": 543, 11:50:11 INFO - "slavename": "talos-linux64-ix-006", 11:50:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:50:11 INFO - "platform": "ubuntu64_hw", 11:50:11 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 11:50:11 INFO - "branch": "mozilla-aurora", 11:50:11 INFO - "repo_path": "releases/mozilla-aurora", 11:50:11 INFO - "revision": "2997ae20bac0d7cebeb238dc919b63d86100e9da", 11:50:11 INFO - "stage_platform": "linux64-pgo", 11:50:11 INFO - "builduid": "e6d7915ae0d54378b103cd8a49f0b257", 11:50:11 INFO - "slavebuilddir": "test" 11:50:11 INFO - } 11:50:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 11:50:11 INFO - Running post-action listener: _resource_record_post_action 11:50:11 INFO - [mozharness: 2016-09-09 18:50:11.191035Z] Finished read-buildbot-config step (success) 11:50:11 INFO - [mozharness: 2016-09-09 18:50:11.191125Z] Running download-and-extract step. 11:50:11 INFO - Running pre-action listener: _resource_record_pre_action 11:50:11 INFO - Running main action method: download_and_extract 11:50:11 INFO - mkdir: /builds/slave/test/build/tests 11:50:11 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')]} 11:50:11 INFO - https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:50:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:50:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:50:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:50:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:50:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:50:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:50:11 INFO - retry: Failed, sleeping 30 seconds before retrying 11:50:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:50:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:50:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:50:41 INFO - retry: Failed, sleeping 60 seconds before retrying 11:51:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:51:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:51:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:51:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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! 11:51:41 INFO - Caught exception: HTTP Error 404: Not Found 11:51:41 INFO - Caught exception: HTTP Error 404: Not Found 11:51:41 INFO - Caught exception: HTTP Error 404: Not Found 11:51:41 INFO - trying https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:51:41 INFO - Downloading https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:51:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:51:41 INFO - Downloaded 1361 bytes. 11:51:41 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 11:51:41 INFO - Using the following test package requirements: 11:51:41 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 11:51:41 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 11:51:41 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 11:51:41 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:41 INFO - u'jsshell-linux-x86_64.zip'], 11:51:41 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 11:51:41 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 11:51:41 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 11:51:41 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 11:51:41 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 11:51:41 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 11:51:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 11:51:41 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:51:41 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')]} 11:51:41 INFO - https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:51:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:51:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:51:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:51:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:51:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:51:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:51:42 INFO - retry: Failed, sleeping 30 seconds before retrying 11:52:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:52:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:52:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:52:12 INFO - retry: Failed, sleeping 60 seconds before retrying 11:53:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:53:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:53:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:53:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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! 11:53:12 INFO - Caught exception: HTTP Error 404: Not Found 11:53:12 INFO - Caught exception: HTTP Error 404: Not Found 11:53:12 INFO - Caught exception: HTTP Error 404: Not Found 11:53:12 INFO - trying https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:53:12 INFO - Downloading https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:53:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:53:13 INFO - Downloaded 21769137 bytes. 11:53:13 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'] 11:53:13 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 11:53:14 INFO - Return code: 0 11:53:14 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 11:53:14 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')]} 11:53:14 INFO - https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:53:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:53:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:53:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:53:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:53:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:53:14 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:53:14 INFO - retry: Failed, sleeping 30 seconds before retrying 11:53:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:53:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:53:44 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:53:44 INFO - retry: Failed, sleeping 60 seconds before retrying 11:54:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:54:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:54:44 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:54:44 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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! 11:54:44 INFO - Caught exception: HTTP Error 404: Not Found 11:54:44 INFO - Caught exception: HTTP Error 404: Not Found 11:54:44 INFO - Caught exception: HTTP Error 404: Not Found 11:54:44 INFO - trying https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 11:54:44 INFO - Downloading https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:54:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:54:45 INFO - Downloaded 21769137 bytes. 11:54:45 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'] 11:54:45 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 11:54:47 INFO - Return code: 0 11:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:54:47 INFO - https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:54:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 11:54:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 11:54:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:54:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:54:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 11:54:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:54:47 INFO - retry: Failed, sleeping 30 seconds before retrying 11:55:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:55:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 11:55:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:55:17 INFO - retry: Failed, sleeping 60 seconds before retrying 11:56:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:56:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 11:56:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:56:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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! 11:56:17 INFO - Caught exception: HTTP Error 404: Not Found 11:56:17 INFO - Caught exception: HTTP Error 404: Not Found 11:56:17 INFO - Caught exception: HTTP Error 404: Not Found 11:56:17 INFO - trying https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 11:56:17 INFO - Downloading https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:56:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:56:18 INFO - Downloaded 14413740 bytes. 11:56:18 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'] 11:56:18 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 11:56:19 INFO - Return code: 0 11:56:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:19 INFO - https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:56:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:56:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:56:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:56:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:56:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:56:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:56:19 INFO - retry: Failed, sleeping 30 seconds before retrying 11:56:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:56:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:56:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:56:49 INFO - retry: Failed, sleeping 60 seconds before retrying 11:57:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:57:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:57:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:57:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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! 11:57:49 INFO - Caught exception: HTTP Error 404: Not Found 11:57:49 INFO - Caught exception: HTTP Error 404: Not Found 11:57:49 INFO - Caught exception: HTTP Error 404: Not Found 11:57:49 INFO - trying https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:57:49 INFO - Downloading https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:57:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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 11:57:50 INFO - Downloaded 63908978 bytes. 11:57:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:57:50 INFO - mkdir: /builds/slave/test/properties 11:57:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:57:50 INFO - Writing to file /builds/slave/test/properties/build_url 11:57:50 INFO - Contents: 11:57:50 INFO - build_url:https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 11:57:51 INFO - Running post-action listener: _resource_record_post_action 11:57:51 INFO - Running post-action listener: set_extra_try_arguments 11:57:51 INFO - [mozharness: 2016-09-09 18:57:51.084910Z] Finished download-and-extract step (success) 11:57:51 INFO - [mozharness: 2016-09-09 18:57:51.085141Z] Running populate-webroot step. 11:57:51 INFO - Running pre-action listener: _resource_record_pre_action 11:57:51 INFO - Running main action method: populate_webroot 11:57:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:57:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:57:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:57:51 INFO - '--tppagecycles', 11:57:51 INFO - '7'], 11:57:51 INFO - 'tests': ['tsvgm']}}, 11:57:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:57:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:57:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:57:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:57:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:57:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:57:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:51 INFO - 'talos_options': ['--disable-e10s'], 11:57:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:51 INFO - 'talos_options': ['--disable-e10s'], 11:57:51 INFO - 'tests': ['damp', 'tps']}, 11:57:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:51 INFO - 'tests': ['damp', 'tps']}, 11:57:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:57:51 INFO - 'tests': ['dromaeo_dom']}, 11:57:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:57:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:57:51 INFO - 'tests': ['basic_compositor_video']}, 11:57:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:57:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:57:51 INFO - 'tests': ['a11yr', 11:57:51 INFO - 'ts_paint', 11:57:51 INFO - 'tpaint', 11:57:51 INFO - 'sessionrestore', 11:57:51 INFO - 'sessionrestore_no_auto_restore', 11:57:51 INFO - 'tabpaint']}, 11:57:51 INFO - 'other-e10s': {'tests': ['a11yr', 11:57:51 INFO - 'ts_paint', 11:57:51 INFO - 'tpaint', 11:57:51 INFO - 'sessionrestore', 11:57:51 INFO - 'sessionrestore_no_auto_restore', 11:57:51 INFO - 'tabpaint']}, 11:57:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:57:51 INFO - 'ts_paint', 11:57:51 INFO - 'tpaint', 11:57:51 INFO - 'sessionrestore', 11:57:51 INFO - 'sessionrestore_no_auto_restore', 11:57:51 INFO - 'tabpaint']}, 11:57:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:57:51 INFO - 'ts_paint', 11:57:51 INFO - 'tpaint', 11:57:51 INFO - 'sessionrestore', 11:57:51 INFO - 'sessionrestore_no_auto_restore', 11:57:51 INFO - 'tabpaint']}, 11:57:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:57:51 INFO - 'tests': ['a11yr', 11:57:51 INFO - 'ts_paint', 11:57:51 INFO - 'tpaint', 11:57:51 INFO - 'sessionrestore', 11:57:51 INFO - 'sessionrestore_no_auto_restore', 11:57:51 INFO - 'tabpaint']}, 11:57:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:57:51 INFO - 'tests': ['a11yr', 11:57:51 INFO - 'ts_paint', 11:57:51 INFO - 'tpaint', 11:57:51 INFO - 'sessionrestore', 11:57:51 INFO - 'sessionrestore_no_auto_restore', 11:57:51 INFO - 'tabpaint']}, 11:57:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:57:51 INFO - 'tests': ['tsvgx', 11:57:51 INFO - 'tsvgr_opacity', 11:57:51 INFO - 'tart', 11:57:51 INFO - 'tscrollx', 11:57:51 INFO - 'cart']}, 11:57:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:57:51 INFO - 'tsvgr_opacity', 11:57:51 INFO - 'tart', 11:57:51 INFO - 'tscrollx', 11:57:51 INFO - 'cart']}, 11:57:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:51 INFO - 'talos_options': ['--disable-e10s'], 11:57:51 INFO - 'tests': ['tp5o']}, 11:57:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:51 INFO - 'tests': ['tp5o']}, 11:57:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:51 INFO - 'talos_options': ['--disable-e10s', 11:57:51 INFO - '--xperf_path', 11:57:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:51 INFO - 'tests': ['tp5n']}, 11:57:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:51 INFO - 'talos_options': ['--xperf_path', 11:57:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:51 INFO - 'tests': ['tp5n']}}, 11:57:51 INFO - 'talos.zip': {'path': '', 11:57:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:57:51 INFO - Running post-action listener: _resource_record_post_action 11:57:51 INFO - [mozharness: 2016-09-09 18:57:51.103741Z] Finished populate-webroot step (success) 11:57:51 INFO - [mozharness: 2016-09-09 18:57:51.103830Z] Running create-virtualenv step. 11:57:51 INFO - Running pre-action listener: _resource_record_pre_action 11:57:51 INFO - Running main action method: create_virtualenv 11:57:51 INFO - Creating virtualenv /builds/slave/test/build/venv 11:57:51 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 11:57:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:57:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:57:51 INFO - Using real prefix '/usr' 11:57:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:57:51 INFO - Installing distribute.............................................................................................................................................................................................done. 11:57:53 INFO - Installing pip.................done. 11:57:53 INFO - Return code: 0 11:57:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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')]} 11:57:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2801400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890570>, '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 0x2891470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', '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 11:57: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', 'pip>=1.5'] in /builds/slave/test/build 11:57: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 pip>=1.5 11:57:53 INFO - Using env: {'DISPLAY': ':0', 11:57:53 INFO - 'HOME': '/home/cltbld', 11:57:53 INFO - 'LANG': 'en_US.UTF-8', 11:57:53 INFO - 'LANGUAGE': 'en_US:en', 11:57:53 INFO - 'LOGNAME': 'cltbld', 11:57:53 INFO - 'MAIL': '/var/mail/cltbld', 11:57:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:53 INFO - 'MOZ_NO_REMOTE': '1', 11:57:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:53 INFO - 'NO_EM_RESTART': '1', 11:57:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:53 INFO - 'PWD': '/builds/slave/test', 11:57:53 INFO - 'SHELL': '/bin/bash', 11:57:53 INFO - 'SHLVL': '1', 11:57:53 INFO - 'TERM': 'linux', 11:57:53 INFO - 'TMOUT': '86400', 11:57:53 INFO - 'USER': 'cltbld', 11:57:53 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', 11:57:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:53 INFO - '_': '/tools/buildbot/bin/python'} 11:57:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:53 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 11:57:53 INFO - Cleaning up... 11:57:53 INFO - Return code: 0 11:57:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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')]} 11:57:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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', '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 0x25d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2801400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890570>, '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 0x2891470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', '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 11:57: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:57: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 psutil>=3.1.1 11:57:53 INFO - Using env: {'DISPLAY': ':0', 11:57:53 INFO - 'HOME': '/home/cltbld', 11:57:53 INFO - 'LANG': 'en_US.UTF-8', 11:57:53 INFO - 'LANGUAGE': 'en_US:en', 11:57:53 INFO - 'LOGNAME': 'cltbld', 11:57:53 INFO - 'MAIL': '/var/mail/cltbld', 11:57:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:53 INFO - 'MOZ_NO_REMOTE': '1', 11:57:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:53 INFO - 'NO_EM_RESTART': '1', 11:57:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:53 INFO - 'PWD': '/builds/slave/test', 11:57:53 INFO - 'SHELL': '/bin/bash', 11:57:53 INFO - 'SHLVL': '1', 11:57:53 INFO - 'TERM': 'linux', 11:57:53 INFO - 'TMOUT': '86400', 11:57:53 INFO - 'USER': 'cltbld', 11:57:53 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', 11:57:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:53 INFO - '_': '/tools/buildbot/bin/python'} 11:57:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:54 INFO - Downloading/unpacking psutil>=3.1.1 11:57:54 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 11:57:54 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 11:57:54 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 11:57:54 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 11:57:54 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 11:57:54 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 11:57:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:57:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:56 INFO - Installing collected packages: psutil 11:57:56 INFO - Running setup.py install for psutil 11:57:56 INFO - building 'psutil._psutil_linux' extension 11:57:56 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 11:57:56 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 11:57:56 INFO - building 'psutil._psutil_posix' extension 11:57:56 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 11:57:56 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 11:57:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:56 INFO - Successfully installed psutil 11:57:56 INFO - Cleaning up... 11:57:56 INFO - Return code: 0 11:57:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:57:56 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')]} 11:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:56 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')]} 11:57:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:56 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 0x25d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2801400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890570>, '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 0x2891470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', '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 11:57:56 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 11:57:56 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 11:57:56 INFO - Using env: {'DISPLAY': ':0', 11:57:56 INFO - 'HOME': '/home/cltbld', 11:57:56 INFO - 'LANG': 'en_US.UTF-8', 11:57:56 INFO - 'LANGUAGE': 'en_US:en', 11:57:56 INFO - 'LOGNAME': 'cltbld', 11:57:56 INFO - 'MAIL': '/var/mail/cltbld', 11:57:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:56 INFO - 'MOZ_NO_REMOTE': '1', 11:57:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:56 INFO - 'NO_EM_RESTART': '1', 11:57:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:56 INFO - 'PWD': '/builds/slave/test', 11:57:56 INFO - 'SHELL': '/bin/bash', 11:57:56 INFO - 'SHLVL': '1', 11:57:56 INFO - 'TERM': 'linux', 11:57:56 INFO - 'TMOUT': '86400', 11:57:56 INFO - 'USER': 'cltbld', 11:57:56 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', 11:57:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:56 INFO - '_': '/tools/buildbot/bin/python'} 11:57:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:57:56 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 11:57:56 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 11:57:56 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 11:57:56 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 11:57:56 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 11:57:56 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 11:57:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:57:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:57:58 INFO - Installing collected packages: mozsystemmonitor 11:57:58 INFO - Running setup.py install for mozsystemmonitor 11:57:58 INFO - Successfully installed mozsystemmonitor 11:57:58 INFO - Cleaning up... 11:57:58 INFO - Return code: 0 11:57:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:57:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:57:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2801400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890570>, '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 0x2891470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', '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 11:57:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:57:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:57:58 INFO - Using env: {'DISPLAY': ':0', 11:57:58 INFO - 'HOME': '/home/cltbld', 11:57:58 INFO - 'LANG': 'en_US.UTF-8', 11:57:58 INFO - 'LANGUAGE': 'en_US:en', 11:57:58 INFO - 'LOGNAME': 'cltbld', 11:57:58 INFO - 'MAIL': '/var/mail/cltbld', 11:57:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:58 INFO - 'MOZ_NO_REMOTE': '1', 11:57:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:57:58 INFO - 'NO_EM_RESTART': '1', 11:57:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:57:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:58 INFO - 'PWD': '/builds/slave/test', 11:57:58 INFO - 'SHELL': '/bin/bash', 11:57:58 INFO - 'SHLVL': '1', 11:57:58 INFO - 'TERM': 'linux', 11:57:58 INFO - 'TMOUT': '86400', 11:57:58 INFO - 'USER': 'cltbld', 11:57:58 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', 11:57:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:58 INFO - '_': '/tools/buildbot/bin/python'} 11:57:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:59 INFO - Downloading/unpacking jsonschema==2.5.1 11:57:59 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 11:57:59 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 11:57:59 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 11:57:59 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 11:57:59 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 11:57:59 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 11:58:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:58:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:58:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:01 INFO - Downloading functools32-3.2.3-2.tar.gz 11:58:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:58:01 INFO - warning: no files found matching '*.txt' 11:58:01 INFO - no previously-included directories found matching 'build' 11:58:01 INFO - no previously-included directories found matching 'dist' 11:58:01 INFO - no previously-included directories found matching '.git*' 11:58:01 INFO - Installing collected packages: jsonschema, functools32 11:58:01 INFO - Running setup.py install for functools32 11:58:01 INFO - warning: no files found matching '*.txt' 11:58:01 INFO - no previously-included directories found matching 'build' 11:58:01 INFO - no previously-included directories found matching 'dist' 11:58:01 INFO - no previously-included directories found matching '.git*' 11:58:01 INFO - Successfully installed jsonschema functools32 11:58:01 INFO - Cleaning up... 11:58:01 INFO - Return code: 0 11:58:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2801400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890570>, '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 0x2891470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', '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 11:58:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:58:01 INFO - Using env: {'DISPLAY': ':0', 11:58:01 INFO - 'HOME': '/home/cltbld', 11:58:01 INFO - 'LANG': 'en_US.UTF-8', 11:58:01 INFO - 'LANGUAGE': 'en_US:en', 11:58:01 INFO - 'LOGNAME': 'cltbld', 11:58:01 INFO - 'MAIL': '/var/mail/cltbld', 11:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:01 INFO - 'MOZ_NO_REMOTE': '1', 11:58:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:01 INFO - 'NO_EM_RESTART': '1', 11:58:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:01 INFO - 'PWD': '/builds/slave/test', 11:58:01 INFO - 'SHELL': '/bin/bash', 11:58:01 INFO - 'SHLVL': '1', 11:58:01 INFO - 'TERM': 'linux', 11:58:01 INFO - 'TMOUT': '86400', 11:58:01 INFO - 'USER': 'cltbld', 11:58:01 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', 11:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:01 INFO - '_': '/tools/buildbot/bin/python'} 11:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:58:01 INFO - Cleaning up... 11:58:01 INFO - Return code: 0 11:58:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2801400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890570>, '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 0x2891470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', '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 11:58:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:58:01 INFO - Using env: {'DISPLAY': ':0', 11:58:01 INFO - 'HOME': '/home/cltbld', 11:58:01 INFO - 'LANG': 'en_US.UTF-8', 11:58:01 INFO - 'LANGUAGE': 'en_US:en', 11:58:01 INFO - 'LOGNAME': 'cltbld', 11:58:01 INFO - 'MAIL': '/var/mail/cltbld', 11:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:01 INFO - 'MOZ_NO_REMOTE': '1', 11:58:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:01 INFO - 'NO_EM_RESTART': '1', 11:58:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:01 INFO - 'PWD': '/builds/slave/test', 11:58:01 INFO - 'SHELL': '/bin/bash', 11:58:01 INFO - 'SHLVL': '1', 11:58:01 INFO - 'TERM': 'linux', 11:58:01 INFO - 'TMOUT': '86400', 11:58:01 INFO - 'USER': 'cltbld', 11:58:01 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', 11:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:01 INFO - '_': '/tools/buildbot/bin/python'} 11:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:01 INFO - Downloading/unpacking blobuploader==1.2.4 11:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:58:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:03 INFO - Downloading blobuploader-1.2.4.tar.gz 11:58:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:58:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:58:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:58:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:58:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:58:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:58:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:04 INFO - Downloading docopt-0.6.1.tar.gz 11:58:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:58:04 INFO - Installing collected packages: blobuploader, requests, docopt 11:58:04 INFO - Running setup.py install for blobuploader 11:58:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:58:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:58:04 INFO - Running setup.py install for requests 11:58:05 INFO - Running setup.py install for docopt 11:58:05 INFO - Successfully installed blobuploader requests docopt 11:58:05 INFO - Cleaning up... 11:58:05 INFO - Return code: 0 11:58:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:58:05 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')]} 11:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:05 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')]} 11:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:05 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 0x25d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2801400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890570>, '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 0x2891470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', '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 11:58:05 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 11:58:05 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 11:58:05 INFO - Using env: {'DISPLAY': ':0', 11:58:05 INFO - 'HOME': '/home/cltbld', 11:58:05 INFO - 'LANG': 'en_US.UTF-8', 11:58:05 INFO - 'LANGUAGE': 'en_US:en', 11:58:05 INFO - 'LOGNAME': 'cltbld', 11:58:05 INFO - 'MAIL': '/var/mail/cltbld', 11:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:05 INFO - 'MOZ_NO_REMOTE': '1', 11:58:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:05 INFO - 'NO_EM_RESTART': '1', 11:58:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:05 INFO - 'PWD': '/builds/slave/test', 11:58:05 INFO - 'SHELL': '/bin/bash', 11:58:05 INFO - 'SHLVL': '1', 11:58:05 INFO - 'TERM': 'linux', 11:58:05 INFO - 'TMOUT': '86400', 11:58:05 INFO - 'USER': 'cltbld', 11:58:05 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', 11:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:05 INFO - '_': '/tools/buildbot/bin/python'} 11:58:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:58:05 INFO - Running setup.py (path:/tmp/pip-y2HLcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:58:05 INFO - Running setup.py (path:/tmp/pip-GrI57b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:58:05 INFO - Running setup.py (path:/tmp/pip-Owla3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:58:05 INFO - Running setup.py (path:/tmp/pip-r243GX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:58:05 INFO - Running setup.py (path:/tmp/pip-302FQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:58:05 INFO - Running setup.py (path:/tmp/pip-8kXxlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:58:05 INFO - Running setup.py (path:/tmp/pip-YDc6tm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:58:05 INFO - Running setup.py (path:/tmp/pip-Ln9wI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:58:06 INFO - Running setup.py (path:/tmp/pip-dYCwTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:58:06 INFO - Running setup.py (path:/tmp/pip-A1Ah5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:58:06 INFO - Running setup.py (path:/tmp/pip-H_mSpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:58:06 INFO - Running setup.py (path:/tmp/pip-7hgdYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:58:06 INFO - Running setup.py (path:/tmp/pip-kE9hk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:58:06 INFO - Running setup.py (path:/tmp/pip-IkLUku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:06 INFO - Running setup.py (path:/tmp/pip-Cdhufd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:58:06 INFO - Running setup.py (path:/tmp/pip-UlS877-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:58:06 INFO - Running setup.py (path:/tmp/pip-iXlc_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:58:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:58:06 INFO - Running setup.py install for manifestparser 11:58:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:58:06 INFO - Running setup.py install for mozcrash 11:58:06 INFO - Running setup.py install for mozdebug 11:58:06 INFO - Running setup.py install for mozdevice 11:58:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:58:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:58:07 INFO - Running setup.py install for mozfile 11:58:07 INFO - Running setup.py install for mozhttpd 11:58:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:58:07 INFO - Running setup.py install for mozinfo 11:58:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:58:07 INFO - Running setup.py install for mozInstall 11:58:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:58:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:58:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:58:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:58:07 INFO - Running setup.py install for mozleak 11:58:07 INFO - Running setup.py install for mozlog 11:58:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:58:07 INFO - Running setup.py install for moznetwork 11:58:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:58:07 INFO - Running setup.py install for mozprocess 11:58:07 INFO - Running setup.py install for mozprofile 11:58:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:58:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:58:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:58:08 INFO - Running setup.py install for mozrunner 11:58:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:58:08 INFO - Running setup.py install for mozscreenshot 11:58:08 INFO - Running setup.py install for moztest 11:58:08 INFO - Running setup.py install for mozversion 11:58:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:58:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:58:08 INFO - Cleaning up... 11:58:08 INFO - Return code: 0 11:58:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:58:08 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')]} 11:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:08 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')]} 11:58:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:08 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 0x25d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2801400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890570>, '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 0x2891470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', '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 11:58:08 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 11:58:08 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 11:58:08 INFO - Using env: {'DISPLAY': ':0', 11:58:08 INFO - 'HOME': '/home/cltbld', 11:58:08 INFO - 'LANG': 'en_US.UTF-8', 11:58:08 INFO - 'LANGUAGE': 'en_US:en', 11:58:08 INFO - 'LOGNAME': 'cltbld', 11:58:08 INFO - 'MAIL': '/var/mail/cltbld', 11:58:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:08 INFO - 'MOZ_NO_REMOTE': '1', 11:58:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:08 INFO - 'NO_EM_RESTART': '1', 11:58:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:08 INFO - 'PWD': '/builds/slave/test', 11:58:08 INFO - 'SHELL': '/bin/bash', 11:58:08 INFO - 'SHLVL': '1', 11:58:08 INFO - 'TERM': 'linux', 11:58:08 INFO - 'TMOUT': '86400', 11:58:08 INFO - 'USER': 'cltbld', 11:58:08 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', 11:58:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:08 INFO - '_': '/tools/buildbot/bin/python'} 11:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:58:08 INFO - Running setup.py (path:/tmp/pip-t1BCkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:58:08 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)) 11:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:58:08 INFO - Running setup.py (path:/tmp/pip-WuvSFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:58:08 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)) 11:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:58:08 INFO - Running setup.py (path:/tmp/pip-g97Eu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:58:08 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)) 11:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:58:08 INFO - Running setup.py (path:/tmp/pip-EtQXPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:58:08 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)) 11:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:58:08 INFO - Running setup.py (path:/tmp/pip-F4CKPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:58:09 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)) 11:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:58:09 INFO - Running setup.py (path:/tmp/pip-e7Cf85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:58:09 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)) 11:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:58:09 INFO - Running setup.py (path:/tmp/pip-fvgaYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:58:09 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)) 11:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:58:09 INFO - Running setup.py (path:/tmp/pip-LyStdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:58:09 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)) 11:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:58:09 INFO - Running setup.py (path:/tmp/pip-1BuaAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:58:09 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)) 11:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:58:09 INFO - Running setup.py (path:/tmp/pip-T7Tu5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:58:09 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)) 11:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:58:09 INFO - Running setup.py (path:/tmp/pip-zmCxXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:58:09 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)) 11:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:58:09 INFO - Running setup.py (path:/tmp/pip-im1hL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:58:09 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)) 11:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:58:09 INFO - Running setup.py (path:/tmp/pip-SFZ8jo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:58:09 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)) 11:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:58:09 INFO - Running setup.py (path:/tmp/pip-Oc3RYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:58:09 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)) 11:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:09 INFO - Running setup.py (path:/tmp/pip-KPhqPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:09 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)) 11:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:58:09 INFO - Running setup.py (path:/tmp/pip-sOVCUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:58:09 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)) 11:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:58:09 INFO - Running setup.py (path:/tmp/pip-RaH2vT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:58:09 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)) 11:58:09 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)) 11:58:09 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)) 11:58:09 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)) 11:58:09 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)) 11:58:09 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)) 11:58:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:58:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:58:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:11 INFO - Downloading blessings-1.6.tar.gz 11:58:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:58:11 INFO - Installing collected packages: blessings 11:58:11 INFO - Running setup.py install for blessings 11:58:12 INFO - Successfully installed blessings 11:58:12 INFO - Cleaning up... 11:58:12 INFO - Return code: 0 11:58:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:58:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:58:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:58:12 INFO - Reading from file tmpfile_stdout 11:58:12 INFO - Current package versions: 11:58:12 INFO - argparse == 1.2.1 11:58:12 INFO - blessings == 1.6 11:58:12 INFO - blobuploader == 1.2.4 11:58:12 INFO - docopt == 0.6.1 11:58:12 INFO - functools32 == 3.2.3-2 11:58:12 INFO - jsonschema == 2.5.1 11:58:12 INFO - manifestparser == 1.1 11:58:12 INFO - mozInstall == 1.12 11:58:12 INFO - mozcrash == 0.17 11:58:12 INFO - mozdebug == 0.1 11:58:12 INFO - mozdevice == 0.48 11:58:12 INFO - mozfile == 1.2 11:58:12 INFO - mozhttpd == 0.7 11:58:12 INFO - mozinfo == 0.9 11:58:12 INFO - mozleak == 0.1 11:58:12 INFO - mozlog == 3.3 11:58:12 INFO - moznetwork == 0.27 11:58:12 INFO - mozprocess == 0.23 11:58:12 INFO - mozprofile == 0.28 11:58:12 INFO - mozrunner == 6.12 11:58:12 INFO - mozscreenshot == 0.1 11:58:12 INFO - mozsystemmonitor == 0.3 11:58:12 INFO - moztest == 0.7 11:58:12 INFO - mozversion == 1.4 11:58:12 INFO - psutil == 3.1.1 11:58:12 INFO - requests == 1.2.3 11:58:12 INFO - wsgiref == 0.1.2 11:58:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:12 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 0x25d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2801400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890570>, '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 0x2891470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', '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 11:58:12 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 11:58:12 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 11:58:12 INFO - Using env: {'DISPLAY': ':0', 11:58:12 INFO - 'HOME': '/home/cltbld', 11:58:12 INFO - 'LANG': 'en_US.UTF-8', 11:58:12 INFO - 'LANGUAGE': 'en_US:en', 11:58:12 INFO - 'LOGNAME': 'cltbld', 11:58:12 INFO - 'MAIL': '/var/mail/cltbld', 11:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:12 INFO - 'MOZ_NO_REMOTE': '1', 11:58:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:12 INFO - 'NO_EM_RESTART': '1', 11:58:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:12 INFO - 'PWD': '/builds/slave/test', 11:58:12 INFO - 'SHELL': '/bin/bash', 11:58:12 INFO - 'SHLVL': '1', 11:58:12 INFO - 'TERM': 'linux', 11:58:12 INFO - 'TMOUT': '86400', 11:58:12 INFO - 'USER': 'cltbld', 11:58:12 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', 11:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:12 INFO - '_': '/tools/buildbot/bin/python'} 11:58:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:12 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)) 11:58:12 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)) 11:58:12 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)) 11:58:12 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)) 11:58:12 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)) 11:58:12 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)) 11:58:12 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)) 11:58:12 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)) 11:58:12 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)) 11:58:12 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)) 11:58:12 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)) 11:58:12 INFO - Cleaning up... 11:58:12 INFO - Return code: 0 11:58:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:58:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2801400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890570>, '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 0x2891470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', '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 11:58:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:58:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:58:12 INFO - Using env: {'DISPLAY': ':0', 11:58:12 INFO - 'HOME': '/home/cltbld', 11:58:12 INFO - 'LANG': 'en_US.UTF-8', 11:58:12 INFO - 'LANGUAGE': 'en_US:en', 11:58:12 INFO - 'LOGNAME': 'cltbld', 11:58:12 INFO - 'MAIL': '/var/mail/cltbld', 11:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:12 INFO - 'MOZ_NO_REMOTE': '1', 11:58:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:12 INFO - 'NO_EM_RESTART': '1', 11:58:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:12 INFO - 'PWD': '/builds/slave/test', 11:58:12 INFO - 'SHELL': '/bin/bash', 11:58:12 INFO - 'SHLVL': '1', 11:58:12 INFO - 'TERM': 'linux', 11:58:12 INFO - 'TMOUT': '86400', 11:58:12 INFO - 'USER': 'cltbld', 11:58:12 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', 11:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:12 INFO - '_': '/tools/buildbot/bin/python'} 11:58:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:58:12 INFO - Cleaning up... 11:58:12 INFO - Return code: 0 11:58:12 INFO - Running post-action listener: _resource_record_post_action 11:58:12 INFO - Running post-action listener: _start_resource_monitoring 11:58:12 INFO - Starting resource monitoring. 11:58:12 INFO - [mozharness: 2016-09-09 18:58:12.757604Z] Finished create-virtualenv step (success) 11:58:12 INFO - [mozharness: 2016-09-09 18:58:12.758036Z] Running install step. 11:58:12 INFO - Running pre-action listener: _resource_record_pre_action 11:58:12 INFO - Running main action method: install 11:58:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:58:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:58:12 INFO - Reading from file tmpfile_stdout 11:58:12 INFO - Detecting whether we're running mozinstall >=1.0... 11:58:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:58:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:58:13 INFO - Reading from file tmpfile_stdout 11:58:13 INFO - Output received: 11:58:13 INFO - Usage: mozinstall [options] installer 11:58:13 INFO - Options: 11:58:13 INFO - -h, --help show this help message and exit 11:58:13 INFO - -d DEST, --destination=DEST 11:58:13 INFO - Directory to install application into. [default: 11:58:13 INFO - "/builds/slave/test"] 11:58:13 INFO - --app=APP Application being installed. [default: firefox] 11:58:13 INFO - mkdir: /builds/slave/test/build/application 11:58:13 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'] 11:58:13 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 11:58:29 INFO - Reading from file tmpfile_stdout 11:58:29 INFO - Output received: 11:58:29 INFO - /builds/slave/test/build/application/firefox/firefox 11:58:29 INFO - Running post-action listener: _resource_record_post_action 11:58:29 INFO - [mozharness: 2016-09-09 18:58:29.129669Z] Finished install step (success) 11:58:29 INFO - [mozharness: 2016-09-09 18:58:29.129943Z] Running run-tests step. 11:58:29 INFO - Running pre-action listener: _resource_record_pre_action 11:58:29 INFO - Running main action method: run_tests 11:58:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:58:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:58:29 INFO - Python 2.7.3 11:58:29 INFO - Return code: 0 11:58:29 INFO - grabbing minidump binary from tooltool 11:58:29 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')]} 11:58:29 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 0x2890570>, '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 0x2891470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:58:29 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 11:58:29 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 11:58:29 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 11:58:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:58:29 INFO - Return code: 0 11:58:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:58:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:58:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:58:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:58:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:58:29 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/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 11:58:29 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/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 11:58:29 INFO - Using env: {'DISPLAY': ':0', 11:58:29 INFO - 'HOME': '/home/cltbld', 11:58:29 INFO - 'LANG': 'en_US.UTF-8', 11:58:29 INFO - 'LANGUAGE': 'en_US:en', 11:58:29 INFO - 'LOGNAME': 'cltbld', 11:58:29 INFO - 'MAIL': '/var/mail/cltbld', 11:58:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:58:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:58:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:29 INFO - 'MOZ_NO_REMOTE': '1', 11:58:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:58:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:58:29 INFO - 'NO_EM_RESTART': '1', 11:58:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:58:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:29 INFO - 'PWD': '/builds/slave/test', 11:58:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:58:29 INFO - 'SHELL': '/bin/bash', 11:58:29 INFO - 'SHLVL': '1', 11:58:29 INFO - 'TERM': 'linux', 11:58:29 INFO - 'TMOUT': '86400', 11:58:29 INFO - 'USER': 'cltbld', 11:58:29 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071', 11:58:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:29 INFO - '_': '/tools/buildbot/bin/python'} 11:58:29 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/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 11:58:29 INFO - mozversion application_buildid: 20160909090454 11:58:29 INFO - mozversion application_changeset: 2997ae20bac0d7cebeb238dc919b63d86100e9da 11:58:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:58:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:58:29 INFO - mozversion application_name: Firefox 11:58:29 INFO - mozversion application_remotingname: firefox-dev 11:58:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:58:29 INFO - mozversion application_vendor: Mozilla 11:58:29 INFO - mozversion application_version: 50.0a2 11:58:29 INFO - mozversion platform_buildid: 20160909090454 11:58:29 INFO - mozversion platform_changeset: 2997ae20bac0d7cebeb238dc919b63d86100e9da 11:58:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:58:29 INFO - mozversion platform_version: 50.0a2 11:58:29 INFO - using testdate: 1473447509 11:58:29 INFO - actual date: 1473447509 11:58:29 INFO - starting webserver on 'localhost:48548' 11:58:29 INFO - SUITE-START | Running 2 tests 11:58:29 INFO - TEST-START | dromaeo_css 11:58:29 INFO - operating with platform_type : linux_ 11:58:29 INFO - Initialising browser for dromaeo_css test... 11:58:29 INFO - TEST-INFO | started process 5441 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJnDmf6/profile http://localhost:48548/getInfo.html) 11:58:33 INFO - PROCESS | 5441 | __metrics Screen width/height:1600/1200 11:58:33 INFO - PROCESS | 5441 | colorDepth:24 11:58:33 INFO - PROCESS | 5441 | Browser inner width/height: 1024/697 11:58:33 INFO - PROCESS | 5441 | __metrics 11:58:34 ERROR - PROCESS | 5441 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 11:58:35 INFO - TEST-INFO | 5441: exit 0 11:58:35 INFO - Browser initialized. 11:58:35 INFO - Running cycle 1/1 for dromaeo_css test... 11:58:35 INFO - TEST-INFO | started process 5562 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJnDmf6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:58:46 INFO - PROCESS | 5562 | RSS: Main: 153812992 11:58:46 INFO - PROCESS | 5562 | 11:59:36 INFO - PROCESS | 5562 | 1473447576550 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] 12:01:44 INFO - PROCESS | 5562 | Cycle 1(1): loaded http://localhost:48548/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48548/tests/dromaeo/cssquery-ext.html) 12:01:46 INFO - PROCESS | 5562 | RSS: Main: 160051200 12:01:46 INFO - PROCESS | 5562 | 12:04:48 INFO - PROCESS | 5562 | Cycle 1(1): loaded http://localhost:48548/tests/dromaeo/cssquery-ext.html (next: http://localhost:48548/tests/dromaeo/cssquery-jquery.html) 12:04:48 INFO - PROCESS | 5562 | RSS: Main: 155942912 12:04:48 INFO - PROCESS | 5562 | 12:07:50 INFO - PROCESS | 5562 | Cycle 1(1): loaded http://localhost:48548/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48548/tests/dromaeo/cssquery-mootools.html) 12:07:51 INFO - PROCESS | 5562 | RSS: Main: 155701248 12:07:51 INFO - PROCESS | 5562 | 12:10:52 INFO - PROCESS | 5562 | Cycle 1(1): loaded http://localhost:48548/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48548/tests/dromaeo/cssquery-prototype.html) 12:10:53 INFO - PROCESS | 5562 | RSS: Main: 154284032 12:10:53 INFO - PROCESS | 5562 | 12:13:55 INFO - PROCESS | 5562 | Cycle 1(1): loaded http://localhost:48548/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48548/tests/dromaeo/cssquery-yui.html) 12:13:56 INFO - PROCESS | 5562 | RSS: Main: 157024256 12:13:56 INFO - PROCESS | 5562 | 12:16:58 INFO - PROCESS | 5562 | Cycle 1(1): loaded http://localhost:48548/tests/dromaeo/cssquery-yui.html (next: http://localhost:48548/tests/dromaeo/cssquery-dojo.html) 12:16:59 INFO - PROCESS | 5562 | RSS: Main: 157724672 12:16:59 INFO - PROCESS | 5562 | 12:16:59 INFO - PROCESS | 5562 | __start_tp_report 12:16:59 INFO - PROCESS | 5562 | _x_x_mozilla_page_load 12:16:59 INFO - PROCESS | 5562 | _x_x_mozilla_page_load_details 12:16:59 INFO - PROCESS | 5562 | |i|pagename|runs| 12:16:59 INFO - PROCESS | 5562 | |0;dojo.html;3273.1817045738567;3304.917377065575;3308.3548708001936;3309.4373956427403;3323.6344002159744;5631.099024156136;5663.2767237735325;5683.943160568393;5717.371089180187;5748.166515855209;5628.155776633507;5630.071038278679;5640.435956404358;5652.09566469365;5666.914996275059;5144.9742751286285;5165.509276618715;5194.532492075712;5231.372235331746;5265.183896496049;5140.408852981899;5158.2520534522655;5166.509181627758;5206.3231779868565;5215.269862219292;5143.228515722652;5153.098207813637;5168.767405466763;5217.217417387404;5276.314079169702;5216.5305122538775;5221.06020916235;5225.111731005738;5232.424433312332;5259.552938000236;4954.43024052235;5042.369703787027;5100.617453690951;5113.105206588869;5189.922151167735;5610.18652295419;5664.54683625309;5671.177679236529;5677.659340439587;5701.173329867188;6058.606190597641;6074.392560743934;6083.6349819011;6089.512838972872;6102;5920.763169473216;5943.613665111754;6151.231096112986;6169.198004259462;6179.38206179383;5589.860253493654;5674.659520428788;5679.971600142014;5708.514776244025;5715.2855893013375;6122.693865306717;6194.039923811816;6204.472619827275;6210.223722034746;6211.844703882439;5762;5890.175375447441;5905.43898329658;5946.762129514771;5967.283925928917;6001.789937352171;6115.4190351916495;6127.3140759655425;6130.2146971548445;6138.204480310071;6068.848278793065;6098.878022439526;6101.542384321194;6111.816645500642;6126.23422072241;5908.438698323653;5948.345681974979;6102.267727872595;6102.298235702951;6115.235595550556;4602.953970460252;4636.884077898079;4642.326862604903;4673.556012178838;4674.883127921829;4156.56356082613;4158.064435501988;4169.687273454503;4192.140611174702;4193.22425351311;5663.518600918886;5751.741171647286;5852.356240813507;5852.678102704393;5856.326522449887;6091.664958427329;6115.149072927094;6120.233815636221;6122.581191725645;6135.509159267248;5820.621659592113;5844.736986835603;5846.181534585162;5848.912266316009;5868.823935281948;5145.07388670043;5184.066867963727;5255.973720131451;5261.76322065508;5292.391374991848;6158.876822463526;6227.688615569204;6269.46709529686;6271.05934109878;6271.090691849657;6055.757769689163;6069.211002569726;6076.878462430638;6086.239220097488;6124.234470691164;6077;6176.289726681489;6313.958196897461;6316.936830631818;6329.367063293818;5949.524038076857;6013.789517366871;6014.0678194879865;6040.09398590215;6073.969630151821;6099;6103.725332359965;6108.11432342299;6114.877702445837;6120.540959427972;5784;5812.099124635688;5989.161517387656;6003.90994135097;6004.609700369462;5785.508231800344;5854.560907931837;5879.529637628894;5886.293644762656;5906.173135761082;5708.65748055118;5736;5776.249087618583;5782.421757824184;5787.074068149076;5836.912446313394;5857.736401862012;5874.500667443315;5916.053431450947;5933.199018132497;5819.738111785065;5906.025505791668;5920.911186332295;5922.052471604523;5929.140274660236 12:16:59 INFO - PROCESS | 5562 | |1;ext.html;591.3229352391512;642.1010585180748;657.4247533408268;665.1021121485992;697.6651207420441;41357.79321103394;45385.546144538544;45670;45693;45830.541694583044;45310;45645.771771141095;45820.541794582045;45934.31098533524;46019.99999999991;9086.909130908705;9164.358494905358;9165.816683666322;9217.585208665627;9249.213816825579;9112.088791120901;9122.179003889649;9162.129597688243;9167.266618670523;9177.495237761921;9153.267738580898;9193.770155746093;9230.76923076925;9248.306377021716;9270.072992700743;9190.035046320141;9202.677906273315;9228.538573071353;9237.722868313962;9267.536623168815;9170.358074934691;9251.398659087137;9266.119718626787;9272.721818345393;9274.53627318638;41873.581264187276;42239.366409503884;42296;42312.57687423117;42390.57609423897;3301.755640292339;3812.90467738306;3828.808559572038;3901.43429202764;3984.203159368138;32419.18952026218;32894.34211315784;33127.3374532508;33313.16717082068;33336.666633333596;4348.934765978513;4368.4372000039575;4419.4033805436175;4421.336799480072;4438.866833994985;315346;315368;317595.4120229407;319536;320959;37434.812825935696;37537.43693844594;37576.43635345472;37839.432408513894;37873.62126378701;38329.23341533154;38414.23171536554;39012;39649.80175099106;40212;19718.915459649572;19815.504612384804;19923.900380498006;19956.800431995784;19983.600327993357;15136.318865651072;15186.924065379602;15231.390744370323;15244;15269.694606108038;20631.277905273244;20717.58564828725;20972.47568810792;21007.05468253932;21108.89445552793;7500.774976750707;7601.505902116345;7634.236576342321;7645.350145237717;7833.686652533835;1012.7215015870695;1067.9038886500252;1086.782643471293;1113.8551988241477;1137.7371118058948;32669.18327041843;33172;33406.83296583501;33654;33854.49218261728;1103.933763974164;1173.501261963662;1227.9386030698429;1242.7882814256209;1297.5004623219938;17523.73714394285;17612.735808962876;17643.647127057386;17681.204345804468;17769.200385982662;620784;625525;626540;627222;629202;63235.6838215806;63300.68349658222;63724;63746.36253637497;63797.68101159464;1436.2675035731852;1466.9193194374411;1467.207707837777;1475.4540819896492;1480.6372438752574;31455;32656.83671581627;33149.66850331466;33361.33277334488;34174.14564635904;53937.190942135116;54278.18582721341;54304.72847635736;54369.72815135899;54450.18324725211;466680;467844;469547;469750;469907;27844.582331264643;28250.71749282563;28542;30462.695373045986;30750;51836.74081629568;52040.739796300775;52186.47813521816;52514.737426312626;53280.2007969873;42903.35644965265;44249;44551.55448445474;44660.776696116314;44722.55277447184;45153.77423112995;45208.54791452044;45642.315365268885;45922.54077459183;46011.53988460072;36566.268674625826;43417.78291108524;43685;44279.335809962235;44631;37995.43006854955;39626.60373396229;40021.39967900426;40370;40712;29323.560146598244;29849.55225671573;30185.547216791325;30198.84900575483;30500.84749576238 12:16:59 INFO - PROCESS | 5562 | |2;jquery.html;394.55021275745673;406.29507803960104;428.55216298967576;431.2065798929969;431.97408155510664;19370.128344224508;20075.498112547175;20895.268665596708;21056.263030793925;21157.471063223387;19637;20034.599308013832;20194.89902550489;20652.380428587167;20708;5798.362180160189;5941.494972927307;5977.940220597804;5982.521398288149;5985.551083668721;5884.2056322396575;5902.61632993854;5917.408259174092;5923.992921203384;5926.3184733755525;5694.943050569504;5845.912311315334;5881.794137205198;5911.793087241947;5927;5818.796342128006;5836.037053886103;5864.677442740647;5869.589128760988;5887.499562537189;4999.075171093323;5083.974580127076;5086.720230387327;5094.566961808251;5096.592272618182;23888.522229555485;24690.13584524551;24932.00271989118;24939.750602494107;25050.24849254525;2566.8203225774205;2632.552466080771;2793.5809628555676;2794.217619066644;3100.3644252928098;15405.768913466307;15447.150406727626;15526.76709849342;15812.525624231292;16239.918800405922;2025.4126303372004;2048.1193086972594;2074.9377518674314;2077.559565347426;2097.28692244636;58962.41037589612;60278.821651314356;80838.19161808366;81243;86890.56554717186;10864.674059778219;10951.45242737876;11054.834177487344;11246.100311975022;11560.479778409992;15925.442609508611;15955.441559545361;16014.919925400298;16083.678326433639;16088.11515366666;8660.913390866137;8905.777355566033;8978.551072446351;9042.502662353634;9058.516034562801;8787.648494060293;8842.955785221162;8933;9093.499857507768;9163.541822908828;6755.121834161528;7013.964930175316;7074.92925070753;7103.715851365888;7214.530296263296;1994.2225321247251;2027.2701827342146;2065.173930427841;2096.287262330815;2098.9370318890456;1076.4671487613682;1082.815921293382;1111.0334009411863;1121;1125.9697376900208;21103.472413189487;21548.4612884676;21583.892080539496;21682.78317216839;21892.014859331368;1076.3541874875011;1080.2276372393776;1103;1126.926749761263;1151.326474012712;11475.540978360772;12049.277043377426;12231.938840305742;12350.505979760708;12435.253884766942;487038;495343;498225;500497;500758;84155;84637.57681211678;84751;85433.57283213544;85890.57054714812;1090.5583238788145;1128.3963079752293;1135.7898788723946;1146.2835727670206;1146.7305183281921;18161.091945402943;18387.17257723379;18613.906930465262;18965.90517047406;19153.904230478758;40414.59585404109;41084.79457602693;41496.377554336104;42430;42707;57633;57780.4221957775;59196.40803591909;59624;63339;7844.21557844211;7918.841623167619;8154.959225203836;8264.669413223404;9083.273338132802;13233.404496797859;13610.93194534021;14135.858641413453;14521.419143234152;14539.345729442412;11119.944400277947;11565.826512602149;12014.159008869296;12121.81817272724;12225;11478.368689722145;11496.82754758696;12234.632961011184;12406.875931240573;12651.873481265438;12620.873791261969;12655.303958282382;12775.808362874379;13063.150895191782;13165.341732913508;8984.86522702173;9032.23226025769;9050.27597792162;9237.12247336502;9241.537923103953;8715;12409.193402428813;12716.745665086832;12873.098883078095;13057.28184949837 12:16:59 INFO - PROCESS | 5562 | |3;mootools.html;1413.6748547833995;1500.0549653718156;1600.4958438092;1779.724142757873;1802.864785141114;7890.644920978555;8425.957870210648;8446.282066024396;9040.050794666564;9040.27677785778;8448.408611397204;8741.256993155574;8800.295976321895;8828.646854125844;8844.336674749387;2309.7343805462333;2348.3424641100432;2355.611324131516;2355.9175428859994;2363;2311.849729767568;2321.9767802322012;2350.8354415190943;2352.3883790214495;2357.740648528669;2322.6167682332393;2326.9185578504757;2342.3441436397834;2350.670906073151;2354.281944007077;2269.47802005538;2276.4877902471976;2281.0533628544135;2283.7373702024315;2313.8611683299055;2316.8262380321544;2333.171724037959;2343.215022967308;2343.953120937572;2346.0615753698485;8904.955475222583;8906.955465222632;8957.910420895772;8976.281897448254;8984.326175536795;5159.974200129013;5332.466753324676;5338.546223571001;5345.064613692588;5353.544948679328;3139.9686003139905;3251.918702032468;3346.832658367072;3367.3938691035605;3417.0773891049525;1139.4132022008673;1159.8492196014465;2222.288867562381;2242.4169715873827;2323.3726893738767;11651.184417090806;12063.095267854858;12066.818997715041;12145.453454594475;12167.026637868948;4433.357163211316;4609.262517997105;4703.764811759467;4745.786439610226;4790.089882922233;10430.426326552113;10453.477326133814;10465.9476702616;10470.633527826489;10492.422916739653;5386.218998245233;5396.757145928443;5421.132618780976;5425.9186112208345;5434.483724046209;4365.628921541641;4899.412070551557;4962.181240095344;4987.600991920638;4991.376077990251;4161.063760653889;4179.331306990867;4228.090960443519;4245.8513952012145;4258.190943720683;1349;1379.951701690436;1387.2924808347816;1393.6864205553873;1394.7350003499303;999.8800143982623;1002.173207104142;1017.5115944346755;1021.1320377678913;1046.3669479964512;5762.567807414461;5882.3529411764675;5886.676232807151;5938.257717785277;6076.665783381956;473.7820602522809;478.92816077587946;483.4561118741416;493.80988319497254;494.38696016938735;4018.3771515415156;4107.815148318333;4151.310375782373;4166.208420400114;4227.598378154071;10726.410047447309;10928.125749939985;11048;11131.276467029616;11148.832767508495;10964.890351096547;10999.61501347465;11009.449527523591;11023.228374013903;11080.279781814157;8333.8333233335;8405.327573794082;8425.199606037417;8425.662973480992;8452.957735211285;5909.084091965752;5914.260717410324;5951.137085122719;5963.73163207648;5995.430434108753;9986.35088719231;9993;10220.540075696761;10297.3821570706;10366.585336586451;10250.23123265773;10338.483075846329;10397.896021039693;10405.791884162425;10406.531706073396;7078.362947334765;7377.631118443949;7490.063742032246;7698.769036928901;7784.377249819888;8200.58997050157;8272.090070092165;8330.916690833013;8337.791555210926;8339.45793523419;7522.6614802335125;7543.358814500829;7807.687692492236;7890;8057.5165490070785;7026.648667566499;7040.471964602778;7088.929110709033;7097.396721278543;7110.822229444305;6873.793786186422;6994.230634630085;6996.055532503047;7005.404540614105;7005.649717514002;5050.015247026941;5166.6125040621355;5190.109808022738;5241;5306.177542480922;5145.691258524501;5267.394249661338;5318.547923426552;5326.920096198638;5459.945400545944 12:16:59 INFO - PROCESS | 5562 | |4;prototype.html;333.3649397897007;334.59346893524366;342.4384010223234;344.06414552417414;347.0577382406764;5684.5168160706335;5781.566382521307;5825.330087039986;5829.621074630157;5841;5738.42615738426;5742;5746.367899531048;5746.454086861752;5747.33905600856;4249.51130619978;4377.934330985037;4445.310976798602;4485.3944717463055;4502.572255635726;4476.060027394251;4476.164772443299;4486.910261794762;4490.271245570446;4492.550744925497;4439.822407103712;4440.111977604476;4449.265871131276;4454.688171827974;4456.108778244348;4322.4164737760475;4410.184115938561;4430.357598148283;4460.085682435093;4461.263891457906;3937.370020796659;3939.527256729182;3939.72421930465;3942.4086387041884;3956.8021598920227;5594.692291923942;5599.944000559983;5600.355959064719;5615.466530679579;5617.971910140464;5008.223725322544;5086.160783470723;5107.310513080761;5114.846554603368;5139.306193663845;5949.185498422979;5976.611520251213;5985.970070149665;5999;5999.280086389661;4307.633851122659;4324.243257429937;4333.848315308949;4338.069559046226;4340.175366680321;5941.811637672439;6221.657808820513;6258.186435763367;6272.0799500312305;6273.749050037947;4285.957140428618;4290.399344091829;4314.9784251078545;4317.784110794448;4334.783260836945;4260.631604178757;4344.587264209895;4364.8472303469225;4367.868963931023;4372.256716358227;4918.729469879119;4954.702717836942;4968.453470118284;4979.377577802775;4999.275105109738;5997.730102145414;6020.57855950088;6021.759129634853;6028.457438830438;6046.727897244635;1489.5531340597777;1490.709311684233;1495.2598463760507;1514.4547962733407;1521.7108749337813;932.8787257656597;945.6879229854131;991.8264303746815;1016.755978565139;1032.1691038713757;5803.970980145073;5834.27071616048;5859.886621541893;5863.882722345615;5912.852178695567;6043.335233124353;6076.787312444043;6081.74975259157;6149.692515374213;6160.383961603893;5696.319585722139;5699.544036477072;5759.337676167211;5784.768609255667;5826.417358264224;3240.449123648938;3245.3509298140466;3247.610286765556;3281.982585398534;3285.8849940251976;6057.42454466825;6230.875382492415;6275.246970363585;6275.6548389839;6293.937060629335;6135.32511423743;6189.876202475925;6191.690415479298;6200.81397558074;6223.950264370426;6094.664793436404;6285.245770507477;6296.307406185316;6304.432601065926;6311.33730958252;6168.228971378578;6236.282827474898;6287.685615719287;6291.653959032297;6306.432421082125;6113.318837319773;6167.506599471942;6175.289841668266;6185.8144255672405;6218.108579510549;6269.332440522346;6421.743130274736;6446.484281257582;6487.870242595216;6492.902606460812;4378.496472905656;4415.116976604628;4419.42547468827;4426.44669416323;4447.911041779211;6091.842549915502;6241.21984751906;6316.873662526816;6323.272823625342;6329;6128.540359472968;6131.325554188947;6140.662263575547;6148.815535533941;6148.877022459616;6097.573169878244;6108.450239478469;6112.419320164578;6130.356312587114;6136.907946380898;5886.970565147147;5950.553708471968;6035.879282414239;6064.939350606437;6100.511959043355;6158.876822463436;6185.6907154641185;6192.225971753531;6216.7824126155365;6240.3790408029645;5899;5952.868954898556;5972.805438912322;6035.788747393995;6044.607100538451 12:16:59 INFO - PROCESS | 5562 | |5;yui.html;822.2106777493602;825.5872063968015;861.1087524412235;865.4634126841358;872.7599910024743;16755.32978680853;18068.638627227465;18166.545836354097;18202.5449363766;18250.908745456272;16482.42311519097;16646.583835404123;16649.33402663892;16680.999140051605;16710.498685039438;859.9484030958131;860.8536548786722;897.6005677473527;902.6163880350857;908.8000639859221;885.6767279942807;894.1058941058941;894.217559635319;894.9284057275435;895.1004240738049;928.058021108572;929.739672891588;932.7854593443512;933.7898972731149;934.668192791556;928.1182876267573;928.2713070239897;929.2380248196481;929.5445231836419;930.730088274403;678.762433148399;678.8913773796169;680.8876535371658;683.5386114372828;684.7740245718951;17055.317787288495;17145.65708685832;17167.91416042924;17225.31098756061;17287.48137555863;15924.460126465356;16171.191440428049;16381.590460238589;16704.58238544034;17295.481135565955;13577.185368877801;13621.046526743132;13659.521916732967;13838.861611383858;13896.513622023282;4133.090720041586;4144.295469770116;4181.623653871166;4250.192463431938;4355.7822108894425;93258;96184.03815961821;96663.51668241614;97521.5123924383;99750.50124749403;12115.697107572205;12156.149069565046;12325.630231093082;12336;12496.81254781179;22740.43148921257;22824.885875570515;22883.656745148837;22906.198283060345;22914.770852291596;8968.551572421484;9146.314026448043;9213.30900182489;9346.532673366304;9713.757156071013;5304.787808487695;5382.138857782736;5486.177073439016;5553.083741182741;5679.943200568024;3626.3110009098186;3720.4977328060363;3758.9060273492832;3764.6235376462682;3792.810359482015;609.0924522461501;614.7510258345377;627.2379059442799;627.7677259414046;631.3907079668143;658.4370363339372;694.9583025018516;754.3701009656997;764.1746913333586;769.8806684963839;10840.457977101114;11174.944125279322;11226.775464490664;11289.717757055974;11362.602308919148;739.3863093632273;747.8317378589774;778.6723636200265;791.9722809701633;817.2032268538246;5097.7051858714585;5323.866903327448;5422.132458806572;5436.10304299794;5480.369757477941;72425.27574724291;83814;131537;138556.30721846328;139269;119242.40378798224;120779;121282.3935880315;124021;127065.36467317604;101505;103236;104567;105780.47109764403;108257;10277.84583231267;10400.219983501116;10412.895871041495;10424.176043014853;10440;59451;61448;62491.812786552255;62818.68590657017;63598.36401635924;76909;79567;81926;82864.58567707123;94135;14674.413023479368;14795.852041479448;14812;14829.555113346616;14974.925125374304;15852.76220856665;15982;16620.3351865924;16627.3349066036;16945.23746431438;15230.847691522942;15705.371785128924;15923.283452244445;16035.358585656442;16598.336066557684;15096.924515377354;15507.612309692347;15593.996311419958;15971.760423593887;16623.3350665972;14553.345099470762;14719.632009199855;14903.32935017905;15261.084334939938;15535.76696349533;12900.870991289967;12951.5466958656;12986.480540778262;13319.66700832487;13499.595012149652;11895.93674390768;13206.207627542379;13380.866191338351;13479.595612131652;13680 12:16:59 INFO - PROCESS | 5562 | __end_tp_report 12:16:59 INFO - PROCESS | 5562 | __start_cc_report 12:16:59 INFO - PROCESS | 5562 | _x_x_mozilla_cycle_collect,330 12:16:59 INFO - PROCESS | 5562 | __end_cc_report 12:16:59 INFO - PROCESS | 5562 | __startTimestamp1473448619008__endTimestamp 12:16:59 INFO - PROCESS | 5562 | 12:16:59 INFO - PROCESS | 5562 | ------- Summary: start ------- 12:16:59 INFO - PROCESS | 5562 | Number of tests: 6 12:16:59 INFO - PROCESS | 5562 | 12:16:59 INFO - PROCESS | 5562 | [#0] dojo.html Cycles:165 Average:5641.00 Median:5852.36 stddev:632.45 (10.8%) stddev-sans-first:606.50 12:16:59 INFO - PROCESS | 5562 | Values: 3273.2 3304.9 3308.4 3309.4 3323.6 5631.1 5663.3 5683.9 5717.4 5748.2 5628.2 5630.1 5640.4 5652.1 5666.9 5145.0 5165.5 5194.5 5231.4 5265.2 5140.4 5158.3 5166.5 5206.3 5215.3 5143.2 5153.1 5168.8 5217.2 5276.3 5216.5 5221.1 5225.1 5232.4 5259.6 4954.4 5042.4 5100.6 5113.1 5189.9 5610.2 5664.5 5671.2 5677.7 5701.2 6058.6 6074.4 6083.6 6089.5 6102.0 5920.8 5943.6 6151.2 6169.2 6179.4 5589.9 5674.7 5680.0 5708.5 5715.3 6122.7 6194.0 6204.5 6210.2 6211.8 5762.0 5890.2 5905.4 5946.8 5967.3 6001.8 6115.4 6127.3 6130.2 6138.2 6068.8 6098.9 6101.5 6111.8 6126.2 5908.4 5948.3 6102.3 6102.3 6115.2 4603.0 4636.9 4642.3 4673.6 4674.9 4156.6 4158.1 4169.7 4192.1 4193.2 5663.5 5751.7 5852.4 5852.7 5856.3 6091.7 6115.1 6120.2 6122.6 6135.5 5820.6 5844.7 5846.2 5848.9 5868.8 5145.1 5184.1 5256.0 5261.8 5292.4 6158.9 6227.7 6269.5 6271.1 6271.1 6055.8 6069.2 6076.9 6086.2 6124.2 6077.0 6176.3 6314.0 6316.9 6329.4 5949.5 6013.8 6014.1 6040.1 6074.0 6099.0 6103.7 6108.1 6114.9 6120.5 5784.0 5812.1 5989.2 6003.9 6004.6 5785.5 5854.6 5879.5 5886.3 5906.2 5708.7 5736.0 5776.2 5782.4 5787.1 5836.9 5857.7 5874.5 5916.1 5933.2 5819.7 5906.0 5920.9 5922.1 5929.1 12:16:59 INFO - PROCESS | 5562 | 12:16:59 INFO - PROCESS | 5562 | [#1] ext.html Cycles:180 Average:62848.08 Median:42351.58 stddev:129797.66 (306.5%) stddev-sans-first:130077.61 12:16:59 INFO - PROCESS | 5562 | Values: 591.3 642.1 657.4 665.1 697.7 41357.8 45385.5 45670.0 45693.0 45830.5 45310.0 45645.8 45820.5 45934.3 46020.0 9086.9 9164.4 9165.8 9217.6 9249.2 9112.1 9122.2 9162.1 9167.3 9177.5 9153.3 9193.8 9230.8 9248.3 9270.1 9190.0 9202.7 9228.5 9237.7 9267.5 9170.4 9251.4 9266.1 9272.7 9274.5 41873.6 42239.4 42296.0 42312.6 42390.6 3301.8 3812.9 3828.8 3901.4 3984.2 32419.2 32894.3 33127.3 33313.2 33336.7 4348.9 4368.4 4419.4 4421.3 4438.9 315346.0 315368.0 317595.4 319536.0 320959.0 37434.8 37537.4 37576.4 37839.4 37873.6 38329.2 38414.2 39012.0 39649.8 40212.0 19718.9 19815.5 19923.9 19956.8 19983.6 15136.3 15186.9 15231.4 15244.0 15269.7 20631.3 20717.6 20972.5 21007.1 21108.9 7500.8 7601.5 7634.2 7645.4 7833.7 1012.7 1067.9 1086.8 1113.9 1137.7 32669.2 33172.0 33406.8 33654.0 33854.5 1103.9 1173.5 1227.9 1242.8 1297.5 17523.7 17612.7 17643.6 17681.2 17769.2 620784.0 625525.0 626540.0 627222.0 629202.0 63235.7 63300.7 63724.0 63746.4 63797.7 1436.3 1466.9 1467.2 1475.5 1480.6 31455.0 32656.8 33149.7 33361.3 34174.1 53937.2 54278.2 54304.7 54369.7 54450.2 466680.0 467844.0 469547.0 469750.0 469907.0 27844.6 28250.7 28542.0 30462.7 30750.0 51836.7 52040.7 52186.5 52514.7 53280.2 42903.4 44249.0 44551.6 44660.8 44722.6 45153.8 45208.5 45642.3 45922.5 46011.5 36566.3 43417.8 43685.0 44279.3 44631.0 37995.4 39626.6 40021.4 40370.0 40712.0 29323.6 29849.6 30185.5 30198.8 30500.8 12:16:59 INFO - PROCESS | 5562 | 12:16:59 INFO - PROCESS | 5562 | [#2] jquery.html Cycles:180 Average:29472.33 Median:24811.07 stddev:81461.25 (328.3%) stddev-sans-first:81660.51 12:16:59 INFO - PROCESS | 5562 | Values: 394.6 406.3 428.6 431.2 432.0 19370.1 20075.5 20895.3 21056.3 21157.5 19637.0 20034.6 20194.9 20652.4 20708.0 5798.4 5941.5 5977.9 5982.5 5985.6 5884.2 5902.6 5917.4 5924.0 5926.3 5694.9 5845.9 5881.8 5911.8 5927.0 5818.8 5836.0 5864.7 5869.6 5887.5 4999.1 5084.0 5086.7 5094.6 5096.6 23888.5 24690.1 24932.0 24939.8 25050.2 2566.8 2632.6 2793.6 2794.2 3100.4 15405.8 15447.2 15526.8 15812.5 16239.9 2025.4 2048.1 2074.9 2077.6 2097.3 58962.4 60278.8 80838.2 81243.0 86890.6 10864.7 10951.5 11054.8 11246.1 11560.5 15925.4 15955.4 16014.9 16083.7 16088.1 8660.9 8905.8 8978.6 9042.5 9058.5 8787.6 8843.0 8933.0 9093.5 9163.5 6755.1 7014.0 7074.9 7103.7 7214.5 1994.2 2027.3 2065.2 2096.3 2098.9 1076.5 1082.8 1111.0 1121.0 1126.0 21103.5 21548.5 21583.9 21682.8 21892.0 1076.4 1080.2 1103.0 1126.9 1151.3 11475.5 12049.3 12231.9 12350.5 12435.3 487038.0 495343.0 498225.0 500497.0 500758.0 84155.0 84637.6 84751.0 85433.6 85890.6 1090.6 1128.4 1135.8 1146.3 1146.7 18161.1 18387.2 18613.9 18965.9 19153.9 40414.6 41084.8 41496.4 42430.0 42707.0 57633.0 57780.4 59196.4 59624.0 63339.0 7844.2 7918.8 8155.0 8264.7 9083.3 13233.4 13610.9 14135.9 14521.4 14539.3 11119.9 11565.8 12014.2 12121.8 12225.0 11478.4 11496.8 12234.6 12406.9 12651.9 12620.9 12655.3 12775.8 13063.2 13165.3 8984.9 9032.2 9050.3 9237.1 9241.5 8715.0 12409.2 12716.7 12873.1 13057.3 12:16:59 INFO - PROCESS | 5562 | 12:16:59 INFO - PROCESS | 5562 | [#3] mootools.html Cycles:180 Average:5818.41 Median:2588.77 stddev:3272.06 (126.4%) stddev-sans-first:3264.49 12:16:59 INFO - PROCESS | 5562 | Values: 1413.7 1500.1 1600.5 1779.7 1802.9 7890.6 8426.0 8446.3 9040.1 9040.3 8448.4 8741.3 8800.3 8828.6 8844.3 2309.7 2348.3 2355.6 2355.9 2363.0 2311.8 2322.0 2350.8 2352.4 2357.7 2322.6 2326.9 2342.3 2350.7 2354.3 2269.5 2276.5 2281.1 2283.7 2313.9 2316.8 2333.2 2343.2 2344.0 2346.1 8905.0 8907.0 8957.9 8976.3 8984.3 5160.0 5332.5 5338.5 5345.1 5353.5 3140.0 3251.9 3346.8 3367.4 3417.1 1139.4 1159.8 2222.3 2242.4 2323.4 11651.2 12063.1 12066.8 12145.5 12167.0 4433.4 4609.3 4703.8 4745.8 4790.1 10430.4 10453.5 10465.9 10470.6 10492.4 5386.2 5396.8 5421.1 5425.9 5434.5 4365.6 4899.4 4962.2 4987.6 4991.4 4161.1 4179.3 4228.1 4245.9 4258.2 1349.0 1380.0 1387.3 1393.7 1394.7 999.9 1002.2 1017.5 1021.1 1046.4 5762.6 5882.4 5886.7 5938.3 6076.7 473.8 478.9 483.5 493.8 494.4 4018.4 4107.8 4151.3 4166.2 4227.6 10726.4 10928.1 11048.0 11131.3 11148.8 10964.9 10999.6 11009.4 11023.2 11080.3 8333.8 8405.3 8425.2 8425.7 8453.0 5909.1 5914.3 5951.1 5963.7 5995.4 9986.4 9993.0 10220.5 10297.4 10366.6 10250.2 10338.5 10397.9 10405.8 10406.5 7078.4 7377.6 7490.1 7698.8 7784.4 8200.6 8272.1 8330.9 8337.8 8339.5 7522.7 7543.4 7807.7 7890.0 8057.5 7026.6 7040.5 7088.9 7097.4 7110.8 6873.8 6994.2 6996.1 7005.4 7005.6 5050.0 5166.6 5190.1 5241.0 5306.2 5145.7 5267.4 5318.5 5326.9 5459.9 12:16:59 INFO - PROCESS | 5562 | 12:16:59 INFO - PROCESS | 5562 | [#4] prototype.html Cycles:180 Average:5070.19 Median:5828.02 stddev:1510.01 (25.9%) stddev-sans-first:1471.79 12:16:59 INFO - PROCESS | 5562 | Values: 333.4 334.6 342.4 344.1 347.1 5684.5 5781.6 5825.3 5829.6 5841.0 5738.4 5742.0 5746.4 5746.5 5747.3 4249.5 4377.9 4445.3 4485.4 4502.6 4476.1 4476.2 4486.9 4490.3 4492.6 4439.8 4440.1 4449.3 4454.7 4456.1 4322.4 4410.2 4430.4 4460.1 4461.3 3937.4 3939.5 3939.7 3942.4 3956.8 5594.7 5599.9 5600.4 5615.5 5618.0 5008.2 5086.2 5107.3 5114.8 5139.3 5949.2 5976.6 5986.0 5999.0 5999.3 4307.6 4324.2 4333.8 4338.1 4340.2 5941.8 6221.7 6258.2 6272.1 6273.7 4286.0 4290.4 4315.0 4317.8 4334.8 4260.6 4344.6 4364.8 4367.9 4372.3 4918.7 4954.7 4968.5 4979.4 4999.3 5997.7 6020.6 6021.8 6028.5 6046.7 1489.6 1490.7 1495.3 1514.5 1521.7 932.9 945.7 991.8 1016.8 1032.2 5804.0 5834.3 5859.9 5863.9 5912.9 6043.3 6076.8 6081.7 6149.7 6160.4 5696.3 5699.5 5759.3 5784.8 5826.4 3240.4 3245.4 3247.6 3282.0 3285.9 6057.4 6230.9 6275.2 6275.7 6293.9 6135.3 6189.9 6191.7 6200.8 6224.0 6094.7 6285.2 6296.3 6304.4 6311.3 6168.2 6236.3 6287.7 6291.7 6306.4 6113.3 6167.5 6175.3 6185.8 6218.1 6269.3 6421.7 6446.5 6487.9 6492.9 4378.5 4415.1 4419.4 4426.4 4447.9 6091.8 6241.2 6316.9 6323.3 6329.0 6128.5 6131.3 6140.7 6148.8 6148.9 6097.6 6108.5 6112.4 6130.4 6136.9 5887.0 5950.6 6035.9 6064.9 6100.5 6158.9 6185.7 6192.2 6216.8 6240.4 5899.0 5952.9 5972.8 6035.8 6044.6 12:16:59 INFO - PROCESS | 5562 | 12:16:59 INFO - PROCESS | 5562 | [#5] yui.html Cycles:180 Average:23891.54 Median:22894.93 stddev:34731.47 (151.7%) stddev-sans-first:34785.70 12:16:59 INFO - PROCESS | 5562 | Values: 822.2 825.6 861.1 865.5 872.8 16755.3 18068.6 18166.5 18202.5 18250.9 16482.4 16646.6 16649.3 16681.0 16710.5 859.9 860.9 897.6 902.6 908.8 885.7 894.1 894.2 894.9 895.1 928.1 929.7 932.8 933.8 934.7 928.1 928.3 929.2 929.5 930.7 678.8 678.9 680.9 683.5 684.8 17055.3 17145.7 17167.9 17225.3 17287.5 15924.5 16171.2 16381.6 16704.6 17295.5 13577.2 13621.0 13659.5 13838.9 13896.5 4133.1 4144.3 4181.6 4250.2 4355.8 93258.0 96184.0 96663.5 97521.5 99750.5 12115.7 12156.1 12325.6 12336.0 12496.8 22740.4 22824.9 22883.7 22906.2 22914.8 8968.6 9146.3 9213.3 9346.5 9713.8 5304.8 5382.1 5486.2 5553.1 5679.9 3626.3 3720.5 3758.9 3764.6 3792.8 609.1 614.8 627.2 627.8 631.4 658.4 695.0 754.4 764.2 769.9 10840.5 11174.9 11226.8 11289.7 11362.6 739.4 747.8 778.7 792.0 817.2 5097.7 5323.9 5422.1 5436.1 5480.4 72425.3 83814.0 131537.0 138556.3 139269.0 119242.4 120779.0 121282.4 124021.0 127065.4 101505.0 103236.0 104567.0 105780.5 108257.0 10277.8 10400.2 10412.9 10424.2 10440.0 59451.0 61448.0 62491.8 62818.7 63598.4 76909.0 79567.0 81926.0 82864.6 94135.0 14674.4 14795.9 14812.0 14829.6 14974.9 15852.8 15982.0 16620.3 16627.3 16945.2 15230.8 15705.4 15923.3 16035.4 16598.3 15096.9 15507.6 15594.0 15971.8 16623.3 14553.3 14719.6 14903.3 15261.1 15535.8 12900.9 12951.5 12986.5 13319.7 13499.6 11895.9 13206.2 13380.9 13479.6 13680.0 12:16:59 INFO - PROCESS | 5562 | -------- Summary: end -------- 12:16:59 INFO - PROCESS | 5562 | 12:16:59 INFO - TEST-INFO | 5562: exit 0 12:16:59 INFO - TEST-OK | dromaeo_css | took 1110360ms 12:16:59 INFO - TEST-START | kraken 12:16:59 INFO - operating with platform_type : linux_ 12:16:59 INFO - Initialising browser for kraken test... 12:16:59 INFO - TEST-INFO | started process 5835 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcSVf3d/profile http://localhost:48548/getInfo.html) 12:17:04 INFO - PROCESS | 5835 | __metrics Screen width/height:1600/1200 12:17:04 INFO - PROCESS | 5835 | colorDepth:24 12:17:04 INFO - PROCESS | 5835 | Browser inner width/height: 1024/697 12:17:04 INFO - PROCESS | 5835 | __metrics 12:17:04 ERROR - PROCESS | 5835 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 12:17:05 INFO - TEST-INFO | 5835: exit 0 12:17:05 INFO - Browser initialized. 12:17:05 INFO - Running cycle 1/1 for kraken test... 12:17:05 INFO - TEST-INFO | started process 5962 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcSVf3d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:17:17 INFO - PROCESS | 5962 | RSS: Main: 154169344 12:17:17 INFO - PROCESS | 5962 | 12:17:19 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48548/tests/kraken/driver.html?testName=audio-beat-detection) 12:17:20 INFO - PROCESS | 5962 | RSS: Main: 159498240 12:17:20 INFO - PROCESS | 5962 | 12:17:23 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48548/tests/kraken/driver.html?testName=audio-dft) 12:17:23 INFO - PROCESS | 5962 | RSS: Main: 157450240 12:17:23 INFO - PROCESS | 5962 | 12:17:26 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48548/tests/kraken/driver.html?testName=audio-fft) 12:17:27 INFO - PROCESS | 5962 | RSS: Main: 157921280 12:17:27 INFO - PROCESS | 5962 | 12:17:29 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48548/tests/kraken/driver.html?testName=audio-oscillator) 12:17:30 INFO - PROCESS | 5962 | RSS: Main: 158257152 12:17:30 INFO - PROCESS | 5962 | 12:17:32 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48548/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:17:32 INFO - PROCESS | 5962 | RSS: Main: 158355456 12:17:32 INFO - PROCESS | 5962 | 12:17:36 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48548/tests/kraken/driver.html?testName=imaging-darkroom) 12:17:36 INFO - PROCESS | 5962 | RSS: Main: 158400512 12:17:36 INFO - PROCESS | 5962 | 12:17:40 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48548/tests/kraken/driver.html?testName=imaging-desaturate) 12:17:40 INFO - PROCESS | 5962 | RSS: Main: 156581888 12:17:40 INFO - PROCESS | 5962 | 12:17:43 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48548/tests/kraken/driver.html?testName=json-parse-financial) 12:17:44 INFO - PROCESS | 5962 | RSS: Main: 157368320 12:17:44 INFO - PROCESS | 5962 | 12:17:45 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48548/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:17:46 INFO - PROCESS | 5962 | RSS: Main: 156905472 12:17:46 INFO - PROCESS | 5962 | 12:17:48 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48548/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:17:48 INFO - PROCESS | 5962 | RSS: Main: 157642752 12:17:48 INFO - PROCESS | 5962 | 12:17:50 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48548/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:17:51 INFO - PROCESS | 5962 | RSS: Main: 158638080 12:17:51 INFO - PROCESS | 5962 | 12:17:54 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48548/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:17:54 INFO - PROCESS | 5962 | RSS: Main: 158195712 12:17:54 INFO - PROCESS | 5962 | 12:17:57 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48548/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:17:57 INFO - PROCESS | 5962 | RSS: Main: 159088640 12:17:57 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | Cycle 1(1): loaded http://localhost:48548/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48548/tests/kraken/driver.html?testName=ai-astar) 12:17:59 INFO - PROCESS | 5962 | RSS: Main: 160460800 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | __start_tp_report 12:17:59 INFO - PROCESS | 5962 | _x_x_mozilla_page_load 12:17:59 INFO - PROCESS | 5962 | _x_x_mozilla_page_load_details 12:17:59 INFO - PROCESS | 5962 | |i|pagename|runs| 12:17:59 INFO - PROCESS | 5962 | |0;ai-astar;108;111;103;111;112;112;97;111;112;110 12:17:59 INFO - PROCESS | 5962 | |1;audio-beat-detection;150;115;138;170;117;147;146;121;188;116 12:17:59 INFO - PROCESS | 5962 | |2;audio-dft;161;162;164;161;162;162;161;160;160;162 12:17:59 INFO - PROCESS | 5962 | |3;audio-fft;78;74;76;171;76;75;74;74;169;76 12:17:59 INFO - PROCESS | 5962 | |4;audio-oscillator;84;88;145;82;83;84;134;85;84;144 12:17:59 INFO - PROCESS | 5962 | |5;imaging-gaussian-blur;117;118;118;117;117;118;117;117;118;118 12:17:59 INFO - PROCESS | 5962 | |6;imaging-darkroom;147;148;146;147;149;147;147;147;147;146 12:17:59 INFO - PROCESS | 5962 | |7;imaging-desaturate;96;95;96;95;96;96;95;96;95;101 12:17:59 INFO - PROCESS | 5962 | |8;json-parse-financial;52;58;57;51;54;52;51;53;58;52 12:17:59 INFO - PROCESS | 5962 | |9;json-stringify-tinderbox;59;59;59;59;63;60;59;58;59;61 12:17:59 INFO - PROCESS | 5962 | |10;stanford-crypto-aes;73;73;74;71;76;73;73;77;71;72 12:17:59 INFO - PROCESS | 5962 | |11;stanford-crypto-ccm;133;122;118;129;116;125;127;117;164;118 12:17:59 INFO - PROCESS | 5962 | |12;stanford-crypto-pbkdf2;152;151;151;150;160;156;154;153;153;153 12:17:59 INFO - PROCESS | 5962 | |13;stanford-crypto-sha256-iterative;64;62;61;62;64;68;66;60;61;60 12:17:59 INFO - PROCESS | 5962 | __end_tp_report 12:17:59 INFO - PROCESS | 5962 | __start_cc_report 12:17:59 INFO - PROCESS | 5962 | _x_x_mozilla_cycle_collect,871 12:17:59 INFO - PROCESS | 5962 | __end_cc_report 12:17:59 INFO - PROCESS | 5962 | __startTimestamp1473448679860__endTimestamp 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | ------- Summary: start ------- 12:17:59 INFO - PROCESS | 5962 | Number of tests: 14 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#0] ai-astar Cycles:10 Average:108.70 Median:111.50 stddev:4.95 (4.4%) stddev-sans-first:5.24 12:17:59 INFO - PROCESS | 5962 | Values: 108.0 111.0 103.0 111.0 112.0 112.0 97.0 111.0 112.0 110.0 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#1] audio-beat-detection Cycles:10 Average:140.80 Median:146.50 stddev:24.67 (16.8%) stddev-sans-first:25.94 12:17:59 INFO - PROCESS | 5962 | Values: 150.0 115.0 138.0 170.0 117.0 147.0 146.0 121.0 188.0 116.0 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:1.18 (0.7%) stddev-sans-first:1.24 12:17:59 INFO - PROCESS | 5962 | Values: 161.0 162.0 164.0 161.0 162.0 162.0 161.0 160.0 160.0 162.0 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#3] audio-fft Cycles:10 Average:94.30 Median:75.50 stddev:39.92 (52.9%) stddev-sans-first:41.90 12:17:59 INFO - PROCESS | 5962 | Values: 78.0 74.0 76.0 171.0 76.0 75.0 74.0 74.0 169.0 76.0 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#4] audio-oscillator Cycles:10 Average:101.30 Median:84.00 stddev:27.59 (32.8%) stddev-sans-first:28.54 12:17:59 INFO - PROCESS | 5962 | Values: 84.0 88.0 145.0 82.0 83.0 84.0 134.0 85.0 84.0 144.0 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 12:17:59 INFO - PROCESS | 5962 | Values: 117.0 118.0 118.0 117.0 117.0 118.0 117.0 117.0 118.0 118.0 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 12:17:59 INFO - PROCESS | 5962 | Values: 147.0 148.0 146.0 147.0 149.0 147.0 147.0 147.0 147.0 146.0 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:1.79 (1.9%) stddev-sans-first:1.90 12:17:59 INFO - PROCESS | 5962 | Values: 96.0 95.0 96.0 95.0 96.0 96.0 95.0 96.0 95.0 101.0 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:53.50 stddev:2.82 (5.3%) stddev-sans-first:2.92 12:17:59 INFO - PROCESS | 5962 | Values: 52.0 58.0 57.0 51.0 54.0 52.0 51.0 53.0 58.0 52.0 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:59.00 stddev:1.43 (2.4%) stddev-sans-first:1.50 12:17:59 INFO - PROCESS | 5962 | Values: 59.0 59.0 59.0 59.0 63.0 60.0 59.0 58.0 59.0 61.0 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#10] stanford-crypto-aes Cycles:10 Average:73.30 Median:73.00 stddev:1.95 (2.7%) stddev-sans-first:2.06 12:17:59 INFO - PROCESS | 5962 | Values: 73.0 73.0 74.0 71.0 76.0 73.0 73.0 77.0 71.0 72.0 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#11] stanford-crypto-ccm Cycles:10 Average:126.90 Median:126.00 stddev:14.22 (11.3%) stddev-sans-first:14.91 12:17:59 INFO - PROCESS | 5962 | Values: 133.0 122.0 118.0 129.0 116.0 125.0 127.0 117.0 164.0 118.0 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.30 Median:153.00 stddev:2.91 (1.9%) stddev-sans-first:3.05 12:17:59 INFO - PROCESS | 5962 | Values: 152.0 151.0 151.0 150.0 160.0 156.0 154.0 153.0 153.0 153.0 12:17:59 INFO - PROCESS | 5962 | 12:17:59 INFO - PROCESS | 5962 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:2.66 (4.2%) stddev-sans-first:2.78 12:17:59 INFO - PROCESS | 5962 | Values: 64.0 62.0 61.0 62.0 64.0 68.0 66.0 60.0 61.0 60.0 12:17:59 INFO - PROCESS | 5962 | -------- Summary: end -------- 12:17:59 INFO - PROCESS | 5962 | 12:18:00 INFO - TEST-INFO | 5962: exit 0 12:18:00 INFO - TEST-OK | kraken | took 61099ms 12:18:00 INFO - SUITE-END | took 1171s 12:18:01 INFO - Completed test suite (00:19:31) 12:18:01 INFO - javascript benchmark 12:18:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7689.873543652576, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3273.1817045738567, 3304.917377065575, 3308.3548708001936, 3309.4373956427403, 3323.6344002159744, 5631.099024156136, 5663.2767237735325, 5683.943160568393, 5717.371089180187, 5748.166515855209, 5628.155776633507, 5630.071038278679, 5640.435956404358, 5652.09566469365, 5666.914996275059, 5144.9742751286285, 5165.509276618715, 5194.532492075712, 5231.372235331746, 5265.183896496049, 5140.408852981899, 5158.2520534522655, 5166.509181627758, 5206.3231779868565, 5215.269862219292, 5143.228515722652, 5153.098207813637, 5168.767405466763, 5217.217417387404, 5276.314079169702, 5216.5305122538775, 5221.06020916235, 5225.111731005738, 5232.424433312332, 5259.552938000236, 4954.43024052235, 5042.369703787027, 5100.617453690951, 5113.105206588869, 5189.922151167735, 5610.18652295419, 5664.54683625309, 5671.177679236529, 5677.659340439587, 5701.173329867188, 6058.606190597641, 6074.392560743934, 6083.6349819011, 6089.512838972872, 6102.0, 5920.763169473216, 5943.613665111754, 6151.231096112986, 6169.198004259462, 6179.38206179383, 5589.860253493654, 5674.659520428788, 5679.971600142014, 5708.514776244025, 5715.2855893013375, 6122.693865306717, 6194.039923811816, 6204.472619827275, 6210.223722034746, 6211.844703882439, 5762.0, 5890.175375447441, 5905.43898329658, 5946.762129514771, 5967.283925928917, 6001.789937352171, 6115.4190351916495, 6127.3140759655425, 6130.2146971548445, 6138.204480310071, 6068.848278793065, 6098.878022439526, 6101.542384321194, 6111.816645500642, 6126.23422072241, 5908.438698323653, 5948.345681974979, 6102.267727872595, 6102.298235702951, 6115.235595550556, 4602.953970460252, 4636.884077898079, 4642.326862604903, 4673.556012178838, 4674.883127921829, 4156.56356082613, 4158.064435501988, 4169.687273454503, 4192.140611174702, 4193.22425351311, 5663.518600918886, 5751.741171647286, 5852.356240813507, 5852.678102704393, 5856.326522449887, 6091.664958427329, 6115.149072927094, 6120.233815636221, 6122.581191725645, 6135.509159267248, 5820.621659592113, 5844.736986835603, 5846.181534585162, 5848.912266316009, 5868.823935281948, 5145.07388670043, 5184.066867963727, 5255.973720131451, 5261.76322065508, 5292.391374991848, 6158.876822463526, 6227.688615569204, 6269.46709529686, 6271.05934109878, 6271.090691849657, 6055.757769689163, 6069.211002569726, 6076.878462430638, 6086.239220097488, 6124.234470691164, 6077.0, 6176.289726681489, 6313.958196897461, 6316.936830631818, 6329.367063293818, 5949.524038076857, 6013.789517366871, 6014.0678194879865, 6040.09398590215, 6073.969630151821, 6099.0, 6103.725332359965, 6108.11432342299, 6114.877702445837, 6120.540959427972, 5784.0, 5812.099124635688, 5989.161517387656, 6003.90994135097, 6004.609700369462, 5785.508231800344, 5854.560907931837, 5879.529637628894, 5886.293644762656, 5906.173135761082, 5708.65748055118, 5736.0, 5776.249087618583, 5782.421757824184, 5787.074068149076, 5836.912446313394, 5857.736401862012, 5874.500667443315, 5916.053431450947, 5933.199018132497, 5819.738111785065, 5906.025505791668, 5920.911186332295, 5922.052471604523, 5929.140274660236], "value": 5598.835357631403, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.3229352391512, 642.1010585180748, 657.4247533408268, 665.1021121485992, 697.6651207420441, 41357.79321103394, 45385.546144538544, 45670.0, 45693.0, 45830.541694583044, 45310.0, 45645.771771141095, 45820.541794582045, 45934.31098533524, 46019.99999999991, 9086.909130908705, 9164.358494905358, 9165.816683666322, 9217.585208665627, 9249.213816825579, 9112.088791120901, 9122.179003889649, 9162.129597688243, 9167.266618670523, 9177.495237761921, 9153.267738580898, 9193.770155746093, 9230.76923076925, 9248.306377021716, 9270.072992700743, 9190.035046320141, 9202.677906273315, 9228.538573071353, 9237.722868313962, 9267.536623168815, 9170.358074934691, 9251.398659087137, 9266.119718626787, 9272.721818345393, 9274.53627318638, 41873.581264187276, 42239.366409503884, 42296.0, 42312.57687423117, 42390.57609423897, 3301.755640292339, 3812.90467738306, 3828.808559572038, 3901.43429202764, 3984.203159368138, 32419.18952026218, 32894.34211315784, 33127.3374532508, 33313.16717082068, 33336.666633333596, 4348.934765978513, 4368.4372000039575, 4419.4033805436175, 4421.336799480072, 4438.866833994985, 315346.0, 315368.0, 317595.4120229407, 319536.0, 320959.0, 37434.812825935696, 37537.43693844594, 37576.43635345472, 37839.432408513894, 37873.62126378701, 38329.23341533154, 38414.23171536554, 39012.0, 39649.80175099106, 40212.0, 19718.915459649572, 19815.504612384804, 19923.900380498006, 19956.800431995784, 19983.600327993357, 15136.318865651072, 15186.924065379602, 15231.390744370323, 15244.0, 15269.694606108038, 20631.277905273244, 20717.58564828725, 20972.47568810792, 21007.05468253932, 21108.89445552793, 7500.774976750707, 7601.505902116345, 7634.236576342321, 7645.350145237717, 7833.686652533835, 1012.7215015870695, 1067.9038886500252, 1086.782643471293, 1113.8551988241477, 1137.7371118058948, 32669.18327041843, 33172.0, 33406.83296583501, 33654.0, 33854.49218261728, 1103.933763974164, 1173.501261963662, 1227.9386030698429, 1242.7882814256209, 1297.5004623219938, 17523.73714394285, 17612.735808962876, 17643.647127057386, 17681.204345804468, 17769.200385982662, 620784.0, 625525.0, 626540.0, 627222.0, 629202.0, 63235.6838215806, 63300.68349658222, 63724.0, 63746.36253637497, 63797.68101159464, 1436.2675035731852, 1466.9193194374411, 1467.207707837777, 1475.4540819896492, 1480.6372438752574, 31455.0, 32656.83671581627, 33149.66850331466, 33361.33277334488, 34174.14564635904, 53937.190942135116, 54278.18582721341, 54304.72847635736, 54369.72815135899, 54450.18324725211, 466680.0, 467844.0, 469547.0, 469750.0, 469907.0, 27844.582331264643, 28250.71749282563, 28542.0, 30462.695373045986, 30750.0, 51836.74081629568, 52040.739796300775, 52186.47813521816, 52514.737426312626, 53280.2007969873, 42903.35644965265, 44249.0, 44551.55448445474, 44660.776696116314, 44722.55277447184, 45153.77423112995, 45208.54791452044, 45642.315365268885, 45922.54077459183, 46011.53988460072, 36566.268674625826, 43417.78291108524, 43685.0, 44279.335809962235, 44631.0, 37995.43006854955, 39626.60373396229, 40021.39967900426, 40370.0, 40712.0, 29323.560146598244, 29849.55225671573, 30185.547216791325, 30198.84900575483, 30500.84749576238], "value": 20778.19904531, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.55021275745673, 406.29507803960104, 428.55216298967576, 431.2065798929969, 431.97408155510664, 19370.128344224508, 20075.498112547175, 20895.268665596708, 21056.263030793925, 21157.471063223387, 19637.0, 20034.599308013832, 20194.89902550489, 20652.380428587167, 20708.0, 5798.362180160189, 5941.494972927307, 5977.940220597804, 5982.521398288149, 5985.551083668721, 5884.2056322396575, 5902.61632993854, 5917.408259174092, 5923.992921203384, 5926.3184733755525, 5694.943050569504, 5845.912311315334, 5881.794137205198, 5911.793087241947, 5927.0, 5818.796342128006, 5836.037053886103, 5864.677442740647, 5869.589128760988, 5887.499562537189, 4999.075171093323, 5083.974580127076, 5086.720230387327, 5094.566961808251, 5096.592272618182, 23888.522229555485, 24690.13584524551, 24932.00271989118, 24939.750602494107, 25050.24849254525, 2566.8203225774205, 2632.552466080771, 2793.5809628555676, 2794.217619066644, 3100.3644252928098, 15405.768913466307, 15447.150406727626, 15526.76709849342, 15812.525624231292, 16239.918800405922, 2025.4126303372004, 2048.1193086972594, 2074.9377518674314, 2077.559565347426, 2097.28692244636, 58962.41037589612, 60278.821651314356, 80838.19161808366, 81243.0, 86890.56554717186, 10864.674059778219, 10951.45242737876, 11054.834177487344, 11246.100311975022, 11560.479778409992, 15925.442609508611, 15955.441559545361, 16014.919925400298, 16083.678326433639, 16088.11515366666, 8660.913390866137, 8905.777355566033, 8978.551072446351, 9042.502662353634, 9058.516034562801, 8787.648494060293, 8842.955785221162, 8933.0, 9093.499857507768, 9163.541822908828, 6755.121834161528, 7013.964930175316, 7074.92925070753, 7103.715851365888, 7214.530296263296, 1994.2225321247251, 2027.2701827342146, 2065.173930427841, 2096.287262330815, 2098.9370318890456, 1076.4671487613682, 1082.815921293382, 1111.0334009411863, 1121.0, 1125.9697376900208, 21103.472413189487, 21548.4612884676, 21583.892080539496, 21682.78317216839, 21892.014859331368, 1076.3541874875011, 1080.2276372393776, 1103.0, 1126.926749761263, 1151.326474012712, 11475.540978360772, 12049.277043377426, 12231.938840305742, 12350.505979760708, 12435.253884766942, 487038.0, 495343.0, 498225.0, 500497.0, 500758.0, 84155.0, 84637.57681211678, 84751.0, 85433.57283213544, 85890.57054714812, 1090.5583238788145, 1128.3963079752293, 1135.7898788723946, 1146.2835727670206, 1146.7305183281921, 18161.091945402943, 18387.17257723379, 18613.906930465262, 18965.90517047406, 19153.904230478758, 40414.59585404109, 41084.79457602693, 41496.377554336104, 42430.0, 42707.0, 57633.0, 57780.4221957775, 59196.40803591909, 59624.0, 63339.0, 7844.21557844211, 7918.841623167619, 8154.959225203836, 8264.669413223404, 9083.273338132802, 13233.404496797859, 13610.93194534021, 14135.858641413453, 14521.419143234152, 14539.345729442412, 11119.944400277947, 11565.826512602149, 12014.159008869296, 12121.81817272724, 12225.0, 11478.368689722145, 11496.82754758696, 12234.632961011184, 12406.875931240573, 12651.873481265438, 12620.873791261969, 12655.303958282382, 12775.808362874379, 13063.150895191782, 13165.341732913508, 8984.86522702173, 9032.23226025769, 9050.27597792162, 9237.12247336502, 9241.537923103953, 8715.0, 12409.193402428813, 12716.745665086832, 12873.098883078095, 13057.28184949837], "value": 9851.054564144652, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.6748547833995, 1500.0549653718156, 1600.4958438092, 1779.724142757873, 1802.864785141114, 7890.644920978555, 8425.957870210648, 8446.282066024396, 9040.050794666564, 9040.27677785778, 8448.408611397204, 8741.256993155574, 8800.295976321895, 8828.646854125844, 8844.336674749387, 2309.7343805462333, 2348.3424641100432, 2355.611324131516, 2355.9175428859994, 2363.0, 2311.849729767568, 2321.9767802322012, 2350.8354415190943, 2352.3883790214495, 2357.740648528669, 2322.6167682332393, 2326.9185578504757, 2342.3441436397834, 2350.670906073151, 2354.281944007077, 2269.47802005538, 2276.4877902471976, 2281.0533628544135, 2283.7373702024315, 2313.8611683299055, 2316.8262380321544, 2333.171724037959, 2343.215022967308, 2343.953120937572, 2346.0615753698485, 8904.955475222583, 8906.955465222632, 8957.910420895772, 8976.281897448254, 8984.326175536795, 5159.974200129013, 5332.466753324676, 5338.546223571001, 5345.064613692588, 5353.544948679328, 3139.9686003139905, 3251.918702032468, 3346.832658367072, 3367.3938691035605, 3417.0773891049525, 1139.4132022008673, 1159.8492196014465, 2222.288867562381, 2242.4169715873827, 2323.3726893738767, 11651.184417090806, 12063.095267854858, 12066.818997715041, 12145.453454594475, 12167.026637868948, 4433.357163211316, 4609.262517997105, 4703.764811759467, 4745.786439610226, 4790.089882922233, 10430.426326552113, 10453.477326133814, 10465.9476702616, 10470.633527826489, 10492.422916739653, 5386.218998245233, 5396.757145928443, 5421.132618780976, 5425.9186112208345, 5434.483724046209, 4365.628921541641, 4899.412070551557, 4962.181240095344, 4987.600991920638, 4991.376077990251, 4161.063760653889, 4179.331306990867, 4228.090960443519, 4245.8513952012145, 4258.190943720683, 1349.0, 1379.951701690436, 1387.2924808347816, 1393.6864205553873, 1394.7350003499303, 999.8800143982623, 1002.173207104142, 1017.5115944346755, 1021.1320377678913, 1046.3669479964512, 5762.567807414461, 5882.3529411764675, 5886.676232807151, 5938.257717785277, 6076.665783381956, 473.7820602522809, 478.92816077587946, 483.4561118741416, 493.80988319497254, 494.38696016938735, 4018.3771515415156, 4107.815148318333, 4151.310375782373, 4166.208420400114, 4227.598378154071, 10726.410047447309, 10928.125749939985, 11048.0, 11131.276467029616, 11148.832767508495, 10964.890351096547, 10999.61501347465, 11009.449527523591, 11023.228374013903, 11080.279781814157, 8333.8333233335, 8405.327573794082, 8425.199606037417, 8425.662973480992, 8452.957735211285, 5909.084091965752, 5914.260717410324, 5951.137085122719, 5963.73163207648, 5995.430434108753, 9986.35088719231, 9993.0, 10220.540075696761, 10297.3821570706, 10366.585336586451, 10250.23123265773, 10338.483075846329, 10397.896021039693, 10405.791884162425, 10406.531706073396, 7078.362947334765, 7377.631118443949, 7490.063742032246, 7698.769036928901, 7784.377249819888, 8200.58997050157, 8272.090070092165, 8330.916690833013, 8337.791555210926, 8339.45793523419, 7522.6614802335125, 7543.358814500829, 7807.687692492236, 7890.0, 8057.5165490070785, 7026.648667566499, 7040.471964602778, 7088.929110709033, 7097.396721278543, 7110.822229444305, 6873.793786186422, 6994.230634630085, 6996.055532503047, 7005.404540614105, 7005.649717514002, 5050.015247026941, 5166.6125040621355, 5190.109808022738, 5241.0, 5306.177542480922, 5145.691258524501, 5267.394249661338, 5318.547923426552, 5326.920096198638, 5459.945400545944], "value": 4650.19834766966, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.3649397897007, 334.59346893524366, 342.4384010223234, 344.06414552417414, 347.0577382406764, 5684.5168160706335, 5781.566382521307, 5825.330087039986, 5829.621074630157, 5841.0, 5738.42615738426, 5742.0, 5746.367899531048, 5746.454086861752, 5747.33905600856, 4249.51130619978, 4377.934330985037, 4445.310976798602, 4485.3944717463055, 4502.572255635726, 4476.060027394251, 4476.164772443299, 4486.910261794762, 4490.271245570446, 4492.550744925497, 4439.822407103712, 4440.111977604476, 4449.265871131276, 4454.688171827974, 4456.108778244348, 4322.4164737760475, 4410.184115938561, 4430.357598148283, 4460.085682435093, 4461.263891457906, 3937.370020796659, 3939.527256729182, 3939.72421930465, 3942.4086387041884, 3956.8021598920227, 5594.692291923942, 5599.944000559983, 5600.355959064719, 5615.466530679579, 5617.971910140464, 5008.223725322544, 5086.160783470723, 5107.310513080761, 5114.846554603368, 5139.306193663845, 5949.185498422979, 5976.611520251213, 5985.970070149665, 5999.0, 5999.280086389661, 4307.633851122659, 4324.243257429937, 4333.848315308949, 4338.069559046226, 4340.175366680321, 5941.811637672439, 6221.657808820513, 6258.186435763367, 6272.0799500312305, 6273.749050037947, 4285.957140428618, 4290.399344091829, 4314.9784251078545, 4317.784110794448, 4334.783260836945, 4260.631604178757, 4344.587264209895, 4364.8472303469225, 4367.868963931023, 4372.256716358227, 4918.729469879119, 4954.702717836942, 4968.453470118284, 4979.377577802775, 4999.275105109738, 5997.730102145414, 6020.57855950088, 6021.759129634853, 6028.457438830438, 6046.727897244635, 1489.5531340597777, 1490.709311684233, 1495.2598463760507, 1514.4547962733407, 1521.7108749337813, 932.8787257656597, 945.6879229854131, 991.8264303746815, 1016.755978565139, 1032.1691038713757, 5803.970980145073, 5834.27071616048, 5859.886621541893, 5863.882722345615, 5912.852178695567, 6043.335233124353, 6076.787312444043, 6081.74975259157, 6149.692515374213, 6160.383961603893, 5696.319585722139, 5699.544036477072, 5759.337676167211, 5784.768609255667, 5826.417358264224, 3240.449123648938, 3245.3509298140466, 3247.610286765556, 3281.982585398534, 3285.8849940251976, 6057.42454466825, 6230.875382492415, 6275.246970363585, 6275.6548389839, 6293.937060629335, 6135.32511423743, 6189.876202475925, 6191.690415479298, 6200.81397558074, 6223.950264370426, 6094.664793436404, 6285.245770507477, 6296.307406185316, 6304.432601065926, 6311.33730958252, 6168.228971378578, 6236.282827474898, 6287.685615719287, 6291.653959032297, 6306.432421082125, 6113.318837319773, 6167.506599471942, 6175.289841668266, 6185.8144255672405, 6218.108579510549, 6269.332440522346, 6421.743130274736, 6446.484281257582, 6487.870242595216, 6492.902606460812, 4378.496472905656, 4415.116976604628, 4419.42547468827, 4426.44669416323, 4447.911041779211, 6091.842549915502, 6241.21984751906, 6316.873662526816, 6323.272823625342, 6329.0, 6128.540359472968, 6131.325554188947, 6140.662263575547, 6148.815535533941, 6148.877022459616, 6097.573169878244, 6108.450239478469, 6112.419320164578, 6130.356312587114, 6136.907946380898, 5886.970565147147, 5950.553708471968, 6035.879282414239, 6064.939350606437, 6100.511959043355, 6158.876822463436, 6185.6907154641185, 6192.225971753531, 6216.7824126155365, 6240.3790408029645, 5899.0, 5952.868954898556, 5972.805438912322, 6035.788747393995, 6044.607100538451], "value": 4578.527656155391, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [822.2106777493602, 825.5872063968015, 861.1087524412235, 865.4634126841358, 872.7599910024743, 16755.32978680853, 18068.638627227465, 18166.545836354097, 18202.5449363766, 18250.908745456272, 16482.42311519097, 16646.583835404123, 16649.33402663892, 16680.999140051605, 16710.498685039438, 859.9484030958131, 860.8536548786722, 897.6005677473527, 902.6163880350857, 908.8000639859221, 885.6767279942807, 894.1058941058941, 894.217559635319, 894.9284057275435, 895.1004240738049, 928.058021108572, 929.739672891588, 932.7854593443512, 933.7898972731149, 934.668192791556, 928.1182876267573, 928.2713070239897, 929.2380248196481, 929.5445231836419, 930.730088274403, 678.762433148399, 678.8913773796169, 680.8876535371658, 683.5386114372828, 684.7740245718951, 17055.317787288495, 17145.65708685832, 17167.91416042924, 17225.31098756061, 17287.48137555863, 15924.460126465356, 16171.191440428049, 16381.590460238589, 16704.58238544034, 17295.481135565955, 13577.185368877801, 13621.046526743132, 13659.521916732967, 13838.861611383858, 13896.513622023282, 4133.090720041586, 4144.295469770116, 4181.623653871166, 4250.192463431938, 4355.7822108894425, 93258.0, 96184.03815961821, 96663.51668241614, 97521.5123924383, 99750.50124749403, 12115.697107572205, 12156.149069565046, 12325.630231093082, 12336.0, 12496.81254781179, 22740.43148921257, 22824.885875570515, 22883.656745148837, 22906.198283060345, 22914.770852291596, 8968.551572421484, 9146.314026448043, 9213.30900182489, 9346.532673366304, 9713.757156071013, 5304.787808487695, 5382.138857782736, 5486.177073439016, 5553.083741182741, 5679.943200568024, 3626.3110009098186, 3720.4977328060363, 3758.9060273492832, 3764.6235376462682, 3792.810359482015, 609.0924522461501, 614.7510258345377, 627.2379059442799, 627.7677259414046, 631.3907079668143, 658.4370363339372, 694.9583025018516, 754.3701009656997, 764.1746913333586, 769.8806684963839, 10840.457977101114, 11174.944125279322, 11226.775464490664, 11289.717757055974, 11362.602308919148, 739.3863093632273, 747.8317378589774, 778.6723636200265, 791.9722809701633, 817.2032268538246, 5097.7051858714585, 5323.866903327448, 5422.132458806572, 5436.10304299794, 5480.369757477941, 72425.27574724291, 83814.0, 131537.0, 138556.30721846328, 139269.0, 119242.40378798224, 120779.0, 121282.3935880315, 124021.0, 127065.36467317604, 101505.0, 103236.0, 104567.0, 105780.47109764403, 108257.0, 10277.84583231267, 10400.219983501116, 10412.895871041495, 10424.176043014853, 10440.0, 59451.0, 61448.0, 62491.812786552255, 62818.68590657017, 63598.36401635924, 76909.0, 79567.0, 81926.0, 82864.58567707123, 94135.0, 14674.413023479368, 14795.852041479448, 14812.0, 14829.555113346616, 14974.925125374304, 15852.76220856665, 15982.0, 16620.3351865924, 16627.3349066036, 16945.23746431438, 15230.847691522942, 15705.371785128924, 15923.283452244445, 16035.358585656442, 16598.336066557684, 15096.924515377354, 15507.612309692347, 15593.996311419958, 15971.760423593887, 16623.3350665972, 14553.345099470762, 14719.632009199855, 14903.32935017905, 15261.084334939938, 15535.76696349533, 12900.870991289967, 12951.5466958656, 12986.480540778262, 13319.66700832487, 13499.595012149652, 11895.93674390768, 13206.207627542379, 13380.866191338351, 13479.595612131652, 13680.0], "value": 8473.959024926024, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1497.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 103.0, 111.0, 112.0, 112.0, 97.0, 111.0, 112.0, 110.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 115.0, 138.0, 170.0, 117.0, 147.0, 146.0, 121.0, 188.0, 116.0], "value": 140.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 164.0, 161.0, 162.0, 162.0, 161.0, 160.0, 160.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 76.0, 171.0, 76.0, 75.0, 74.0, 74.0, 169.0, 76.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 145.0, 82.0, 83.0, 84.0, 134.0, 85.0, 84.0, 144.0], "value": 101.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 118.0, 118.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 146.0, 147.0, 149.0, 147.0, 147.0, 147.0, 147.0, 146.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 101.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 58.0, 57.0, 51.0, 54.0, 52.0, 51.0, 53.0, 58.0, 52.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 63.0, 60.0, 59.0, 58.0, 59.0, 61.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 71.0, 76.0, 73.0, 73.0, 77.0, 71.0, 72.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 122.0, 118.0, 129.0, 116.0, 125.0, 127.0, 117.0, 164.0, 118.0], "value": 126.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 151.0, 150.0, 160.0, 156.0, 154.0, 153.0, 153.0, 153.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 61.0, 62.0, 64.0, 68.0, 66.0, 60.0, 61.0, 60.0], "value": 62.8, "unit": "score"}]}]} 12:18:01 INFO - Return code: 0 12:18:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:18:01 ERROR - # TBPL SUCCESS # 12:18:01 INFO - Running post-action listener: _resource_record_post_action 12:18:01 INFO - [mozharness: 2016-09-09 19:18:01.165651Z] Finished run-tests step (success) 12:18:01 INFO - Running post-run listener: _resource_record_post_run 12:18:01 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 765952; Write bytes: 328077312; Read time: 88; Write time: 288432 12:18:01 INFO - TinderboxPrint: CPU usage
13.2% 12:18:01 INFO - TinderboxPrint: I/O read bytes / time
765,952 / 88 12:18:01 INFO - TinderboxPrint: I/O write bytes / time
328,077,312 / 288,432 12:18:01 INFO - TinderboxPrint: CPU idle
8,227.0 (86.8%) 12:18:01 INFO - TinderboxPrint: CPU user
1,195.3 (12.6%) 12:18:01 INFO - TinderboxPrint: Swap in / out
0 / 0 12:18:01 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78524416; Read time: 0; Write time: 117220 12:18:01 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 761856; Write bytes: 248594432; Read time: 80; Write time: 171180 12:18:01 INFO - Running post-run listener: _upload_blobber_files 12:18:01 INFO - Blob upload gear active. 12:18:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:18:01 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 12:18:01 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'] 12:18:01 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 12:18:02 INFO - (blobuploader) - INFO - Open directory for files ... 12:18:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:18:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:18:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:03 INFO - (blobuploader) - INFO - Done attempting. 12:18:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:18:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:18:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:08 INFO - (blobuploader) - INFO - Done attempting. 12:18:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:18:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:18:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:08 INFO - (blobuploader) - INFO - Done attempting. 12:18:08 INFO - (blobuploader) - INFO - Iteration through files over. 12:18:08 INFO - Return code: 0 12:18:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:18:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:18:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b7d2d576653f5a9f015db006c5cea7299e00e41603c1ed4218ce9130343ca55f2fd0c99890e80e21444b69f12bc15e09bd27716246c837d8f8eb68e73f11d47d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/634dc2f8baba3a963aa097d1f27c3202cacb4a0e1091f4270a0918a6a16948e9788556dc833c69422b3f5d8c291ade2f445072459d7a6a45205559ca81898172", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1cf935b68874170c09beb6996de586a5a478df72e23ddf23f99ea2360542acf0d64f866b22ffa90848a9e8748b9e92e81ccf1f9679f565b7147fe716c7a344a6"} 12:18:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:18:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:18:08 INFO - Contents: 12:18:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b7d2d576653f5a9f015db006c5cea7299e00e41603c1ed4218ce9130343ca55f2fd0c99890e80e21444b69f12bc15e09bd27716246c837d8f8eb68e73f11d47d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/634dc2f8baba3a963aa097d1f27c3202cacb4a0e1091f4270a0918a6a16948e9788556dc833c69422b3f5d8c291ade2f445072459d7a6a45205559ca81898172", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1cf935b68874170c09beb6996de586a5a478df72e23ddf23f99ea2360542acf0d64f866b22ffa90848a9e8748b9e92e81ccf1f9679f565b7147fe716c7a344a6"} 12:18:08 INFO - Running post-run listener: copy_logs_to_upload_dir 12:18:08 INFO - Copying logs to upload dir... 12:18:08 INFO - mkdir: /builds/slave/test/build/upload/logs 12:18:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.423081 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-09-09 12:18:08.887946) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 12:18:08.891143) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071 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/b7d2d576653f5a9f015db006c5cea7299e00e41603c1ed4218ce9130343ca55f2fd0c99890e80e21444b69f12bc15e09bd27716246c837d8f8eb68e73f11d47d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/634dc2f8baba3a963aa097d1f27c3202cacb4a0e1091f4270a0918a6a16948e9788556dc833c69422b3f5d8c291ade2f445072459d7a6a45205559ca81898172", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1cf935b68874170c09beb6996de586a5a478df72e23ddf23f99ea2360542acf0d64f866b22ffa90848a9e8748b9e92e81ccf1f9679f565b7147fe716c7a344a6"} build_url:https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011303 build_url: 'https://queue.taskcluster.net/v1/task/BfVZnZOdRlqEVtQRjZnRVA/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/b7d2d576653f5a9f015db006c5cea7299e00e41603c1ed4218ce9130343ca55f2fd0c99890e80e21444b69f12bc15e09bd27716246c837d8f8eb68e73f11d47d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/634dc2f8baba3a963aa097d1f27c3202cacb4a0e1091f4270a0918a6a16948e9788556dc833c69422b3f5d8c291ade2f445072459d7a6a45205559ca81898172", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1cf935b68874170c09beb6996de586a5a478df72e23ddf23f99ea2360542acf0d64f866b22ffa90848a9e8748b9e92e81ccf1f9679f565b7147fe716c7a344a6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 12:18:08.916755) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 12:18:08.917103) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1473445775.188922-768828071 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004406 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 12:18:08.998958) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 12:18:08.999322) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 12:18:08.999625) ========= ========= Total master_lag: 0.18 =========