builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-024 starttime: 1475038895.54 results: success (0) buildid: 20160927191919 builduid: 867d561c309c43e1959684e19fe93542 revision: 5eb0a600c3917cab96f6e31f3dcc66d99dccddc0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:35.536408) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:35.536821) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:35.537095) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:35.573791) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:35.574054) ========= 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=41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-27 22:01:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 21.0M=0.001s 2016-09-27 22:01:36 (21.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119239 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:35.709790) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:35.710104) ========= 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=41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016878 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:35.759140) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:35.759478) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5eb0a600c3917cab96f6e31f3dcc66d99dccddc0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5eb0a600c3917cab96f6e31f3dcc66d99dccddc0 --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=41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-27 22:01:36,178 truncating revision to first 12 chars 2016-09-27 22:01:36,178 Setting DEBUG logging. 2016-09-27 22:01:36,178 attempt 1/10 2016-09-27 22:01:36,178 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/5eb0a600c391?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-27 22:01:36,588 unpacking tar archive at: mozilla-beta-5eb0a600c391/testing/mozharness/ program finished with exit code 0 elapsedTime=0.775575 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:36.551080) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:36.551378) ========= script_repo_revision: 5eb0a600c3917cab96f6e31f3dcc66d99dccddc0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:36.551761) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:36.552253) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 22:01:36.563323) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-09-27 22:01:36.564022) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:01:37 INFO - MultiFileLogger online at 20160927 22:01:37 in /builds/slave/test 22:01:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 22:01:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:01:37 INFO - {'append_to_log': False, 22:01:37 INFO - 'base_work_dir': '/builds/slave/test', 22:01:37 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:01:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:01:37 INFO - 'branch': 'Mozilla-Beta', 22:01:37 INFO - 'buildbot_json_path': 'buildprops.json', 22:01:37 INFO - 'config_files': ('talos/linux_config.py',), 22:01:37 INFO - 'default_actions': ('clobber', 22:01:37 INFO - 'read-buildbot-config', 22:01:37 INFO - 'download-and-extract', 22:01:37 INFO - 'populate-webroot', 22:01:37 INFO - 'create-virtualenv', 22:01:37 INFO - 'install', 22:01:37 INFO - 'run-tests'), 22:01:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:01:37 INFO - 'download_minidump_stackwalk': True, 22:01:37 INFO - 'download_symbols': 'ondemand', 22:01:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:01:37 INFO - 'tooltool.py': '/tools/tooltool.py', 22:01:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:01:37 INFO - '/tools/misc-python/virtualenv.py')}, 22:01:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:01:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:01:37 INFO - 'installer_path': 'installer.exe', 22:01:37 INFO - 'log_level': 'info', 22:01:37 INFO - 'log_name': 'talos', 22:01:37 INFO - 'log_to_console': True, 22:01:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:01:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:01:37 INFO - 'opt_config_files': (), 22:01:37 INFO - 'pip_index': False, 22:01:37 INFO - 'sps_profile': False, 22:01:37 INFO - 'sps_profile_interval': 0, 22:01:37 INFO - 'suite': 'dromaeojs-e10s', 22:01:37 INFO - 'system_bits': '32', 22:01:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:01:37 INFO - 'title': 'talos-linux64-ix-024', 22:01:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:01:37 INFO - 'use_talos_json': True, 22:01:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:01:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:01:37 INFO - 'work_dir': 'build'} 22:01:37 INFO - [mozharness: 2016-09-28 05:01:37.070405Z] Running clobber step. 22:01:37 INFO - Running pre-action listener: _resource_record_pre_action 22:01:37 INFO - Running main action method: clobber 22:01:37 INFO - rmtree: /builds/slave/test/build 22:01:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:01:38 INFO - Running post-action listener: _resource_record_post_action 22:01:38 INFO - [mozharness: 2016-09-28 05:01:38.058709Z] Finished clobber step (success) 22:01:38 INFO - [mozharness: 2016-09-28 05:01:38.058811Z] Running read-buildbot-config step. 22:01:38 INFO - Running pre-action listener: _resource_record_pre_action 22:01:38 INFO - Running main action method: read_buildbot_config 22:01:38 INFO - Using buildbot properties: 22:01:38 INFO - { 22:01:38 INFO - "project": "", 22:01:38 INFO - "product": "firefox", 22:01:38 INFO - "script_repo_revision": "production", 22:01:38 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 22:01:38 INFO - "repository": "", 22:01:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 22:01:38 INFO - "buildid": "20160927191919", 22:01:38 INFO - "pgo_build": "True", 22:01:38 INFO - "basedir": "/builds/slave/test", 22:01:38 INFO - "buildnumber": 263, 22:01:38 INFO - "slavename": "talos-linux64-ix-024", 22:01:38 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:01:38 INFO - "platform": "ubuntu64_hw", 22:01:38 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 22:01:38 INFO - "branch": "mozilla-beta", 22:01:38 INFO - "repo_path": "releases/mozilla-beta", 22:01:38 INFO - "revision": "5eb0a600c3917cab96f6e31f3dcc66d99dccddc0", 22:01:38 INFO - "stage_platform": "linux64-pgo", 22:01:38 INFO - "builduid": "867d561c309c43e1959684e19fe93542", 22:01:38 INFO - "slavebuilddir": "test" 22:01:38 INFO - } 22:01:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 22:01:38 INFO - Running post-action listener: _resource_record_post_action 22:01:38 INFO - [mozharness: 2016-09-28 05:01:38.061248Z] Finished read-buildbot-config step (success) 22:01:38 INFO - [mozharness: 2016-09-28 05:01:38.061337Z] Running download-and-extract step. 22:01:38 INFO - Running pre-action listener: _resource_record_pre_action 22:01:38 INFO - Running main action method: download_and_extract 22:01:38 INFO - mkdir: /builds/slave/test/build/tests 22:01:38 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')]} 22:01:38 INFO - https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:01:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:01:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:01:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:01:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:01:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:01:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:01:38 INFO - retry: Failed, sleeping 30 seconds before retrying 22:02:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 22:02:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:02:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:02:08 INFO - retry: Failed, sleeping 60 seconds before retrying 22:03:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 22:03:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:03:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:03:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 22:03:08 INFO - Caught exception: HTTP Error 404: Not Found 22:03:08 INFO - Caught exception: HTTP Error 404: Not Found 22:03:08 INFO - Caught exception: HTTP Error 404: Not Found 22:03:08 INFO - trying https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:03:08 INFO - Downloading https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:03:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:03:08 INFO - Downloaded 1327 bytes. 22:03:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 22:03:08 INFO - Using the following test package requirements: 22:03:08 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 22:03:08 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:03:08 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 22:03:08 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:03:08 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 22:03:08 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:03:08 INFO - u'jsshell-linux-x86_64.zip'], 22:03:08 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:03:08 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 22:03:08 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 22:03:08 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:03:08 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 22:03:08 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:03:08 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 22:03:08 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:03:08 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 22:03:08 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 22:03:08 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 22:03:08 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:03: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')]} 22:03:08 INFO - https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:03:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:03:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:03:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:03:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:03:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:03:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:03:08 INFO - retry: Failed, sleeping 30 seconds before retrying 22:03:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 22:03:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:03:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:03:38 INFO - retry: Failed, sleeping 60 seconds before retrying 22:04:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 22:04:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:04:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:04:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 22:04:38 INFO - Caught exception: HTTP Error 404: Not Found 22:04:38 INFO - Caught exception: HTTP Error 404: Not Found 22:04:38 INFO - Caught exception: HTTP Error 404: Not Found 22:04:38 INFO - trying https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:04:38 INFO - Downloading https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:04:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:04:44 INFO - Downloaded 21771943 bytes. 22:04:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:04:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:04:45 INFO - Return code: 0 22:04:45 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:04:45 INFO - https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:04:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:04:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:04:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:04:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:04:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:04:45 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:04:45 INFO - retry: Failed, sleeping 30 seconds before retrying 22:05:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 22:05:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:05:15 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:05:15 INFO - retry: Failed, sleeping 60 seconds before retrying 22:06:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 22:06:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:06:15 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:06:15 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 22:06:15 INFO - Caught exception: HTTP Error 404: Not Found 22:06:15 INFO - Caught exception: HTTP Error 404: Not Found 22:06:15 INFO - Caught exception: HTTP Error 404: Not Found 22:06:15 INFO - trying https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:06:15 INFO - Downloading https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 22:06:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:06:16 INFO - Downloaded 21771943 bytes. 22:06:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:06:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:06:17 INFO - Return code: 0 22:06:17 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')]} 22:06:17 INFO - https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:06:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:06:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:06:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:06:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:06:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:06:17 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:06:17 INFO - retry: Failed, sleeping 30 seconds before retrying 22:06:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 22:06:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:06:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:06:47 INFO - retry: Failed, sleeping 60 seconds before retrying 22:07:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 22:07:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:07:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:07:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 22:07:47 INFO - Caught exception: HTTP Error 404: Not Found 22:07:47 INFO - Caught exception: HTTP Error 404: Not Found 22:07:47 INFO - Caught exception: HTTP Error 404: Not Found 22:07:47 INFO - trying https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:07:47 INFO - Downloading https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 22:07:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:07:49 INFO - Downloaded 14413740 bytes. 22:07:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:07:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:07:49 INFO - Return code: 0 22:07:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:07:49 INFO - https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:07:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:07:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:07:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:07:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:07:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:07:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 22:07:49 INFO - retry: Failed, sleeping 30 seconds before retrying 22:08:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 22:08:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:08:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 22:08:19 INFO - retry: Failed, sleeping 60 seconds before retrying 22:09:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 22:09:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:09:19 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 22:09:19 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 22:09:19 INFO - Caught exception: HTTP Error 404: Not Found 22:09:19 INFO - Caught exception: HTTP Error 404: Not Found 22:09:19 INFO - Caught exception: HTTP Error 404: Not Found 22:09:19 INFO - trying https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:09:19 INFO - Downloading https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:09:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:09:20 INFO - Downloaded 56786359 bytes. 22:09:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:09:20 INFO - mkdir: /builds/slave/test/properties 22:09:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:09:20 INFO - Writing to file /builds/slave/test/properties/build_url 22:09:20 INFO - Contents: 22:09:20 INFO - build_url:https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 22:09:21 INFO - Running post-action listener: _resource_record_post_action 22:09:21 INFO - Running post-action listener: set_extra_try_arguments 22:09:21 INFO - [mozharness: 2016-09-28 05:09:21.609997Z] Finished download-and-extract step (success) 22:09:21 INFO - [mozharness: 2016-09-28 05:09:21.610230Z] Running populate-webroot step. 22:09:21 INFO - Running pre-action listener: _resource_record_pre_action 22:09:21 INFO - Running main action method: populate_webroot 22:09:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:09:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:09:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:09:21 INFO - '--tppagecycles', 22:09:21 INFO - '7'], 22:09:21 INFO - 'tests': ['tsvgm']}}, 22:09:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:09:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:09:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:09:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:09:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:09:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:09:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:21 INFO - 'talos_options': ['--disable-e10s'], 22:09:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:09:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:21 INFO - 'talos_options': ['--disable-e10s'], 22:09:21 INFO - 'tests': ['damp', 'tps']}, 22:09:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:21 INFO - 'tests': ['damp', 'tps']}, 22:09:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:09:21 INFO - 'tests': ['dromaeo_dom']}, 22:09:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:09:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:09:21 INFO - 'tests': ['basic_compositor_video']}, 22:09:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:09:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:09:21 INFO - 'tests': ['a11yr', 22:09:21 INFO - 'ts_paint', 22:09:21 INFO - 'tpaint', 22:09:21 INFO - 'sessionrestore', 22:09:21 INFO - 'sessionrestore_no_auto_restore', 22:09:21 INFO - 'tabpaint']}, 22:09:21 INFO - 'other-e10s': {'tests': ['a11yr', 22:09:21 INFO - 'ts_paint', 22:09:21 INFO - 'tpaint', 22:09:21 INFO - 'sessionrestore', 22:09:21 INFO - 'sessionrestore_no_auto_restore', 22:09:21 INFO - 'tabpaint']}, 22:09:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:09:21 INFO - 'ts_paint', 22:09:21 INFO - 'tpaint', 22:09:21 INFO - 'sessionrestore', 22:09:21 INFO - 'sessionrestore_no_auto_restore', 22:09:21 INFO - 'tabpaint']}, 22:09:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:09:21 INFO - 'ts_paint', 22:09:21 INFO - 'tpaint', 22:09:21 INFO - 'sessionrestore', 22:09:21 INFO - 'sessionrestore_no_auto_restore', 22:09:21 INFO - 'tabpaint']}, 22:09:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:09:21 INFO - 'tests': ['a11yr', 22:09:21 INFO - 'ts_paint', 22:09:21 INFO - 'tpaint', 22:09:21 INFO - 'sessionrestore', 22:09:21 INFO - 'sessionrestore_no_auto_restore', 22:09:21 INFO - 'tabpaint']}, 22:09:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:09:21 INFO - 'tests': ['a11yr', 22:09:21 INFO - 'ts_paint', 22:09:21 INFO - 'tpaint', 22:09:21 INFO - 'sessionrestore', 22:09:21 INFO - 'sessionrestore_no_auto_restore', 22:09:21 INFO - 'tabpaint']}, 22:09:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:09:21 INFO - 'tests': ['tsvgx', 22:09:21 INFO - 'tsvgr_opacity', 22:09:21 INFO - 'tart', 22:09:21 INFO - 'tscrollx', 22:09:21 INFO - 'cart']}, 22:09:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:09:21 INFO - 'tsvgr_opacity', 22:09:21 INFO - 'tart', 22:09:21 INFO - 'tscrollx', 22:09:21 INFO - 'cart']}, 22:09:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:21 INFO - 'talos_options': ['--disable-e10s'], 22:09:21 INFO - 'tests': ['tp5o']}, 22:09:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:21 INFO - 'tests': ['tp5o']}, 22:09:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:21 INFO - 'talos_options': ['--disable-e10s', 22:09:21 INFO - '--xperf_path', 22:09:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:21 INFO - 'tests': ['tp5n']}, 22:09:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:09:21 INFO - 'talos_options': ['--xperf_path', 22:09:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:09:21 INFO - 'tests': ['tp5n']}}, 22:09:21 INFO - 'talos.zip': {'path': '', 22:09:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:09:21 INFO - Running post-action listener: _resource_record_post_action 22:09:21 INFO - [mozharness: 2016-09-28 05:09:21.625461Z] Finished populate-webroot step (success) 22:09:21 INFO - [mozharness: 2016-09-28 05:09:21.625550Z] Running create-virtualenv step. 22:09:21 INFO - Running pre-action listener: _resource_record_pre_action 22:09:21 INFO - Running main action method: create_virtualenv 22:09:21 INFO - Creating virtualenv /builds/slave/test/build/venv 22:09:21 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 22:09:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:09:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:09:21 INFO - Using real prefix '/usr' 22:09:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:09:22 INFO - Installing distribute.............................................................................................................................................................................................done. 22:09:24 INFO - Installing pip.................done. 22:09:24 INFO - Return code: 0 22:09:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:09:24 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')]} 22:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:24 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')]} 22:09:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:24 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2e570>, '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 0xf2f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf2f900>, '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': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', '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 22:09:24 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 22:09:24 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 22:09:24 INFO - Using env: {'DISPLAY': ':0', 22:09:24 INFO - 'HOME': '/home/cltbld', 22:09:24 INFO - 'LANG': 'en_US.UTF-8', 22:09:24 INFO - 'LANGUAGE': 'en_US:en', 22:09:24 INFO - 'LOGNAME': 'cltbld', 22:09:24 INFO - 'MAIL': '/var/mail/cltbld', 22:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:24 INFO - 'MOZ_NO_REMOTE': '1', 22:09:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:24 INFO - 'NO_EM_RESTART': '1', 22:09:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:24 INFO - 'PWD': '/builds/slave/test', 22:09:24 INFO - 'SHELL': '/bin/bash', 22:09:24 INFO - 'SHLVL': '1', 22:09:24 INFO - 'TERM': 'linux', 22:09:24 INFO - 'TMOUT': '86400', 22:09:24 INFO - 'USER': 'cltbld', 22:09:24 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', 22:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:24 INFO - '_': '/tools/buildbot/bin/python'} 22:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:24 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 22:09:24 INFO - Cleaning up... 22:09:24 INFO - Return code: 0 22:09:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:09:24 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')]} 22:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:24 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')]} 22:09:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:24 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2e570>, '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 0xf2f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf2f900>, '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': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', '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 22:09:24 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 22:09:24 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 22:09:24 INFO - Using env: {'DISPLAY': ':0', 22:09:24 INFO - 'HOME': '/home/cltbld', 22:09:24 INFO - 'LANG': 'en_US.UTF-8', 22:09:24 INFO - 'LANGUAGE': 'en_US:en', 22:09:24 INFO - 'LOGNAME': 'cltbld', 22:09:24 INFO - 'MAIL': '/var/mail/cltbld', 22:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:24 INFO - 'MOZ_NO_REMOTE': '1', 22:09:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:24 INFO - 'NO_EM_RESTART': '1', 22:09:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:24 INFO - 'PWD': '/builds/slave/test', 22:09:24 INFO - 'SHELL': '/bin/bash', 22:09:24 INFO - 'SHLVL': '1', 22:09:24 INFO - 'TERM': 'linux', 22:09:24 INFO - 'TMOUT': '86400', 22:09:24 INFO - 'USER': 'cltbld', 22:09:24 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', 22:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:24 INFO - '_': '/tools/buildbot/bin/python'} 22:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:24 INFO - Downloading/unpacking psutil>=3.1.1 22:09:24 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 22:09:24 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 22:09:24 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 22:09:24 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 22:09:24 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 22:09:24 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 22:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:09:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:26 INFO - Installing collected packages: psutil 22:09:26 INFO - Running setup.py install for psutil 22:09:26 INFO - building 'psutil._psutil_linux' extension 22:09:26 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 22:09:26 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 22:09:26 INFO - building 'psutil._psutil_posix' extension 22:09:26 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 22:09:27 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 22:09:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:09:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:09:27 INFO - Successfully installed psutil 22:09:27 INFO - Cleaning up... 22:09:27 INFO - Return code: 0 22:09:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:09:27 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')]} 22:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:27 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')]} 22:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:27 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2e570>, '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 0xf2f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf2f900>, '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': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', '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 22:09:27 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 22:09:27 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 22:09:27 INFO - Using env: {'DISPLAY': ':0', 22:09:27 INFO - 'HOME': '/home/cltbld', 22:09:27 INFO - 'LANG': 'en_US.UTF-8', 22:09:27 INFO - 'LANGUAGE': 'en_US:en', 22:09:27 INFO - 'LOGNAME': 'cltbld', 22:09:27 INFO - 'MAIL': '/var/mail/cltbld', 22:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:27 INFO - 'MOZ_NO_REMOTE': '1', 22:09:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:27 INFO - 'NO_EM_RESTART': '1', 22:09:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:27 INFO - 'PWD': '/builds/slave/test', 22:09:27 INFO - 'SHELL': '/bin/bash', 22:09:27 INFO - 'SHLVL': '1', 22:09:27 INFO - 'TERM': 'linux', 22:09:27 INFO - 'TMOUT': '86400', 22:09:27 INFO - 'USER': 'cltbld', 22:09:27 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', 22:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:27 INFO - '_': '/tools/buildbot/bin/python'} 22:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:09:27 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 22:09:27 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 22:09:27 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 22:09:27 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 22:09:27 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 22:09:27 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 22:09:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:09:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:09:29 INFO - Installing collected packages: mozsystemmonitor 22:09:29 INFO - Running setup.py install for mozsystemmonitor 22:09:29 INFO - Successfully installed mozsystemmonitor 22:09:29 INFO - Cleaning up... 22:09:29 INFO - Return code: 0 22:09:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:09: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')]} 22:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09: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')]} 22:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:29 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2e570>, '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 0xf2f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf2f900>, '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': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', '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 22:09:29 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 22:09:29 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 22:09:29 INFO - Using env: {'DISPLAY': ':0', 22:09:29 INFO - 'HOME': '/home/cltbld', 22:09:29 INFO - 'LANG': 'en_US.UTF-8', 22:09:29 INFO - 'LANGUAGE': 'en_US:en', 22:09:29 INFO - 'LOGNAME': 'cltbld', 22:09:29 INFO - 'MAIL': '/var/mail/cltbld', 22:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:29 INFO - 'MOZ_NO_REMOTE': '1', 22:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:29 INFO - 'NO_EM_RESTART': '1', 22:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:29 INFO - 'PWD': '/builds/slave/test', 22:09:29 INFO - 'SHELL': '/bin/bash', 22:09:29 INFO - 'SHLVL': '1', 22:09:29 INFO - 'TERM': 'linux', 22:09:29 INFO - 'TMOUT': '86400', 22:09:29 INFO - 'USER': 'cltbld', 22:09:29 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', 22:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:29 INFO - '_': '/tools/buildbot/bin/python'} 22:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:29 INFO - Downloading/unpacking jsonschema==2.5.1 22:09:29 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 22:09:29 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 22:09:29 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 22:09:29 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 22:09:29 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 22:09:29 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 22:09:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:09:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:09:31 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 22:09:31 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 22:09:31 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 22:09:31 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 22:09:31 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 22:09:31 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 22:09:31 INFO - Downloading functools32-3.2.3-2.tar.gz 22:09:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:09:31 INFO - warning: no files found matching '*.txt' 22:09:31 INFO - no previously-included directories found matching 'build' 22:09:31 INFO - no previously-included directories found matching 'dist' 22:09:31 INFO - no previously-included directories found matching '.git*' 22:09:31 INFO - Installing collected packages: jsonschema, functools32 22:09:31 INFO - Running setup.py install for functools32 22:09:31 INFO - warning: no files found matching '*.txt' 22:09:31 INFO - no previously-included directories found matching 'build' 22:09:31 INFO - no previously-included directories found matching 'dist' 22:09:31 INFO - no previously-included directories found matching '.git*' 22:09:32 INFO - Successfully installed jsonschema functools32 22:09:32 INFO - Cleaning up... 22:09:32 INFO - Return code: 0 22:09:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:32 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2e570>, '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 0xf2f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf2f900>, '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': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', '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 22:09:32 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 22:09:32 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 22:09:32 INFO - Using env: {'DISPLAY': ':0', 22:09:32 INFO - 'HOME': '/home/cltbld', 22:09:32 INFO - 'LANG': 'en_US.UTF-8', 22:09:32 INFO - 'LANGUAGE': 'en_US:en', 22:09:32 INFO - 'LOGNAME': 'cltbld', 22:09:32 INFO - 'MAIL': '/var/mail/cltbld', 22:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:32 INFO - 'MOZ_NO_REMOTE': '1', 22:09:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:32 INFO - 'NO_EM_RESTART': '1', 22:09:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:32 INFO - 'PWD': '/builds/slave/test', 22:09:32 INFO - 'SHELL': '/bin/bash', 22:09:32 INFO - 'SHLVL': '1', 22:09:32 INFO - 'TERM': 'linux', 22:09:32 INFO - 'TMOUT': '86400', 22:09:32 INFO - 'USER': 'cltbld', 22:09:32 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', 22:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:32 INFO - '_': '/tools/buildbot/bin/python'} 22:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:09:32 INFO - Cleaning up... 22:09:32 INFO - Return code: 0 22:09:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:32 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2e570>, '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 0xf2f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf2f900>, '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': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', '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 22:09:32 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 22:09:32 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 22:09:32 INFO - Using env: {'DISPLAY': ':0', 22:09:32 INFO - 'HOME': '/home/cltbld', 22:09:32 INFO - 'LANG': 'en_US.UTF-8', 22:09:32 INFO - 'LANGUAGE': 'en_US:en', 22:09:32 INFO - 'LOGNAME': 'cltbld', 22:09:32 INFO - 'MAIL': '/var/mail/cltbld', 22:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:32 INFO - 'MOZ_NO_REMOTE': '1', 22:09:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:32 INFO - 'NO_EM_RESTART': '1', 22:09:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:32 INFO - 'PWD': '/builds/slave/test', 22:09:32 INFO - 'SHELL': '/bin/bash', 22:09:32 INFO - 'SHLVL': '1', 22:09:32 INFO - 'TERM': 'linux', 22:09:32 INFO - 'TMOUT': '86400', 22:09:32 INFO - 'USER': 'cltbld', 22:09:32 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', 22:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:32 INFO - '_': '/tools/buildbot/bin/python'} 22:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:32 INFO - Downloading/unpacking blobuploader==1.2.4 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 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 22:09:32 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 22:09:34 INFO - Downloading blobuploader-1.2.4.tar.gz 22:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:09:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:09:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:09:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:09:35 INFO - Downloading docopt-0.6.1.tar.gz 22:09:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:09:35 INFO - Installing collected packages: blobuploader, requests, docopt 22:09:35 INFO - Running setup.py install for blobuploader 22:09:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:09:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:09:35 INFO - Running setup.py install for requests 22:09:35 INFO - Running setup.py install for docopt 22:09:35 INFO - Successfully installed blobuploader requests docopt 22:09:35 INFO - Cleaning up... 22:09:35 INFO - Return code: 0 22:09:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:35 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2e570>, '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 0xf2f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf2f900>, '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': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', '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 22:09:35 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 22:09:35 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 22:09:35 INFO - Using env: {'DISPLAY': ':0', 22:09:35 INFO - 'HOME': '/home/cltbld', 22:09:35 INFO - 'LANG': 'en_US.UTF-8', 22:09:35 INFO - 'LANGUAGE': 'en_US:en', 22:09:35 INFO - 'LOGNAME': 'cltbld', 22:09:35 INFO - 'MAIL': '/var/mail/cltbld', 22:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:35 INFO - 'MOZ_NO_REMOTE': '1', 22:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:35 INFO - 'NO_EM_RESTART': '1', 22:09:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:35 INFO - 'PWD': '/builds/slave/test', 22:09:35 INFO - 'SHELL': '/bin/bash', 22:09:35 INFO - 'SHLVL': '1', 22:09:35 INFO - 'TERM': 'linux', 22:09:35 INFO - 'TMOUT': '86400', 22:09:35 INFO - 'USER': 'cltbld', 22:09:35 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', 22:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:35 INFO - '_': '/tools/buildbot/bin/python'} 22:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:35 INFO - Running setup.py (path:/tmp/pip-6G5GL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:09:36 INFO - Running setup.py (path:/tmp/pip-PbThMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:09:36 INFO - Running setup.py (path:/tmp/pip-Lj9zsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:09:36 INFO - Running setup.py (path:/tmp/pip-VLJqXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:09:36 INFO - Running setup.py (path:/tmp/pip-HhIQNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:09:36 INFO - Running setup.py (path:/tmp/pip-x4UYw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:09:36 INFO - Running setup.py (path:/tmp/pip-G8Lcnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:09:36 INFO - Running setup.py (path:/tmp/pip-pyZ7xM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:09:36 INFO - Running setup.py (path:/tmp/pip-UwD9pC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:09:36 INFO - Running setup.py (path:/tmp/pip-3TkOux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:09:36 INFO - Running setup.py (path:/tmp/pip-aWYkMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:09:36 INFO - Running setup.py (path:/tmp/pip-O1TVrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:09:36 INFO - Running setup.py (path:/tmp/pip-jcglRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:09:36 INFO - Running setup.py (path:/tmp/pip-IIiEe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:36 INFO - Running setup.py (path:/tmp/pip-2iWJ9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:09:37 INFO - Running setup.py (path:/tmp/pip-2xfmox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:09:37 INFO - Running setup.py (path:/tmp/pip-b_YFYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:09:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:09:37 INFO - Running setup.py install for manifestparser 22:09:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:09:37 INFO - Running setup.py install for mozcrash 22:09:37 INFO - Running setup.py install for mozdebug 22:09:37 INFO - Running setup.py install for mozdevice 22:09:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:09:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:09:37 INFO - Running setup.py install for mozfile 22:09:37 INFO - Running setup.py install for mozhttpd 22:09:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:09:37 INFO - Running setup.py install for mozinfo 22:09:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:09:37 INFO - Running setup.py install for mozInstall 22:09:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:09:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:09:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:09:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:09:38 INFO - Running setup.py install for mozleak 22:09:38 INFO - Running setup.py install for mozlog 22:09:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:09:38 INFO - Running setup.py install for moznetwork 22:09:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:09:38 INFO - Running setup.py install for mozprocess 22:09:38 INFO - Running setup.py install for mozprofile 22:09:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:09:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:09:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:09:38 INFO - Running setup.py install for mozrunner 22:09:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:09:38 INFO - Running setup.py install for mozscreenshot 22:09:38 INFO - Running setup.py install for moztest 22:09:38 INFO - Running setup.py install for mozversion 22:09:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:09:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:09:39 INFO - Cleaning up... 22:09:39 INFO - Return code: 0 22:09:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:39 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2e570>, '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 0xf2f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf2f900>, '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': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', '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 22:09:39 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 22:09:39 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 22:09:39 INFO - Using env: {'DISPLAY': ':0', 22:09:39 INFO - 'HOME': '/home/cltbld', 22:09:39 INFO - 'LANG': 'en_US.UTF-8', 22:09:39 INFO - 'LANGUAGE': 'en_US:en', 22:09:39 INFO - 'LOGNAME': 'cltbld', 22:09:39 INFO - 'MAIL': '/var/mail/cltbld', 22:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:39 INFO - 'MOZ_NO_REMOTE': '1', 22:09:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:39 INFO - 'NO_EM_RESTART': '1', 22:09:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:39 INFO - 'PWD': '/builds/slave/test', 22:09:39 INFO - 'SHELL': '/bin/bash', 22:09:39 INFO - 'SHLVL': '1', 22:09:39 INFO - 'TERM': 'linux', 22:09:39 INFO - 'TMOUT': '86400', 22:09:39 INFO - 'USER': 'cltbld', 22:09:39 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', 22:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:39 INFO - '_': '/tools/buildbot/bin/python'} 22:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:09:39 INFO - Running setup.py (path:/tmp/pip-gLJdwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:09:39 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)) 22:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:09:39 INFO - Running setup.py (path:/tmp/pip-lv6J6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:09:39 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)) 22:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:09:39 INFO - Running setup.py (path:/tmp/pip-DdLLIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:09:39 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)) 22:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:09:39 INFO - Running setup.py (path:/tmp/pip-D9pu34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:09:39 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)) 22:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:09:39 INFO - Running setup.py (path:/tmp/pip-TFGzsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:09:39 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)) 22:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:09:39 INFO - Running setup.py (path:/tmp/pip-aPKzQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:09:39 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)) 22:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:09:39 INFO - Running setup.py (path:/tmp/pip-i4v2Wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:09:39 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)) 22:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:09:39 INFO - Running setup.py (path:/tmp/pip-DMmfJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:09:39 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)) 22:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:09:39 INFO - Running setup.py (path:/tmp/pip-EQrsFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:09:39 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)) 22:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:09:39 INFO - Running setup.py (path:/tmp/pip-e5Ha4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:09:39 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)) 22:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:09:39 INFO - Running setup.py (path:/tmp/pip-vkN3mU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:09:40 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)) 22:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:09:40 INFO - Running setup.py (path:/tmp/pip-oIagwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:09:40 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)) 22:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:09:40 INFO - Running setup.py (path:/tmp/pip-nd2ZqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:09:40 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)) 22:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:09:40 INFO - Running setup.py (path:/tmp/pip-WH9cPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:09:40 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)) 22:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:40 INFO - Running setup.py (path:/tmp/pip-xjoWp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:09:40 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)) 22:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:09:40 INFO - Running setup.py (path:/tmp/pip-9JumYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:09:40 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)) 22:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:09:40 INFO - Running setup.py (path:/tmp/pip-YImBPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:09:40 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)) 22:09:40 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)) 22:09:40 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)) 22:09:40 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)) 22:09:40 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)) 22:09:40 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)) 22:09:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:09:40 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 22:09:40 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 22:09:40 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 22:09:40 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 22:09:40 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 22:09:40 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 22:09:42 INFO - Downloading blessings-1.6.tar.gz 22:09:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:09:42 INFO - Installing collected packages: blessings 22:09:42 INFO - Running setup.py install for blessings 22:09:42 INFO - Successfully installed blessings 22:09:42 INFO - Cleaning up... 22:09:42 INFO - Return code: 0 22:09:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:09:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:09:42 INFO - Reading from file tmpfile_stdout 22:09:42 INFO - Current package versions: 22:09:42 INFO - argparse == 1.2.1 22:09:42 INFO - blessings == 1.6 22:09:42 INFO - blobuploader == 1.2.4 22:09:42 INFO - docopt == 0.6.1 22:09:42 INFO - functools32 == 3.2.3-2 22:09:42 INFO - jsonschema == 2.5.1 22:09:42 INFO - manifestparser == 1.1 22:09:42 INFO - mozInstall == 1.12 22:09:42 INFO - mozcrash == 0.17 22:09:42 INFO - mozdebug == 0.1 22:09:42 INFO - mozdevice == 0.48 22:09:42 INFO - mozfile == 1.2 22:09:42 INFO - mozhttpd == 0.7 22:09:42 INFO - mozinfo == 0.9 22:09:42 INFO - mozleak == 0.1 22:09:42 INFO - mozlog == 3.3 22:09:42 INFO - moznetwork == 0.27 22:09:42 INFO - mozprocess == 0.23 22:09:42 INFO - mozprofile == 0.28 22:09:42 INFO - mozrunner == 6.12 22:09:42 INFO - mozscreenshot == 0.1 22:09:42 INFO - mozsystemmonitor == 0.3 22:09:42 INFO - moztest == 0.7 22:09:42 INFO - mozversion == 1.4 22:09:42 INFO - psutil == 3.1.1 22:09:42 INFO - requests == 1.2.3 22:09:42 INFO - wsgiref == 0.1.2 22:09:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:42 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2e570>, '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 0xf2f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf2f900>, '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': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', '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 22:09:42 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 22:09:42 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 22:09:42 INFO - Using env: {'DISPLAY': ':0', 22:09:42 INFO - 'HOME': '/home/cltbld', 22:09:42 INFO - 'LANG': 'en_US.UTF-8', 22:09:42 INFO - 'LANGUAGE': 'en_US:en', 22:09:42 INFO - 'LOGNAME': 'cltbld', 22:09:42 INFO - 'MAIL': '/var/mail/cltbld', 22:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:42 INFO - 'MOZ_NO_REMOTE': '1', 22:09:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:42 INFO - 'NO_EM_RESTART': '1', 22:09:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:42 INFO - 'PWD': '/builds/slave/test', 22:09:42 INFO - 'SHELL': '/bin/bash', 22:09:42 INFO - 'SHLVL': '1', 22:09:42 INFO - 'TERM': 'linux', 22:09:42 INFO - 'TMOUT': '86400', 22:09:42 INFO - 'USER': 'cltbld', 22:09:42 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', 22:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:42 INFO - '_': '/tools/buildbot/bin/python'} 22:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:43 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)) 22:09:43 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)) 22:09:43 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)) 22:09:43 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)) 22:09:43 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)) 22:09:43 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)) 22:09:43 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)) 22:09:43 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)) 22:09:43 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)) 22:09:43 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)) 22:09:43 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)) 22:09:43 INFO - Cleaning up... 22:09:43 INFO - Return code: 0 22:09:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9f400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2e570>, '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 0xf2f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf2f900>, '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': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', '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 22:09:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:09:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:09:43 INFO - Using env: {'DISPLAY': ':0', 22:09:43 INFO - 'HOME': '/home/cltbld', 22:09:43 INFO - 'LANG': 'en_US.UTF-8', 22:09:43 INFO - 'LANGUAGE': 'en_US:en', 22:09:43 INFO - 'LOGNAME': 'cltbld', 22:09:43 INFO - 'MAIL': '/var/mail/cltbld', 22:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:43 INFO - 'MOZ_NO_REMOTE': '1', 22:09:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:43 INFO - 'NO_EM_RESTART': '1', 22:09:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:43 INFO - 'PWD': '/builds/slave/test', 22:09:43 INFO - 'SHELL': '/bin/bash', 22:09:43 INFO - 'SHLVL': '1', 22:09:43 INFO - 'TERM': 'linux', 22:09:43 INFO - 'TMOUT': '86400', 22:09:43 INFO - 'USER': 'cltbld', 22:09:43 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', 22:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:43 INFO - '_': '/tools/buildbot/bin/python'} 22:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:09:43 INFO - Cleaning up... 22:09:43 INFO - Return code: 0 22:09:43 INFO - Running post-action listener: _resource_record_post_action 22:09:43 INFO - Running post-action listener: _start_resource_monitoring 22:09:43 INFO - Starting resource monitoring. 22:09:43 INFO - [mozharness: 2016-09-28 05:09:43.289522Z] Finished create-virtualenv step (success) 22:09:43 INFO - [mozharness: 2016-09-28 05:09:43.289952Z] Running install step. 22:09:43 INFO - Running pre-action listener: _resource_record_pre_action 22:09:43 INFO - Running main action method: install 22:09:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:09:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:09:43 INFO - Reading from file tmpfile_stdout 22:09:43 INFO - Detecting whether we're running mozinstall >=1.0... 22:09:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:09:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:09:43 INFO - Reading from file tmpfile_stdout 22:09:43 INFO - Output received: 22:09:43 INFO - Usage: mozinstall [options] installer 22:09:43 INFO - Options: 22:09:43 INFO - -h, --help show this help message and exit 22:09:43 INFO - -d DEST, --destination=DEST 22:09:43 INFO - Directory to install application into. [default: 22:09:43 INFO - "/builds/slave/test"] 22:09:43 INFO - --app=APP Application being installed. [default: firefox] 22:09:43 INFO - mkdir: /builds/slave/test/build/application 22:09:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:09:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:09:57 INFO - Reading from file tmpfile_stdout 22:09:57 INFO - Output received: 22:09:57 INFO - /builds/slave/test/build/application/firefox/firefox 22:09:57 INFO - Running post-action listener: _resource_record_post_action 22:09:57 INFO - [mozharness: 2016-09-28 05:09:57.526462Z] Finished install step (success) 22:09:57 INFO - [mozharness: 2016-09-28 05:09:57.526739Z] Running run-tests step. 22:09:57 INFO - Running pre-action listener: _resource_record_pre_action 22:09:57 INFO - Running main action method: run_tests 22:09:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:09:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:09:57 INFO - Python 2.7.3 22:09:57 INFO - Return code: 0 22:09:57 INFO - grabbing minidump binary from tooltool 22:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:09:57 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 0xf2e570>, '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 0xf2f470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf2f900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:09:57 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 22:09:57 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 22:09:57 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 22:09:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:09:57 INFO - Return code: 0 22:09:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:09:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:09:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:09:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:09:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:09:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 22:09:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --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 22:09:57 INFO - Using env: {'DISPLAY': ':0', 22:09:57 INFO - 'HOME': '/home/cltbld', 22:09:57 INFO - 'LANG': 'en_US.UTF-8', 22:09:57 INFO - 'LANGUAGE': 'en_US:en', 22:09:57 INFO - 'LOGNAME': 'cltbld', 22:09:57 INFO - 'MAIL': '/var/mail/cltbld', 22:09:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:09:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:57 INFO - 'MOZ_NO_REMOTE': '1', 22:09:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:09:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:09:57 INFO - 'NO_EM_RESTART': '1', 22:09:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:57 INFO - 'PWD': '/builds/slave/test', 22:09:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:09:57 INFO - 'SHELL': '/bin/bash', 22:09:57 INFO - 'SHLVL': '1', 22:09:57 INFO - 'TERM': 'linux', 22:09:57 INFO - 'TMOUT': '86400', 22:09:57 INFO - 'USER': 'cltbld', 22:09:57 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544', 22:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:57 INFO - '_': '/tools/buildbot/bin/python'} 22:09:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 22:09:57 INFO - mozversion application_buildid: 20160927191919 22:09:57 INFO - mozversion application_changeset: 5eb0a600c3917cab96f6e31f3dcc66d99dccddc0 22:09:57 INFO - mozversion application_display_name: Firefox 22:09:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:09:57 INFO - mozversion application_name: Firefox 22:09:57 INFO - mozversion application_remotingname: firefox 22:09:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:09:57 INFO - mozversion application_vendor: Mozilla 22:09:57 INFO - mozversion application_version: 50.0 22:09:57 INFO - mozversion platform_buildid: 20160927191919 22:09:57 INFO - mozversion platform_changeset: 5eb0a600c3917cab96f6e31f3dcc66d99dccddc0 22:09:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:09:57 INFO - mozversion platform_version: 50.0 22:09:57 INFO - using testdate: 1475039397 22:09:57 INFO - actual date: 1475039397 22:09:57 INFO - starting webserver on 'localhost:50566' 22:09:57 INFO - SUITE-START | Running 2 tests 22:09:57 INFO - TEST-START | dromaeo_css 22:09:57 INFO - operating with platform_type : linux_ 22:09:57 INFO - Initialising browser for dromaeo_css test... 22:09:57 INFO - TEST-INFO | started process 15946 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVyBLiB/profile http://localhost:50566/getInfo.html) 22:10:02 INFO - PROCESS | 15946 | __metrics Screen width/height:1600/1200 22:10:02 INFO - PROCESS | 15946 | colorDepth:24 22:10:02 INFO - PROCESS | 15946 | Browser inner width/height: 1024/697 22:10:02 INFO - PROCESS | 15946 | __metrics 22:10:03 ERROR - PROCESS | 15946 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:10:03 INFO - TEST-INFO | 15946: exit 0 22:10:03 INFO - Browser initialized. 22:10:03 INFO - Running cycle 1/1 for dromaeo_css test... 22:10:03 INFO - TEST-INFO | started process 16069 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVyBLiB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:10:15 INFO - PROCESS | 16069 | RSS: Main: 149508096 22:10:15 INFO - PROCESS | 16069 | 22:11:05 INFO - PROCESS | 16069 | 1475039465192 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] 22:13:13 INFO - PROCESS | 16069 | Cycle 1(1): loaded http://localhost:50566/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50566/tests/dromaeo/cssquery-ext.html) 22:13:14 INFO - PROCESS | 16069 | RSS: Main: 157507584 22:13:14 INFO - PROCESS | 16069 | 22:16:16 INFO - PROCESS | 16069 | Cycle 1(1): loaded http://localhost:50566/tests/dromaeo/cssquery-ext.html (next: http://localhost:50566/tests/dromaeo/cssquery-jquery.html) 22:16:17 INFO - PROCESS | 16069 | RSS: Main: 154243072 22:16:17 INFO - PROCESS | 16069 | 22:19:19 INFO - PROCESS | 16069 | Cycle 1(1): loaded http://localhost:50566/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50566/tests/dromaeo/cssquery-mootools.html) 22:19:19 INFO - PROCESS | 16069 | RSS: Main: 154902528 22:19:19 INFO - PROCESS | 16069 | 22:22:21 INFO - PROCESS | 16069 | Cycle 1(1): loaded http://localhost:50566/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50566/tests/dromaeo/cssquery-prototype.html) 22:22:22 INFO - PROCESS | 16069 | RSS: Main: 155705344 22:22:22 INFO - PROCESS | 16069 | 22:25:24 INFO - PROCESS | 16069 | Cycle 1(1): loaded http://localhost:50566/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50566/tests/dromaeo/cssquery-yui.html) 22:25:25 INFO - PROCESS | 16069 | RSS: Main: 156819456 22:25:25 INFO - PROCESS | 16069 | 22:28:27 INFO - PROCESS | 16069 | Cycle 1(1): loaded http://localhost:50566/tests/dromaeo/cssquery-yui.html (next: http://localhost:50566/tests/dromaeo/cssquery-dojo.html) 22:28:27 INFO - PROCESS | 16069 | RSS: Main: 156696576 22:28:27 INFO - PROCESS | 16069 | 22:28:27 INFO - PROCESS | 16069 | __start_tp_report 22:28:27 INFO - PROCESS | 16069 | _x_x_mozilla_page_load 22:28:27 INFO - PROCESS | 16069 | _x_x_mozilla_page_load_details 22:28:27 INFO - PROCESS | 16069 | |i|pagename|runs| 22:28:27 INFO - PROCESS | 16069 | |0;dojo.html;3298.785578937369;3307.9834600826994;3314.4862546305326;3325.4679251319794;3340.766146369755;5773.884522309557;5816.447437493442;5849.239598852148;5851.268591426072;5864.560157988158;5760.0783874580075;5814.360420353758;5839.211706419632;5863.677497737623;5872.207252020976;5250.868728281795;5358.758855851476;5359.437259087798;5373.623846330758;5375.274337964383;5388.811391601294;5399.487048730364;5402.621816472849;5402.864928376783;5442.945570544283;5251.343582052244;5277.3139491866195;5399.757010934498;5402.594805389612;5403.621746477748;5309.920351194735;5381.838544843661;5403.891922161535;5417.187421886709;5441.972790136064;5267.9736601316745;5358.490943360374;5365.463453654643;5378.354597448292;5397.109476936301;5634.0703783875615;5697.6866272355;5764.769409223626;5808.622439541458;5873.471387575139;6140.293866205354;6247.937520624827;6250.656213908233;6263.279722831841;6264.655443950581;6248.656323902183;6299.433051025429;6312.936870631327;6315.24217093947;6322.462590679797;5657.377688454226;5752.712364381848;5851.4148585141575;5853.829234153186;5867.911981320241;6312.715927783261;6349.396807303298;6360.236771587439;6392.808215753535;6428.485721142298;6052.939470605237;6114.205153330038;6126.142340072393;6126.571140020245;6168.444839964424;6046.606970546901;6136.917362303726;6204.410580994799;6249.843753906098;6292.056191571301;6203.627782333075;6207.131001659771;6210.192674952227;6210.31686514483;6240.063990401476;6160.784372546939;6185.505159587222;6207.472364848948;6213.937860621426;6218.657973811393;4623.976880115578;4677.2750223715375;4736.976315118402;4740.051989602093;4755.191617425046;4165.708400411942;4219.810108545123;4220.177065472265;4240.978795106066;4250.468691413574;6010.729517171738;6044;6050.290984956602;6052.909206361908;6079.665618390943;6084.421979911901;6151.938480615226;6239.937600624026;6342.778002769881;6353.5870168438905;6000.249968753906;6085.056816193497;6103.725332360054;6105.511559075263;6126.693665316806;5413.593980451482;5421.539169170585;5425.945740542544;5428.5928555358505;5433.483819037185;6294.653794041372;6350.523710721714;6426.8714625707225;6504.317046710122;6510.414062734377;6288.465480434214;6325.810225693236;6329.1139240505445;6332.683365831598;6333.20834895638;6397.104405383343;6439.356064393523;6445.935540644534;6472.902906456313;6487.18910136233;5900.675462849585;6004.759809607567;6027.517798576016;6136.815895523141;6139.109829074849;6204.565680402509;6244.812655620339;6268.27914789805;6277.96861015692;6293.37066293367;6083.361247068994;6120.969395152996;6125.1424800527075;6135.11040899076;6147.231596050494;5964.433378829004;6066.575339726177;6077.270727512727;6102.664353460602;6121.387861213843;6115.4190351916495;6147.877042459037;6162.907556386747;6167.414095660905;6175.228096487939;6177.660228687286;6195.19462469876;6214.130021797043;6228.128062071404;6233.75064997395;5886.882262354815;5934.821955341346;6003.6397816131175;6079.270487541523;6088.178095957166 22:28:27 INFO - PROCESS | 16069 | |1;ext.html;592.3454582686131;639.6641763074387;659.3801826283291;663.5421559124205;717.8528401677656;42612;47004;47055;47064.52935470644;47342.053158936804;47688;48233;48279.758601206944;48305.75847120759;48522.51477485224;9115.863262051074;9254.352195346328;9257.675981340655;9260;9273.119053689888;9186.908130918706;9226.584803683816;9232.215261702764;9241.768955776126;9255.39839910407;9235.492047937361;9277.489738064403;9278.582463789113;9279.0720927907;9286.396384235037;9287.76780580484;9316.068393160664;9341.81316373672;9343.953280233556;9369.718908432758;9176.082391760838;9186.402883812598;9237.399569027992;9274;9293.674721384787;45351.546484535065;45708.3143752844;45734.085318293444;45843;45857;3557.2707594942976;3688.6680198782237;3705.6850167735774;3775.62243775623;4075.3886916962692;35650.28699425997;36501.087472813124;36613.633863661285;36678;36694.449583256275;4763.642726795504;4827.106985207713;4856.732879691615;4864.805407783684;4865.075635629254;383665;385125;386052;387478;390035;68548;68974.65512672404;69036.6548167266;69037;69159.30840691629;40886.59113408828;42233;42382.78808605937;43742.78128609336;43920.3411948821;17932.641347173245;18092.457226283233;18095.63808723818;18111.45665630033;18229.54426139357;14564.927175364055;14725.116493010453;14739;14853.183074930983;14958.40166393332;21334.14663413477;21374.145034198456;21672.56654866894;21682.56634867294;21688.132474700837;7589.962050189713;7662.310392064741;7873.842523149505;7905.881411778828;7936.761897143095;1030.5465595138114;1073.8550295710136;1077.272840832435;1145.5990403358924;1153;35713.821430892676;36005;36098.45852312217;36193.63806361955;36250.81874590663;1115.2583531951161;1155.8959693627446;1156.392893730798;1169.2049406403557;1241.5840693367822;18648.347307844433;18663.346782862536;18761.7185742214;18868.528286792665;18928.14823332926;570411;575597;580035;581643;582297;67180.32819671741;67675;67793.32206677968;67815;67988;1443.487561915526;1459.4381163252012;1473.2560057170845;1474.955751327462;1489.2107183192925;34615.480767788;35341;35493.82253088718;35609.28781424409;35806;58592.414075860404;59061.70469147627;59279;59398;59452.40547594642;459427;463319;463350;463575;469809;32197.51703724493;32630.34739305248;32770;32791.34417311688;32872.50691239681;55467.44532554623;56044;56401.71799140978;57037.14444283256;57242.7137864308;45794.771026144655;46628.300575492074;47322;48375;48702.26946595733;46574.30138547857;47243.763781180875;48043.75978120087;48882.266765997825;52607.21089183742;40557.59442405656;45373;46495.535044650474;46614;47388.7630561845;39846.800765995984;40967.590324096374;41237.96905077397;41857.581424185366;42123.368149477166;30851.382972340878;31083.689163108986;31831;31906.36187276195;31979.36041279208 22:28:27 INFO - PROCESS | 16069 | |2;jquery.html;401.3878834776967;415.4536784128872;428.4665591338784;436.21481333599513;436.6899501354038;19439.319623813153;21087.894560527195;21101.261455849028;21275.893620531875;21963.560728785455;20190.39428817134;20194.29319973801;20494;20740.585188296223;20896.37310880672;5872.61827981182;6102.72537735803;6105.664188469633;6109.419605137505;6109.663968481732;5994.2806863176265;6000.399960004008;6023.096535519685;6027.246594175728;6067.332593414721;5947.970260148693;5949.9107513387335;5950.166976623276;5958.404159584029;5983.1623572699855;5950.434708702688;5962.522998160137;5989.281286245657;6014.939850601526;6018.307894592134;5253.684778913277;5305.602079844027;5397.568194544427;5410.188471729232;5413.648112872651;24411.63382549263;24512.50974980508;24751.38121546976;25335;25421.237362879143;2473.3816545863533;2536.1503896194795;2780.5134101532153;2859.2851787053237;3112.517559778238;15288.847111528854;15315;15442.305096270695;15642.45251416195;15681.137537435436;1941.4855063408208;2001.399580125971;2027.7465316835396;2035.4707775978204;2129.4889226585665;85961.57019214863;86880;88453.55773221157;90366.5481672594;96679.0332096677;11344.48949797261;11791.29252244851;11880.584179553847;12138.393080345948;12205;16985.40551080706;17095.743563846303;17107.230174642173;17139.400120995953;17176.14119294055;6713.328667133345;6785.491088168407;6823;7867.527948323119;8283.337333013344;7778.572178530122;7862.764117076496;8038.276555110068;8043.5576043317005;8212.137725538732;5052.1663925451885;5052.747362631853;5055.721935293521;5163.870903227375;5191.32512773337;2135.444784356079;2202.5793363280914;2213.291746641092;2224.121472018576;2232;970.9271804614682;981.5730157381562;1002.8846682631445;1014.644874294006;1087.8748943871399;20678.586428271352;21001.474963126046;21105.788942110383;21135.577288454453;21194.258200962893;976.62888102522;1028.388109075099;1073.8550295709979;1096.4956120184804;1115.2082021764638;10766.892331076746;11382.430878456044;11478.368689722145;11587.942060289644;11670.76658466826;403204;404474;408154;490139;547289;93420.53289733508;96536;108313.45843270891;108607.45696271468;111142.44428777804;1075.1774892207468;1080.6703955293553;1118.3904771899333;1118.8321751737142;1122.4275619433984;18119.72820407721;18125.546861328574;18716.438506844817;18729.15718792685;19080.427587172406;43851.56148438475;44083.55916440923;44896.77551612221;44912.32631510595;47004.29493557531;64573;65473.672631638445;66170.33829661642;66403.66798165978;66851;8171.959140204261;8382.16178382157;8496.405251632326;8717.738467845975;8876.467411955045;9762.511874406393;13284.468621255042;13767.38046787917;13814.102083364549;14022.368993395117;11827.763444731228;11868.881311186778;12289.56986505468;12326.383680815745;12376.195547289397;10797.24419290642;11236.101111910864;11921.880781192078;12127;12387.504499819906;11681.707957301136;11741;12235.816462752888;12358.75282494327;12451.128421010442;8589.226969572768;8601.612927418157;8602.78493037679;8796.07641197665;8960.820783584422;11085.889141108486;11919.642410727693;12123.939380303042;12204.389780511117;12762.744745104861 22:28:27 INFO - PROCESS | 16069 | |3;mootools.html;1518.3243456661783;1549.9535013949583;1726.042046664101;1813.3109418420997;1821.9726704099432;9114.726558203249;9462.574184161713;9741.172000379967;9751.804963900726;9929.40423574586;9804.215662746981;9831.41011539308;9945.154661853732;9958.053984871425;9960;2569.9977008966566;2614.712381638018;2616.5028644557474;2633.9604905926426;2634.5126151662002;2517.546841568517;2614.0458732562574;2614.4509652972897;2614.5163144818175;2622.239550530344;2583.418730785577;2598.8050896182863;2636.578147496401;2637.841729496227;2644.9735502645017;2541.072508534381;2547.8726063696927;2552.731963143871;2582.0317380982133;2588.2623452315997;2489.35276828026;2489.614109812982;2496.163785131983;2571.5885458326766;2572.421205228827;9767.853482197772;9807.460589667566;9828.557714902847;9876.35803672766;9884.456354900478;5771.711414429262;5924.4667979882015;5932.733027013752;5938.198343223653;5948.40515948406;3527.4708793680898;3602.4596310553366;3655.670989610948;3711.536057992751;3733.831977561016;1184.2480025183984;1207.957721479744;1213.466074927038;1229.6679896427913;1232.4515590562203;12798.16811907223;13193.670158246025;13324.467021319135;13630.659233519043;13701.862981370257;4909.435414927258;4919.311296418504;4959.528844759742;5040.445550989388;5122.129238029469;10850.45747712627;10860.185486088574;10876.075533579735;10884.748253950738;10889.618863339745;5820.272465941757;5820.650760954356;5822.534197264209;5827.271591051119;5841.9415805842245;4758.476567577563;5296.788128474818;5395.379531353945;5411.053065713563;5447.673139611636;4161.91676166475;4199.70602057853;4282.293421585404;4286.914261714748;4292.098659281523;1241.490988694631;1272.2366580051894;1272.611853384708;1277.2272774971009;1289.3424353579742;1087.3149915553308;1088.6189833558192;1100.301308669001;1107.6843099716702;1113.933164010162;6516.152900123048;6601.240857301375;6683.532152749358;6816.931830681729;6974.965125174342;473.7181377080631;473.7252393611697;476.2546614548221;484.64378681668944;485.75712143928035;4625;4720.787564559603;4760.95239047612;4770.76146192689;4803.447603525639;11074.169437292236;11421.543138274543;11831.230969986924;12146.939265303794;12235.32705701195;11322;11753.941230293794;11780;11792;11804.52781888732;8817.735467935972;8999.05509921462;9019.819603608023;9022.233110185576;9045.366824322235;6684.799456016327;6720.764773232913;6737.292584278776;6817.386435220854;6994.825129371807;10620.946895265473;10731.463426828783;10847.728806779895;10848.67453976382;10883.183761217782;10871.619493318012;11168.1065514757;11286;11292.209545331747;11376.08991280679;8198.50808951441;9048.366614336934;9077.228435583049;9121.224695900657;9234.769130771785;9382.249420046517;9416.623335066795;9430.669926552539;9444.291678124031;9463.337566370288;7903.84192316162;7953.721619743281;7954.602269886367;7994.640241189277;8225.79435514117;7964.044314682275;8050.557219352758;8057.717979870677;8117.512949223065;8205.71280005197;7687.500312479671;7712.344450721752;7765.495242809426;7766.184550622329;7828.647710853139;5690.487856092972;5801.767929282782;5843.590948633555;5857.941420585739;5908.084246941731;5757.971210143923;5766.625169363978;5791.420857914175;5896.380880007709;5935.673537955454 22:28:27 INFO - PROCESS | 16069 | |4;prototype.html;346.45087536255033;350.9806960617167;351.5851295471344;358.9285981345684;358.99820500897494;5827;5828.125781132832;5837.9124313135235;5853.824385268444;5872.76508939643;5748.655080695151;5765.884682306362;5769.971150144253;5782.884342313151;5814.215080964069;4292.484901811779;4460.286354183331;4464;4465.37484752135;4493.505714371417;4430.689851710382;4449.243628583133;4462.152191083684;4463.732176069446;4469.150861336336;4433.7118087324225;4458.5541445855315;4472.798724057409;4477.798499067533;4481.305397663367;4357.782110894458;4391.5827996340295;4421.562992027604;4424.269995450779;4446.3552784846015;3862.6523612874976;3870.0007494192228;3874.302625527404;3883.4369016492733;3892.2020985697864;5513.669179849222;5565.30433695788;5583.35791383992;5591.720413979326;5596.397675081066;4910.975445122787;5053.67151135175;5101.948980510221;5107.540321371094;5107.668001579923;6028.246469191351;6030.879382412371;6036.3360030396625;6036.909446358308;6037.094435834616;4256.08494173751;4258.339957306623;4259.1268789897995;4274.914501709979;4275.337322714984;6091.238595175603;6118.827816125256;6210.50315974721;6226.906596401058;6296.087067375251;4235.2800023996;4245.97877010613;4248.660107191417;4257.169851878916;4263.59495847894;4301.763403012865;4303.354496825501;4309.245881970643;4311;4320.135972805451;4940.876478088005;5029.74851257443;5030.496950304941;5030.905992814466;5031.4465408805;6071.51427885768;6071.755290165505;6078.270607527125;6118.877622447526;6135.785247516315;1538.9230538473032;1547.4738588879834;1553.8834587405763;1555.6655319106446;1556.3852278350214;912.6896855069308;947.824652439301;950.2493030505823;958.2525630008604;960.7934294126796;5274.41553638722;5320.600954928396;5358.544523715528;5358.732063396815;5358.767483478821;5531.668099914018;5569.275994120739;5594.524465420403;5597.63615365;5611.410801865827;4976.42771081323;5269.446708095595;5271.42014378418;5285.445028271976;5288.391834938954;2947.4252520758673;2968.3822316837377;2970.143660212367;3007.9548806767916;3015.4722923488303;5646.632968857011;5683.54531637468;5687.630304030225;5716.599838011382;5717.571182161355;5276.419593844674;5504.724763761876;5559.555235581144;5574.442555744393;5586.525145362609;5596.384397716248;5671.376148623648;5695.832354367345;5714.371419143891;5730.484256416859;5473.917891231555;5493.313335833021;5502.449755024466;5785.9132113017495;6288.874222515433;6227.06594010902;6249.687515624109;6250.93719072714;6255.466536052906;6286.214223222098;6347.238331400262;6547.377999090078;6554.4756419485375;6573.769918052845;6581.572197807127;4468.575485328888;4530.2558514971315;4536.5236650151255;4536.72779633223;4542.477615074309;6115.296740874856;6253.062040693932;6260.906086408617;6264.5614806964895;6270.247570291412;6099;6105.8168254952425;6106.480949119197;6116.296625887901;6127.754889804358;6096.390360963868;6103.877922441615;6107.823931555655;6128.050152226234;6134.754609815557;5855.824325270249;6028.367021462858;6047.365026672136;6073.635581865102;6074.605150665193;6095.207623008981;6111.7249723763425;6115.0827375892195;6131.877362452637;6145.231846019247;5780.913286300624;5848.835930705666;5930.318013428511;5979.252593425822;5996.130561068708 22:28:27 INFO - PROCESS | 16069 | |5;yui.html;779.8674225381689;797.9401544884136;817.2562967699395;895.2122132523378;901.0583939782927;16773.580660483494;17611.207495662693;18370.173342199618;18403.079846007713;18483.44549663509;15000.399984000655;15191.46829860955;15198.848011519882;15211.695766084671;15257.923710381461;884.3588398411165;886.0873300500494;904.4844438669961;910.8132832769267;912.1835956818643;914.131575003749;914.7621618379236;916.7983043730369;917.6696389299847;917.8439665256923;959.9760005999871;968.5447839515416;970.8689326940843;971.2278738402952;975.1321324021626;962.4081190068127;962.4177372689562;962.841131213349;963.2246041936273;968.3173362779223;707.4517249131912;707.7735124760079;711.4557363616838;713.7858642407309;714.7462650758957;16786.244618992052;16800;16812.24344904482;16847.66304673912;16913.238904249338;14036.578902632835;14381.712365752626;14732.55802325932;15141.848581514154;15283.235838208046;14735.336909838976;15006.249687515581;15170;15227.847721522754;15300.770488442682;4839.370881785346;4846.151923413388;4943.307936888838;4977;4979.402471703383;87395.56302218512;87751;88177.55911220468;88445;88490.11509884884;12076.456559454846;12178.756424871453;12251.816222756666;12794.808077878839;12818.359082046047;22343.66484502734;22456.32631021072;22468.775312246667;22470;22562;9067.501287429122;9137.77155571116;9238.491882946368;9317.627294908263;9392.483413412192;5139.974300128551;5217.50433708797;5231.372235331708;5306.973465132727;5357.249985002103;3942.3889297158985;4024.3158663027357;4055.8174882130374;4073.7759423231414;4075.5313138988804;664.1697877652933;683.135833171039;687.3847906123991;689.7516893918186;713.8393861381147;611.0825455839938;727.2000799120925;734.2657342657343;734.797930569087;772.7179579453572;9695.466749328854;9827.459489728133;9965.601375944882;10480.423576703355;10637.73405664849;680.1735890892589;683.6786710246176;686.7562015484531;723.8081908294306;726.4733068525383;5016.147254966591;5056.342675452242;5096.362954630671;5119.513646203604;5126.743662816844;75110.62444687741;75204.62397687977;75280;113885.86114138918;130352.3482382582;62137.68931155315;63212.36787632065;63439.682801585695;70368;112152.43923780492;103339;104532;104760.95239047664;105180.47409762902;106712;9459.952700236454;9702.9029709702;9872;9911.900880991387;9956.55195516189;58937.41062589319;59093.40906591051;59736.70131649488;59872.70063649654;62047.6897615509;79855;83280.58359708163;88841.55579222062;89697;93151;11554.075673946232;13682.93158534201;13706.451741930212;14009.649758756113;14864.554063378117;15435.382584696486;15504.379824807333;16134.838651613334;16225;16500.17499125063;15072.924635376752;15233.238338083273;15330.080195188324;15798.447054353042;16025.519234422985;14532.27338633043;14600.85399145995;14780.48268310604;15364.846351536342;15421.306041228396;13761.931190343985;14323.283835807959;14416.5583441655;14551.199684017482;14829.184394858386;11696.191694499174;12145.332006739714;12363.93818030904;12541.122121451412;12806.039547034195;13000.869991299965;13496.325183740577;13585.116967397484;13645.658858528615;13685.86314136846 22:28:27 INFO - PROCESS | 16069 | __end_tp_report 22:28:27 INFO - PROCESS | 16069 | __start_cc_report 22:28:27 INFO - PROCESS | 16069 | _x_x_mozilla_cycle_collect,332 22:28:27 INFO - PROCESS | 16069 | __end_cc_report 22:28:27 INFO - PROCESS | 16069 | __startTimestamp1475040507640__endTimestamp 22:28:27 INFO - PROCESS | 16069 | 22:28:27 INFO - PROCESS | 16069 | ------- Summary: start ------- 22:28:27 INFO - PROCESS | 16069 | Number of tests: 6 22:28:27 INFO - PROCESS | 16069 | 22:28:27 INFO - PROCESS | 16069 | [#0] dojo.html Cycles:165 Average:5810.47 Median:6079.67 stddev:665.78 (11.0%) stddev-sans-first:638.00 22:28:27 INFO - PROCESS | 16069 | Values: 3298.8 3308.0 3314.5 3325.5 3340.8 5773.9 5816.4 5849.2 5851.3 5864.6 5760.1 5814.4 5839.2 5863.7 5872.2 5250.9 5358.8 5359.4 5373.6 5375.3 5388.8 5399.5 5402.6 5402.9 5442.9 5251.3 5277.3 5399.8 5402.6 5403.6 5309.9 5381.8 5403.9 5417.2 5442.0 5268.0 5358.5 5365.5 5378.4 5397.1 5634.1 5697.7 5764.8 5808.6 5873.5 6140.3 6247.9 6250.7 6263.3 6264.7 6248.7 6299.4 6312.9 6315.2 6322.5 5657.4 5752.7 5851.4 5853.8 5867.9 6312.7 6349.4 6360.2 6392.8 6428.5 6052.9 6114.2 6126.1 6126.6 6168.4 6046.6 6136.9 6204.4 6249.8 6292.1 6203.6 6207.1 6210.2 6210.3 6240.1 6160.8 6185.5 6207.5 6213.9 6218.7 4624.0 4677.3 4737.0 4740.1 4755.2 4165.7 4219.8 4220.2 4241.0 4250.5 6010.7 6044.0 6050.3 6052.9 6079.7 6084.4 6151.9 6239.9 6342.8 6353.6 6000.2 6085.1 6103.7 6105.5 6126.7 5413.6 5421.5 5425.9 5428.6 5433.5 6294.7 6350.5 6426.9 6504.3 6510.4 6288.5 6325.8 6329.1 6332.7 6333.2 6397.1 6439.4 6445.9 6472.9 6487.2 5900.7 6004.8 6027.5 6136.8 6139.1 6204.6 6244.8 6268.3 6278.0 6293.4 6083.4 6121.0 6125.1 6135.1 6147.2 5964.4 6066.6 6077.3 6102.7 6121.4 6115.4 6147.9 6162.9 6167.4 6175.2 6177.7 6195.2 6214.1 6228.1 6233.8 5886.9 5934.8 6003.6 6079.3 6088.2 22:28:27 INFO - PROCESS | 16069 | 22:28:27 INFO - PROCESS | 16069 | [#1] ext.html Cycles:180 Average:65504.86 Median:463334.50 stddev:127809.42 (27.6%) stddev-sans-first:128075.04 22:28:27 INFO - PROCESS | 16069 | Values: 592.3 639.7 659.4 663.5 717.9 42612.0 47004.0 47055.0 47064.5 47342.1 47688.0 48233.0 48279.8 48305.8 48522.5 9115.9 9254.4 9257.7 9260.0 9273.1 9186.9 9226.6 9232.2 9241.8 9255.4 9235.5 9277.5 9278.6 9279.1 9286.4 9287.8 9316.1 9341.8 9344.0 9369.7 9176.1 9186.4 9237.4 9274.0 9293.7 45351.5 45708.3 45734.1 45843.0 45857.0 3557.3 3688.7 3705.7 3775.6 4075.4 35650.3 36501.1 36613.6 36678.0 36694.4 4763.6 4827.1 4856.7 4864.8 4865.1 383665.0 385125.0 386052.0 387478.0 390035.0 68548.0 68974.7 69036.7 69037.0 69159.3 40886.6 42233.0 42382.8 43742.8 43920.3 17932.6 18092.5 18095.6 18111.5 18229.5 14564.9 14725.1 14739.0 14853.2 14958.4 21334.1 21374.1 21672.6 21682.6 21688.1 7590.0 7662.3 7873.8 7905.9 7936.8 1030.5 1073.9 1077.3 1145.6 1153.0 35713.8 36005.0 36098.5 36193.6 36250.8 1115.3 1155.9 1156.4 1169.2 1241.6 18648.3 18663.3 18761.7 18868.5 18928.1 570411.0 575597.0 580035.0 581643.0 582297.0 67180.3 67675.0 67793.3 67815.0 67988.0 1443.5 1459.4 1473.3 1475.0 1489.2 34615.5 35341.0 35493.8 35609.3 35806.0 58592.4 59061.7 59279.0 59398.0 59452.4 459427.0 463319.0 463350.0 463575.0 469809.0 32197.5 32630.3 32770.0 32791.3 32872.5 55467.4 56044.0 56401.7 57037.1 57242.7 45794.8 46628.3 47322.0 48375.0 48702.3 46574.3 47243.8 48043.8 48882.3 52607.2 40557.6 45373.0 46495.5 46614.0 47388.8 39846.8 40967.6 41238.0 41857.6 42123.4 30851.4 31083.7 31831.0 31906.4 31979.4 22:28:27 INFO - PROCESS | 16069 | 22:28:27 INFO - PROCESS | 16069 | [#2] jquery.html Cycles:180 Average:29305.34 Median:13492.95 stddev:75689.13 (561.0%) stddev-sans-first:75870.35 22:28:27 INFO - PROCESS | 16069 | Values: 401.4 415.5 428.5 436.2 436.7 19439.3 21087.9 21101.3 21275.9 21963.6 20190.4 20194.3 20494.0 20740.6 20896.4 5872.6 6102.7 6105.7 6109.4 6109.7 5994.3 6000.4 6023.1 6027.2 6067.3 5948.0 5949.9 5950.2 5958.4 5983.2 5950.4 5962.5 5989.3 6014.9 6018.3 5253.7 5305.6 5397.6 5410.2 5413.6 24411.6 24512.5 24751.4 25335.0 25421.2 2473.4 2536.2 2780.5 2859.3 3112.5 15288.8 15315.0 15442.3 15642.5 15681.1 1941.5 2001.4 2027.7 2035.5 2129.5 85961.6 86880.0 88453.6 90366.5 96679.0 11344.5 11791.3 11880.6 12138.4 12205.0 16985.4 17095.7 17107.2 17139.4 17176.1 6713.3 6785.5 6823.0 7867.5 8283.3 7778.6 7862.8 8038.3 8043.6 8212.1 5052.2 5052.7 5055.7 5163.9 5191.3 2135.4 2202.6 2213.3 2224.1 2232.0 970.9 981.6 1002.9 1014.6 1087.9 20678.6 21001.5 21105.8 21135.6 21194.3 976.6 1028.4 1073.9 1096.5 1115.2 10766.9 11382.4 11478.4 11587.9 11670.8 403204.0 404474.0 408154.0 490139.0 547289.0 93420.5 96536.0 108313.5 108607.5 111142.4 1075.2 1080.7 1118.4 1118.8 1122.4 18119.7 18125.5 18716.4 18729.2 19080.4 43851.6 44083.6 44896.8 44912.3 47004.3 64573.0 65473.7 66170.3 66403.7 66851.0 8172.0 8382.2 8496.4 8717.7 8876.5 9762.5 13284.5 13767.4 13814.1 14022.4 11827.8 11868.9 12289.6 12326.4 12376.2 10797.2 11236.1 11921.9 12127.0 12387.5 11681.7 11741.0 12235.8 12358.8 12451.1 8589.2 8601.6 8602.8 8796.1 8960.8 11085.9 11919.6 12123.9 12204.4 12762.7 22:28:27 INFO - PROCESS | 16069 | 22:28:27 INFO - PROCESS | 16069 | [#3] mootools.html Cycles:180 Average:6349.11 Median:2618.60 stddev:3594.18 (137.3%) stddev-sans-first:3585.93 22:28:27 INFO - PROCESS | 16069 | Values: 1518.3 1550.0 1726.0 1813.3 1822.0 9114.7 9462.6 9741.2 9751.8 9929.4 9804.2 9831.4 9945.2 9958.1 9960.0 2570.0 2614.7 2616.5 2634.0 2634.5 2517.5 2614.0 2614.5 2614.5 2622.2 2583.4 2598.8 2636.6 2637.8 2645.0 2541.1 2547.9 2552.7 2582.0 2588.3 2489.4 2489.6 2496.2 2571.6 2572.4 9767.9 9807.5 9828.6 9876.4 9884.5 5771.7 5924.5 5932.7 5938.2 5948.4 3527.5 3602.5 3655.7 3711.5 3733.8 1184.2 1208.0 1213.5 1229.7 1232.5 12798.2 13193.7 13324.5 13630.7 13701.9 4909.4 4919.3 4959.5 5040.4 5122.1 10850.5 10860.2 10876.1 10884.7 10889.6 5820.3 5820.7 5822.5 5827.3 5841.9 4758.5 5296.8 5395.4 5411.1 5447.7 4161.9 4199.7 4282.3 4286.9 4292.1 1241.5 1272.2 1272.6 1277.2 1289.3 1087.3 1088.6 1100.3 1107.7 1113.9 6516.2 6601.2 6683.5 6816.9 6975.0 473.7 473.7 476.3 484.6 485.8 4625.0 4720.8 4761.0 4770.8 4803.4 11074.2 11421.5 11831.2 12146.9 12235.3 11322.0 11753.9 11780.0 11792.0 11804.5 8817.7 8999.1 9019.8 9022.2 9045.4 6684.8 6720.8 6737.3 6817.4 6994.8 10620.9 10731.5 10847.7 10848.7 10883.2 10871.6 11168.1 11286.0 11292.2 11376.1 8198.5 9048.4 9077.2 9121.2 9234.8 9382.2 9416.6 9430.7 9444.3 9463.3 7903.8 7953.7 7954.6 7994.6 8225.8 7964.0 8050.6 8057.7 8117.5 8205.7 7687.5 7712.3 7765.5 7766.2 7828.6 5690.5 5801.8 5843.6 5857.9 5908.1 5758.0 5766.6 5791.4 5896.4 5935.7 22:28:27 INFO - PROCESS | 16069 | 22:28:27 INFO - PROCESS | 16069 | [#4] prototype.html Cycles:180 Average:4955.17 Median:5578.90 stddev:1465.94 (26.3%) stddev-sans-first:1428.66 22:28:27 INFO - PROCESS | 16069 | Values: 346.5 351.0 351.6 358.9 359.0 5827.0 5828.1 5837.9 5853.8 5872.8 5748.7 5765.9 5770.0 5782.9 5814.2 4292.5 4460.3 4464.0 4465.4 4493.5 4430.7 4449.2 4462.2 4463.7 4469.2 4433.7 4458.6 4472.8 4477.8 4481.3 4357.8 4391.6 4421.6 4424.3 4446.4 3862.7 3870.0 3874.3 3883.4 3892.2 5513.7 5565.3 5583.4 5591.7 5596.4 4911.0 5053.7 5101.9 5107.5 5107.7 6028.2 6030.9 6036.3 6036.9 6037.1 4256.1 4258.3 4259.1 4274.9 4275.3 6091.2 6118.8 6210.5 6226.9 6296.1 4235.3 4246.0 4248.7 4257.2 4263.6 4301.8 4303.4 4309.2 4311.0 4320.1 4940.9 5029.7 5030.5 5030.9 5031.4 6071.5 6071.8 6078.3 6118.9 6135.8 1538.9 1547.5 1553.9 1555.7 1556.4 912.7 947.8 950.2 958.3 960.8 5274.4 5320.6 5358.5 5358.7 5358.8 5531.7 5569.3 5594.5 5597.6 5611.4 4976.4 5269.4 5271.4 5285.4 5288.4 2947.4 2968.4 2970.1 3008.0 3015.5 5646.6 5683.5 5687.6 5716.6 5717.6 5276.4 5504.7 5559.6 5574.4 5586.5 5596.4 5671.4 5695.8 5714.4 5730.5 5473.9 5493.3 5502.4 5785.9 6288.9 6227.1 6249.7 6250.9 6255.5 6286.2 6347.2 6547.4 6554.5 6573.8 6581.6 4468.6 4530.3 4536.5 4536.7 4542.5 6115.3 6253.1 6260.9 6264.6 6270.2 6099.0 6105.8 6106.5 6116.3 6127.8 6096.4 6103.9 6107.8 6128.1 6134.8 5855.8 6028.4 6047.4 6073.6 6074.6 6095.2 6111.7 6115.1 6131.9 6145.2 5780.9 5848.8 5930.3 5979.3 5996.1 22:28:27 INFO - PROCESS | 16069 | 22:28:27 INFO - PROCESS | 16069 | [#5] yui.html Cycles:180 Average:21629.24 Median:5036.24 stddev:29958.48 (594.9%) stddev-sans-first:30001.62 22:28:27 INFO - PROCESS | 16069 | Values: 779.9 797.9 817.3 895.2 901.1 16773.6 17611.2 18370.2 18403.1 18483.4 15000.4 15191.5 15198.8 15211.7 15257.9 884.4 886.1 904.5 910.8 912.2 914.1 914.8 916.8 917.7 917.8 960.0 968.5 970.9 971.2 975.1 962.4 962.4 962.8 963.2 968.3 707.5 707.8 711.5 713.8 714.7 16786.2 16800.0 16812.2 16847.7 16913.2 14036.6 14381.7 14732.6 15141.8 15283.2 14735.3 15006.2 15170.0 15227.8 15300.8 4839.4 4846.2 4943.3 4977.0 4979.4 87395.6 87751.0 88177.6 88445.0 88490.1 12076.5 12178.8 12251.8 12794.8 12818.4 22343.7 22456.3 22468.8 22470.0 22562.0 9067.5 9137.8 9238.5 9317.6 9392.5 5140.0 5217.5 5231.4 5307.0 5357.2 3942.4 4024.3 4055.8 4073.8 4075.5 664.2 683.1 687.4 689.8 713.8 611.1 727.2 734.3 734.8 772.7 9695.5 9827.5 9965.6 10480.4 10637.7 680.2 683.7 686.8 723.8 726.5 5016.1 5056.3 5096.4 5119.5 5126.7 75110.6 75204.6 75280.0 113885.9 130352.3 62137.7 63212.4 63439.7 70368.0 112152.4 103339.0 104532.0 104761.0 105180.5 106712.0 9460.0 9702.9 9872.0 9911.9 9956.6 58937.4 59093.4 59736.7 59872.7 62047.7 79855.0 83280.6 88841.6 89697.0 93151.0 11554.1 13682.9 13706.5 14009.6 14864.6 15435.4 15504.4 16134.8 16225.0 16500.2 15072.9 15233.2 15330.1 15798.4 16025.5 14532.3 14600.9 14780.5 15364.8 15421.3 13761.9 14323.3 14416.6 14551.2 14829.2 11696.2 12145.3 12363.9 12541.1 12806.0 13000.9 13496.3 13585.1 13645.7 13685.9 22:28:27 INFO - PROCESS | 16069 | -------- Summary: end -------- 22:28:27 INFO - PROCESS | 16069 | 22:28:28 INFO - TEST-INFO | 16069: exit 0 22:28:28 INFO - TEST-OK | dromaeo_css | took 1110566ms 22:28:28 INFO - TEST-START | kraken 22:28:28 INFO - operating with platform_type : linux_ 22:28:28 INFO - Initialising browser for kraken test... 22:28:28 INFO - TEST-INFO | started process 16317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuEXBbZ/profile http://localhost:50566/getInfo.html) 22:28:32 INFO - PROCESS | 16317 | __metrics Screen width/height:1600/1200 22:28:32 INFO - PROCESS | 16317 | colorDepth:24 22:28:32 INFO - PROCESS | 16317 | Browser inner width/height: 1024/697 22:28:32 INFO - PROCESS | 16317 | __metrics 22:28:33 ERROR - PROCESS | 16317 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:28:34 INFO - TEST-INFO | 16317: exit 0 22:28:34 INFO - Browser initialized. 22:28:34 INFO - Running cycle 1/1 for kraken test... 22:28:34 INFO - TEST-INFO | started process 16443 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuEXBbZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:28:46 INFO - PROCESS | 16443 | RSS: Main: 149975040 22:28:46 INFO - PROCESS | 16443 | 22:28:48 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50566/tests/kraken/driver.html?testName=audio-beat-detection) 22:28:49 INFO - PROCESS | 16443 | RSS: Main: 156426240 22:28:49 INFO - PROCESS | 16443 | 22:28:52 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50566/tests/kraken/driver.html?testName=audio-dft) 22:28:52 INFO - PROCESS | 16443 | RSS: Main: 155250688 22:28:52 INFO - PROCESS | 16443 | 22:28:55 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50566/tests/kraken/driver.html?testName=audio-fft) 22:28:56 INFO - PROCESS | 16443 | RSS: Main: 156409856 22:28:56 INFO - PROCESS | 16443 | 22:28:58 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50566/tests/kraken/driver.html?testName=audio-oscillator) 22:28:58 INFO - PROCESS | 16443 | RSS: Main: 155828224 22:28:58 INFO - PROCESS | 16443 | 22:29:00 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50566/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:29:01 INFO - PROCESS | 16443 | RSS: Main: 155602944 22:29:01 INFO - PROCESS | 16443 | 22:29:04 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50566/tests/kraken/driver.html?testName=imaging-darkroom) 22:29:05 INFO - PROCESS | 16443 | RSS: Main: 155959296 22:29:05 INFO - PROCESS | 16443 | 22:29:08 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50566/tests/kraken/driver.html?testName=imaging-desaturate) 22:29:09 INFO - PROCESS | 16443 | RSS: Main: 156319744 22:29:09 INFO - PROCESS | 16443 | 22:29:12 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50566/tests/kraken/driver.html?testName=json-parse-financial) 22:29:12 INFO - PROCESS | 16443 | RSS: Main: 155926528 22:29:12 INFO - PROCESS | 16443 | 22:29:14 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50566/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:29:14 INFO - PROCESS | 16443 | RSS: Main: 155238400 22:29:14 INFO - PROCESS | 16443 | 22:29:16 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50566/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:29:17 INFO - PROCESS | 16443 | RSS: Main: 155615232 22:29:17 INFO - PROCESS | 16443 | 22:29:19 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50566/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:29:19 INFO - PROCESS | 16443 | RSS: Main: 155865088 22:29:19 INFO - PROCESS | 16443 | 22:29:22 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:29:22 INFO - PROCESS | 16443 | RSS: Main: 156114944 22:29:22 INFO - PROCESS | 16443 | 22:29:25 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:29:26 INFO - PROCESS | 16443 | RSS: Main: 156610560 22:29:26 INFO - PROCESS | 16443 | 22:29:27 INFO - PROCESS | 16443 | Cycle 1(1): loaded http://localhost:50566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50566/tests/kraken/driver.html?testName=ai-astar) 22:29:28 INFO - PROCESS | 16443 | RSS: Main: 158203904 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | __start_tp_report 22:29:28 INFO - PROCESS | 16443 | _x_x_mozilla_page_load 22:29:28 INFO - PROCESS | 16443 | _x_x_mozilla_page_load_details 22:29:28 INFO - PROCESS | 16443 | |i|pagename|runs| 22:29:28 INFO - PROCESS | 16443 | |0;ai-astar;103;113;107;109;110;110;111;110;112;111 22:29:28 INFO - PROCESS | 16443 | |1;audio-beat-detection;146;116;150;172;116;146;147;120;162;117 22:29:28 INFO - PROCESS | 16443 | |2;audio-dft;164;163;164;163;162;161;162;161;163;162 22:29:28 INFO - PROCESS | 16443 | |3;audio-fft;75;75;75;184;74;75;75;74;164;74 22:29:28 INFO - PROCESS | 16443 | |4;audio-oscillator;83;82;137;84;82;83;147;82;83;134 22:29:28 INFO - PROCESS | 16443 | |5;imaging-gaussian-blur;117;118;118;118;118;117;118;118;117;118 22:29:28 INFO - PROCESS | 16443 | |6;imaging-darkroom;148;148;147;150;150;148;148;149;148;147 22:29:28 INFO - PROCESS | 16443 | |7;imaging-desaturate;95;95;95;97;95;95;96;95;96;96 22:29:28 INFO - PROCESS | 16443 | |8;json-parse-financial;51;55;51;51;54;52;53;54;50;51 22:29:28 INFO - PROCESS | 16443 | |9;json-stringify-tinderbox;63;60;60;60;61;60;59;61;60;61 22:29:28 INFO - PROCESS | 16443 | |10;stanford-crypto-aes;71;72;81;74;72;72;72;72;73;72 22:29:28 INFO - PROCESS | 16443 | |11;stanford-crypto-ccm;116;126;120;118;121;130;119;124;147;119 22:29:28 INFO - PROCESS | 16443 | |12;stanford-crypto-pbkdf2;150;153;156;151;149;150;163;153;154;149 22:29:28 INFO - PROCESS | 16443 | |13;stanford-crypto-sha256-iterative;61;66;66;62;63;61;64;64;62;61 22:29:28 INFO - PROCESS | 16443 | __end_tp_report 22:29:28 INFO - PROCESS | 16443 | __start_cc_report 22:29:28 INFO - PROCESS | 16443 | _x_x_mozilla_cycle_collect,856 22:29:28 INFO - PROCESS | 16443 | __end_cc_report 22:29:28 INFO - PROCESS | 16443 | __startTimestamp1475040568103__endTimestamp 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | ------- Summary: start ------- 22:29:28 INFO - PROCESS | 16443 | Number of tests: 14 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.50 stddev:2.84 (2.6%) stddev-sans-first:1.73 22:29:28 INFO - PROCESS | 16443 | Values: 103.0 113.0 107.0 109.0 110.0 110.0 111.0 110.0 112.0 111.0 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:146.50 stddev:20.56 (14.0%) stddev-sans-first:21.66 22:29:28 INFO - PROCESS | 16443 | Values: 146.0 116.0 150.0 172.0 116.0 146.0 147.0 120.0 162.0 117.0 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:1.08 (0.7%) stddev-sans-first:1.00 22:29:28 INFO - PROCESS | 16443 | Values: 164.0 163.0 164.0 163.0 162.0 161.0 162.0 161.0 163.0 162.0 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#3] audio-fft Cycles:10 Average:94.50 Median:75.00 stddev:42.17 (56.2%) stddev-sans-first:44.13 22:29:28 INFO - PROCESS | 16443 | Values: 75.0 75.0 75.0 184.0 74.0 75.0 75.0 74.0 164.0 74.0 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#4] audio-oscillator Cycles:10 Average:99.70 Median:82.50 stddev:27.54 (33.4%) stddev-sans-first:28.54 22:29:28 INFO - PROCESS | 16443 | Values: 83.0 82.0 137.0 84.0 82.0 83.0 147.0 82.0 83.0 134.0 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 22:29:28 INFO - PROCESS | 16443 | Values: 117.0 118.0 118.0 118.0 118.0 117.0 118.0 118.0 117.0 118.0 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 22:29:28 INFO - PROCESS | 16443 | Values: 148.0 148.0 147.0 150.0 150.0 148.0 148.0 149.0 148.0 147.0 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 22:29:28 INFO - PROCESS | 16443 | Values: 95.0 95.0 95.0 97.0 95.0 95.0 96.0 95.0 96.0 96.0 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.50 stddev:1.69 (3.2%) stddev-sans-first:1.73 22:29:28 INFO - PROCESS | 16443 | Values: 51.0 55.0 51.0 51.0 54.0 52.0 53.0 54.0 50.0 51.0 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:60.50 stddev:1.08 (1.8%) stddev-sans-first:0.67 22:29:28 INFO - PROCESS | 16443 | Values: 63.0 60.0 60.0 60.0 61.0 60.0 59.0 61.0 60.0 61.0 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#10] stanford-crypto-aes Cycles:10 Average:73.10 Median:72.00 stddev:2.88 (4.0%) stddev-sans-first:2.96 22:29:28 INFO - PROCESS | 16443 | Values: 71.0 72.0 81.0 74.0 72.0 72.0 72.0 72.0 73.0 72.0 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#11] stanford-crypto-ccm Cycles:10 Average:124.00 Median:122.50 stddev:9.09 (7.4%) stddev-sans-first:9.17 22:29:28 INFO - PROCESS | 16443 | Values: 116.0 126.0 120.0 118.0 121.0 130.0 119.0 124.0 147.0 119.0 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.80 Median:153.00 stddev:4.26 (2.8%) stddev-sans-first:4.40 22:29:28 INFO - PROCESS | 16443 | Values: 150.0 153.0 156.0 151.0 149.0 150.0 163.0 153.0 154.0 149.0 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - PROCESS | 16443 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:63.50 stddev:1.94 (3.1%) stddev-sans-first:1.92 22:29:28 INFO - PROCESS | 16443 | Values: 61.0 66.0 66.0 62.0 63.0 61.0 64.0 64.0 62.0 61.0 22:29:28 INFO - PROCESS | 16443 | -------- Summary: end -------- 22:29:28 INFO - PROCESS | 16443 | 22:29:28 INFO - TEST-INFO | 16443: exit 0 22:29:28 INFO - TEST-OK | kraken | took 60635ms 22:29:28 INFO - SUITE-END | took 1171s 22:29:29 INFO - Completed test suite (00:19:31) 22:29:29 INFO - javascript benchmark 22:29:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7815.4420635230335, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3298.785578937369, 3307.9834600826994, 3314.4862546305326, 3325.4679251319794, 3340.766146369755, 5773.884522309557, 5816.447437493442, 5849.239598852148, 5851.268591426072, 5864.560157988158, 5760.0783874580075, 5814.360420353758, 5839.211706419632, 5863.677497737623, 5872.207252020976, 5250.868728281795, 5358.758855851476, 5359.437259087798, 5373.623846330758, 5375.274337964383, 5388.811391601294, 5399.487048730364, 5402.621816472849, 5402.864928376783, 5442.945570544283, 5251.343582052244, 5277.3139491866195, 5399.757010934498, 5402.594805389612, 5403.621746477748, 5309.920351194735, 5381.838544843661, 5403.891922161535, 5417.187421886709, 5441.972790136064, 5267.9736601316745, 5358.490943360374, 5365.463453654643, 5378.354597448292, 5397.109476936301, 5634.0703783875615, 5697.6866272355, 5764.769409223626, 5808.622439541458, 5873.471387575139, 6140.293866205354, 6247.937520624827, 6250.656213908233, 6263.279722831841, 6264.655443950581, 6248.656323902183, 6299.433051025429, 6312.936870631327, 6315.24217093947, 6322.462590679797, 5657.377688454226, 5752.712364381848, 5851.4148585141575, 5853.829234153186, 5867.911981320241, 6312.715927783261, 6349.396807303298, 6360.236771587439, 6392.808215753535, 6428.485721142298, 6052.939470605237, 6114.205153330038, 6126.142340072393, 6126.571140020245, 6168.444839964424, 6046.606970546901, 6136.917362303726, 6204.410580994799, 6249.843753906098, 6292.056191571301, 6203.627782333075, 6207.131001659771, 6210.192674952227, 6210.31686514483, 6240.063990401476, 6160.784372546939, 6185.505159587222, 6207.472364848948, 6213.937860621426, 6218.657973811393, 4623.976880115578, 4677.2750223715375, 4736.976315118402, 4740.051989602093, 4755.191617425046, 4165.708400411942, 4219.810108545123, 4220.177065472265, 4240.978795106066, 4250.468691413574, 6010.729517171738, 6044.0, 6050.290984956602, 6052.909206361908, 6079.665618390943, 6084.421979911901, 6151.938480615226, 6239.937600624026, 6342.778002769881, 6353.5870168438905, 6000.249968753906, 6085.056816193497, 6103.725332360054, 6105.511559075263, 6126.693665316806, 5413.593980451482, 5421.539169170585, 5425.945740542544, 5428.5928555358505, 5433.483819037185, 6294.653794041372, 6350.523710721714, 6426.8714625707225, 6504.317046710122, 6510.414062734377, 6288.465480434214, 6325.810225693236, 6329.1139240505445, 6332.683365831598, 6333.20834895638, 6397.104405383343, 6439.356064393523, 6445.935540644534, 6472.902906456313, 6487.18910136233, 5900.675462849585, 6004.759809607567, 6027.517798576016, 6136.815895523141, 6139.109829074849, 6204.565680402509, 6244.812655620339, 6268.27914789805, 6277.96861015692, 6293.37066293367, 6083.361247068994, 6120.969395152996, 6125.1424800527075, 6135.11040899076, 6147.231596050494, 5964.433378829004, 6066.575339726177, 6077.270727512727, 6102.664353460602, 6121.387861213843, 6115.4190351916495, 6147.877042459037, 6162.907556386747, 6167.414095660905, 6175.228096487939, 6177.660228687286, 6195.19462469876, 6214.130021797043, 6228.128062071404, 6233.75064997395, 5886.882262354815, 5934.821955341346, 6003.6397816131175, 6079.270487541523, 6088.178095957166], "value": 5764.333823960854, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.3454582686131, 639.6641763074387, 659.3801826283291, 663.5421559124205, 717.8528401677656, 42612.0, 47004.0, 47055.0, 47064.52935470644, 47342.053158936804, 47688.0, 48233.0, 48279.758601206944, 48305.75847120759, 48522.51477485224, 9115.863262051074, 9254.352195346328, 9257.675981340655, 9260.0, 9273.119053689888, 9186.908130918706, 9226.584803683816, 9232.215261702764, 9241.768955776126, 9255.39839910407, 9235.492047937361, 9277.489738064403, 9278.582463789113, 9279.0720927907, 9286.396384235037, 9287.76780580484, 9316.068393160664, 9341.81316373672, 9343.953280233556, 9369.718908432758, 9176.082391760838, 9186.402883812598, 9237.399569027992, 9274.0, 9293.674721384787, 45351.546484535065, 45708.3143752844, 45734.085318293444, 45843.0, 45857.0, 3557.2707594942976, 3688.6680198782237, 3705.6850167735774, 3775.62243775623, 4075.3886916962692, 35650.28699425997, 36501.087472813124, 36613.633863661285, 36678.0, 36694.449583256275, 4763.642726795504, 4827.106985207713, 4856.732879691615, 4864.805407783684, 4865.075635629254, 383665.0, 385125.0, 386052.0, 387478.0, 390035.0, 68548.0, 68974.65512672404, 69036.6548167266, 69037.0, 69159.30840691629, 40886.59113408828, 42233.0, 42382.78808605937, 43742.78128609336, 43920.3411948821, 17932.641347173245, 18092.457226283233, 18095.63808723818, 18111.45665630033, 18229.54426139357, 14564.927175364055, 14725.116493010453, 14739.0, 14853.183074930983, 14958.40166393332, 21334.14663413477, 21374.145034198456, 21672.56654866894, 21682.56634867294, 21688.132474700837, 7589.962050189713, 7662.310392064741, 7873.842523149505, 7905.881411778828, 7936.761897143095, 1030.5465595138114, 1073.8550295710136, 1077.272840832435, 1145.5990403358924, 1153.0, 35713.821430892676, 36005.0, 36098.45852312217, 36193.63806361955, 36250.81874590663, 1115.2583531951161, 1155.8959693627446, 1156.392893730798, 1169.2049406403557, 1241.5840693367822, 18648.347307844433, 18663.346782862536, 18761.7185742214, 18868.528286792665, 18928.14823332926, 570411.0, 575597.0, 580035.0, 581643.0, 582297.0, 67180.32819671741, 67675.0, 67793.32206677968, 67815.0, 67988.0, 1443.487561915526, 1459.4381163252012, 1473.2560057170845, 1474.955751327462, 1489.2107183192925, 34615.480767788, 35341.0, 35493.82253088718, 35609.28781424409, 35806.0, 58592.414075860404, 59061.70469147627, 59279.0, 59398.0, 59452.40547594642, 459427.0, 463319.0, 463350.0, 463575.0, 469809.0, 32197.51703724493, 32630.34739305248, 32770.0, 32791.34417311688, 32872.50691239681, 55467.44532554623, 56044.0, 56401.71799140978, 57037.14444283256, 57242.7137864308, 45794.771026144655, 46628.300575492074, 47322.0, 48375.0, 48702.26946595733, 46574.30138547857, 47243.763781180875, 48043.75978120087, 48882.266765997825, 52607.21089183742, 40557.59442405656, 45373.0, 46495.535044650474, 46614.0, 47388.7630561845, 39846.800765995984, 40967.590324096374, 41237.96905077397, 41857.581424185366, 42123.368149477166, 30851.382972340878, 31083.689163108986, 31831.0, 31906.36187276195, 31979.36041279208], "value": 21917.92834299221, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.3878834776967, 415.4536784128872, 428.4665591338784, 436.21481333599513, 436.6899501354038, 19439.319623813153, 21087.894560527195, 21101.261455849028, 21275.893620531875, 21963.560728785455, 20190.39428817134, 20194.29319973801, 20494.0, 20740.585188296223, 20896.37310880672, 5872.61827981182, 6102.72537735803, 6105.664188469633, 6109.419605137505, 6109.663968481732, 5994.2806863176265, 6000.399960004008, 6023.096535519685, 6027.246594175728, 6067.332593414721, 5947.970260148693, 5949.9107513387335, 5950.166976623276, 5958.404159584029, 5983.1623572699855, 5950.434708702688, 5962.522998160137, 5989.281286245657, 6014.939850601526, 6018.307894592134, 5253.684778913277, 5305.602079844027, 5397.568194544427, 5410.188471729232, 5413.648112872651, 24411.63382549263, 24512.50974980508, 24751.38121546976, 25335.0, 25421.237362879143, 2473.3816545863533, 2536.1503896194795, 2780.5134101532153, 2859.2851787053237, 3112.517559778238, 15288.847111528854, 15315.0, 15442.305096270695, 15642.45251416195, 15681.137537435436, 1941.4855063408208, 2001.399580125971, 2027.7465316835396, 2035.4707775978204, 2129.4889226585665, 85961.57019214863, 86880.0, 88453.55773221157, 90366.5481672594, 96679.0332096677, 11344.48949797261, 11791.29252244851, 11880.584179553847, 12138.393080345948, 12205.0, 16985.40551080706, 17095.743563846303, 17107.230174642173, 17139.400120995953, 17176.14119294055, 6713.328667133345, 6785.491088168407, 6823.0, 7867.527948323119, 8283.337333013344, 7778.572178530122, 7862.764117076496, 8038.276555110068, 8043.5576043317005, 8212.137725538732, 5052.1663925451885, 5052.747362631853, 5055.721935293521, 5163.870903227375, 5191.32512773337, 2135.444784356079, 2202.5793363280914, 2213.291746641092, 2224.121472018576, 2232.0, 970.9271804614682, 981.5730157381562, 1002.8846682631445, 1014.644874294006, 1087.8748943871399, 20678.586428271352, 21001.474963126046, 21105.788942110383, 21135.577288454453, 21194.258200962893, 976.62888102522, 1028.388109075099, 1073.8550295709979, 1096.4956120184804, 1115.2082021764638, 10766.892331076746, 11382.430878456044, 11478.368689722145, 11587.942060289644, 11670.76658466826, 403204.0, 404474.0, 408154.0, 490139.0, 547289.0, 93420.53289733508, 96536.0, 108313.45843270891, 108607.45696271468, 111142.44428777804, 1075.1774892207468, 1080.6703955293553, 1118.3904771899333, 1118.8321751737142, 1122.4275619433984, 18119.72820407721, 18125.546861328574, 18716.438506844817, 18729.15718792685, 19080.427587172406, 43851.56148438475, 44083.55916440923, 44896.77551612221, 44912.32631510595, 47004.29493557531, 64573.0, 65473.672631638445, 66170.33829661642, 66403.66798165978, 66851.0, 8171.959140204261, 8382.16178382157, 8496.405251632326, 8717.738467845975, 8876.467411955045, 9762.511874406393, 13284.468621255042, 13767.38046787917, 13814.102083364549, 14022.368993395117, 11827.763444731228, 11868.881311186778, 12289.56986505468, 12326.383680815745, 12376.195547289397, 10797.24419290642, 11236.101111910864, 11921.880781192078, 12127.0, 12387.504499819906, 11681.707957301136, 11741.0, 12235.816462752888, 12358.75282494327, 12451.128421010442, 8589.226969572768, 8601.612927418157, 8602.78493037679, 8796.07641197665, 8960.820783584422, 11085.889141108486, 11919.642410727693, 12123.939380303042, 12204.389780511117, 12762.744745104861], "value": 9792.198001430308, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1518.3243456661783, 1549.9535013949583, 1726.042046664101, 1813.3109418420997, 1821.9726704099432, 9114.726558203249, 9462.574184161713, 9741.172000379967, 9751.804963900726, 9929.40423574586, 9804.215662746981, 9831.41011539308, 9945.154661853732, 9958.053984871425, 9960.0, 2569.9977008966566, 2614.712381638018, 2616.5028644557474, 2633.9604905926426, 2634.5126151662002, 2517.546841568517, 2614.0458732562574, 2614.4509652972897, 2614.5163144818175, 2622.239550530344, 2583.418730785577, 2598.8050896182863, 2636.578147496401, 2637.841729496227, 2644.9735502645017, 2541.072508534381, 2547.8726063696927, 2552.731963143871, 2582.0317380982133, 2588.2623452315997, 2489.35276828026, 2489.614109812982, 2496.163785131983, 2571.5885458326766, 2572.421205228827, 9767.853482197772, 9807.460589667566, 9828.557714902847, 9876.35803672766, 9884.456354900478, 5771.711414429262, 5924.4667979882015, 5932.733027013752, 5938.198343223653, 5948.40515948406, 3527.4708793680898, 3602.4596310553366, 3655.670989610948, 3711.536057992751, 3733.831977561016, 1184.2480025183984, 1207.957721479744, 1213.466074927038, 1229.6679896427913, 1232.4515590562203, 12798.16811907223, 13193.670158246025, 13324.467021319135, 13630.659233519043, 13701.862981370257, 4909.435414927258, 4919.311296418504, 4959.528844759742, 5040.445550989388, 5122.129238029469, 10850.45747712627, 10860.185486088574, 10876.075533579735, 10884.748253950738, 10889.618863339745, 5820.272465941757, 5820.650760954356, 5822.534197264209, 5827.271591051119, 5841.9415805842245, 4758.476567577563, 5296.788128474818, 5395.379531353945, 5411.053065713563, 5447.673139611636, 4161.91676166475, 4199.70602057853, 4282.293421585404, 4286.914261714748, 4292.098659281523, 1241.490988694631, 1272.2366580051894, 1272.611853384708, 1277.2272774971009, 1289.3424353579742, 1087.3149915553308, 1088.6189833558192, 1100.301308669001, 1107.6843099716702, 1113.933164010162, 6516.152900123048, 6601.240857301375, 6683.532152749358, 6816.931830681729, 6974.965125174342, 473.7181377080631, 473.7252393611697, 476.2546614548221, 484.64378681668944, 485.75712143928035, 4625.0, 4720.787564559603, 4760.95239047612, 4770.76146192689, 4803.447603525639, 11074.169437292236, 11421.543138274543, 11831.230969986924, 12146.939265303794, 12235.32705701195, 11322.0, 11753.941230293794, 11780.0, 11792.0, 11804.52781888732, 8817.735467935972, 8999.05509921462, 9019.819603608023, 9022.233110185576, 9045.366824322235, 6684.799456016327, 6720.764773232913, 6737.292584278776, 6817.386435220854, 6994.825129371807, 10620.946895265473, 10731.463426828783, 10847.728806779895, 10848.67453976382, 10883.183761217782, 10871.619493318012, 11168.1065514757, 11286.0, 11292.209545331747, 11376.08991280679, 8198.50808951441, 9048.366614336934, 9077.228435583049, 9121.224695900657, 9234.769130771785, 9382.249420046517, 9416.623335066795, 9430.669926552539, 9444.291678124031, 9463.337566370288, 7903.84192316162, 7953.721619743281, 7954.602269886367, 7994.640241189277, 8225.79435514117, 7964.044314682275, 8050.557219352758, 8057.717979870677, 8117.512949223065, 8205.71280005197, 7687.500312479671, 7712.344450721752, 7765.495242809426, 7766.184550622329, 7828.647710853139, 5690.487856092972, 5801.767929282782, 5843.590948633555, 5857.941420585739, 5908.084246941731, 5757.971210143923, 5766.625169363978, 5791.420857914175, 5896.380880007709, 5935.673537955454], "value": 4992.565512783171, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.45087536255033, 350.9806960617167, 351.5851295471344, 358.9285981345684, 358.99820500897494, 5827.0, 5828.125781132832, 5837.9124313135235, 5853.824385268444, 5872.76508939643, 5748.655080695151, 5765.884682306362, 5769.971150144253, 5782.884342313151, 5814.215080964069, 4292.484901811779, 4460.286354183331, 4464.0, 4465.37484752135, 4493.505714371417, 4430.689851710382, 4449.243628583133, 4462.152191083684, 4463.732176069446, 4469.150861336336, 4433.7118087324225, 4458.5541445855315, 4472.798724057409, 4477.798499067533, 4481.305397663367, 4357.782110894458, 4391.5827996340295, 4421.562992027604, 4424.269995450779, 4446.3552784846015, 3862.6523612874976, 3870.0007494192228, 3874.302625527404, 3883.4369016492733, 3892.2020985697864, 5513.669179849222, 5565.30433695788, 5583.35791383992, 5591.720413979326, 5596.397675081066, 4910.975445122787, 5053.67151135175, 5101.948980510221, 5107.540321371094, 5107.668001579923, 6028.246469191351, 6030.879382412371, 6036.3360030396625, 6036.909446358308, 6037.094435834616, 4256.08494173751, 4258.339957306623, 4259.1268789897995, 4274.914501709979, 4275.337322714984, 6091.238595175603, 6118.827816125256, 6210.50315974721, 6226.906596401058, 6296.087067375251, 4235.2800023996, 4245.97877010613, 4248.660107191417, 4257.169851878916, 4263.59495847894, 4301.763403012865, 4303.354496825501, 4309.245881970643, 4311.0, 4320.135972805451, 4940.876478088005, 5029.74851257443, 5030.496950304941, 5030.905992814466, 5031.4465408805, 6071.51427885768, 6071.755290165505, 6078.270607527125, 6118.877622447526, 6135.785247516315, 1538.9230538473032, 1547.4738588879834, 1553.8834587405763, 1555.6655319106446, 1556.3852278350214, 912.6896855069308, 947.824652439301, 950.2493030505823, 958.2525630008604, 960.7934294126796, 5274.41553638722, 5320.600954928396, 5358.544523715528, 5358.732063396815, 5358.767483478821, 5531.668099914018, 5569.275994120739, 5594.524465420403, 5597.63615365, 5611.410801865827, 4976.42771081323, 5269.446708095595, 5271.42014378418, 5285.445028271976, 5288.391834938954, 2947.4252520758673, 2968.3822316837377, 2970.143660212367, 3007.9548806767916, 3015.4722923488303, 5646.632968857011, 5683.54531637468, 5687.630304030225, 5716.599838011382, 5717.571182161355, 5276.419593844674, 5504.724763761876, 5559.555235581144, 5574.442555744393, 5586.525145362609, 5596.384397716248, 5671.376148623648, 5695.832354367345, 5714.371419143891, 5730.484256416859, 5473.917891231555, 5493.313335833021, 5502.449755024466, 5785.9132113017495, 6288.874222515433, 6227.06594010902, 6249.687515624109, 6250.93719072714, 6255.466536052906, 6286.214223222098, 6347.238331400262, 6547.377999090078, 6554.4756419485375, 6573.769918052845, 6581.572197807127, 4468.575485328888, 4530.2558514971315, 4536.5236650151255, 4536.72779633223, 4542.477615074309, 6115.296740874856, 6253.062040693932, 6260.906086408617, 6264.5614806964895, 6270.247570291412, 6099.0, 6105.8168254952425, 6106.480949119197, 6116.296625887901, 6127.754889804358, 6096.390360963868, 6103.877922441615, 6107.823931555655, 6128.050152226234, 6134.754609815557, 5855.824325270249, 6028.367021462858, 6047.365026672136, 6073.635581865102, 6074.605150665193, 6095.207623008981, 6111.7249723763425, 6115.0827375892195, 6131.877362452637, 6145.231846019247, 5780.913286300624, 5848.835930705666, 5930.318013428511, 5979.252593425822, 5996.130561068708], "value": 4485.416426398373, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.8674225381689, 797.9401544884136, 817.2562967699395, 895.2122132523378, 901.0583939782927, 16773.580660483494, 17611.207495662693, 18370.173342199618, 18403.079846007713, 18483.44549663509, 15000.399984000655, 15191.46829860955, 15198.848011519882, 15211.695766084671, 15257.923710381461, 884.3588398411165, 886.0873300500494, 904.4844438669961, 910.8132832769267, 912.1835956818643, 914.131575003749, 914.7621618379236, 916.7983043730369, 917.6696389299847, 917.8439665256923, 959.9760005999871, 968.5447839515416, 970.8689326940843, 971.2278738402952, 975.1321324021626, 962.4081190068127, 962.4177372689562, 962.841131213349, 963.2246041936273, 968.3173362779223, 707.4517249131912, 707.7735124760079, 711.4557363616838, 713.7858642407309, 714.7462650758957, 16786.244618992052, 16800.0, 16812.24344904482, 16847.66304673912, 16913.238904249338, 14036.578902632835, 14381.712365752626, 14732.55802325932, 15141.848581514154, 15283.235838208046, 14735.336909838976, 15006.249687515581, 15170.0, 15227.847721522754, 15300.770488442682, 4839.370881785346, 4846.151923413388, 4943.307936888838, 4977.0, 4979.402471703383, 87395.56302218512, 87751.0, 88177.55911220468, 88445.0, 88490.11509884884, 12076.456559454846, 12178.756424871453, 12251.816222756666, 12794.808077878839, 12818.359082046047, 22343.66484502734, 22456.32631021072, 22468.775312246667, 22470.0, 22562.0, 9067.501287429122, 9137.77155571116, 9238.491882946368, 9317.627294908263, 9392.483413412192, 5139.974300128551, 5217.50433708797, 5231.372235331708, 5306.973465132727, 5357.249985002103, 3942.3889297158985, 4024.3158663027357, 4055.8174882130374, 4073.7759423231414, 4075.5313138988804, 664.1697877652933, 683.135833171039, 687.3847906123991, 689.7516893918186, 713.8393861381147, 611.0825455839938, 727.2000799120925, 734.2657342657343, 734.797930569087, 772.7179579453572, 9695.466749328854, 9827.459489728133, 9965.601375944882, 10480.423576703355, 10637.73405664849, 680.1735890892589, 683.6786710246176, 686.7562015484531, 723.8081908294306, 726.4733068525383, 5016.147254966591, 5056.342675452242, 5096.362954630671, 5119.513646203604, 5126.743662816844, 75110.62444687741, 75204.62397687977, 75280.0, 113885.86114138918, 130352.3482382582, 62137.68931155315, 63212.36787632065, 63439.682801585695, 70368.0, 112152.43923780492, 103339.0, 104532.0, 104760.95239047664, 105180.47409762902, 106712.0, 9459.952700236454, 9702.9029709702, 9872.0, 9911.900880991387, 9956.55195516189, 58937.41062589319, 59093.40906591051, 59736.70131649488, 59872.70063649654, 62047.6897615509, 79855.0, 83280.58359708163, 88841.55579222062, 89697.0, 93151.0, 11554.075673946232, 13682.93158534201, 13706.451741930212, 14009.649758756113, 14864.554063378117, 15435.382584696486, 15504.379824807333, 16134.838651613334, 16225.0, 16500.17499125063, 15072.924635376752, 15233.238338083273, 15330.080195188324, 15798.447054353042, 16025.519234422985, 14532.27338633043, 14600.85399145995, 14780.48268310604, 15364.846351536342, 15421.306041228396, 13761.931190343985, 14323.283835807959, 14416.5583441655, 14551.199684017482, 14829.184394858386, 11696.191694499174, 12145.332006739714, 12363.93818030904, 12541.122121451412, 12806.039547034195, 13000.869991299965, 13496.325183740577, 13585.116967397484, 13645.658858528615, 13685.86314136846], "value": 8224.76000028154, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1492.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 107.0, 109.0, 110.0, 110.0, 111.0, 110.0, 112.0, 111.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 150.0, 172.0, 116.0, 146.0, 147.0, 120.0, 162.0, 117.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 163.0, 162.0, 161.0, 162.0, 161.0, 163.0, 162.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 184.0, 74.0, 75.0, 75.0, 74.0, 164.0, 74.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 137.0, 84.0, 82.0, 83.0, 147.0, 82.0, 83.0, 134.0], "value": 99.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 118.0, 118.0, 117.0, 118.0, 118.0, 117.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 150.0, 150.0, 148.0, 148.0, 149.0, 148.0, 147.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 97.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 55.0, 51.0, 51.0, 54.0, 52.0, 53.0, 54.0, 50.0, 51.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 60.0, 61.0, 60.0, 59.0, 61.0, 60.0, 61.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 81.0, 74.0, 72.0, 72.0, 72.0, 72.0, 73.0, 72.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 126.0, 120.0, 118.0, 121.0, 130.0, 119.0, 124.0, 147.0, 119.0], "value": 124.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 153.0, 156.0, 151.0, 149.0, 150.0, 163.0, 153.0, 154.0, 149.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 66.0, 66.0, 62.0, 63.0, 61.0, 64.0, 64.0, 62.0, 61.0], "value": 63.0, "unit": "score"}]}]} 22:29:29 INFO - Return code: 0 22:29:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:29:29 ERROR - # TBPL SUCCESS # 22:29:29 INFO - Running post-action listener: _resource_record_post_action 22:29:29 INFO - [mozharness: 2016-09-28 05:29:29.408866Z] Finished run-tests step (success) 22:29:29 INFO - Running post-run listener: _resource_record_post_run 22:29:29 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 288428032; Read time: 0; Write time: 131108 22:29:29 INFO - TinderboxPrint: CPU usage
13.2% 22:29:29 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 22:29:29 INFO - TinderboxPrint: I/O write bytes / time
288,428,032 / 131,108 22:29:29 INFO - TinderboxPrint: CPU idle
8,213.9 (86.8%) 22:29:29 INFO - TinderboxPrint: CPU user
1,196.4 (12.6%) 22:29:29 INFO - TinderboxPrint: Swap in / out
0 / 0 22:29:29 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70098944; Read time: 0; Write time: 29392 22:29:29 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 217776128; Read time: 0; Write time: 101664 22:29:30 INFO - Running post-run listener: _upload_blobber_files 22:29:30 INFO - Blob upload gear active. 22:29:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:29:30 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 22:29:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:29:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:29:30 INFO - (blobuploader) - INFO - Open directory for files ... 22:29:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:29:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:29:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:31 INFO - (blobuploader) - INFO - Done attempting. 22:29:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:29:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:29:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:35 INFO - (blobuploader) - INFO - Done attempting. 22:29:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:29:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:29:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:35 INFO - (blobuploader) - INFO - Done attempting. 22:29:35 INFO - (blobuploader) - INFO - Iteration through files over. 22:29:35 INFO - Return code: 0 22:29:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:29:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:29:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e6bed3d2c2d9d63ed79074f1d3b6e5044b6b05b3db7a4ec270b12d9810dbddd08ab640674ae24c3c7dd4d8bd7f4a6c0ce30875f05c88e623e17707d2578a56b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/caafc44d0d32c08bd8953df0b0fe53fe48e755623fd423b24dacf915df58700419ce0f81738f88585398bbfb9c0840b718d6e911054b71dbdc5b0602fbe4611d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80c59d0f78012e12b18cf7eb57d3cdaeed30f6b636518a8b4fe5836fe03f93d690ecf8db3718e3f33279b04bd719df775e47723c04acb33d321ea0f0faa06ede"} 22:29:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:29:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:29:35 INFO - Contents: 22:29:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e6bed3d2c2d9d63ed79074f1d3b6e5044b6b05b3db7a4ec270b12d9810dbddd08ab640674ae24c3c7dd4d8bd7f4a6c0ce30875f05c88e623e17707d2578a56b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/caafc44d0d32c08bd8953df0b0fe53fe48e755623fd423b24dacf915df58700419ce0f81738f88585398bbfb9c0840b718d6e911054b71dbdc5b0602fbe4611d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80c59d0f78012e12b18cf7eb57d3cdaeed30f6b636518a8b4fe5836fe03f93d690ecf8db3718e3f33279b04bd719df775e47723c04acb33d321ea0f0faa06ede"} 22:29:35 INFO - Running post-run listener: copy_logs_to_upload_dir 22:29:35 INFO - Copying logs to upload dir... 22:29:35 INFO - mkdir: /builds/slave/test/build/upload/logs 22:29:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.967625 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-09-27 22:29:35.558188) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 22:29:35.562080) ========= 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=41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544 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-Beta/sha512/e6bed3d2c2d9d63ed79074f1d3b6e5044b6b05b3db7a4ec270b12d9810dbddd08ab640674ae24c3c7dd4d8bd7f4a6c0ce30875f05c88e623e17707d2578a56b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/caafc44d0d32c08bd8953df0b0fe53fe48e755623fd423b24dacf915df58700419ce0f81738f88585398bbfb9c0840b718d6e911054b71dbdc5b0602fbe4611d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80c59d0f78012e12b18cf7eb57d3cdaeed30f6b636518a8b4fe5836fe03f93d690ecf8db3718e3f33279b04bd719df775e47723c04acb33d321ea0f0faa06ede"} build_url:https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/FYp0KxZKS-C7yg41VQ35Qw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e6bed3d2c2d9d63ed79074f1d3b6e5044b6b05b3db7a4ec270b12d9810dbddd08ab640674ae24c3c7dd4d8bd7f4a6c0ce30875f05c88e623e17707d2578a56b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/caafc44d0d32c08bd8953df0b0fe53fe48e755623fd423b24dacf915df58700419ce0f81738f88585398bbfb9c0840b718d6e911054b71dbdc5b0602fbe4611d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80c59d0f78012e12b18cf7eb57d3cdaeed30f6b636518a8b4fe5836fe03f93d690ecf8db3718e3f33279b04bd719df775e47723c04acb33d321ea0f0faa06ede"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 22:29:35.587485) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 22:29:35.587886) ========= 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=41a7e32711b8e08598a63237000001d6-1475038378.358159-417056544 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004494 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 22:29:35.674449) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-27 22:29:35.675271) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-27 22:29:35.676042) ========= ========= Total master_lag: 0.19 =========