builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0032 starttime: 1472331625.16 results: success (0) buildid: 20160827105213 builduid: 856d608892144123b0d518d75e509696 revision: 125b052639cb1879d4a593c715d244c551129118 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-27 14:00:25.161261) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-27 14:00:25.161739) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-27 14:00:25.162054) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-27 14:00:25.588727) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 14:00:25.588998) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w5iHHvXnk7/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-27 14:00:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to 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% 8.26M=0.001s 2016-08-27 14:00:26 (8.26 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.487176 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 14:00:26.181900) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 14:00:26.182204) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w5iHHvXnk7/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020991 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 14:00:26.287611) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-27 14:00:26.288010) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 125b052639cb1879d4a593c715d244c551129118 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 125b052639cb1879d4a593c715d244c551129118 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w5iHHvXnk7/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-27 14:00:26,400 truncating revision to first 12 chars 2016-08-27 14:00:26,400 Setting DEBUG logging. 2016-08-27 14:00:26,400 attempt 1/10 2016-08-27 14:00:26,400 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/125b052639cb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-27 14:00:27,183 unpacking tar archive at: fx-team-125b052639cb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.039320 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-27 14:00:27.380361) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-27 14:00:27.380675) ========= script_repo_revision: 125b052639cb1879d4a593c715d244c551129118 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-27 14:00:27.381121) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-27 14:00:27.381440) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-27 14:00:27.400492) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-27 14:00:27.400949) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w5iHHvXnk7/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:00:27 INFO - MultiFileLogger online at 20160827 14:00:27 in /builds/slave/test 14:00:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 14:00:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:00:27 INFO - {'append_to_log': False, 14:00:27 INFO - 'base_work_dir': '/builds/slave/test', 14:00:27 INFO - 'blob_upload_branch': 'Fx-Team', 14:00:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:00:27 INFO - 'branch': 'Fx-Team', 14:00:27 INFO - 'buildbot_json_path': 'buildprops.json', 14:00:27 INFO - 'config_files': ('talos/mac_config.py',), 14:00:27 INFO - 'default_actions': ('clobber', 14:00:27 INFO - 'read-buildbot-config', 14:00:27 INFO - 'download-and-extract', 14:00:27 INFO - 'populate-webroot', 14:00:27 INFO - 'create-virtualenv', 14:00:27 INFO - 'install', 14:00:27 INFO - 'run-tests'), 14:00:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:00:27 INFO - 'download_minidump_stackwalk': True, 14:00:27 INFO - 'download_symbols': 'ondemand', 14:00:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:00:27 INFO - 'tooltool.py': '/tools/tooltool.py', 14:00:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:00:27 INFO - '/tools/misc-python/virtualenv.py')}, 14:00:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:00:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:00:27 INFO - 'installer_path': 'installer.exe', 14:00:27 INFO - 'log_level': 'info', 14:00:27 INFO - 'log_name': 'talos', 14:00:27 INFO - 'log_to_console': True, 14:00:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:00:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:00:27 INFO - 'opt_config_files': (), 14:00:27 INFO - 'pip_index': False, 14:00:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:00:27 INFO - 'cmd': ('bash', 14:00:27 INFO - '-c', 14:00:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:00:27 INFO - 'enabled': True, 14:00:27 INFO - 'halt_on_failure': False, 14:00:27 INFO - 'name': 'check_screen_resolution'},), 14:00:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:00:27 INFO - 'cmd': ('bash', 14:00:27 INFO - '-c', 14:00:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:00:27 INFO - 'enabled': True, 14:00:27 INFO - 'halt_on_failure': False, 14:00:27 INFO - 'name': 'check_screen_resolution'},), 14:00:27 INFO - 'run_cmd_checks_enabled': True, 14:00:27 INFO - 'sps_profile': False, 14:00:27 INFO - 'sps_profile_interval': 0, 14:00:27 INFO - 'suite': 'dromaeojs-e10s', 14:00:27 INFO - 'system_bits': '32', 14:00:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:00:27 INFO - 'title': 't-yosemite-r7-0032', 14:00:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:00:27 INFO - 'use_talos_json': True, 14:00:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:00:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:00:27 INFO - 'work_dir': 'build'} 14:00:27 INFO - [mozharness: 2016-08-27 21:00:27.594260Z] Running clobber step. 14:00:27 INFO - Running pre-action listener: _resource_record_pre_action 14:00:27 INFO - Running main action method: clobber 14:00:27 INFO - rmtree: /builds/slave/test/build 14:00:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:00:29 INFO - Running post-action listener: _resource_record_post_action 14:00:29 INFO - [mozharness: 2016-08-27 21:00:29.554781Z] Finished clobber step (success) 14:00:29 INFO - [mozharness: 2016-08-27 21:00:29.554966Z] Running read-buildbot-config step. 14:00:29 INFO - Running pre-action listener: _resource_record_pre_action 14:00:29 INFO - Running main action method: read_buildbot_config 14:00:29 INFO - Using buildbot properties: 14:00:29 INFO - { 14:00:29 INFO - "project": "", 14:00:29 INFO - "product": "firefox", 14:00:29 INFO - "script_repo_revision": "production", 14:00:29 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 14:00:29 INFO - "repository": "", 14:00:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 14:00:29 INFO - "buildid": "20160827105213", 14:00:29 INFO - "pgo_build": "False", 14:00:29 INFO - "basedir": "/builds/slave/test", 14:00:29 INFO - "buildnumber": 773, 14:00:29 INFO - "slavename": "t-yosemite-r7-0032", 14:00:29 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 14:00:29 INFO - "platform": "yosemite_r7", 14:00:29 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 14:00:29 INFO - "branch": "fx-team", 14:00:29 INFO - "repo_path": "integration/fx-team", 14:00:29 INFO - "revision": "125b052639cb1879d4a593c715d244c551129118", 14:00:29 INFO - "stage_platform": "macosx64", 14:00:29 INFO - "builduid": "856d608892144123b0d518d75e509696", 14:00:29 INFO - "slavebuilddir": "test" 14:00:29 INFO - } 14:00:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 14:00:29 INFO - Running post-action listener: _resource_record_post_action 14:00:29 INFO - [mozharness: 2016-08-27 21:00:29.559187Z] Finished read-buildbot-config step (success) 14:00:29 INFO - [mozharness: 2016-08-27 21:00:29.559296Z] Running download-and-extract step. 14:00:29 INFO - Running pre-action listener: _resource_record_pre_action 14:00:29 INFO - Running main action method: download_and_extract 14:00:29 INFO - mkdir: /builds/slave/test/build/tests 14:00: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:29 INFO - trying https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 14:00:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 14:00:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 14:00:30 INFO - Downloaded 1199 bytes. 14:00:30 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 14:00:30 INFO - Using the following test package requirements: 14:00:30 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 14:00:30 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:30 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 14:00:30 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:30 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 14:00:30 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:30 INFO - u'jsshell-mac.zip'], 14:00:30 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:30 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 14:00:30 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 14:00:30 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:30 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 14:00:30 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:30 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 14:00:30 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:30 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 14:00:30 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:00:30 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 14:00:30 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:30 INFO - trying https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 14:00:30 INFO - Downloading https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 14:00:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:00:31 INFO - Downloaded 16369444 bytes. 14:00:31 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 14:00:38 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:00: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:38 INFO - trying https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 14:00:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 14:00:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:00:41 INFO - Downloaded 16369444 bytes. 14:00:41 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 14:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:48 INFO - trying https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 14:00:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 14:00:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:00:48 INFO - Downloaded 14413998 bytes. 14:00:48 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 14:00: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:49 INFO - trying https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 14:00:49 INFO - Downloading https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 14:00:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 14:00:52 INFO - Downloaded 111545990 bytes. 14:00:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 14:00:52 INFO - mkdir: /builds/slave/test/properties 14:00:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:00:52 INFO - Writing to file /builds/slave/test/properties/build_url 14:00:52 INFO - Contents: 14:00:52 INFO - build_url:https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 14:00:52 INFO - Running post-action listener: _resource_record_post_action 14:00:52 INFO - Running post-action listener: set_extra_try_arguments 14:00:52 INFO - [mozharness: 2016-08-27 21:00:52.918273Z] Finished download-and-extract step (success) 14:00:52 INFO - [mozharness: 2016-08-27 21:00:52.918397Z] Running populate-webroot step. 14:00:52 INFO - Running pre-action listener: _resource_record_pre_action 14:00:52 INFO - Running main action method: populate_webroot 14:00:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:00:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:00:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:00:52 INFO - '--tppagecycles', 14:00:52 INFO - '7'], 14:00:52 INFO - 'tests': ['tsvgm']}}, 14:00:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:00:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:00:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:00:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:00:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:00:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:00:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:52 INFO - 'talos_options': ['--disable-e10s'], 14:00:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:00:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:00:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:52 INFO - 'talos_options': ['--disable-e10s'], 14:00:52 INFO - 'tests': ['damp', 'tps']}, 14:00:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:52 INFO - 'tests': ['damp', 'tps']}, 14:00:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:00:52 INFO - 'tests': ['dromaeo_dom']}, 14:00:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:00:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:00:52 INFO - 'tests': ['basic_compositor_video']}, 14:00:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:00:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:00:52 INFO - 'tests': ['a11yr', 14:00:52 INFO - 'ts_paint', 14:00:52 INFO - 'tpaint', 14:00:52 INFO - 'sessionrestore', 14:00:52 INFO - 'sessionrestore_no_auto_restore', 14:00:52 INFO - 'tabpaint']}, 14:00:52 INFO - 'other-e10s': {'tests': ['a11yr', 14:00:52 INFO - 'ts_paint', 14:00:52 INFO - 'tpaint', 14:00:52 INFO - 'sessionrestore', 14:00:52 INFO - 'sessionrestore_no_auto_restore', 14:00:52 INFO - 'tabpaint']}, 14:00:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:00:52 INFO - 'ts_paint', 14:00:52 INFO - 'tpaint', 14:00:52 INFO - 'sessionrestore', 14:00:52 INFO - 'sessionrestore_no_auto_restore', 14:00:52 INFO - 'tabpaint']}, 14:00:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:00:52 INFO - 'ts_paint', 14:00:52 INFO - 'tpaint', 14:00:52 INFO - 'sessionrestore', 14:00:52 INFO - 'sessionrestore_no_auto_restore', 14:00:52 INFO - 'tabpaint']}, 14:00:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:00:52 INFO - 'tests': ['a11yr', 14:00:52 INFO - 'ts_paint', 14:00:52 INFO - 'tpaint', 14:00:52 INFO - 'sessionrestore', 14:00:52 INFO - 'sessionrestore_no_auto_restore', 14:00:52 INFO - 'tabpaint']}, 14:00:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:00:52 INFO - 'tests': ['a11yr', 14:00:52 INFO - 'ts_paint', 14:00:52 INFO - 'tpaint', 14:00:52 INFO - 'sessionrestore', 14:00:52 INFO - 'sessionrestore_no_auto_restore', 14:00:52 INFO - 'tabpaint']}, 14:00:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:00:52 INFO - 'tests': ['tsvgx', 14:00:52 INFO - 'tsvgr_opacity', 14:00:52 INFO - 'tart', 14:00:52 INFO - 'tscrollx', 14:00:52 INFO - 'cart']}, 14:00:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:00:52 INFO - 'tsvgr_opacity', 14:00:52 INFO - 'tart', 14:00:52 INFO - 'tscrollx', 14:00:52 INFO - 'cart']}, 14:00:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:52 INFO - 'talos_options': ['--disable-e10s'], 14:00:52 INFO - 'tests': ['tp5o']}, 14:00:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:52 INFO - 'tests': ['tp5o']}, 14:00:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:52 INFO - 'talos_options': ['--disable-e10s', 14:00:52 INFO - '--xperf_path', 14:00:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:00:52 INFO - 'tests': ['tp5n']}, 14:00:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:52 INFO - 'talos_options': ['--xperf_path', 14:00:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:00:52 INFO - 'tests': ['tp5n']}}, 14:00:52 INFO - 'talos.zip': {'path': '', 14:00:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:00:52 INFO - Running post-action listener: _resource_record_post_action 14:00:52 INFO - [mozharness: 2016-08-27 21:00:52.936829Z] Finished populate-webroot step (success) 14:00:52 INFO - [mozharness: 2016-08-27 21:00:52.936942Z] Running create-virtualenv step. 14:00:52 INFO - Running pre-action listener: _resource_record_pre_action 14:00:52 INFO - Running main action method: create_virtualenv 14:00:52 INFO - Creating virtualenv /builds/slave/test/build/venv 14:00:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:00:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:00:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:00:53 INFO - Using real prefix '/tools/python27' 14:00:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:00:53 INFO - Installing distribute.............................................................................................................................................................................................done. 14:00:56 INFO - Installing pip.................done. 14:00:56 INFO - Return code: 0 14:00:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f808a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f89d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d8c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f76cd30>, '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 0x10f4d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f764b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:00:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:00:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 14:00:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:56 INFO - 'HOME': '/Users/cltbld', 14:00:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:56 INFO - 'LOGNAME': 'cltbld', 14:00:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:56 INFO - 'MOZ_NO_REMOTE': '1', 14:00:56 INFO - 'NO_EM_RESTART': '1', 14:00:56 INFO - 'PAGER': '/bin/cat', 14:00:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:56 INFO - 'PWD': '/builds/slave/test', 14:00:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:56 INFO - 'SHELL': '/bin/bash', 14:00:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 14:00:56 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 14:00:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:56 INFO - 'USER': 'cltbld', 14:00:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:56 INFO - 'XPC_FLAGS': '0x0', 14:00:56 INFO - 'XPC_SERVICE_NAME': '0', 14:00:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:00:56 INFO - Cleaning up... 14:00:56 INFO - Return code: 0 14:00:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f808a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f89d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d8c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f76cd30>, '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 0x10f4d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f764b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:00:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:00:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 14:00:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:56 INFO - 'HOME': '/Users/cltbld', 14:00:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:56 INFO - 'LOGNAME': 'cltbld', 14:00:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:56 INFO - 'MOZ_NO_REMOTE': '1', 14:00:56 INFO - 'NO_EM_RESTART': '1', 14:00:56 INFO - 'PAGER': '/bin/cat', 14:00:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:56 INFO - 'PWD': '/builds/slave/test', 14:00:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:56 INFO - 'SHELL': '/bin/bash', 14:00:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 14:00:56 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 14:00:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:56 INFO - 'USER': 'cltbld', 14:00:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:56 INFO - 'XPC_FLAGS': '0x0', 14:00:56 INFO - 'XPC_SERVICE_NAME': '0', 14:00:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:57 INFO - Downloading/unpacking psutil>=3.1.1 14:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:00:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:01:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:01:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:01:00 INFO - Installing collected packages: psutil 14:01:00 INFO - Running setup.py install for psutil 14:01:00 INFO - building 'psutil._psutil_osx' extension 14:01:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:01:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:01:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:01:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:01:01 INFO - building 'psutil._psutil_posix' extension 14:01:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:01:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:01:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:01:01 INFO - ^ 14:01:01 INFO - 1 warning generated. 14:01:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:01:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:01:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:01:01 INFO - Successfully installed psutil 14:01:01 INFO - Cleaning up... 14:01:01 INFO - Return code: 0 14:01:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f808a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f89d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d8c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f76cd30>, '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 0x10f4d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f764b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:01:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:01:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:01:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 14:01:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:01 INFO - 'HOME': '/Users/cltbld', 14:01:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:01 INFO - 'LOGNAME': 'cltbld', 14:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:01 INFO - 'MOZ_NO_REMOTE': '1', 14:01:01 INFO - 'NO_EM_RESTART': '1', 14:01:01 INFO - 'PAGER': '/bin/cat', 14:01:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:01 INFO - 'PWD': '/builds/slave/test', 14:01:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:01 INFO - 'SHELL': '/bin/bash', 14:01:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 14:01:01 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 14:01:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:01 INFO - 'USER': 'cltbld', 14:01:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:01 INFO - 'XPC_FLAGS': '0x0', 14:01:01 INFO - 'XPC_SERVICE_NAME': '0', 14:01:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:01:04 INFO - Installing collected packages: mozsystemmonitor 14:01:04 INFO - Running setup.py install for mozsystemmonitor 14:01:04 INFO - Successfully installed mozsystemmonitor 14:01:04 INFO - Cleaning up... 14:01:04 INFO - Return code: 0 14:01:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f808a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f89d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d8c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f76cd30>, '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 0x10f4d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f764b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:01:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:01:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 14:01:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:04 INFO - 'HOME': '/Users/cltbld', 14:01:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:04 INFO - 'LOGNAME': 'cltbld', 14:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:04 INFO - 'MOZ_NO_REMOTE': '1', 14:01:04 INFO - 'NO_EM_RESTART': '1', 14:01:04 INFO - 'PAGER': '/bin/cat', 14:01:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:04 INFO - 'PWD': '/builds/slave/test', 14:01:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:04 INFO - 'SHELL': '/bin/bash', 14:01:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 14:01:04 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 14:01:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:04 INFO - 'USER': 'cltbld', 14:01:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:04 INFO - 'XPC_FLAGS': '0x0', 14:01:04 INFO - 'XPC_SERVICE_NAME': '0', 14:01:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:05 INFO - Downloading/unpacking jsonschema==2.5.1 14:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:01:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:07 INFO - Downloading functools32-3.2.3-2.tar.gz 14:01:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:01:08 INFO - warning: no files found matching '*.txt' 14:01:08 INFO - no previously-included directories found matching 'build' 14:01:08 INFO - no previously-included directories found matching 'dist' 14:01:08 INFO - no previously-included directories found matching '.git*' 14:01:08 INFO - Installing collected packages: jsonschema, functools32 14:01:08 INFO - Running setup.py install for functools32 14:01:08 INFO - warning: no files found matching '*.txt' 14:01:08 INFO - no previously-included directories found matching 'build' 14:01:08 INFO - no previously-included directories found matching 'dist' 14:01:08 INFO - no previously-included directories found matching '.git*' 14:01:08 INFO - Successfully installed jsonschema functools32 14:01:08 INFO - Cleaning up... 14:01:08 INFO - Return code: 0 14:01:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:01: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:08 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 0x10f808a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f89d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d8c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f76cd30>, '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 0x10f4d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f764b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:01:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:01:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:01:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 14:01:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:08 INFO - 'HOME': '/Users/cltbld', 14:01:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:08 INFO - 'LOGNAME': 'cltbld', 14:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:08 INFO - 'MOZ_NO_REMOTE': '1', 14:01:08 INFO - 'NO_EM_RESTART': '1', 14:01:08 INFO - 'PAGER': '/bin/cat', 14:01:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:08 INFO - 'PWD': '/builds/slave/test', 14:01:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:08 INFO - 'SHELL': '/bin/bash', 14:01:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 14:01:08 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 14:01:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:08 INFO - 'USER': 'cltbld', 14:01:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:08 INFO - 'XPC_FLAGS': '0x0', 14:01:08 INFO - 'XPC_SERVICE_NAME': '0', 14:01:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:01:08 INFO - Cleaning up... 14:01:08 INFO - Return code: 0 14:01:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:01: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:08 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 0x10f808a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f89d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d8c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f76cd30>, '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 0x10f4d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f764b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:01:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:01:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:01:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 14:01:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:08 INFO - 'HOME': '/Users/cltbld', 14:01:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:08 INFO - 'LOGNAME': 'cltbld', 14:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:08 INFO - 'MOZ_NO_REMOTE': '1', 14:01:08 INFO - 'NO_EM_RESTART': '1', 14:01:08 INFO - 'PAGER': '/bin/cat', 14:01:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:08 INFO - 'PWD': '/builds/slave/test', 14:01:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:08 INFO - 'SHELL': '/bin/bash', 14:01:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 14:01:08 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 14:01:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:08 INFO - 'USER': 'cltbld', 14:01:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:08 INFO - 'XPC_FLAGS': '0x0', 14:01:08 INFO - 'XPC_SERVICE_NAME': '0', 14:01:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:08 INFO - Downloading/unpacking blobuploader==1.2.4 14:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:11 INFO - Downloading blobuploader-1.2.4.tar.gz 14:01:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:01:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:01:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:12 INFO - Downloading docopt-0.6.1.tar.gz 14:01:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:01:12 INFO - Installing collected packages: blobuploader, requests, docopt 14:01:12 INFO - Running setup.py install for blobuploader 14:01:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:01:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:01:12 INFO - Running setup.py install for requests 14:01:13 INFO - Running setup.py install for docopt 14:01:13 INFO - Successfully installed blobuploader requests docopt 14:01:13 INFO - Cleaning up... 14:01:13 INFO - Return code: 0 14:01:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:01:13 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:13 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:13 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 0x10f808a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f89d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d8c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f76cd30>, '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 0x10f4d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f764b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:01:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:01:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:01:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 14:01:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:13 INFO - 'HOME': '/Users/cltbld', 14:01:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:13 INFO - 'LOGNAME': 'cltbld', 14:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:13 INFO - 'MOZ_NO_REMOTE': '1', 14:01:13 INFO - 'NO_EM_RESTART': '1', 14:01:13 INFO - 'PAGER': '/bin/cat', 14:01:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:13 INFO - 'PWD': '/builds/slave/test', 14:01:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:13 INFO - 'SHELL': '/bin/bash', 14:01:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 14:01:13 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 14:01:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:13 INFO - 'USER': 'cltbld', 14:01:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:13 INFO - 'XPC_FLAGS': '0x0', 14:01:13 INFO - 'XPC_SERVICE_NAME': '0', 14:01:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:01:13 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-9624az-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:01:13 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-sD71ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:01:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:01:13 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-6tY_6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:01:14 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-S5jqOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:01:14 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-FHSmKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:01:14 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-lsq4Y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:01:14 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-aY2mUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:01:14 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-__Ui67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:01:14 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-9pFzwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:01:14 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-cdYGAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:01:14 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-OUc3gd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:01:14 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-jhjoKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:01:14 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-askiJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:01:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-S2iVyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:01:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-E2oo4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:01:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-XZEq8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:01:15 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-iWVCQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:01:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:01:15 INFO - Running setup.py install for manifestparser 14:01:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:01:15 INFO - Running setup.py install for mozcrash 14:01:15 INFO - Running setup.py install for mozdebug 14:01:15 INFO - Running setup.py install for mozdevice 14:01:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:01:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:01:16 INFO - Running setup.py install for mozfile 14:01:16 INFO - Running setup.py install for mozhttpd 14:01:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:01:16 INFO - Running setup.py install for mozinfo 14:01:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:01:16 INFO - Running setup.py install for mozInstall 14:01:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:01:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:01:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:01:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:01:16 INFO - Running setup.py install for mozleak 14:01:16 INFO - Running setup.py install for mozlog 14:01:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:01:17 INFO - Running setup.py install for moznetwork 14:01:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:01:17 INFO - Running setup.py install for mozprocess 14:01:17 INFO - Running setup.py install for mozprofile 14:01:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:01:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:01:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:01:17 INFO - Running setup.py install for mozrunner 14:01:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:01:17 INFO - Running setup.py install for mozscreenshot 14:01:17 INFO - Running setup.py install for moztest 14:01:17 INFO - Running setup.py install for mozversion 14:01:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:01:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:01:18 INFO - Cleaning up... 14:01:18 INFO - Return code: 0 14:01:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:01:18 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:18 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:18 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 0x10f808a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f89d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d8c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f76cd30>, '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 0x10f4d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f764b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:01:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:01:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:01:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 14:01:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:18 INFO - 'HOME': '/Users/cltbld', 14:01:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:18 INFO - 'LOGNAME': 'cltbld', 14:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:18 INFO - 'MOZ_NO_REMOTE': '1', 14:01:18 INFO - 'NO_EM_RESTART': '1', 14:01:18 INFO - 'PAGER': '/bin/cat', 14:01:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:18 INFO - 'PWD': '/builds/slave/test', 14:01:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:18 INFO - 'SHELL': '/bin/bash', 14:01:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 14:01:18 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 14:01:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:18 INFO - 'USER': 'cltbld', 14:01:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:18 INFO - 'XPC_FLAGS': '0x0', 14:01:18 INFO - 'XPC_SERVICE_NAME': '0', 14:01:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:01:18 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-t2fldT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:01:18 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-ZwYDil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:01:18 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-mIU16D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:01:18 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-HCdz5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:01:18 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-ak2GJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:01:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:01:18 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-jIHW_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:01:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:01:19 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-gepRbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:01:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:01:19 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-oYoePF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:01:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:01:19 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-JOtHVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:01:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:01:19 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-WDSlYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:01:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:01:19 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-daeTOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:01:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:01:19 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-U_ytiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:01:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:01:19 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-7XlPwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:01:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:01:19 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-NB2_F7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:01:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:01:19 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-yrg_fN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:01:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:01:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:01:20 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-nye78b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:01:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:01:20 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-LP_CUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:01:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:01:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:22 INFO - Downloading blessings-1.6.tar.gz 14:01:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:01:22 INFO - Installing collected packages: blessings 14:01:22 INFO - Running setup.py install for blessings 14:01:22 INFO - Successfully installed blessings 14:01:22 INFO - Cleaning up... 14:01:23 INFO - Return code: 0 14:01:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:01:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:01:23 INFO - Reading from file tmpfile_stdout 14:01:23 INFO - Current package versions: 14:01:23 INFO - blessings == 1.6 14:01:23 INFO - blobuploader == 1.2.4 14:01:23 INFO - docopt == 0.6.1 14:01:23 INFO - functools32 == 3.2.3-2 14:01:23 INFO - jsonschema == 2.5.1 14:01:23 INFO - manifestparser == 1.1 14:01:23 INFO - mozInstall == 1.12 14:01:23 INFO - mozcrash == 0.17 14:01:23 INFO - mozdebug == 0.1 14:01:23 INFO - mozdevice == 0.48 14:01:23 INFO - mozfile == 1.2 14:01:23 INFO - mozhttpd == 0.7 14:01:23 INFO - mozinfo == 0.9 14:01:23 INFO - mozleak == 0.1 14:01:23 INFO - mozlog == 3.3 14:01:23 INFO - moznetwork == 0.27 14:01:23 INFO - mozprocess == 0.23 14:01:23 INFO - mozprofile == 0.28 14:01:23 INFO - mozrunner == 6.12 14:01:23 INFO - mozscreenshot == 0.1 14:01:23 INFO - mozsystemmonitor == 0.3 14:01:23 INFO - moztest == 0.7 14:01:23 INFO - mozversion == 1.4 14:01:23 INFO - psutil == 3.1.1 14:01:23 INFO - requests == 1.2.3 14:01:23 INFO - wsgiref == 0.1.2 14:01:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:23 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 0x10f808a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f89d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d8c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f76cd30>, '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 0x10f4d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f764b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:01:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:01:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 14:01:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:23 INFO - 'HOME': '/Users/cltbld', 14:01:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:23 INFO - 'LOGNAME': 'cltbld', 14:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:23 INFO - 'MOZ_NO_REMOTE': '1', 14:01:23 INFO - 'NO_EM_RESTART': '1', 14:01:23 INFO - 'PAGER': '/bin/cat', 14:01:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:23 INFO - 'PWD': '/builds/slave/test', 14:01:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:23 INFO - 'SHELL': '/bin/bash', 14:01:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 14:01:23 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 14:01:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:23 INFO - 'USER': 'cltbld', 14:01:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:23 INFO - 'XPC_FLAGS': '0x0', 14:01:23 INFO - 'XPC_SERVICE_NAME': '0', 14:01:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:01:23 INFO - Cleaning up... 14:01:23 INFO - Return code: 0 14:01:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f808a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f89d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88d8c8eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f76cd30>, '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 0x10f4d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f764b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:01:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:01:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 14:01:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:23 INFO - 'HOME': '/Users/cltbld', 14:01:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:23 INFO - 'LOGNAME': 'cltbld', 14:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:23 INFO - 'MOZ_NO_REMOTE': '1', 14:01:23 INFO - 'NO_EM_RESTART': '1', 14:01:23 INFO - 'PAGER': '/bin/cat', 14:01:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:23 INFO - 'PWD': '/builds/slave/test', 14:01:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:23 INFO - 'SHELL': '/bin/bash', 14:01:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 14:01:23 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 14:01:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:23 INFO - 'USER': 'cltbld', 14:01:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:23 INFO - 'XPC_FLAGS': '0x0', 14:01:23 INFO - 'XPC_SERVICE_NAME': '0', 14:01:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:01:23 INFO - Cleaning up... 14:01:23 INFO - Return code: 0 14:01:23 INFO - Running post-action listener: _resource_record_post_action 14:01:23 INFO - Running post-action listener: _start_resource_monitoring 14:01:23 INFO - Starting resource monitoring. 14:01:23 INFO - [mozharness: 2016-08-27 21:01:23.894612Z] Finished create-virtualenv step (success) 14:01:23 INFO - [mozharness: 2016-08-27 21:01:23.895616Z] Running install step. 14:01:23 INFO - Running pre-action listener: _resource_record_pre_action 14:01:23 INFO - Running main action method: install 14:01:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:01:24 INFO - Reading from file tmpfile_stdout 14:01:24 INFO - Detecting whether we're running mozinstall >=1.0... 14:01:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:01:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:01:24 INFO - Reading from file tmpfile_stdout 14:01:24 INFO - Output received: 14:01:24 INFO - Usage: mozinstall [options] installer 14:01:24 INFO - Options: 14:01:24 INFO - -h, --help show this help message and exit 14:01:24 INFO - -d DEST, --destination=DEST 14:01:24 INFO - Directory to install application into. [default: 14:01:24 INFO - "/builds/slave/test"] 14:01:24 INFO - --app=APP Application being installed. [default: firefox] 14:01:24 INFO - mkdir: /builds/slave/test/build/application 14:01:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:01:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:01:58 INFO - Reading from file tmpfile_stdout 14:01:58 INFO - Output received: 14:01:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:01:58 INFO - Running post-action listener: _resource_record_post_action 14:01:58 INFO - [mozharness: 2016-08-27 21:01:58.827067Z] Finished install step (success) 14:01:58 INFO - [mozharness: 2016-08-27 21:01:58.827216Z] Running run-tests step. 14:01:58 INFO - Running pre-action listener: _resource_record_pre_action 14:01:58 INFO - Running main action method: run_tests 14:01:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:01:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:01:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:01:58 INFO - 2016-08-27 14:01:58.841 screenresolution[2013:33646] starting screenresolution argv=screenresolution get 14:01:58 INFO - 2016-08-27 14:01:58.844 screenresolution[2013:33646] Display 0: 1600x1200x32@60 14:01:58 INFO - 2016-08-27 14:01:58.850 screenresolution[2014:33648] starting screenresolution argv=screenresolution list 14:01:58 INFO - Available Modes on Display 0 14:01:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:01:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:01:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:01:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:01:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:01:59 INFO - Intel Iris: 14:01:59 INFO - Chipset Model: Intel Iris 14:01:59 INFO - Type: GPU 14:01:59 INFO - Bus: Built-In 14:01:59 INFO - VRAM (Dynamic, Max): 1536 MB 14:01:59 INFO - Vendor: Intel (0x8086) 14:01:59 INFO - Device ID: 0x0a2e 14:01:59 INFO - Revision ID: 0x0009 14:01:59 INFO - Displays: 14:01:59 INFO - Display: 14:01:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:01:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:01:59 INFO - Display Serial Number: 1600x1200 60 14:01:59 INFO - Main Display: Yes 14:01:59 INFO - Mirror: Off 14:01:59 INFO - Online: Yes 14:01:59 INFO - Rotation: Supported 14:01:59 INFO - Return code: 0 14:01:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:01:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:01:59 INFO - Python 2.7.3 14:01:59 INFO - Return code: 0 14:01:59 INFO - grabbing minidump binary from tooltool 14:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:59 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/macosx64/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 0x10f76cd30>, '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 0x10f4d1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f764b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:01:59 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:01:59 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:01:59 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:01:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:01:59 INFO - Return code: 0 14:01:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:01:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:01:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:01:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:01:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:01:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0032', '--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 14:01:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0032 --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 14:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w5iHHvXnk7/Render', 14:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:59 INFO - 'HOME': '/Users/cltbld', 14:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:59 INFO - 'LOGNAME': 'cltbld', 14:01:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:01:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:59 INFO - 'MOZ_NO_REMOTE': '1', 14:01:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:01:59 INFO - 'NO_EM_RESTART': '1', 14:01:59 INFO - 'PAGER': '/bin/cat', 14:01:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:59 INFO - 'PWD': '/builds/slave/test', 14:01:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:59 INFO - 'SHELL': '/bin/bash', 14:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners', 14:01:59 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 14:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:59 INFO - 'USER': 'cltbld', 14:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:59 INFO - 'XPC_FLAGS': '0x0', 14:01:59 INFO - 'XPC_SERVICE_NAME': '0', 14:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0032', '--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 14:01:59 INFO - mozversion application_buildid: 20160827105213 14:01:59 INFO - mozversion application_changeset: 125b052639cb1879d4a593c715d244c551129118 14:01:59 INFO - mozversion application_display_name: Nightly 14:01:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:01:59 INFO - mozversion application_name: Firefox 14:01:59 INFO - mozversion application_remotingname: firefox 14:01:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:01:59 INFO - mozversion application_vendor: Mozilla 14:01:59 INFO - mozversion application_version: 51.0a1 14:01:59 INFO - mozversion platform_buildid: 20160827105213 14:01:59 INFO - mozversion platform_changeset: 125b052639cb1879d4a593c715d244c551129118 14:01:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:01:59 INFO - mozversion platform_version: 51.0a1 14:01:59 INFO - using testdate: 1472331719 14:01:59 INFO - actual date: 1472331719 14:01:59 INFO - starting webserver on 'localhost:56081' 14:01:59 INFO - SUITE-START | Running 2 tests 14:01:59 INFO - TEST-START | dromaeo_css 14:01:59 INFO - operating with platform_type : mac_ 14:01:59 INFO - Initialising browser for dromaeo_css test... 14:01:59 INFO - TEST-INFO | started process 2024 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpo1skxV/profile http://localhost:56081/getInfo.html) 14:02:02 INFO - PROCESS | 2024 | __metrics Screen width/height:1600/1200 14:02:02 INFO - PROCESS | 2024 | colorDepth:24 14:02:02 INFO - PROCESS | 2024 | Browser inner width/height: 1024/689 14:02:02 INFO - PROCESS | 2024 | __metrics 14:02:02 ERROR - PROCESS | 2024 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 14:02:02 INFO - TEST-INFO | 2024: exit 0 14:02:02 INFO - Browser initialized. 14:02:02 INFO - Running cycle 1/1 for dromaeo_css test... 14:02:02 INFO - TEST-INFO | started process 2027 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpo1skxV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:02:14 INFO - PROCESS | 2027 | RSS: Main: 243855360 14:02:14 INFO - PROCESS | 2027 | 14:03:04 INFO - PROCESS | 2027 | 1472331784538 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:05:16 INFO - PROCESS | 2027 | Cycle 1(1): loaded http://localhost:56081/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56081/tests/dromaeo/cssquery-ext.html) 14:05:18 INFO - PROCESS | 2027 | RSS: Main: 266928128 14:05:18 INFO - PROCESS | 2027 | 14:08:21 INFO - PROCESS | 2027 | Cycle 1(1): loaded http://localhost:56081/tests/dromaeo/cssquery-ext.html (next: http://localhost:56081/tests/dromaeo/cssquery-jquery.html) 14:08:21 INFO - PROCESS | 2027 | RSS: Main: 275419136 14:08:21 INFO - PROCESS | 2027 | 14:11:23 INFO - PROCESS | 2027 | Cycle 1(1): loaded http://localhost:56081/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56081/tests/dromaeo/cssquery-mootools.html) 14:11:24 INFO - PROCESS | 2027 | RSS: Main: 276422656 14:11:24 INFO - PROCESS | 2027 | 14:14:26 INFO - PROCESS | 2027 | Cycle 1(1): loaded http://localhost:56081/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56081/tests/dromaeo/cssquery-prototype.html) 14:14:26 INFO - PROCESS | 2027 | RSS: Main: 271798272 14:14:26 INFO - PROCESS | 2027 | 14:17:29 INFO - PROCESS | 2027 | Cycle 1(1): loaded http://localhost:56081/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56081/tests/dromaeo/cssquery-yui.html) 14:17:30 INFO - PROCESS | 2027 | RSS: Main: 277143552 14:17:30 INFO - PROCESS | 2027 | 14:20:33 INFO - PROCESS | 2027 | Cycle 1(1): loaded http://localhost:56081/tests/dromaeo/cssquery-yui.html (next: http://localhost:56081/tests/dromaeo/cssquery-dojo.html) 14:20:33 INFO - PROCESS | 2027 | RSS: Main: 279851008 14:20:33 INFO - PROCESS | 2027 | 14:20:33 INFO - PROCESS | 2027 | __start_tp_report 14:20:33 INFO - PROCESS | 2027 | _x_x_mozilla_page_load 14:20:33 INFO - PROCESS | 2027 | _x_x_mozilla_page_load_details 14:20:33 INFO - PROCESS | 2027 | |i|pagename|runs| 14:20:33 INFO - PROCESS | 2027 | |0;dojo.html;2779.388534522407;3402.6767457091573;3419.9487007694875;3436.450167973124;3455.118944669109;5666.688332141731;5833.212516310296;5868.61853979491;5912.467877890989;5917.319508256546;4553.27147656375;5551.472610102044;5814.854628634286;5892.528597712183;5988.862116197919;5245.580353571715;5355.116405793039;5387.622866399353;5409.999150157233;5413.323334583177;4134.441850350209;5078.466760990098;5235.11003129467;5259.079661059319;5309.681419114846;4408.647308215335;5160.380754309469;5259.079661059319;5294.258803767457;5307.761150748225;3998.460207871958;5059;5282.181261904411;5316.175992721134;5351.250824884481;5104.872378190538;5189.870253243661;5238.240455133984;5266.683998960075;5290.523852853262;5742.397048309951;5839.970800146015;5858.53131749459;5868.325142608612;5882.147088672119;6143.1092491588915;6193.163922870445;6194.473469755077;6206.751729930797;6216.627002379872;6179.938200617981;6193.102000209944;6259.37406259375;6283.905741413882;6288.685565721696;5936.643801371845;5954.642721436728;5973.880522389528;5993.820185394402;6004.759809607567;6356.745730170834;6386.457151142112;6394.680265986682;6401.103845461639;6421.743130274736;6119.5104391648565;6142.007694998287;6146.401935496803;6157.922363586355;6221.906671399933;6191.318954914956;6219.689015549295;6223;6253.1245625612455;6254.6872656366995;4904.705717656952;6218.595791273553;6256.249250090018;6260.874782504325;6262.968685156545;6148.8770224595255;6156.353582873823;6163.3836616338;6168.938310616926;6213.689315534205;4949.381327334083;4973.229149481836;4981.252812078217;4996.800127994839;5007.173816320266;4497.100579884036;4508.954910450919;4520.683552151384;4527.637788976874;4539.205639013159;5451.509364157245;5796.072628379523;5841.269841269841;5866.911996320058;5972.193753843176;6210.285817131088;6211.751529938842;6213;6215.689215539205;6237.750489980441;4879.097366987119;5840.1239814027385;5848.976429124886;5936.347001829795;5938.851528711817;5384.380796208407;5438.809641662523;5442;5466.808661696821;5483.122700367922;6424.678766061772;6469.902951455638;6482.54622176462;6488.059231411513;6505.642189679613;6233.470155036873;6251.218597675291;6264.404881536247;6268.122462855295;6359.046143078575;6240.12638230639;6338.080978258219;6353.4599559038;6373.840653983688;6470.838229044311;6204.844878877883;6215.316315205233;6222.128901953821;6227.719752611053;6237.563370564017;6136.987397079611;6203.472704820141;6206.224221972254;6238.189035425547;6244.1570387997035;4620.330052142488;5877.118432235199;6132.845047373448;6140.412224875342;6221;5741.311042674905;5908.970455147697;6099.725512352044;6207.782727604512;6208.0998255253635;5466.289382380265;6094.908576371269;6096.0855871619615;6103.572749907641;6128.785492507741;5614.550835933197;6107.917136652877;6108.969455152695;6169.50643948474;6183.319834818077;6029.57792954489;6060.878782424415;6075.544334174866;6099.969500152648;6109.450149486567 14:20:33 INFO - PROCESS | 2027 | |1;ext.html;443.9589163411799;464.6491898616544;481.8772260632725;505.3481009497748;516.382922407723;32157.678423215762;40128.19743605126;40211.39682904759;40507;41252;41053.17893642125;41188.38217426741;41599;41626.37560436596;41728.37407438891;9527.857082143792;9739.707808765714;9740.4642744649;9746.31775775696;9772.511374431286;8242.423030387876;9777.511124443785;9787.217022638171;9808.607655693764;9834.508274586278;9782.755431114208;9789.265805064613;9794.363366381198;9847.409155450696;9854.655087071955;9771.657991970282;9775.31572789905;9782.755431114243;9828.852567211497;9858.90141098587;9550.235981121563;9623.03769623039;9624.615015399375;9635.759106022335;9672.56473458696;39286.60713392887;39652.81041568758;39725.20549589021;39887.20225595472;39922.800385998176;2939.808912420686;3072.8002679825963;3079.8306093164865;3367.2255381262203;3739.5699494558203;30863.2284192897;31081.844590777127;31157.065288041395;31528.842355788303;32444;3092.196029032445;3132.2795756975797;3156.037408590379;3211.550382946389;3221.64561898191;485575;491396;496528;496667;499835.5008224972;63334;63485.68257158685;63666.68166659137;63930.68034659797;64229;33194.50208246878;34836.651633483845;35896.820515897256;37055.814720926224;37093.62906370902;16427.91786041062;16569.08870012161;16636;16652.333906643842;16672.83327166713;12617.936910315573;12711.110222284537;12720.809187862189;12735.172213806072;12737.490500380067;18700.625987480173;19059.90470047641;20479.590408191754;20615.38153855387;20694.068766905526;7406.407487400995;8812.250958668457;8923.643054277885;8955.2835773138;9052.456852588866;848.9702860399856;983.3657291047234;1018.3838777539482;1031.5358088859894;1043.9791204175874;23036.884815575813;24098;25553.23340299794;25606.74393256081;29145.417091658474;796.1163108949062;1033.106362996018;1048.3238311289176;1051.206339213904;1133.2916926920675;13046.869531304756;14590.416383344547;15094.698106037818;15534.611634709223;15579.647052384964;616255;618843;620876;623063;623545;48657.7835554114;57243.42756572464;57629.71185144047;57701;58049.4195058044;1340.684939039325;1355.6678613739698;1375.3673310277184;1393.9093958892843;1396.043710058613;28211.57682634803;29413;29595.556066658588;29964;30366;50038.249426257906;50445.74777126091;50455;50491.747541262055;50816.491835082656;549471.2949399346;549497;550605;551264;554101;26970.32574185661;27145.72854271432;27191.72808271892;27253;27372.726272737018;47177.528224718684;47973;48039.039219216116;48134.518654814405;48280;31353.372932540766;38021.80989095037;39103.2179356417;39389;40537.391939120345;38752.80623596864;38812.80593597014;39119.80440097781;39897.80051099842;40092.79953600213;32065;37457.62542374541;37953.81023094867;38443;38845.22309553737;29943.85028074846;32524.83737581297;33895;34110.147246319044;34820.82589587036;25679.871600641876;25718.87140564285;25802.225933222035;26011.82946767438;26239 14:20:33 INFO - PROCESS | 2027 | |2;jquery.html;335.3795478365024;369.56391458079474;376.97926614036226;383.1952898912283;399.30520893645047;16785.66428671426;19586.706199407003;19998.60002799943;20580.588388232245;20801;19979;20006.59986800263;20396.388108356772;20496.282630107948;20666.89666551665;5788.5658575606785;5897.203877476529;5923.5853490255595;6157.538184636148;6163.167972323746;5090.414602320725;5234.712090835022;5302.522772950434;6064.848378790543;6133.478654314366;6012.819615411545;6086.117512960618;6112.419320164599;6510.9023364649565;6817.69320380584;6651.467882569383;6843.692033858463;6886;6908.585484870924;6936.4797640177185;6024.1566180734735;6034;6061.333253342129;6063.878722425527;6072.997955337364;11660.00889924349;12657.43041563132;12968.092233543655;12968.286744229064;13091.934540327238;2102.7266455360705;2106.884121373324;2218.367765186914;2311.7688231176917;2356.5274623039686;9664.466746213659;9685.70942871715;9718.951405242928;10042.690181258278;10114.039166279192;1851.5556266495944;1954.6774782160926;1964.1750464804816;1966.8131527504863;1967.4982879365814;97996;112514.43742781234;128111;132719.6728032717;134815.999999999;9350.485723285148;9596.856047159297;9846.606135754633;10015.349002314826;10143.949280253551;12151.210171338835;12205.619904118788;12217.328046957502;12228.266304021787;12232;6753;7963.721269755531;8117.756467305991;8227.218414250636;8275.668973241123;8203.30271926893;8433.746987590383;8436.072032076467;8650.826983460296;8659.004214515286;3867.2458870519977;3871.9806400967814;3917.3732202847978;3925.1757131002237;3975.662068724133;1877.2303355624128;1922.5770330527262;1927.6530224559715;1932.7970563090955;1976.416956997689;717.9517903860423;839.8242460555149;906.207068814787;1035.420164707751;1042.3849928542197;15355.769663455058;19229.615407691766;20472.69290960637;20800.583988320148;21234.150633974776;814.7515007922522;868.1925808997566;877.3025444771422;913.3971578757988;924.67174153176;8318.37612179089;9143.268538516902;10523.105536029523;10667.413292268844;10855.751588567258;507512;550151;561659;563996;564978;103936.48031759793;106417;108175.45912270388;129172;137091;895.552223888056;955.1833182628891;977.8239916814899;1020.7295066807302;1022.4836457589018;16840.494785156465;18767.34314298993;19080.332188373897;19168.945707986193;19182.712259315842;37714.811425942695;38041.717496950274;45696;51442.228366573785;55934;77687.61156194408;78114;79012.60493697495;82801.92995175169;84077.70678984465;6439.903401448888;7542.208068152765;7585.924140758521;7677.539347639159;7853.646585903533;10512.316699414514;11713.887180717818;11729;12055.879441205476;12210.023198144305;8455.704050358208;10149.543270552991;10381.377117372982;10384.792304153723;10428.27002109845;9909.405435673883;10124.898751012395;10134.391936483835;10198.235132364955;10416.947915260374;8914.197722205032;10342.689719308433;10480.528376223241;10552.886898365154;10579.10077643409;6201.565890387629;6640.966795165993;7486.251374862471;7677.462577619513;7681.42389320792;7608.619569021416;8265.297449716601;8624.310055195694;9911.256655750703;10309.690709278733 14:20:33 INFO - PROCESS | 2027 | |3;mootools.html;1507.299105915749;1541.160067763069;1572.7955365802443;1735.4273089880348;1753.5440785395792;7699.191584883591;8282.503049817007;8299.087100418965;8705;8718.302535797136;8451;8545.401821872456;8548.102449242833;8554.230119289263;8573.785655358619;2311.6185829338137;2356.8114550835894;2357.163207061494;2362.681038059866;2363.4800343924308;2318.3740390094663;2342.531493701258;2372.323887692008;2376.702912135983;2384.9046038158453;2103.8737675739426;2289.774970390841;2348.847324923883;2357.163207061494;2365.254944692425;2305.4236440889777;2319.7680231976838;2328.592496313147;2334.253039027512;2335.5679199348174;2295.2769877488704;2350.518143780521;2355.7644235576477;2360.3155085025323;2365.0185173153122;8619.827603447897;8656.394052416334;8668.696595619185;8689.739307820775;8703.782405439915;4538.29656403258;5163.612729045299;5273.630845840793;5300.072487314743;5310.893782124336;3091.737202337804;3383.780054296463;3434.210131669705;3570.8571657133684;3571.607123216444;999.9350042247231;1021.6020033754683;1026.3380119822748;1049.3389164826187;1053.3416614615865;9712.902870971271;11143.944280278627;11354.148438867118;11359.829602555968;11359.977602015775;4519.932201016987;4583.685238954374;4764.404449443819;4768.260919557474;4784.808607655655;9648.71053868385;9797.804043919225;9811.362261452983;9822.803543929082;9823.607055717834;5217.8173763918085;5279.709615971082;5303.7348132593215;5305.708186049727;5322.41453440129;4360.869173924724;4677.6959497633225;4822.083804077214;4837.177679794443;4859.708417494961;3870.632289932452;3927.489426374553;3945.2898478274187;4012.7592344459426;4025.5370632377067;1223.1865809236936;1234.160770675931;1235.721962558417;1249.0881656390886;1264.8229247905301;791.8060075281478;864.9481031138026;909.6088681866728;926.6822103746756;930.7812664023949;5614.29821272341;5851.502622277154;5854.297484301911;5984.7606095756555;6008.4592386684535;816.4285000499556;922.6078916460477;938.5166639180827;948.184561277301;998.9560908850124;3748.625137486284;3919.7452165609143;3998.7600743955454;4051.959480405158;4071.42999980003;9087.954560227157;10230.335028223142;10349.63776267827;10393.48032598367;10406.791864162675;9094.772630684154;10357.171426285879;10396.66075133089;10401.68794936153;10410.063094321396;7813;7987.880181797278;8177.4684645497855;8185.3042491387705;8211.58942052907;5625.915611265752;5653.236813030353;5747.36778954323;5755.395683453345;5786.594938354275;9156.03861594523;9248.352615316864;9694.75763105928;9749.512524373895;9947.801043979225;7400.370968467525;8174.509529428254;9839.950800245953;9861.901380986099;9939;6891.896621550581;7104.538205016657;7217.783466496014;7355.926440735524;7527;8166.224208700164;8229.012518497579;8242.17578242171;8265.421420500506;8266.421350505407;6377.330380310186;7242.493025488166;7320.670569824264;7456.701731930662;7459.701611935462;7216.603086830275;7219.530730502501;7234.312740289664;7269.818254543678;7389.519681220703;5597.608167428241;6803.625800581016;6913.481488888454;6925.757598484029;6985.825354366181;4842.128416884994;4884.92672609904;4913.28757330178;4973.577245933991;4990.976849745721;4556.362109304766;4570.81716731327;4670.322803193586;4707.91717904877;4820 14:20:33 INFO - PROCESS | 2027 | |4;prototype.html;304.34869379527805;305.3358944296155;311.23746820290296;315.48890796908995;317.7537408508407;5049.798008079677;5411.377691565471;5415.864603384917;5514.862128446781;5765.6540607563575;5319.813806516772;5563.13771365438;5649.983003059448;5655.434456554342;5657.745401456934;3578.964210357889;3967.2462232175867;4320.200762858881;4338.58783415575;4353.912921741563;3551.058969373118;3757.849686012556;3935.395587163026;4266.722663026893;4347.021920067975;4223.788810559475;4229.090745489734;4251.80866860992;4266.402703621496;4278.315469524877;3779.811009449544;4222;4245.872623821259;4249.277622804131;4287.59267869555;3675.9081022974374;3686.170611612366;3688.989500722113;3698.223373091654;3700.4449332600057;4620.792064357112;4875.219964805614;5011.84964451067;5443.237946687467;5461.590380721422;4770.356001939728;4790.42514898211;4793.640476964241;4813.638977076734;4841.958978819571;5319.787208511655;5525;5580.63725857822;5814.011618024903;5914.940850591482;3506.982974937265;3554.48459973305;3593.658602432765;3944.980275098635;3952.288588054149;5212.8957420851375;5925.844460330238;6082.939170608326;6142.600730952474;6224.9688751556405;3541.8760343387858;3871.6383006366095;3877.515310586177;4014.116894283253;4024.309789888254;3371.8145501997624;3406.795592264472;3564.376234159012;3578.246659137056;3973.4238535412205;4195.832166713297;4241.27898257297;4634.189016922025;4731.432228132646;4769.713817170981;5613.971930140405;5767.462316580775;5891.970540147272;5920.763169473259;6033.969830150909;1190.6011486152236;1212.090931801149;1298.487097596444;1303.8891694205909;1304.699919018612;877.7630039889322;900.7079077285597;910.3081657939881;921.3458444504477;926.5922993882805;4746.477887432379;4794.1714388510945;5006.424261209934;5387.730613469311;5394.595405344615;4525.841595544141;4747.934648427945;5323.2737084040555;5447.229650363648;6046.244219472566;5060.924086138711;5198.558122559549;5231.541779536351;5441.483059109379;5519.420460851633;2216.5592364962904;2453.877306134686;2544.727636181909;2572.8842202100577;2597.779188768976;4401.603855653007;4420.58004489573;4616.3537104805355;4851.902961940741;5220.686758794484;4454.977725111354;4598.793054312564;4924.8276310328965;5086.990638697767;5251.663009960607;4696.718196908197;4992.775325110379;5080.364954380702;5227.529522342931;5307.734613269398;4267.274563324305;4357.956420435755;4785.707858878153;5122.79508819658;5137.768800403916;4792.616590672807;4914.385701787277;5007.198848184273;5083.6187285952965;5091.974540127276;4318.697691161588;4631.76841157934;5281.15159694104;5363.41002489718;5396.730163491889;3098.7830851840154;3299.541841328226;3357.8488967996987;3510.26284480262;3511.5259439975275;4270.444842170498;4815.001774263641;4893.730844803427;4993.350864387607;5524.392316845224;5025.858838226109;5542.7782888684005;5877.764889404376;5917.863532327873;5954.493868021266;4713.905721885474;5908.05471124618;6052.66710330936;6057.363976782374;6086.939130608637;5057.949420505748;5151.021305951857;5270.78916843322;5686.317641883001;5698.800541981011;5252.894942101213;5916.615419997686;5935.258092738408;5957.421283359827;5968.76124954997;5555.3333599966645;5753.864144041424;5803.883922321445;5813.709314534341;5826.184334193132 14:20:33 INFO - PROCESS | 2027 | |5;yui.html;622.4056026494698;694.7429451103093;700.6076597105618;740.785172300033;772.806798300425;15238.923805380971;15591.688166236669;16025.038497690146;16198.75701864473;16238.188090595468;14497.855021449783;14577.635559111;14659.780103298459;14666.413343466274;14678.706425871476;849.3587341557115;854.7948492361851;856.905740368559;857.1214505132227;858.2018722587991;677.9017042528831;839.0895877972407;860.3031544448985;865.8960924689046;867.3408209760564;897.7351681254013;920.2270093121776;922.5433410461798;925.4724806860092;925.7222833150062;906.8685040669131;915.094056883687;915.6612053540233;918.3020904112856;918.5912269040247;677.4411110833582;680.0207700910673;680.1226417920925;681.2812482830611;683.0505597219868;14665.973381863274;14715.705685886329;14763.96652234344;14776.335064922105;14826.629334266621;13863;13891.583252502442;13978.580642580739;14249.50126745559;14289.857101428957;10960.397178155199;10991.230613857033;11085.869153663709;11226.550937962553;11478.827817582744;3694.224212915291;3702.0559757261994;3767.6232376762377;3860.8069596520063;3872.4384964180317;122476;124141.75858241483;124535.3773231128;124905;125734.3713281437;10666.520006599721;10715.98198171188;10721.83917241242;10766.24636275469;10792.730181745363;22653;22663.773362266496;22679.659805102936;22681.319560413212;22684.773152268597;7170.282971702788;7580;7749.806254843674;7822.687092516349;7938.682452701943;3869.2068126034096;3958.88123356294;4038.7172897896867;4250.703535421664;4330.848420305274;3479.582450105953;3586.7668601540818;3617.149969757105;3624.3838547447;3631.8728844490315;531.256241262236;626.3736263736264;647.0423772816258;647.9740810367532;653.31075715121;567.1096378685506;572.181780054526;576.9557101645951;597.7101105963604;604.4318340759672;7089.291070892973;7357.153927298322;7547.584882831387;8192.34461243099;8692.696095585587;512.4823927832915;524.3367140567185;546.9198333291796;552.0642510943911;569.5728203847115;4191.2874811281545;4515.796789144496;4927;4930.285108659296;4963.45402005779;153726;160269;160953;161053.38946610384;162899.5565066525;79374.603126984;108102;136511;138936.30531847276;144727;93041.5347923256;103466.48266758618;105614;106881.84622460854;107826;7392.789674885989;9025.503597302211;9398.483083430478;9400.670976516063;9505.382150160218;46559;56337.15494267507;59220.40779592149;60592.78814423775;62961;84419.15580844358;86750.56624716836;89631.55184224037;90607.64088538545;91861;11130;12245.510179592717;12576.30830304342;12760.106792524433;13092;13833.584992450242;14322.57032289033;14357.712845742817;14443.205623690797;14901.552953411416;13236.470541178245;13278.800817987545;13534.729305414034;13590.32048397596;13690.794638080635;12563.74872502563;13048.412821423248;13166.868331316948;13386.732265354833;13487.460501579826;12179.817302740643;12550.058745593935;12907.225566466042;13021.934890325489;13054.869451305365;11030.05835798224;11064.778704425706;11160.218784684994;11173.553057877594;11178.832317515082;8657.82684346297;10797.460126993461;10800.189985750943;11038.392888391216;11045.116390688887 14:20:33 INFO - PROCESS | 2027 | __end_tp_report 14:20:33 INFO - PROCESS | 2027 | __start_cc_report 14:20:33 INFO - PROCESS | 2027 | _x_x_mozilla_cycle_collect,385 14:20:33 INFO - PROCESS | 2027 | __end_cc_report 14:20:33 INFO - PROCESS | 2027 | __startTimestamp1472332833683__endTimestamp 14:20:33 INFO - PROCESS | 2027 | 14:20:33 INFO - PROCESS | 2027 | ------- Summary: start ------- 14:20:33 INFO - PROCESS | 2027 | Number of tests: 6 14:20:33 INFO - PROCESS | 2027 | 14:20:33 INFO - PROCESS | 2027 | [#0] dojo.html Cycles:165 Average:5746.37 Median:6029.58 stddev:695.43 (11.5%) stddev-sans-first:657.47 14:20:33 INFO - PROCESS | 2027 | Values: 2779.4 3402.7 3419.9 3436.5 3455.1 5666.7 5833.2 5868.6 5912.5 5917.3 4553.3 5551.5 5814.9 5892.5 5988.9 5245.6 5355.1 5387.6 5410.0 5413.3 4134.4 5078.5 5235.1 5259.1 5309.7 4408.6 5160.4 5259.1 5294.3 5307.8 3998.5 5059.0 5282.2 5316.2 5351.3 5104.9 5189.9 5238.2 5266.7 5290.5 5742.4 5840.0 5858.5 5868.3 5882.1 6143.1 6193.2 6194.5 6206.8 6216.6 6179.9 6193.1 6259.4 6283.9 6288.7 5936.6 5954.6 5973.9 5993.8 6004.8 6356.7 6386.5 6394.7 6401.1 6421.7 6119.5 6142.0 6146.4 6157.9 6221.9 6191.3 6219.7 6223.0 6253.1 6254.7 4904.7 6218.6 6256.2 6260.9 6263.0 6148.9 6156.4 6163.4 6168.9 6213.7 4949.4 4973.2 4981.3 4996.8 5007.2 4497.1 4509.0 4520.7 4527.6 4539.2 5451.5 5796.1 5841.3 5866.9 5972.2 6210.3 6211.8 6213.0 6215.7 6237.8 4879.1 5840.1 5849.0 5936.3 5938.9 5384.4 5438.8 5442.0 5466.8 5483.1 6424.7 6469.9 6482.5 6488.1 6505.6 6233.5 6251.2 6264.4 6268.1 6359.0 6240.1 6338.1 6353.5 6373.8 6470.8 6204.8 6215.3 6222.1 6227.7 6237.6 6137.0 6203.5 6206.2 6238.2 6244.2 4620.3 5877.1 6132.8 6140.4 6221.0 5741.3 5909.0 6099.7 6207.8 6208.1 5466.3 6094.9 6096.1 6103.6 6128.8 5614.6 6107.9 6109.0 6169.5 6183.3 6029.6 6060.9 6075.5 6100.0 6109.5 14:20:33 INFO - PROCESS | 2027 | 14:20:33 INFO - PROCESS | 2027 | [#1] ext.html Cycles:180 Average:68246.48 Median:39520.91 stddev:148938.23 (376.9%) stddev-sans-first:149269.04 14:20:33 INFO - PROCESS | 2027 | Values: 444.0 464.6 481.9 505.3 516.4 32157.7 40128.2 40211.4 40507.0 41252.0 41053.2 41188.4 41599.0 41626.4 41728.4 9527.9 9739.7 9740.5 9746.3 9772.5 8242.4 9777.5 9787.2 9808.6 9834.5 9782.8 9789.3 9794.4 9847.4 9854.7 9771.7 9775.3 9782.8 9828.9 9858.9 9550.2 9623.0 9624.6 9635.8 9672.6 39286.6 39652.8 39725.2 39887.2 39922.8 2939.8 3072.8 3079.8 3367.2 3739.6 30863.2 31081.8 31157.1 31528.8 32444.0 3092.2 3132.3 3156.0 3211.6 3221.6 485575.0 491396.0 496528.0 496667.0 499835.5 63334.0 63485.7 63666.7 63930.7 64229.0 33194.5 34836.7 35896.8 37055.8 37093.6 16427.9 16569.1 16636.0 16652.3 16672.8 12617.9 12711.1 12720.8 12735.2 12737.5 18700.6 19059.9 20479.6 20615.4 20694.1 7406.4 8812.3 8923.6 8955.3 9052.5 849.0 983.4 1018.4 1031.5 1044.0 23036.9 24098.0 25553.2 25606.7 29145.4 796.1 1033.1 1048.3 1051.2 1133.3 13046.9 14590.4 15094.7 15534.6 15579.6 616255.0 618843.0 620876.0 623063.0 623545.0 48657.8 57243.4 57629.7 57701.0 58049.4 1340.7 1355.7 1375.4 1393.9 1396.0 28211.6 29413.0 29595.6 29964.0 30366.0 50038.2 50445.7 50455.0 50491.7 50816.5 549471.3 549497.0 550605.0 551264.0 554101.0 26970.3 27145.7 27191.7 27253.0 27372.7 47177.5 47973.0 48039.0 48134.5 48280.0 31353.4 38021.8 39103.2 39389.0 40537.4 38752.8 38812.8 39119.8 39897.8 40092.8 32065.0 37457.6 37953.8 38443.0 38845.2 29943.9 32524.8 33895.0 34110.1 34820.8 25679.9 25718.9 25802.2 26011.8 26239.0 14:20:33 INFO - PROCESS | 2027 | 14:20:33 INFO - PROCESS | 2027 | [#2] jquery.html Cycles:180 Average:32553.42 Median:3894.68 stddev:92289.67 (2369.6%) stddev-sans-first:92516.86 14:20:33 INFO - PROCESS | 2027 | Values: 335.4 369.6 377.0 383.2 399.3 16785.7 19586.7 19998.6 20580.6 20801.0 19979.0 20006.6 20396.4 20496.3 20666.9 5788.6 5897.2 5923.6 6157.5 6163.2 5090.4 5234.7 5302.5 6064.8 6133.5 6012.8 6086.1 6112.4 6510.9 6817.7 6651.5 6843.7 6886.0 6908.6 6936.5 6024.2 6034.0 6061.3 6063.9 6073.0 11660.0 12657.4 12968.1 12968.3 13091.9 2102.7 2106.9 2218.4 2311.8 2356.5 9664.5 9685.7 9719.0 10042.7 10114.0 1851.6 1954.7 1964.2 1966.8 1967.5 97996.0 112514.4 128111.0 132719.7 134816.0 9350.5 9596.9 9846.6 10015.3 10143.9 12151.2 12205.6 12217.3 12228.3 12232.0 6753.0 7963.7 8117.8 8227.2 8275.7 8203.3 8433.7 8436.1 8650.8 8659.0 3867.2 3872.0 3917.4 3925.2 3975.7 1877.2 1922.6 1927.7 1932.8 1976.4 718.0 839.8 906.2 1035.4 1042.4 15355.8 19229.6 20472.7 20800.6 21234.2 814.8 868.2 877.3 913.4 924.7 8318.4 9143.3 10523.1 10667.4 10855.8 507512.0 550151.0 561659.0 563996.0 564978.0 103936.5 106417.0 108175.5 129172.0 137091.0 895.6 955.2 977.8 1020.7 1022.5 16840.5 18767.3 19080.3 19168.9 19182.7 37714.8 38041.7 45696.0 51442.2 55934.0 77687.6 78114.0 79012.6 82801.9 84077.7 6439.9 7542.2 7585.9 7677.5 7853.6 10512.3 11713.9 11729.0 12055.9 12210.0 8455.7 10149.5 10381.4 10384.8 10428.3 9909.4 10124.9 10134.4 10198.2 10416.9 8914.2 10342.7 10480.5 10552.9 10579.1 6201.6 6641.0 7486.3 7677.5 7681.4 7608.6 8265.3 8624.3 9911.3 10309.7 14:20:33 INFO - PROCESS | 2027 | 14:20:33 INFO - PROCESS | 2027 | [#3] mootools.html Cycles:180 Average:5539.19 Median:5289.89 stddev:3044.90 (57.6%) stddev-sans-first:3038.37 14:20:33 INFO - PROCESS | 2027 | Values: 1507.3 1541.2 1572.8 1735.4 1753.5 7699.2 8282.5 8299.1 8705.0 8718.3 8451.0 8545.4 8548.1 8554.2 8573.8 2311.6 2356.8 2357.2 2362.7 2363.5 2318.4 2342.5 2372.3 2376.7 2384.9 2103.9 2289.8 2348.8 2357.2 2365.3 2305.4 2319.8 2328.6 2334.3 2335.6 2295.3 2350.5 2355.8 2360.3 2365.0 8619.8 8656.4 8668.7 8689.7 8703.8 4538.3 5163.6 5273.6 5300.1 5310.9 3091.7 3383.8 3434.2 3570.9 3571.6 999.9 1021.6 1026.3 1049.3 1053.3 9712.9 11143.9 11354.1 11359.8 11360.0 4519.9 4583.7 4764.4 4768.3 4784.8 9648.7 9797.8 9811.4 9822.8 9823.6 5217.8 5279.7 5303.7 5305.7 5322.4 4360.9 4677.7 4822.1 4837.2 4859.7 3870.6 3927.5 3945.3 4012.8 4025.5 1223.2 1234.2 1235.7 1249.1 1264.8 791.8 864.9 909.6 926.7 930.8 5614.3 5851.5 5854.3 5984.8 6008.5 816.4 922.6 938.5 948.2 999.0 3748.6 3919.7 3998.8 4052.0 4071.4 9088.0 10230.3 10349.6 10393.5 10406.8 9094.8 10357.2 10396.7 10401.7 10410.1 7813.0 7987.9 8177.5 8185.3 8211.6 5625.9 5653.2 5747.4 5755.4 5786.6 9156.0 9248.4 9694.8 9749.5 9947.8 7400.4 8174.5 9840.0 9861.9 9939.0 6891.9 7104.5 7217.8 7355.9 7527.0 8166.2 8229.0 8242.2 8265.4 8266.4 6377.3 7242.5 7320.7 7456.7 7459.7 7216.6 7219.5 7234.3 7269.8 7389.5 5597.6 6803.6 6913.5 6925.8 6985.8 4842.1 4884.9 4913.3 4973.6 4991.0 4556.4 4570.8 4670.3 4707.9 4820.0 14:20:33 INFO - PROCESS | 2027 | 14:20:33 INFO - PROCESS | 2027 | [#4] prototype.html Cycles:180 Average:4461.41 Median:4863.56 stddev:1389.28 (28.6%) stddev-sans-first:1357.69 14:20:33 INFO - PROCESS | 2027 | Values: 304.3 305.3 311.2 315.5 317.8 5049.8 5411.4 5415.9 5514.9 5765.7 5319.8 5563.1 5650.0 5655.4 5657.7 3579.0 3967.2 4320.2 4338.6 4353.9 3551.1 3757.8 3935.4 4266.7 4347.0 4223.8 4229.1 4251.8 4266.4 4278.3 3779.8 4222.0 4245.9 4249.3 4287.6 3675.9 3686.2 3689.0 3698.2 3700.4 4620.8 4875.2 5011.8 5443.2 5461.6 4770.4 4790.4 4793.6 4813.6 4842.0 5319.8 5525.0 5580.6 5814.0 5914.9 3507.0 3554.5 3593.7 3945.0 3952.3 5212.9 5925.8 6082.9 6142.6 6225.0 3541.9 3871.6 3877.5 4014.1 4024.3 3371.8 3406.8 3564.4 3578.2 3973.4 4195.8 4241.3 4634.2 4731.4 4769.7 5614.0 5767.5 5892.0 5920.8 6034.0 1190.6 1212.1 1298.5 1303.9 1304.7 877.8 900.7 910.3 921.3 926.6 4746.5 4794.2 5006.4 5387.7 5394.6 4525.8 4747.9 5323.3 5447.2 6046.2 5060.9 5198.6 5231.5 5441.5 5519.4 2216.6 2453.9 2544.7 2572.9 2597.8 4401.6 4420.6 4616.4 4851.9 5220.7 4455.0 4598.8 4924.8 5087.0 5251.7 4696.7 4992.8 5080.4 5227.5 5307.7 4267.3 4358.0 4785.7 5122.8 5137.8 4792.6 4914.4 5007.2 5083.6 5092.0 4318.7 4631.8 5281.2 5363.4 5396.7 3098.8 3299.5 3357.8 3510.3 3511.5 4270.4 4815.0 4893.7 4993.4 5524.4 5025.9 5542.8 5877.8 5917.9 5954.5 4713.9 5908.1 6052.7 6057.4 6086.9 5057.9 5151.0 5270.8 5686.3 5698.8 5252.9 5916.6 5935.3 5957.4 5968.8 5555.3 5753.9 5803.9 5813.7 5826.2 14:20:33 INFO - PROCESS | 2027 | 14:20:33 INFO - PROCESS | 2027 | [#5] yui.html Cycles:180 Average:24819.32 Median:3628.13 stddev:40629.37 (1119.8%) stddev-sans-first:40702.73 14:20:33 INFO - PROCESS | 2027 | Values: 622.4 694.7 700.6 740.8 772.8 15238.9 15591.7 16025.0 16198.8 16238.2 14497.9 14577.6 14659.8 14666.4 14678.7 849.4 854.8 856.9 857.1 858.2 677.9 839.1 860.3 865.9 867.3 897.7 920.2 922.5 925.5 925.7 906.9 915.1 915.7 918.3 918.6 677.4 680.0 680.1 681.3 683.1 14666.0 14715.7 14764.0 14776.3 14826.6 13863.0 13891.6 13978.6 14249.5 14289.9 10960.4 10991.2 11085.9 11226.6 11478.8 3694.2 3702.1 3767.6 3860.8 3872.4 122476.0 124141.8 124535.4 124905.0 125734.4 10666.5 10716.0 10721.8 10766.2 10792.7 22653.0 22663.8 22679.7 22681.3 22684.8 7170.3 7580.0 7749.8 7822.7 7938.7 3869.2 3958.9 4038.7 4250.7 4330.8 3479.6 3586.8 3617.1 3624.4 3631.9 531.3 626.4 647.0 648.0 653.3 567.1 572.2 577.0 597.7 604.4 7089.3 7357.2 7547.6 8192.3 8692.7 512.5 524.3 546.9 552.1 569.6 4191.3 4515.8 4927.0 4930.3 4963.5 153726.0 160269.0 160953.0 161053.4 162899.6 79374.6 108102.0 136511.0 138936.3 144727.0 93041.5 103466.5 105614.0 106881.8 107826.0 7392.8 9025.5 9398.5 9400.7 9505.4 46559.0 56337.2 59220.4 60592.8 62961.0 84419.2 86750.6 89631.6 90607.6 91861.0 11130.0 12245.5 12576.3 12760.1 13092.0 13833.6 14322.6 14357.7 14443.2 14901.6 13236.5 13278.8 13534.7 13590.3 13690.8 12563.7 13048.4 13166.9 13386.7 13487.5 12179.8 12550.1 12907.2 13021.9 13054.9 11030.1 11064.8 11160.2 11173.6 11178.8 8657.8 10797.5 10800.2 11038.4 11045.1 14:20:33 INFO - PROCESS | 2027 | -------- Summary: end -------- 14:20:33 INFO - PROCESS | 2027 | 14:20:34 INFO - TEST-INFO | 2027: exit 0 14:20:34 INFO - TEST-OK | dromaeo_css | took 1115009ms 14:20:34 INFO - TEST-START | kraken 14:20:34 INFO - operating with platform_type : mac_ 14:20:34 INFO - Initialising browser for kraken test... 14:20:34 INFO - TEST-INFO | started process 2031 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpKrkPi8/profile http://localhost:56081/getInfo.html) 14:20:37 INFO - PROCESS | 2031 | __metrics Screen width/height:1600/1200 14:20:37 INFO - PROCESS | 2031 | colorDepth:24 14:20:37 INFO - PROCESS | 2031 | Browser inner width/height: 1024/689 14:20:37 INFO - PROCESS | 2031 | __metrics 14:20:37 ERROR - PROCESS | 2031 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 14:20:37 INFO - TEST-INFO | 2031: exit 0 14:20:37 INFO - Browser initialized. 14:20:37 INFO - Running cycle 1/1 for kraken test... 14:20:37 INFO - TEST-INFO | started process 2033 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpKrkPi8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:20:49 INFO - PROCESS | 2033 | RSS: Main: 244051968 14:20:49 INFO - PROCESS | 2033 | 14:20:52 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56081/tests/kraken/driver.html?testName=audio-beat-detection) 14:20:53 INFO - PROCESS | 2033 | RSS: Main: 256901120 14:20:53 INFO - PROCESS | 2033 | 14:20:56 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56081/tests/kraken/driver.html?testName=audio-dft) 14:20:56 INFO - PROCESS | 2033 | RSS: Main: 265613312 14:20:56 INFO - PROCESS | 2033 | 14:21:00 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56081/tests/kraken/driver.html?testName=audio-fft) 14:21:01 INFO - PROCESS | 2033 | RSS: Main: 263278592 14:21:01 INFO - PROCESS | 2033 | 14:21:03 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56081/tests/kraken/driver.html?testName=audio-oscillator) 14:21:03 INFO - PROCESS | 2033 | RSS: Main: 264548352 14:21:03 INFO - PROCESS | 2033 | 14:21:05 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56081/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:21:06 INFO - PROCESS | 2033 | RSS: Main: 266240000 14:21:06 INFO - PROCESS | 2033 | 14:21:09 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56081/tests/kraken/driver.html?testName=imaging-darkroom) 14:21:10 INFO - PROCESS | 2033 | RSS: Main: 266788864 14:21:10 INFO - PROCESS | 2033 | 14:21:13 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56081/tests/kraken/driver.html?testName=imaging-desaturate) 14:21:14 INFO - PROCESS | 2033 | RSS: Main: 267345920 14:21:14 INFO - PROCESS | 2033 | 14:21:17 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56081/tests/kraken/driver.html?testName=json-parse-financial) 14:21:17 INFO - PROCESS | 2033 | RSS: Main: 267120640 14:21:17 INFO - PROCESS | 2033 | 14:21:19 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56081/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:21:20 INFO - PROCESS | 2033 | RSS: Main: 267051008 14:21:20 INFO - PROCESS | 2033 | 14:21:22 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56081/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:21:22 INFO - PROCESS | 2033 | RSS: Main: 266661888 14:21:22 INFO - PROCESS | 2033 | 14:21:24 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56081/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:21:25 INFO - PROCESS | 2033 | RSS: Main: 266706944 14:21:25 INFO - PROCESS | 2033 | 14:21:28 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56081/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:21:29 INFO - PROCESS | 2033 | RSS: Main: 266878976 14:21:29 INFO - PROCESS | 2033 | 14:21:32 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56081/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:21:32 INFO - PROCESS | 2033 | RSS: Main: 266948608 14:21:32 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:56081/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56081/tests/kraken/driver.html?testName=ai-astar) 14:21:34 INFO - PROCESS | 2033 | RSS: Main: 268939264 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | __start_tp_report 14:21:34 INFO - PROCESS | 2033 | _x_x_mozilla_page_load 14:21:34 INFO - PROCESS | 2033 | _x_x_mozilla_page_load_details 14:21:34 INFO - PROCESS | 2033 | |i|pagename|runs| 14:21:34 INFO - PROCESS | 2033 | |0;ai-astar;159;90;172;102;169;146;174;117;116;104 14:21:34 INFO - PROCESS | 2033 | |1;audio-beat-detection;147;113;137;163;111;132;130;105;159;100 14:21:34 INFO - PROCESS | 2033 | |2;audio-dft;197;210;216;272;281;229;268;269;198;201 14:21:34 INFO - PROCESS | 2033 | |3;audio-fft;71;80;78;149;61;60;60;66;135;60 14:21:34 INFO - PROCESS | 2033 | |4;audio-oscillator;67;86;121;71;72;83;112;70;74;113 14:21:34 INFO - PROCESS | 2033 | |5;imaging-gaussian-blur;133;119;88;85;89;74;134;126;90;86 14:21:34 INFO - PROCESS | 2033 | |6;imaging-darkroom;94;122;145;149;97;129;107;150;150;109 14:21:34 INFO - PROCESS | 2033 | |7;imaging-desaturate;101;81;135;143;92;86;95;115;118;92 14:21:34 INFO - PROCESS | 2033 | |8;json-parse-financial;57;58;64;84;87;83;48;52;78;83 14:21:34 INFO - PROCESS | 2033 | |9;json-stringify-tinderbox;71;72;75;76;73;106;111;89;72;104 14:21:34 INFO - PROCESS | 2033 | |10;stanford-crypto-aes;90;90;88;90;89;94;88;87;78;102 14:21:34 INFO - PROCESS | 2033 | |11;stanford-crypto-ccm;147;149;144;138;140;139;164;165;183;142 14:21:34 INFO - PROCESS | 2033 | |12;stanford-crypto-pbkdf2;171;174;174;195;237;197;176;172;174;175 14:21:34 INFO - PROCESS | 2033 | |13;stanford-crypto-sha256-iterative;66;71;68;68;71;70;67;68;67;68 14:21:34 INFO - PROCESS | 2033 | __end_tp_report 14:21:34 INFO - PROCESS | 2033 | __start_cc_report 14:21:34 INFO - PROCESS | 2033 | _x_x_mozilla_cycle_collect,969 14:21:34 INFO - PROCESS | 2033 | __end_cc_report 14:21:34 INFO - PROCESS | 2033 | __startTimestamp1472332894938__endTimestamp 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | ------- Summary: start ------- 14:21:34 INFO - PROCESS | 2033 | Number of tests: 14 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#0] ai-astar Cycles:10 Average:134.90 Median:164.00 stddev:32.49 (19.8%) stddev-sans-first:33.27 14:21:34 INFO - PROCESS | 2033 | Values: 159.0 90.0 172.0 102.0 169.0 146.0 174.0 117.0 116.0 104.0 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:134.50 stddev:22.23 (16.5%) stddev-sans-first:22.68 14:21:34 INFO - PROCESS | 2033 | Values: 147.0 113.0 137.0 163.0 111.0 132.0 130.0 105.0 159.0 100.0 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#2] audio-dft Cycles:10 Average:234.10 Median:248.50 stddev:34.50 (13.9%) stddev-sans-first:33.88 14:21:34 INFO - PROCESS | 2033 | Values: 197.0 210.0 216.0 272.0 281.0 229.0 268.0 269.0 198.0 201.0 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#3] audio-fft Cycles:10 Average:82.00 Median:63.50 stddev:32.64 (51.4%) stddev-sans-first:34.38 14:21:34 INFO - PROCESS | 2033 | Values: 71.0 80.0 78.0 149.0 61.0 60.0 60.0 66.0 135.0 60.0 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#4] audio-oscillator Cycles:10 Average:86.90 Median:71.50 stddev:20.58 (28.8%) stddev-sans-first:20.53 14:21:34 INFO - PROCESS | 2033 | Values: 67.0 86.0 121.0 71.0 72.0 83.0 112.0 70.0 74.0 113.0 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#5] imaging-gaussian-blur Cycles:10 Average:102.40 Median:85.50 stddev:22.82 (26.7%) stddev-sans-first:21.35 14:21:34 INFO - PROCESS | 2033 | Values: 133.0 119.0 88.0 85.0 89.0 74.0 134.0 126.0 90.0 86.0 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#6] imaging-darkroom Cycles:10 Average:125.20 Median:149.50 stddev:22.55 (15.1%) stddev-sans-first:20.90 14:21:34 INFO - PROCESS | 2033 | Values: 94.0 122.0 145.0 149.0 97.0 129.0 107.0 150.0 150.0 109.0 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#7] imaging-desaturate Cycles:10 Average:105.80 Median:83.50 stddev:21.08 (25.2%) stddev-sans-first:22.28 14:21:34 INFO - PROCESS | 2033 | Values: 101.0 81.0 135.0 143.0 92.0 86.0 95.0 115.0 118.0 92.0 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#8] json-parse-financial Cycles:10 Average:69.40 Median:80.50 stddev:15.06 (18.7%) stddev-sans-first:15.29 14:21:34 INFO - PROCESS | 2033 | Values: 57.0 58.0 64.0 84.0 87.0 83.0 48.0 52.0 78.0 83.0 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#9] json-stringify-tinderbox Cycles:10 Average:84.90 Median:72.50 stddev:16.17 (22.3%) stddev-sans-first:16.35 14:21:34 INFO - PROCESS | 2033 | Values: 71.0 72.0 75.0 76.0 73.0 106.0 111.0 89.0 72.0 104.0 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:89.50 stddev:5.97 (6.7%) stddev-sans-first:6.33 14:21:34 INFO - PROCESS | 2033 | Values: 90.0 90.0 88.0 90.0 89.0 94.0 88.0 87.0 78.0 102.0 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#11] stanford-crypto-ccm Cycles:10 Average:151.10 Median:148.00 stddev:14.81 (10.0%) stddev-sans-first:15.63 14:21:34 INFO - PROCESS | 2033 | Values: 147.0 149.0 144.0 138.0 140.0 139.0 164.0 165.0 183.0 142.0 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.50 Median:175.50 stddev:20.69 (11.8%) stddev-sans-first:21.37 14:21:34 INFO - PROCESS | 2033 | Values: 171.0 174.0 174.0 195.0 237.0 197.0 176.0 172.0 174.0 175.0 14:21:34 INFO - PROCESS | 2033 | 14:21:34 INFO - PROCESS | 2033 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.00 stddev:1.71 (2.5%) stddev-sans-first:1.58 14:21:34 INFO - PROCESS | 2033 | Values: 66.0 71.0 68.0 68.0 71.0 70.0 67.0 68.0 67.0 68.0 14:21:34 INFO - PROCESS | 2033 | -------- Summary: end -------- 14:21:34 INFO - PROCESS | 2033 | 14:21:35 INFO - TEST-INFO | 2033: exit 0 14:21:35 INFO - TEST-OK | kraken | took 61575ms 14:21:35 INFO - SUITE-END | took 1176s 14:21:36 INFO - Completed test suite (00:19:36) 14:21:36 INFO - javascript benchmark 14:21:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7184.118105646706, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2779.388534522407, 3402.6767457091573, 3419.9487007694875, 3436.450167973124, 3455.118944669109, 5666.688332141731, 5833.212516310296, 5868.61853979491, 5912.467877890989, 5917.319508256546, 4553.27147656375, 5551.472610102044, 5814.854628634286, 5892.528597712183, 5988.862116197919, 5245.580353571715, 5355.116405793039, 5387.622866399353, 5409.999150157233, 5413.323334583177, 4134.441850350209, 5078.466760990098, 5235.11003129467, 5259.079661059319, 5309.681419114846, 4408.647308215335, 5160.380754309469, 5259.079661059319, 5294.258803767457, 5307.761150748225, 3998.460207871958, 5059.0, 5282.181261904411, 5316.175992721134, 5351.250824884481, 5104.872378190538, 5189.870253243661, 5238.240455133984, 5266.683998960075, 5290.523852853262, 5742.397048309951, 5839.970800146015, 5858.53131749459, 5868.325142608612, 5882.147088672119, 6143.1092491588915, 6193.163922870445, 6194.473469755077, 6206.751729930797, 6216.627002379872, 6179.938200617981, 6193.102000209944, 6259.37406259375, 6283.905741413882, 6288.685565721696, 5936.643801371845, 5954.642721436728, 5973.880522389528, 5993.820185394402, 6004.759809607567, 6356.745730170834, 6386.457151142112, 6394.680265986682, 6401.103845461639, 6421.743130274736, 6119.5104391648565, 6142.007694998287, 6146.401935496803, 6157.922363586355, 6221.906671399933, 6191.318954914956, 6219.689015549295, 6223.0, 6253.1245625612455, 6254.6872656366995, 4904.705717656952, 6218.595791273553, 6256.249250090018, 6260.874782504325, 6262.968685156545, 6148.8770224595255, 6156.353582873823, 6163.3836616338, 6168.938310616926, 6213.689315534205, 4949.381327334083, 4973.229149481836, 4981.252812078217, 4996.800127994839, 5007.173816320266, 4497.100579884036, 4508.954910450919, 4520.683552151384, 4527.637788976874, 4539.205639013159, 5451.509364157245, 5796.072628379523, 5841.269841269841, 5866.911996320058, 5972.193753843176, 6210.285817131088, 6211.751529938842, 6213.0, 6215.689215539205, 6237.750489980441, 4879.097366987119, 5840.1239814027385, 5848.976429124886, 5936.347001829795, 5938.851528711817, 5384.380796208407, 5438.809641662523, 5442.0, 5466.808661696821, 5483.122700367922, 6424.678766061772, 6469.902951455638, 6482.54622176462, 6488.059231411513, 6505.642189679613, 6233.470155036873, 6251.218597675291, 6264.404881536247, 6268.122462855295, 6359.046143078575, 6240.12638230639, 6338.080978258219, 6353.4599559038, 6373.840653983688, 6470.838229044311, 6204.844878877883, 6215.316315205233, 6222.128901953821, 6227.719752611053, 6237.563370564017, 6136.987397079611, 6203.472704820141, 6206.224221972254, 6238.189035425547, 6244.1570387997035, 4620.330052142488, 5877.118432235199, 6132.845047373448, 6140.412224875342, 6221.0, 5741.311042674905, 5908.970455147697, 6099.725512352044, 6207.782727604512, 6208.0998255253635, 5466.289382380265, 6094.908576371269, 6096.0855871619615, 6103.572749907641, 6128.785492507741, 5614.550835933197, 6107.917136652877, 6108.969455152695, 6169.50643948474, 6183.319834818077, 6029.57792954489, 6060.878782424415, 6075.544334174866, 6099.969500152648, 6109.450149486567], "value": 5702.438565694577, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.9589163411799, 464.6491898616544, 481.8772260632725, 505.3481009497748, 516.382922407723, 32157.678423215762, 40128.19743605126, 40211.39682904759, 40507.0, 41252.0, 41053.17893642125, 41188.38217426741, 41599.0, 41626.37560436596, 41728.37407438891, 9527.857082143792, 9739.707808765714, 9740.4642744649, 9746.31775775696, 9772.511374431286, 8242.423030387876, 9777.511124443785, 9787.217022638171, 9808.607655693764, 9834.508274586278, 9782.755431114208, 9789.265805064613, 9794.363366381198, 9847.409155450696, 9854.655087071955, 9771.657991970282, 9775.31572789905, 9782.755431114243, 9828.852567211497, 9858.90141098587, 9550.235981121563, 9623.03769623039, 9624.615015399375, 9635.759106022335, 9672.56473458696, 39286.60713392887, 39652.81041568758, 39725.20549589021, 39887.20225595472, 39922.800385998176, 2939.808912420686, 3072.8002679825963, 3079.8306093164865, 3367.2255381262203, 3739.5699494558203, 30863.2284192897, 31081.844590777127, 31157.065288041395, 31528.842355788303, 32444.0, 3092.196029032445, 3132.2795756975797, 3156.037408590379, 3211.550382946389, 3221.64561898191, 485575.0, 491396.0, 496528.0, 496667.0, 499835.5008224972, 63334.0, 63485.68257158685, 63666.68166659137, 63930.68034659797, 64229.0, 33194.50208246878, 34836.651633483845, 35896.820515897256, 37055.814720926224, 37093.62906370902, 16427.91786041062, 16569.08870012161, 16636.0, 16652.333906643842, 16672.83327166713, 12617.936910315573, 12711.110222284537, 12720.809187862189, 12735.172213806072, 12737.490500380067, 18700.625987480173, 19059.90470047641, 20479.590408191754, 20615.38153855387, 20694.068766905526, 7406.407487400995, 8812.250958668457, 8923.643054277885, 8955.2835773138, 9052.456852588866, 848.9702860399856, 983.3657291047234, 1018.3838777539482, 1031.5358088859894, 1043.9791204175874, 23036.884815575813, 24098.0, 25553.23340299794, 25606.74393256081, 29145.417091658474, 796.1163108949062, 1033.106362996018, 1048.3238311289176, 1051.206339213904, 1133.2916926920675, 13046.869531304756, 14590.416383344547, 15094.698106037818, 15534.611634709223, 15579.647052384964, 616255.0, 618843.0, 620876.0, 623063.0, 623545.0, 48657.7835554114, 57243.42756572464, 57629.71185144047, 57701.0, 58049.4195058044, 1340.684939039325, 1355.6678613739698, 1375.3673310277184, 1393.9093958892843, 1396.043710058613, 28211.57682634803, 29413.0, 29595.556066658588, 29964.0, 30366.0, 50038.249426257906, 50445.74777126091, 50455.0, 50491.747541262055, 50816.491835082656, 549471.2949399346, 549497.0, 550605.0, 551264.0, 554101.0, 26970.32574185661, 27145.72854271432, 27191.72808271892, 27253.0, 27372.726272737018, 47177.528224718684, 47973.0, 48039.039219216116, 48134.518654814405, 48280.0, 31353.372932540766, 38021.80989095037, 39103.2179356417, 39389.0, 40537.391939120345, 38752.80623596864, 38812.80593597014, 39119.80440097781, 39897.80051099842, 40092.79953600213, 32065.0, 37457.62542374541, 37953.81023094867, 38443.0, 38845.22309553737, 29943.85028074846, 32524.83737581297, 33895.0, 34110.147246319044, 34820.82589587036, 25679.871600641876, 25718.87140564285, 25802.225933222035, 26011.82946767438, 26239.0], "value": 19560.822847373653, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.3795478365024, 369.56391458079474, 376.97926614036226, 383.1952898912283, 399.30520893645047, 16785.66428671426, 19586.706199407003, 19998.60002799943, 20580.588388232245, 20801.0, 19979.0, 20006.59986800263, 20396.388108356772, 20496.282630107948, 20666.89666551665, 5788.5658575606785, 5897.203877476529, 5923.5853490255595, 6157.538184636148, 6163.167972323746, 5090.414602320725, 5234.712090835022, 5302.522772950434, 6064.848378790543, 6133.478654314366, 6012.819615411545, 6086.117512960618, 6112.419320164599, 6510.9023364649565, 6817.69320380584, 6651.467882569383, 6843.692033858463, 6886.0, 6908.585484870924, 6936.4797640177185, 6024.1566180734735, 6034.0, 6061.333253342129, 6063.878722425527, 6072.997955337364, 11660.00889924349, 12657.43041563132, 12968.092233543655, 12968.286744229064, 13091.934540327238, 2102.7266455360705, 2106.884121373324, 2218.367765186914, 2311.7688231176917, 2356.5274623039686, 9664.466746213659, 9685.70942871715, 9718.951405242928, 10042.690181258278, 10114.039166279192, 1851.5556266495944, 1954.6774782160926, 1964.1750464804816, 1966.8131527504863, 1967.4982879365814, 97996.0, 112514.43742781234, 128111.0, 132719.6728032717, 134815.999999999, 9350.485723285148, 9596.856047159297, 9846.606135754633, 10015.349002314826, 10143.949280253551, 12151.210171338835, 12205.619904118788, 12217.328046957502, 12228.266304021787, 12232.0, 6753.0, 7963.721269755531, 8117.756467305991, 8227.218414250636, 8275.668973241123, 8203.30271926893, 8433.746987590383, 8436.072032076467, 8650.826983460296, 8659.004214515286, 3867.2458870519977, 3871.9806400967814, 3917.3732202847978, 3925.1757131002237, 3975.662068724133, 1877.2303355624128, 1922.5770330527262, 1927.6530224559715, 1932.7970563090955, 1976.416956997689, 717.9517903860423, 839.8242460555149, 906.207068814787, 1035.420164707751, 1042.3849928542197, 15355.769663455058, 19229.615407691766, 20472.69290960637, 20800.583988320148, 21234.150633974776, 814.7515007922522, 868.1925808997566, 877.3025444771422, 913.3971578757988, 924.67174153176, 8318.37612179089, 9143.268538516902, 10523.105536029523, 10667.413292268844, 10855.751588567258, 507512.0, 550151.0, 561659.0, 563996.0, 564978.0, 103936.48031759793, 106417.0, 108175.45912270388, 129172.0, 137091.0, 895.552223888056, 955.1833182628891, 977.8239916814899, 1020.7295066807302, 1022.4836457589018, 16840.494785156465, 18767.34314298993, 19080.332188373897, 19168.945707986193, 19182.712259315842, 37714.811425942695, 38041.717496950274, 45696.0, 51442.228366573785, 55934.0, 77687.61156194408, 78114.0, 79012.60493697495, 82801.92995175169, 84077.70678984465, 6439.903401448888, 7542.208068152765, 7585.924140758521, 7677.539347639159, 7853.646585903533, 10512.316699414514, 11713.887180717818, 11729.0, 12055.879441205476, 12210.023198144305, 8455.704050358208, 10149.543270552991, 10381.377117372982, 10384.792304153723, 10428.27002109845, 9909.405435673883, 10124.898751012395, 10134.391936483835, 10198.235132364955, 10416.947915260374, 8914.197722205032, 10342.689719308433, 10480.528376223241, 10552.886898365154, 10579.10077643409, 6201.565890387629, 6640.966795165993, 7486.251374862471, 7677.462577619513, 7681.42389320792, 7608.619569021416, 8265.297449716601, 8624.310055195694, 9911.256655750703, 10309.690709278733], "value": 8957.097328871625, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1507.299105915749, 1541.160067763069, 1572.7955365802443, 1735.4273089880348, 1753.5440785395792, 7699.191584883591, 8282.503049817007, 8299.087100418965, 8705.0, 8718.302535797136, 8451.0, 8545.401821872456, 8548.102449242833, 8554.230119289263, 8573.785655358619, 2311.6185829338137, 2356.8114550835894, 2357.163207061494, 2362.681038059866, 2363.4800343924308, 2318.3740390094663, 2342.531493701258, 2372.323887692008, 2376.702912135983, 2384.9046038158453, 2103.8737675739426, 2289.774970390841, 2348.847324923883, 2357.163207061494, 2365.254944692425, 2305.4236440889777, 2319.7680231976838, 2328.592496313147, 2334.253039027512, 2335.5679199348174, 2295.2769877488704, 2350.518143780521, 2355.7644235576477, 2360.3155085025323, 2365.0185173153122, 8619.827603447897, 8656.394052416334, 8668.696595619185, 8689.739307820775, 8703.782405439915, 4538.29656403258, 5163.612729045299, 5273.630845840793, 5300.072487314743, 5310.893782124336, 3091.737202337804, 3383.780054296463, 3434.210131669705, 3570.8571657133684, 3571.607123216444, 999.9350042247231, 1021.6020033754683, 1026.3380119822748, 1049.3389164826187, 1053.3416614615865, 9712.902870971271, 11143.944280278627, 11354.148438867118, 11359.829602555968, 11359.977602015775, 4519.932201016987, 4583.685238954374, 4764.404449443819, 4768.260919557474, 4784.808607655655, 9648.71053868385, 9797.804043919225, 9811.362261452983, 9822.803543929082, 9823.607055717834, 5217.8173763918085, 5279.709615971082, 5303.7348132593215, 5305.708186049727, 5322.41453440129, 4360.869173924724, 4677.6959497633225, 4822.083804077214, 4837.177679794443, 4859.708417494961, 3870.632289932452, 3927.489426374553, 3945.2898478274187, 4012.7592344459426, 4025.5370632377067, 1223.1865809236936, 1234.160770675931, 1235.721962558417, 1249.0881656390886, 1264.8229247905301, 791.8060075281478, 864.9481031138026, 909.6088681866728, 926.6822103746756, 930.7812664023949, 5614.29821272341, 5851.502622277154, 5854.297484301911, 5984.7606095756555, 6008.4592386684535, 816.4285000499556, 922.6078916460477, 938.5166639180827, 948.184561277301, 998.9560908850124, 3748.625137486284, 3919.7452165609143, 3998.7600743955454, 4051.959480405158, 4071.42999980003, 9087.954560227157, 10230.335028223142, 10349.63776267827, 10393.48032598367, 10406.791864162675, 9094.772630684154, 10357.171426285879, 10396.66075133089, 10401.68794936153, 10410.063094321396, 7813.0, 7987.880181797278, 8177.4684645497855, 8185.3042491387705, 8211.58942052907, 5625.915611265752, 5653.236813030353, 5747.36778954323, 5755.395683453345, 5786.594938354275, 9156.03861594523, 9248.352615316864, 9694.75763105928, 9749.512524373895, 9947.801043979225, 7400.370968467525, 8174.509529428254, 9839.950800245953, 9861.901380986099, 9939.0, 6891.896621550581, 7104.538205016657, 7217.783466496014, 7355.926440735524, 7527.0, 8166.224208700164, 8229.012518497579, 8242.17578242171, 8265.421420500506, 8266.421350505407, 6377.330380310186, 7242.493025488166, 7320.670569824264, 7456.701731930662, 7459.701611935462, 7216.603086830275, 7219.530730502501, 7234.312740289664, 7269.818254543678, 7389.519681220703, 5597.608167428241, 6803.625800581016, 6913.481488888454, 6925.757598484029, 6985.825354366181, 4842.128416884994, 4884.92672609904, 4913.28757330178, 4973.577245933991, 4990.976849745721, 4556.362109304766, 4570.81716731327, 4670.322803193586, 4707.91717904877, 4820.0], "value": 4481.14651079558, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.34869379527805, 305.3358944296155, 311.23746820290296, 315.48890796908995, 317.7537408508407, 5049.798008079677, 5411.377691565471, 5415.864603384917, 5514.862128446781, 5765.6540607563575, 5319.813806516772, 5563.13771365438, 5649.983003059448, 5655.434456554342, 5657.745401456934, 3578.964210357889, 3967.2462232175867, 4320.200762858881, 4338.58783415575, 4353.912921741563, 3551.058969373118, 3757.849686012556, 3935.395587163026, 4266.722663026893, 4347.021920067975, 4223.788810559475, 4229.090745489734, 4251.80866860992, 4266.402703621496, 4278.315469524877, 3779.811009449544, 4222.0, 4245.872623821259, 4249.277622804131, 4287.59267869555, 3675.9081022974374, 3686.170611612366, 3688.989500722113, 3698.223373091654, 3700.4449332600057, 4620.792064357112, 4875.219964805614, 5011.84964451067, 5443.237946687467, 5461.590380721422, 4770.356001939728, 4790.42514898211, 4793.640476964241, 4813.638977076734, 4841.958978819571, 5319.787208511655, 5525.0, 5580.63725857822, 5814.011618024903, 5914.940850591482, 3506.982974937265, 3554.48459973305, 3593.658602432765, 3944.980275098635, 3952.288588054149, 5212.8957420851375, 5925.844460330238, 6082.939170608326, 6142.600730952474, 6224.9688751556405, 3541.8760343387858, 3871.6383006366095, 3877.515310586177, 4014.116894283253, 4024.309789888254, 3371.8145501997624, 3406.795592264472, 3564.376234159012, 3578.246659137056, 3973.4238535412205, 4195.832166713297, 4241.27898257297, 4634.189016922025, 4731.432228132646, 4769.713817170981, 5613.971930140405, 5767.462316580775, 5891.970540147272, 5920.763169473259, 6033.969830150909, 1190.6011486152236, 1212.090931801149, 1298.487097596444, 1303.8891694205909, 1304.699919018612, 877.7630039889322, 900.7079077285597, 910.3081657939881, 921.3458444504477, 926.5922993882805, 4746.477887432379, 4794.1714388510945, 5006.424261209934, 5387.730613469311, 5394.595405344615, 4525.841595544141, 4747.934648427945, 5323.2737084040555, 5447.229650363648, 6046.244219472566, 5060.924086138711, 5198.558122559549, 5231.541779536351, 5441.483059109379, 5519.420460851633, 2216.5592364962904, 2453.877306134686, 2544.727636181909, 2572.8842202100577, 2597.779188768976, 4401.603855653007, 4420.58004489573, 4616.3537104805355, 4851.902961940741, 5220.686758794484, 4454.977725111354, 4598.793054312564, 4924.8276310328965, 5086.990638697767, 5251.663009960607, 4696.718196908197, 4992.775325110379, 5080.364954380702, 5227.529522342931, 5307.734613269398, 4267.274563324305, 4357.956420435755, 4785.707858878153, 5122.79508819658, 5137.768800403916, 4792.616590672807, 4914.385701787277, 5007.198848184273, 5083.6187285952965, 5091.974540127276, 4318.697691161588, 4631.76841157934, 5281.15159694104, 5363.41002489718, 5396.730163491889, 3098.7830851840154, 3299.541841328226, 3357.8488967996987, 3510.26284480262, 3511.5259439975275, 4270.444842170498, 4815.001774263641, 4893.730844803427, 4993.350864387607, 5524.392316845224, 5025.858838226109, 5542.7782888684005, 5877.764889404376, 5917.863532327873, 5954.493868021266, 4713.905721885474, 5908.05471124618, 6052.66710330936, 6057.363976782374, 6086.939130608637, 5057.949420505748, 5151.021305951857, 5270.78916843322, 5686.317641883001, 5698.800541981011, 5252.894942101213, 5916.615419997686, 5935.258092738408, 5957.421283359827, 5968.76124954997, 5555.3333599966645, 5753.864144041424, 5803.883922321445, 5813.709314534341, 5826.184334193132], "value": 4022.1624320194046, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.4056026494698, 694.7429451103093, 700.6076597105618, 740.785172300033, 772.806798300425, 15238.923805380971, 15591.688166236669, 16025.038497690146, 16198.75701864473, 16238.188090595468, 14497.855021449783, 14577.635559111, 14659.780103298459, 14666.413343466274, 14678.706425871476, 849.3587341557115, 854.7948492361851, 856.905740368559, 857.1214505132227, 858.2018722587991, 677.9017042528831, 839.0895877972407, 860.3031544448985, 865.8960924689046, 867.3408209760564, 897.7351681254013, 920.2270093121776, 922.5433410461798, 925.4724806860092, 925.7222833150062, 906.8685040669131, 915.094056883687, 915.6612053540233, 918.3020904112856, 918.5912269040247, 677.4411110833582, 680.0207700910673, 680.1226417920925, 681.2812482830611, 683.0505597219868, 14665.973381863274, 14715.705685886329, 14763.96652234344, 14776.335064922105, 14826.629334266621, 13863.0, 13891.583252502442, 13978.580642580739, 14249.50126745559, 14289.857101428957, 10960.397178155199, 10991.230613857033, 11085.869153663709, 11226.550937962553, 11478.827817582744, 3694.224212915291, 3702.0559757261994, 3767.6232376762377, 3860.8069596520063, 3872.4384964180317, 122476.0, 124141.75858241483, 124535.3773231128, 124905.0, 125734.3713281437, 10666.520006599721, 10715.98198171188, 10721.83917241242, 10766.24636275469, 10792.730181745363, 22653.0, 22663.773362266496, 22679.659805102936, 22681.319560413212, 22684.773152268597, 7170.282971702788, 7580.0, 7749.806254843674, 7822.687092516349, 7938.682452701943, 3869.2068126034096, 3958.88123356294, 4038.7172897896867, 4250.703535421664, 4330.848420305274, 3479.582450105953, 3586.7668601540818, 3617.149969757105, 3624.3838547447, 3631.8728844490315, 531.256241262236, 626.3736263736264, 647.0423772816258, 647.9740810367532, 653.31075715121, 567.1096378685506, 572.181780054526, 576.9557101645951, 597.7101105963604, 604.4318340759672, 7089.291070892973, 7357.153927298322, 7547.584882831387, 8192.34461243099, 8692.696095585587, 512.4823927832915, 524.3367140567185, 546.9198333291796, 552.0642510943911, 569.5728203847115, 4191.2874811281545, 4515.796789144496, 4927.0, 4930.285108659296, 4963.45402005779, 153726.0, 160269.0, 160953.0, 161053.38946610384, 162899.5565066525, 79374.603126984, 108102.0, 136511.0, 138936.30531847276, 144727.0, 93041.5347923256, 103466.48266758618, 105614.0, 106881.84622460854, 107826.0, 7392.789674885989, 9025.503597302211, 9398.483083430478, 9400.670976516063, 9505.382150160218, 46559.0, 56337.15494267507, 59220.40779592149, 60592.78814423775, 62961.0, 84419.15580844358, 86750.56624716836, 89631.55184224037, 90607.64088538545, 91861.0, 11130.0, 12245.510179592717, 12576.30830304342, 12760.106792524433, 13092.0, 13833.584992450242, 14322.57032289033, 14357.712845742817, 14443.205623690797, 14901.552953411416, 13236.470541178245, 13278.800817987545, 13534.729305414034, 13590.32048397596, 13690.794638080635, 12563.74872502563, 13048.412821423248, 13166.868331316948, 13386.732265354833, 13487.460501579826, 12179.817302740643, 12550.058745593935, 12907.225566466042, 13021.934890325489, 13054.869451305365, 11030.05835798224, 11064.778704425706, 11160.218784684994, 11173.553057877594, 11178.832317515082, 8657.82684346297, 10797.460126993461, 10800.189985750943, 11038.392888391216, 11045.116390688887], "value": 7633.629843457871, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1648.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 90.0, 172.0, 102.0, 169.0, 146.0, 174.0, 117.0, 116.0, 104.0], "value": 134.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 137.0, 163.0, 111.0, 132.0, 130.0, 105.0, 159.0, 100.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 210.0, 216.0, 272.0, 281.0, 229.0, 268.0, 269.0, 198.0, 201.0], "value": 234.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 80.0, 78.0, 149.0, 61.0, 60.0, 60.0, 66.0, 135.0, 60.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 86.0, 121.0, 71.0, 72.0, 83.0, 112.0, 70.0, 74.0, 113.0], "value": 86.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 119.0, 88.0, 85.0, 89.0, 74.0, 134.0, 126.0, 90.0, 86.0], "value": 102.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 122.0, 145.0, 149.0, 97.0, 129.0, 107.0, 150.0, 150.0, 109.0], "value": 125.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 81.0, 135.0, 143.0, 92.0, 86.0, 95.0, 115.0, 118.0, 92.0], "value": 105.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 64.0, 84.0, 87.0, 83.0, 48.0, 52.0, 78.0, 83.0], "value": 69.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 75.0, 76.0, 73.0, 106.0, 111.0, 89.0, 72.0, 104.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 88.0, 90.0, 89.0, 94.0, 88.0, 87.0, 78.0, 102.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 144.0, 138.0, 140.0, 139.0, 164.0, 165.0, 183.0, 142.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 174.0, 174.0, 195.0, 237.0, 197.0, 176.0, 172.0, 174.0, 175.0], "value": 184.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 68.0, 68.0, 71.0, 70.0, 67.0, 68.0, 67.0, 68.0], "value": 68.4, "unit": "score"}]}]} 14:21:36 INFO - Return code: 0 14:21:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:21:36 ERROR - # TBPL SUCCESS # 14:21:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:21:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:21:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:21:36 INFO - 2016-08-27 14:21:36.269 screenresolution[2038:36318] starting screenresolution argv=screenresolution get 14:21:36 INFO - 2016-08-27 14:21:36.272 screenresolution[2038:36318] Display 0: 1600x1200x32@60 14:21:36 INFO - 2016-08-27 14:21:36.278 screenresolution[2039:36321] starting screenresolution argv=screenresolution list 14:21:36 INFO - Available Modes on Display 0 14:21:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:21:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:21:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:21:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:21:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:21:36 INFO - Intel Iris: 14:21:36 INFO - Chipset Model: Intel Iris 14:21:36 INFO - Type: GPU 14:21:36 INFO - Bus: Built-In 14:21:36 INFO - VRAM (Dynamic, Max): 1536 MB 14:21:36 INFO - Vendor: Intel (0x8086) 14:21:36 INFO - Device ID: 0x0a2e 14:21:36 INFO - Revision ID: 0x0009 14:21:36 INFO - Displays: 14:21:36 INFO - Display: 14:21:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:21:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:21:36 INFO - Display Serial Number: 1600x1200 60 14:21:36 INFO - Main Display: Yes 14:21:36 INFO - Mirror: Off 14:21:36 INFO - Online: Yes 14:21:36 INFO - Rotation: Supported 14:21:36 INFO - Return code: 0 14:21:36 INFO - Running post-action listener: _resource_record_post_action 14:21:36 INFO - [mozharness: 2016-08-27 21:21:36.521832Z] Finished run-tests step (success) 14:21:36 INFO - Running post-run listener: _resource_record_post_run 14:21:36 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 8192; Write bytes: 710811648; Read time: 0; Write time: 2190 14:21:36 INFO - TinderboxPrint: CPU usage
54.5% 14:21:36 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 0 14:21:36 INFO - TinderboxPrint: I/O write bytes / time
710,811,648 / 2,190 14:21:36 INFO - TinderboxPrint: CPU idle
2,208.7 (45.5%) 14:21:36 INFO - TinderboxPrint: CPU system
982.5 (20.3%) 14:21:36 INFO - TinderboxPrint: CPU user
1,658.5 (34.2%) 14:21:36 INFO - TinderboxPrint: Swap in / out
405,282,816 / 0 14:21:36 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 385917440; Write bytes: 381364224; Read time: 28745; Write time: 987 14:21:36 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 8192; Write bytes: 301131776; Read time: 0; Write time: 1152 14:21:37 INFO - Running post-run listener: _upload_blobber_files 14:21:37 INFO - Blob upload gear active. 14:21:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:21:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:21:37 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:21:37 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:21:37 INFO - Traceback (most recent call last): 14:21:37 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 14:21:37 INFO - main() 14:21:37 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 14:21:37 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 14:21:37 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 14:21:37 INFO - return set(response.json().get('whitelist', [])) 14:21:37 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/models.py", line 651, in json 14:21:37 INFO - return json.loads(self.text or self.content, **kwargs) 14:21:37 INFO - File "/tools/python27/lib/python2.7/json/__init__.py", line 326, in loads 14:21:37 INFO - return _default_decoder.decode(s) 14:21:37 INFO - File "/tools/python27/lib/python2.7/json/decoder.py", line 366, in decode 14:21:37 INFO - obj, end = self.raw_decode(s, idx=_w(s, 0).end()) 14:21:37 INFO - File "/tools/python27/lib/python2.7/json/decoder.py", line 384, in raw_decode 14:21:37 INFO - raise ValueError("No JSON object could be decoded") 14:21:37 INFO - ValueError: No JSON object could be decoded 14:21:37 ERROR - Return code: 1 14:21:37 INFO - Setting buildbot property blobber_files to {} 14:21:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:21:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:21:37 INFO - Contents: 14:21:37 INFO - blobber_files:{} 14:21:37 INFO - Running post-run listener: copy_logs_to_upload_dir 14:21:37 INFO - Copying logs to upload dir... 14:21:37 INFO - mkdir: /builds/slave/test/build/upload/logs 14:21:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.049411 ========= master_lag: 0.62 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-27 14:21:38.066452) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-27 14:21:38.069983) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w5iHHvXnk7/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://queue.taskcluster.net/v1/task/Il47hrIsTGqoOrjs1XO8Pg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-27 14:21:38.109069) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 14:21:38.109973) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w5iHHvXnk7/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.mE92f4rS5J/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005003 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-27 14:21:38.159701) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-27 14:21:38.160073) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-27 14:21:38.160661) ========= ========= Total master_lag: 0.93 =========