builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0071 starttime: 1473213131.1 results: success (0) buildid: 20160906173516 builduid: 3a5de2850eeb4b32a06a5d9a0ae4e8e9 revision: 91c2b9d5c1354ca79e5b174591dbb03b32b15bbf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 18:52:11.104716) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 18:52:11.105164) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 18:52:11.105472) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 18:52:11.130939) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:52:11.131337) ========= 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.xabwqh0DlY/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.WNYgvToalZ/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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-09-06 18:52:11-- 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% 9.70M=0.001s 2016-09-06 18:52:11 (9.70 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094402 ========= master_lag: 0.81 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:52:12.031218) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:52:12.032544) ========= 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.xabwqh0DlY/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.WNYgvToalZ/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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.027807 ========= master_lag: 0.11 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:52:12.165669) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 18:52:12.166035) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 91c2b9d5c1354ca79e5b174591dbb03b32b15bbf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 91c2b9d5c1354ca79e5b174591dbb03b32b15bbf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xabwqh0DlY/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.WNYgvToalZ/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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-09-06 18:52:12,244 truncating revision to first 12 chars 2016-09-06 18:52:12,244 Setting DEBUG logging. 2016-09-06 18:52:12,244 attempt 1/10 2016-09-06 18:52:12,244 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/91c2b9d5c135?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 18:52:13,287 unpacking tar archive at: mozilla-central-91c2b9d5c135/testing/mozharness/ program finished with exit code 0 elapsedTime=1.303390 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 18:52:13.491437) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 18:52:13.494777) ========= script_repo_revision: 91c2b9d5c1354ca79e5b174591dbb03b32b15bbf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 18:52:13.495215) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 18:52:13.495486) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 18:52:13.514267) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-06 18:52:13.514613) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xabwqh0DlY/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.WNYgvToalZ/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 18:52:13 INFO - MultiFileLogger online at 20160906 18:52:13 in /builds/slave/test 18:52:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:52:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:52:13 INFO - {'append_to_log': False, 18:52:13 INFO - 'base_work_dir': '/builds/slave/test', 18:52:13 INFO - 'blob_upload_branch': 'Firefox', 18:52:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:52:13 INFO - 'branch': 'Firefox', 18:52:13 INFO - 'buildbot_json_path': 'buildprops.json', 18:52:13 INFO - 'config_files': ('talos/mac_config.py',), 18:52:13 INFO - 'default_actions': ('clobber', 18:52:13 INFO - 'read-buildbot-config', 18:52:13 INFO - 'download-and-extract', 18:52:13 INFO - 'populate-webroot', 18:52:13 INFO - 'create-virtualenv', 18:52:13 INFO - 'install', 18:52:13 INFO - 'run-tests'), 18:52:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:52:13 INFO - 'download_minidump_stackwalk': True, 18:52:13 INFO - 'download_symbols': 'ondemand', 18:52:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:52:13 INFO - 'tooltool.py': '/tools/tooltool.py', 18:52:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:52:13 INFO - '/tools/misc-python/virtualenv.py')}, 18:52:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:52:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:52:13 INFO - 'installer_path': 'installer.exe', 18:52:13 INFO - 'log_level': 'info', 18:52:13 INFO - 'log_name': 'talos', 18:52:13 INFO - 'log_to_console': True, 18:52:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:52:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:52:13 INFO - 'opt_config_files': (), 18:52:13 INFO - 'pip_index': False, 18:52:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:52:13 INFO - 'cmd': ('bash', 18:52:13 INFO - '-c', 18:52:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:52:13 INFO - 'enabled': True, 18:52:13 INFO - 'halt_on_failure': False, 18:52:13 INFO - 'name': 'check_screen_resolution'},), 18:52:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:52:13 INFO - 'cmd': ('bash', 18:52:13 INFO - '-c', 18:52:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:52:13 INFO - 'enabled': True, 18:52:13 INFO - 'halt_on_failure': False, 18:52:13 INFO - 'name': 'check_screen_resolution'},), 18:52:13 INFO - 'run_cmd_checks_enabled': True, 18:52:13 INFO - 'sps_profile': False, 18:52:13 INFO - 'sps_profile_interval': 0, 18:52:13 INFO - 'suite': 'dromaeojs-e10s', 18:52:13 INFO - 'system_bits': '32', 18:52:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:52:13 INFO - 'title': 't-yosemite-r7-0071', 18:52:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:52:13 INFO - 'use_talos_json': True, 18:52:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:52:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:52:13 INFO - 'work_dir': 'build'} 18:52:13 INFO - [mozharness: 2016-09-07 01:52:13.714753Z] Running clobber step. 18:52:13 INFO - Running pre-action listener: _resource_record_pre_action 18:52:13 INFO - Running main action method: clobber 18:52:13 INFO - rmtree: /builds/slave/test/build 18:52:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:52:16 INFO - Running post-action listener: _resource_record_post_action 18:52:16 INFO - [mozharness: 2016-09-07 01:52:16.248766Z] Finished clobber step (success) 18:52:16 INFO - [mozharness: 2016-09-07 01:52:16.248905Z] Running read-buildbot-config step. 18:52:16 INFO - Running pre-action listener: _resource_record_pre_action 18:52:16 INFO - Running main action method: read_buildbot_config 18:52:16 INFO - Using buildbot properties: 18:52:16 INFO - { 18:52:16 INFO - "project": "", 18:52:16 INFO - "product": "firefox", 18:52:16 INFO - "script_repo_revision": "production", 18:52:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:52:16 INFO - "repository": "", 18:52:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:52:16 INFO - "buildid": "20160906173516", 18:52:16 INFO - "pgo_build": "False", 18:52:16 INFO - "basedir": "/builds/slave/test", 18:52:16 INFO - "buildnumber": 49, 18:52:16 INFO - "slavename": "t-yosemite-r7-0071", 18:52:16 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 18:52:16 INFO - "platform": "yosemite_r7", 18:52:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:52:16 INFO - "branch": "mozilla-central", 18:52:16 INFO - "repo_path": "mozilla-central", 18:52:16 INFO - "revision": "91c2b9d5c1354ca79e5b174591dbb03b32b15bbf", 18:52:16 INFO - "stage_platform": "macosx64", 18:52:16 INFO - "builduid": "3a5de2850eeb4b32a06a5d9a0ae4e8e9", 18:52:16 INFO - "slavebuilddir": "test" 18:52:16 INFO - } 18:52:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:52:16 INFO - Running post-action listener: _resource_record_post_action 18:52:16 INFO - [mozharness: 2016-09-07 01:52:16.252164Z] Finished read-buildbot-config step (success) 18:52:16 INFO - [mozharness: 2016-09-07 01:52:16.252285Z] Running download-and-extract step. 18:52:16 INFO - Running pre-action listener: _resource_record_pre_action 18:52:16 INFO - Running main action method: download_and_extract 18:52:16 INFO - mkdir: /builds/slave/test/build/tests 18:52:16 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')]} 18:52:16 INFO - trying https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:52:16 INFO - Downloading https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/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 18:52:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/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 18:52:17 INFO - Downloaded 1199 bytes. 18:52:17 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:52:17 INFO - Using the following test package requirements: 18:52:17 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:52:17 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:52:17 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:52:17 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:52:17 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:52:17 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:52:17 INFO - u'jsshell-mac.zip'], 18:52:17 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:52:17 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:52:17 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:52:17 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:52:17 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:52:17 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:52:17 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:52:17 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:52:17 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:52:17 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:52:17 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:52:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:52:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:52:17 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:52:24 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 18:52:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:52:25 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:52:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:52:32 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:52:33 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')]} 18:52:33 INFO - trying https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:52:33 INFO - Downloading https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:52:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/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 18:52:36 INFO - Downloaded 111508264 bytes. 18:52:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:52:36 INFO - mkdir: /builds/slave/test/properties 18:52:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:52:36 INFO - Writing to file /builds/slave/test/properties/build_url 18:52:36 INFO - Contents: 18:52:36 INFO - build_url:https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:52:37 INFO - Running post-action listener: _resource_record_post_action 18:52:37 INFO - Running post-action listener: set_extra_try_arguments 18:52:37 INFO - [mozharness: 2016-09-07 01:52:37.046669Z] Finished download-and-extract step (success) 18:52:37 INFO - [mozharness: 2016-09-07 01:52:37.046827Z] Running populate-webroot step. 18:52:37 INFO - Running pre-action listener: _resource_record_pre_action 18:52:37 INFO - Running main action method: populate_webroot 18:52:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:52:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:52:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:52:37 INFO - '--tppagecycles', 18:52:37 INFO - '7'], 18:52:37 INFO - 'tests': ['tsvgm']}}, 18:52:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:52:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:52:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:52:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:52:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:52:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:52:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:37 INFO - 'talos_options': ['--disable-e10s'], 18:52:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:52:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:52:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:37 INFO - 'talos_options': ['--disable-e10s'], 18:52:37 INFO - 'tests': ['damp', 'tps']}, 18:52:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:37 INFO - 'tests': ['damp', 'tps']}, 18:52:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:52:37 INFO - 'tests': ['dromaeo_dom']}, 18:52:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:52:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:52:37 INFO - 'tests': ['basic_compositor_video']}, 18:52:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:52:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:52:37 INFO - 'tests': ['a11yr', 18:52:37 INFO - 'ts_paint', 18:52:37 INFO - 'tpaint', 18:52:37 INFO - 'sessionrestore', 18:52:37 INFO - 'sessionrestore_no_auto_restore', 18:52:37 INFO - 'tabpaint']}, 18:52:37 INFO - 'other-e10s': {'tests': ['a11yr', 18:52:37 INFO - 'ts_paint', 18:52:37 INFO - 'tpaint', 18:52:37 INFO - 'sessionrestore', 18:52:37 INFO - 'sessionrestore_no_auto_restore', 18:52:37 INFO - 'tabpaint']}, 18:52:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:52:37 INFO - 'ts_paint', 18:52:37 INFO - 'tpaint', 18:52:37 INFO - 'sessionrestore', 18:52:37 INFO - 'sessionrestore_no_auto_restore', 18:52:37 INFO - 'tabpaint']}, 18:52:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:52:37 INFO - 'ts_paint', 18:52:37 INFO - 'tpaint', 18:52:37 INFO - 'sessionrestore', 18:52:37 INFO - 'sessionrestore_no_auto_restore', 18:52:37 INFO - 'tabpaint']}, 18:52:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:52:37 INFO - 'tests': ['a11yr', 18:52:37 INFO - 'ts_paint', 18:52:37 INFO - 'tpaint', 18:52:37 INFO - 'sessionrestore', 18:52:37 INFO - 'sessionrestore_no_auto_restore', 18:52:37 INFO - 'tabpaint']}, 18:52:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:52:37 INFO - 'tests': ['a11yr', 18:52:37 INFO - 'ts_paint', 18:52:37 INFO - 'tpaint', 18:52:37 INFO - 'sessionrestore', 18:52:37 INFO - 'sessionrestore_no_auto_restore', 18:52:37 INFO - 'tabpaint']}, 18:52:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:52:37 INFO - 'tests': ['tsvgx', 18:52:37 INFO - 'tsvgr_opacity', 18:52:37 INFO - 'tart', 18:52:37 INFO - 'tscrollx', 18:52:37 INFO - 'cart']}, 18:52:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:52:37 INFO - 'tsvgr_opacity', 18:52:37 INFO - 'tart', 18:52:37 INFO - 'tscrollx', 18:52:37 INFO - 'cart']}, 18:52:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:37 INFO - 'talos_options': ['--disable-e10s'], 18:52:37 INFO - 'tests': ['tp5o']}, 18:52:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:37 INFO - 'tests': ['tp5o']}, 18:52:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:37 INFO - 'talos_options': ['--disable-e10s', 18:52:37 INFO - '--xperf_path', 18:52:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:52:37 INFO - 'tests': ['tp5n']}, 18:52:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:52:37 INFO - 'talos_options': ['--xperf_path', 18:52:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:52:37 INFO - 'tests': ['tp5n']}}, 18:52:37 INFO - 'talos.zip': {'path': '', 18:52:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:52:37 INFO - Running post-action listener: _resource_record_post_action 18:52:37 INFO - [mozharness: 2016-09-07 01:52:37.065673Z] Finished populate-webroot step (success) 18:52:37 INFO - [mozharness: 2016-09-07 01:52:37.065790Z] Running create-virtualenv step. 18:52:37 INFO - Running pre-action listener: _resource_record_pre_action 18:52:37 INFO - Running main action method: create_virtualenv 18:52:37 INFO - Creating virtualenv /builds/slave/test/build/venv 18:52:37 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 18:52:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:52:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:52:37 INFO - Using real prefix '/tools/python27' 18:52:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:52:37 INFO - Installing distribute.............................................................................................................................................................................................done. 18:52:40 INFO - Installing pip.................done. 18:52:40 INFO - Return code: 0 18:52:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:40 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 0x107fb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108047618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a8d988b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f16d30>, '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 0x107c7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f0eb20>, '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.xabwqh0DlY/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.WNYgvToalZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 18:52:40 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 18:52:40 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 18:52:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xabwqh0DlY/Render', 18:52:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:40 INFO - 'HOME': '/Users/cltbld', 18:52:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:40 INFO - 'LOGNAME': 'cltbld', 18:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:40 INFO - 'MOZ_NO_REMOTE': '1', 18:52:40 INFO - 'NO_EM_RESTART': '1', 18:52:40 INFO - 'PAGER': '/bin/cat', 18:52:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:40 INFO - 'PWD': '/builds/slave/test', 18:52:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:40 INFO - 'SHELL': '/bin/bash', 18:52:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNYgvToalZ/Listeners', 18:52:40 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 18:52:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:40 INFO - 'USER': 'cltbld', 18:52:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:40 INFO - 'XPC_FLAGS': '0x0', 18:52:40 INFO - 'XPC_SERVICE_NAME': '0', 18:52:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:41 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 18:52:41 INFO - Cleaning up... 18:52:41 INFO - Return code: 0 18:52:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:52:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:41 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 0x107fb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108047618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a8d988b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f16d30>, '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 0x107c7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f0eb20>, '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.xabwqh0DlY/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.WNYgvToalZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 18:52:41 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 18:52:41 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 18:52:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xabwqh0DlY/Render', 18:52:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:41 INFO - 'HOME': '/Users/cltbld', 18:52:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:41 INFO - 'LOGNAME': 'cltbld', 18:52:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:41 INFO - 'MOZ_NO_REMOTE': '1', 18:52:41 INFO - 'NO_EM_RESTART': '1', 18:52:41 INFO - 'PAGER': '/bin/cat', 18:52:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:41 INFO - 'PWD': '/builds/slave/test', 18:52:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:41 INFO - 'SHELL': '/bin/bash', 18:52:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNYgvToalZ/Listeners', 18:52:41 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 18:52:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:41 INFO - 'USER': 'cltbld', 18:52:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:41 INFO - 'XPC_FLAGS': '0x0', 18:52:41 INFO - 'XPC_SERVICE_NAME': '0', 18:52:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:41 INFO - Downloading/unpacking psutil>=3.1.1 18:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:52:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:52:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:52:44 INFO - Installing collected packages: psutil 18:52:44 INFO - Running setup.py install for psutil 18:52:44 INFO - building 'psutil._psutil_osx' extension 18:52:44 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 18:52:45 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 18:52:45 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 18:52:45 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 18:52:45 INFO - building 'psutil._psutil_posix' extension 18:52:45 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 18:52:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:52:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:52:45 INFO - ^ 18:52:45 INFO - 1 warning generated. 18:52:45 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 18:52:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:52:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:52:45 INFO - Successfully installed psutil 18:52:45 INFO - Cleaning up... 18:52:45 INFO - Return code: 0 18:52:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:45 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 0x107fb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108047618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a8d988b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f16d30>, '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 0x107c7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f0eb20>, '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.xabwqh0DlY/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.WNYgvToalZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 18:52:45 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 18:52:45 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 18:52:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xabwqh0DlY/Render', 18:52:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:45 INFO - 'HOME': '/Users/cltbld', 18:52:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:45 INFO - 'LOGNAME': 'cltbld', 18:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:45 INFO - 'MOZ_NO_REMOTE': '1', 18:52:45 INFO - 'NO_EM_RESTART': '1', 18:52:45 INFO - 'PAGER': '/bin/cat', 18:52:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:45 INFO - 'PWD': '/builds/slave/test', 18:52:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:45 INFO - 'SHELL': '/bin/bash', 18:52:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNYgvToalZ/Listeners', 18:52:45 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 18:52:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:45 INFO - 'USER': 'cltbld', 18:52:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:45 INFO - 'XPC_FLAGS': '0x0', 18:52:45 INFO - 'XPC_SERVICE_NAME': '0', 18:52:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:52:46 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 18:52:46 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 18:52:46 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 18:52:46 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 18:52:46 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 18:52:46 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 18:52:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:52:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:52:48 INFO - Installing collected packages: mozsystemmonitor 18:52:48 INFO - Running setup.py install for mozsystemmonitor 18:52:48 INFO - Successfully installed mozsystemmonitor 18:52:48 INFO - Cleaning up... 18:52:48 INFO - Return code: 0 18:52:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:52: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')]} 18:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52: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')]} 18:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:48 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 0x107fb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108047618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a8d988b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f16d30>, '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 0x107c7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f0eb20>, '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.xabwqh0DlY/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.WNYgvToalZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 18:52:48 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 18:52:48 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 18:52:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xabwqh0DlY/Render', 18:52:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:48 INFO - 'HOME': '/Users/cltbld', 18:52:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:48 INFO - 'LOGNAME': 'cltbld', 18:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:48 INFO - 'MOZ_NO_REMOTE': '1', 18:52:48 INFO - 'NO_EM_RESTART': '1', 18:52:48 INFO - 'PAGER': '/bin/cat', 18:52:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:48 INFO - 'PWD': '/builds/slave/test', 18:52:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:48 INFO - 'SHELL': '/bin/bash', 18:52:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNYgvToalZ/Listeners', 18:52:48 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 18:52:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:48 INFO - 'USER': 'cltbld', 18:52:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:48 INFO - 'XPC_FLAGS': '0x0', 18:52:48 INFO - 'XPC_SERVICE_NAME': '0', 18:52:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:49 INFO - Downloading/unpacking jsonschema==2.5.1 18:52:49 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 18:52:49 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 18:52:49 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 18:52:49 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 18:52:49 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 18:52:49 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 18:52:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:52:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:52:51 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 18:52:51 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 18:52:51 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 18:52:51 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 18:52:51 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 18:52:51 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 18:52:52 INFO - Downloading functools32-3.2.3-2.tar.gz 18:52:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:52:52 INFO - warning: no files found matching '*.txt' 18:52:52 INFO - no previously-included directories found matching 'build' 18:52:52 INFO - no previously-included directories found matching 'dist' 18:52:52 INFO - no previously-included directories found matching '.git*' 18:52:52 INFO - Installing collected packages: jsonschema, functools32 18:52:52 INFO - Running setup.py install for functools32 18:52:52 INFO - warning: no files found matching '*.txt' 18:52:52 INFO - no previously-included directories found matching 'build' 18:52:52 INFO - no previously-included directories found matching 'dist' 18:52:52 INFO - no previously-included directories found matching '.git*' 18:52:52 INFO - Successfully installed jsonschema functools32 18:52:52 INFO - Cleaning up... 18:52:52 INFO - Return code: 0 18:52:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:52 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 0x107fb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108047618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a8d988b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f16d30>, '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 0x107c7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f0eb20>, '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.xabwqh0DlY/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.WNYgvToalZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 18:52:52 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 18:52:52 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 18:52:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xabwqh0DlY/Render', 18:52:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:52 INFO - 'HOME': '/Users/cltbld', 18:52:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:52 INFO - 'LOGNAME': 'cltbld', 18:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:52 INFO - 'MOZ_NO_REMOTE': '1', 18:52:52 INFO - 'NO_EM_RESTART': '1', 18:52:52 INFO - 'PAGER': '/bin/cat', 18:52:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:52 INFO - 'PWD': '/builds/slave/test', 18:52:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:52 INFO - 'SHELL': '/bin/bash', 18:52:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNYgvToalZ/Listeners', 18:52:52 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 18:52:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:52 INFO - 'USER': 'cltbld', 18:52:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:52 INFO - 'XPC_FLAGS': '0x0', 18:52:52 INFO - 'XPC_SERVICE_NAME': '0', 18:52:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:52:52 INFO - Cleaning up... 18:52:52 INFO - Return code: 0 18:52:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:52 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 0x107fb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108047618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a8d988b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f16d30>, '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 0x107c7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f0eb20>, '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.xabwqh0DlY/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.WNYgvToalZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 18:52:52 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 18:52:52 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 18:52:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xabwqh0DlY/Render', 18:52:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:52 INFO - 'HOME': '/Users/cltbld', 18:52:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:52 INFO - 'LOGNAME': 'cltbld', 18:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:52 INFO - 'MOZ_NO_REMOTE': '1', 18:52:52 INFO - 'NO_EM_RESTART': '1', 18:52:52 INFO - 'PAGER': '/bin/cat', 18:52:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:52 INFO - 'PWD': '/builds/slave/test', 18:52:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:52 INFO - 'SHELL': '/bin/bash', 18:52:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNYgvToalZ/Listeners', 18:52:52 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 18:52:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:52 INFO - 'USER': 'cltbld', 18:52:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:52 INFO - 'XPC_FLAGS': '0x0', 18:52:52 INFO - 'XPC_SERVICE_NAME': '0', 18:52:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:52 INFO - Downloading/unpacking blobuploader==1.2.4 18:52:52 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 18:52:52 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 18:52:52 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 18:52:52 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 18:52:52 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 18:52:52 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 18:52:55 INFO - Downloading blobuploader-1.2.4.tar.gz 18:52:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:52:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:52:55 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 18:52:55 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 18:52:55 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 18:52:55 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 18:52:55 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 18:52:55 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 18:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:52:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:52:56 INFO - Downloading docopt-0.6.1.tar.gz 18:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:52:56 INFO - Installing collected packages: blobuploader, requests, docopt 18:52:56 INFO - Running setup.py install for blobuploader 18:52:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:52:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:52:56 INFO - Running setup.py install for requests 18:52:57 INFO - Running setup.py install for docopt 18:52:57 INFO - Successfully installed blobuploader requests docopt 18:52:57 INFO - Cleaning up... 18:52:57 INFO - Return code: 0 18:52:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:57 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 0x107fb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108047618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a8d988b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f16d30>, '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 0x107c7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f0eb20>, '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.xabwqh0DlY/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.WNYgvToalZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 18:52:57 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 18:52:57 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 18:52:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xabwqh0DlY/Render', 18:52:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:57 INFO - 'HOME': '/Users/cltbld', 18:52:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:57 INFO - 'LOGNAME': 'cltbld', 18:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:57 INFO - 'MOZ_NO_REMOTE': '1', 18:52:57 INFO - 'NO_EM_RESTART': '1', 18:52:57 INFO - 'PAGER': '/bin/cat', 18:52:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:57 INFO - 'PWD': '/builds/slave/test', 18:52:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:57 INFO - 'SHELL': '/bin/bash', 18:52:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNYgvToalZ/Listeners', 18:52:57 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 18:52:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:57 INFO - 'USER': 'cltbld', 18:52:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:57 INFO - 'XPC_FLAGS': '0x0', 18:52:57 INFO - 'XPC_SERVICE_NAME': '0', 18:52:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:52:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-4LxRGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:52:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-0BJyuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:52:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-XH97qo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:52:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-lwGJkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:52:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-cu72s6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:52:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-8a7CWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:52:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-qqFq8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:52:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-m_zL8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:52:58 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Ree0st-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:52:59 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-882Sgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:52:59 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-GcpVb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:52:59 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-OsgT8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:52:59 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-JCdjaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:52:59 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-MPw6M1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:52:59 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-VfrwHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:52:59 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-Rx2973-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:52:59 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-47zYSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:52:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:52:59 INFO - Running setup.py install for manifestparser 18:52:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:52:59 INFO - Running setup.py install for mozcrash 18:53:00 INFO - Running setup.py install for mozdebug 18:53:00 INFO - Running setup.py install for mozdevice 18:53:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:53:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:53:00 INFO - Running setup.py install for mozfile 18:53:00 INFO - Running setup.py install for mozhttpd 18:53:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:53:00 INFO - Running setup.py install for mozinfo 18:53:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:53:00 INFO - Running setup.py install for mozInstall 18:53:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:53:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:53:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:53:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:53:01 INFO - Running setup.py install for mozleak 18:53:01 INFO - Running setup.py install for mozlog 18:53:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:53:01 INFO - Running setup.py install for moznetwork 18:53:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:53:01 INFO - Running setup.py install for mozprocess 18:53:01 INFO - Running setup.py install for mozprofile 18:53:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:53:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:53:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:53:01 INFO - Running setup.py install for mozrunner 18:53:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:53:01 INFO - Running setup.py install for mozscreenshot 18:53:02 INFO - Running setup.py install for moztest 18:53:02 INFO - Running setup.py install for mozversion 18:53:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:53:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:53:02 INFO - Cleaning up... 18:53:02 INFO - Return code: 0 18:53:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:53:02 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')]} 18:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:02 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')]} 18:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:02 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 0x107fb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108047618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a8d988b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f16d30>, '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 0x107c7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f0eb20>, '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.xabwqh0DlY/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.WNYgvToalZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 18:53:02 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 18:53:02 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 18:53:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xabwqh0DlY/Render', 18:53:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:02 INFO - 'HOME': '/Users/cltbld', 18:53:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:02 INFO - 'LOGNAME': 'cltbld', 18:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:02 INFO - 'MOZ_NO_REMOTE': '1', 18:53:02 INFO - 'NO_EM_RESTART': '1', 18:53:02 INFO - 'PAGER': '/bin/cat', 18:53:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:02 INFO - 'PWD': '/builds/slave/test', 18:53:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:02 INFO - 'SHELL': '/bin/bash', 18:53:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNYgvToalZ/Listeners', 18:53:02 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 18:53:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:02 INFO - 'USER': 'cltbld', 18:53:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:02 INFO - 'XPC_FLAGS': '0x0', 18:53:02 INFO - 'XPC_SERVICE_NAME': '0', 18:53:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:53:02 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-vjfGyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:53:02 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)) 18:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:53:02 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-krQdIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:53:02 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)) 18:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:53:02 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-fBWWkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:53:03 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)) 18:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:53:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-xw6QPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:53:03 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)) 18:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:53:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-AYcHpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:53:03 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)) 18:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:53:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-xEwEvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:53:03 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)) 18:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:53:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-8NqCRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:53:03 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)) 18:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:53:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-7AWqVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:53:03 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)) 18:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:53:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-qKpCFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:53:03 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)) 18:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:53:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-r5j9GO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:53:03 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)) 18:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:53:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-N2lcRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:53:03 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)) 18:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:53:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-vQNsjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:53:03 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)) 18:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:53:03 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-jwOb_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:53:04 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)) 18:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:53:04 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-TlJ2iX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:53:04 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)) 18:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:53:04 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-J8tYaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:53:04 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)) 18:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:53:04 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-V1espd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:53:04 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)) 18:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:53:04 INFO - Running setup.py (path:/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/pip-tqKeOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:53:04 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)) 18:53:04 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)) 18:53:04 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)) 18:53:04 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)) 18:53:04 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)) 18:53:04 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)) 18:53:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:07 INFO - Downloading blessings-1.6.tar.gz 18:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:53:07 INFO - Installing collected packages: blessings 18:53:07 INFO - Running setup.py install for blessings 18:53:07 INFO - Successfully installed blessings 18:53:07 INFO - Cleaning up... 18:53:07 INFO - Return code: 0 18:53:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:53:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:53:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:53:07 INFO - Reading from file tmpfile_stdout 18:53:07 INFO - Current package versions: 18:53:07 INFO - blessings == 1.6 18:53:07 INFO - blobuploader == 1.2.4 18:53:07 INFO - docopt == 0.6.1 18:53:07 INFO - functools32 == 3.2.3-2 18:53:07 INFO - jsonschema == 2.5.1 18:53:07 INFO - manifestparser == 1.1 18:53:07 INFO - mozInstall == 1.12 18:53:07 INFO - mozcrash == 0.17 18:53:07 INFO - mozdebug == 0.1 18:53:07 INFO - mozdevice == 0.48 18:53:07 INFO - mozfile == 1.2 18:53:07 INFO - mozhttpd == 0.7 18:53:07 INFO - mozinfo == 0.9 18:53:07 INFO - mozleak == 0.1 18:53:07 INFO - mozlog == 3.3 18:53:07 INFO - moznetwork == 0.27 18:53:07 INFO - mozprocess == 0.23 18:53:07 INFO - mozprofile == 0.28 18:53:07 INFO - mozrunner == 6.12 18:53:07 INFO - mozscreenshot == 0.1 18:53:07 INFO - mozsystemmonitor == 0.3 18:53:07 INFO - moztest == 0.7 18:53:07 INFO - mozversion == 1.4 18:53:07 INFO - psutil == 3.1.1 18:53:07 INFO - requests == 1.2.3 18:53:07 INFO - wsgiref == 0.1.2 18:53:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:53:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53: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')]} 18:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53:08 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 0x107fb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108047618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a8d988b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f16d30>, '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 0x107c7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f0eb20>, '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.xabwqh0DlY/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.WNYgvToalZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 18:53:08 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 18:53:08 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 18:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xabwqh0DlY/Render', 18:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:08 INFO - 'HOME': '/Users/cltbld', 18:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:08 INFO - 'LOGNAME': 'cltbld', 18:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:08 INFO - 'MOZ_NO_REMOTE': '1', 18:53:08 INFO - 'NO_EM_RESTART': '1', 18:53:08 INFO - 'PAGER': '/bin/cat', 18:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:08 INFO - 'PWD': '/builds/slave/test', 18:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:08 INFO - 'SHELL': '/bin/bash', 18:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNYgvToalZ/Listeners', 18:53:08 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 18:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:08 INFO - 'USER': 'cltbld', 18:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:08 INFO - 'XPC_FLAGS': '0x0', 18:53:08 INFO - 'XPC_SERVICE_NAME': '0', 18:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:08 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)) 18:53:08 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)) 18:53:08 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)) 18:53:08 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)) 18:53:08 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)) 18:53:08 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)) 18:53:08 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)) 18:53:08 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)) 18:53:08 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)) 18:53:08 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)) 18:53:08 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)) 18:53:08 INFO - Cleaning up... 18:53:08 INFO - Return code: 0 18:53:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:53: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')]} 18:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53: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')]} 18:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:53: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107fb2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108047618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0a8d988b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f16d30>, '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 0x107c7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f0eb20>, '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.xabwqh0DlY/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.WNYgvToalZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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 18:53: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', 'jsonschema'] in /builds/slave/test/build 18:53: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 jsonschema 18:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xabwqh0DlY/Render', 18:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:08 INFO - 'HOME': '/Users/cltbld', 18:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:08 INFO - 'LOGNAME': 'cltbld', 18:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:08 INFO - 'MOZ_NO_REMOTE': '1', 18:53:08 INFO - 'NO_EM_RESTART': '1', 18:53:08 INFO - 'PAGER': '/bin/cat', 18:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:08 INFO - 'PWD': '/builds/slave/test', 18:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:08 INFO - 'SHELL': '/bin/bash', 18:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNYgvToalZ/Listeners', 18:53:08 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 18:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:08 INFO - 'USER': 'cltbld', 18:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:08 INFO - 'XPC_FLAGS': '0x0', 18:53:08 INFO - 'XPC_SERVICE_NAME': '0', 18:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:53:08 INFO - Cleaning up... 18:53:08 INFO - Return code: 0 18:53:08 INFO - Running post-action listener: _resource_record_post_action 18:53:08 INFO - Running post-action listener: _start_resource_monitoring 18:53:08 INFO - Starting resource monitoring. 18:53:08 INFO - [mozharness: 2016-09-07 01:53:08.884869Z] Finished create-virtualenv step (success) 18:53:08 INFO - [mozharness: 2016-09-07 01:53:08.885901Z] Running install step. 18:53:08 INFO - Running pre-action listener: _resource_record_pre_action 18:53:08 INFO - Running main action method: install 18:53:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:53:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:53:09 INFO - Reading from file tmpfile_stdout 18:53:09 INFO - Detecting whether we're running mozinstall >=1.0... 18:53:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:53:09 INFO - Reading from file tmpfile_stdout 18:53:09 INFO - Output received: 18:53:09 INFO - Usage: mozinstall [options] installer 18:53:09 INFO - Options: 18:53:09 INFO - -h, --help show this help message and exit 18:53:09 INFO - -d DEST, --destination=DEST 18:53:09 INFO - Directory to install application into. [default: 18:53:09 INFO - "/builds/slave/test"] 18:53:09 INFO - --app=APP Application being installed. [default: firefox] 18:53:09 INFO - mkdir: /builds/slave/test/build/application 18:53:09 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'] 18:53:09 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 18:53:43 INFO - Reading from file tmpfile_stdout 18:53:43 INFO - Output received: 18:53:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:53:43 INFO - Running post-action listener: _resource_record_post_action 18:53:43 INFO - [mozharness: 2016-09-07 01:53:43.842636Z] Finished install step (success) 18:53:43 INFO - [mozharness: 2016-09-07 01:53:43.842746Z] Running run-tests step. 18:53:43 INFO - Running pre-action listener: _resource_record_pre_action 18:53:43 INFO - Running main action method: run_tests 18:53:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:53:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:53:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:53:43 INFO - 2016-09-06 18:53:43.857 screenresolution[1632:7461] starting screenresolution argv=screenresolution get 18:53:43 INFO - 2016-09-06 18:53:43.859 screenresolution[1632:7461] Display 0: 1600x1200x32@60 18:53:43 INFO - 2016-09-06 18:53:43.865 screenresolution[1633:7463] starting screenresolution argv=screenresolution list 18:53:43 INFO - Available Modes on Display 0 18:53:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:53:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:53:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:53:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:53:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:53:44 INFO - Intel Iris: 18:53:44 INFO - Chipset Model: Intel Iris 18:53:44 INFO - Type: GPU 18:53:44 INFO - Bus: Built-In 18:53:44 INFO - VRAM (Dynamic, Max): 1536 MB 18:53:44 INFO - Vendor: Intel (0x8086) 18:53:44 INFO - Device ID: 0x0a2e 18:53:44 INFO - Revision ID: 0x0009 18:53:44 INFO - Displays: 18:53:44 INFO - Display: 18:53:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:53:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:53:44 INFO - Display Serial Number: 1600x1200 60 18:53:44 INFO - Main Display: Yes 18:53:44 INFO - Mirror: Off 18:53:44 INFO - Online: Yes 18:53:44 INFO - Rotation: Supported 18:53:44 INFO - Return code: 0 18:53:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:53:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:53:44 INFO - Python 2.7.3 18:53:44 INFO - Return code: 0 18:53:44 INFO - grabbing minidump binary from tooltool 18:53:44 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')]} 18:53:44 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 0x107f16d30>, '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 0x107c7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f0eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:53:44 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 18:53:44 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 18:53:44 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 18:53:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:53:44 INFO - Return code: 0 18:53:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:53:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:53:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:53:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:53:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:53:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--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 18:53:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0071 --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 18:53:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xabwqh0DlY/Render', 18:53:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:44 INFO - 'HOME': '/Users/cltbld', 18:53:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:44 INFO - 'LOGNAME': 'cltbld', 18:53:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:53:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:44 INFO - 'MOZ_NO_REMOTE': '1', 18:53:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:53:44 INFO - 'NO_EM_RESTART': '1', 18:53:44 INFO - 'PAGER': '/bin/cat', 18:53:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:44 INFO - 'PWD': '/builds/slave/test', 18:53:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:53:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:44 INFO - 'SHELL': '/bin/bash', 18:53:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WNYgvToalZ/Listeners', 18:53:44 INFO - 'TMPDIR': '/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/', 18:53:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:44 INFO - 'USER': 'cltbld', 18:53:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:44 INFO - 'XPC_FLAGS': '0x0', 18:53:44 INFO - 'XPC_SERVICE_NAME': '0', 18:53:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0071', '--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 18:53:44 INFO - mozversion application_buildid: 20160906173516 18:53:44 INFO - mozversion application_changeset: 91c2b9d5c1354ca79e5b174591dbb03b32b15bbf 18:53:44 INFO - mozversion application_display_name: Nightly 18:53:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:53:44 INFO - mozversion application_name: Firefox 18:53:44 INFO - mozversion application_remotingname: firefox 18:53:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:53:44 INFO - mozversion application_vendor: Mozilla 18:53:44 INFO - mozversion application_version: 51.0a1 18:53:44 INFO - mozversion platform_buildid: 20160906173516 18:53:44 INFO - mozversion platform_changeset: 91c2b9d5c1354ca79e5b174591dbb03b32b15bbf 18:53:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:53:44 INFO - mozversion platform_version: 51.0a1 18:53:44 INFO - using testdate: 1473213224 18:53:44 INFO - actual date: 1473213224 18:53:44 INFO - starting webserver on 'localhost:49272' 18:53:44 INFO - SUITE-START | Running 2 tests 18:53:44 INFO - TEST-START | dromaeo_css 18:53:44 INFO - operating with platform_type : mac_ 18:53:44 INFO - Initialising browser for dromaeo_css test... 18:53:44 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpiSOYsH/profile http://localhost:49272/getInfo.html) 18:53:47 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 18:53:47 INFO - PROCESS | 1643 | colorDepth:24 18:53:47 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 18:53:47 INFO - PROCESS | 1643 | __metrics 18:53:47 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 18:53:47 INFO - TEST-INFO | 1643: exit 0 18:53:47 INFO - Browser initialized. 18:53:47 INFO - Running cycle 1/1 for dromaeo_css test... 18:53:47 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmpiSOYsH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:54:00 INFO - PROCESS | 1646 | RSS: Main: 243802112 18:54:00 INFO - PROCESS | 1646 | 18:54:49 INFO - PROCESS | 1646 | 1473213289740 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] 18:57:01 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 18:57:03 INFO - PROCESS | 1646 | RSS: Main: 261615616 18:57:03 INFO - PROCESS | 1646 | 19:00:05 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 19:00:06 INFO - PROCESS | 1646 | RSS: Main: 266768384 19:00:06 INFO - PROCESS | 1646 | 19:03:08 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 19:03:08 INFO - PROCESS | 1646 | RSS: Main: 273395712 19:03:08 INFO - PROCESS | 1646 | 19:06:10 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 19:06:11 INFO - PROCESS | 1646 | RSS: Main: 268509184 19:06:11 INFO - PROCESS | 1646 | 19:09:13 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 19:09:14 INFO - PROCESS | 1646 | RSS: Main: 273936384 19:09:14 INFO - PROCESS | 1646 | 19:12:17 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 19:12:18 INFO - PROCESS | 1646 | RSS: Main: 276754432 19:12:18 INFO - PROCESS | 1646 | 19:12:18 INFO - PROCESS | 1646 | __start_tp_report 19:12:18 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 19:12:18 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 19:12:18 INFO - PROCESS | 1646 | |i|pagename|runs| 19:12:18 INFO - PROCESS | 1646 | |0;dojo.html;3042.19381863806;3335.616404113526;3356.446186379247;3389.779664321819;3400.8639654413823;4336.3495475678665;5595.9160612590795;5617.887642247153;5618.325800903892;5722.141678748179;5705.343885453174;5742.598018138732;5746.195532625426;5765.6828874411995;5774.162746401769;5137.794488220486;5228.816991405301;5235.11003129467;5283.339582552181;5330.333708286465;5242.187460943541;5302.337207849019;5307.2835167252315;5324.600654950894;5341.252224688546;3816;5126.461721519243;5148.459411761749;5214.5306922376785;5299.417064122943;5212.244224587452;5224.895502089937;5245.527902488756;5257.342832145981;5259.079661059338;5111.87220319495;5141.640085194038;5180.792768289264;5193.454687257859;5222.033923724085;5401.270828438189;5607.383187849374;5638.379778224392;5639.379668236491;5696.316442026942;5320.840374788762;6161.84595385119;6164.7534098636;6186.10301506279;6186.103015062836;6161.476274516672;6259.530535209832;6274.937250627481;6275.780347687809;6279.466245369149;5754.827355179351;5795.826125216208;5816.127580862904;5816.505597024216;5878.882422351529;6256.124142620037;6306.1171435998995;6306.180196574508;6332.936670633235;6346.079818426302;5345;5764.74058667361;6169.722362493699;6177.7837775677635;6195.504359651217;6137.193127861533;6179.28938172116;6196.318404975449;6207.503399728011;6228.47058000066;6197.721102550396;6220.533459990519;6232.283287422005;6260.686965651699;6280.842978925472;6169.321374648785;6191.876162476815;6252.155958945576;6252.343503932022;6260.436560709558;4926.261060840902;4942;4971.229459433794;4988.8752781180765;5055.671381360274;3648.434492653643;3949.6642785363565;4114.115465175002;4436.977815110904;4453.22068637987;4806.543378379119;5544.168374743821;5794.82615521535;5802.767889284381;5862.677552734641;6132.539076726632;6213.2233470816145;6244.344343843922;6292.370762923762;6328.335524769925;4835.323054772335;5773.93182261283;5818.4763371296785;5824.708764561755;5857;5439.809606663748;5447.863803404946;5452.345718513804;5465.016297066487;5465.344158700902;6254.437100660963;6270.6551139685935;6284.748610055546;6362.331955144643;6377.7448902044325;6303.810885673437;6324.873502529832;6333.904991425225;6343.9682801585695;6352.650604216812;6384.553081284265;6391.93608063932;6413.39072788103;6430.324815894263;6432.292447830641;6047.425494578184;6102.004547214632;6123.557162997741;6248.656323902229;6252.124702541739;6132.5707200495535;6256.155419018374;6271.811845644638;6327.96836015817;6328.7152078157515;6144.846378840386;6157.476614487819;6160.16837726919;6186.10301506288;6194.721237544411;5150.583589512914;5503;6106.7640410362355;6113.871604717183;6168.537359698131;4916.557509824133;5989.8203053908455;5999.160117583455;6021.518278537619;6122.173506576555;6140.754369825157;6158.353372895961;6164.106204600397;6171.1360409543595;6221.4089661482085;4858.854234372974;5892.175095486549;6010.9699451502465;6016.909746353721;6047.637141771508 19:12:18 INFO - PROCESS | 1646 | |1;ext.html;464.1506043939591;529.5340100711373;529.9814506492272;539.7058603061331;563.9887202255958;32810.34379312412;40102.39846402299;40105.59894401055;40122.59877401229;40257.798711006406;40607.796961015156;41080.58919410805;41119.383209251886;41218;41447.963800904916;9566.760830979229;9714.708558743248;9749.31754777166;9770.462624555646;9783.315167938246;9239.214666753334;9655.75860603487;9707.95146024269;9729;9782.853257201148;9777.560009799576;9790.853137202948;9800.460974646392;9811.018898110167;9823.459709715962;9752.512374381287;9790;9796.804063918682;9800.509974501318;9800.803983920354;9593.664221752275;9603.4718090505;9636.662716804878;9650.324477286593;9674;39107.41338879891;39436.802815985735;39559.81320560388;39749;39936.80031599823;3253.4957081652383;3303.3228188221356;3346.8995930122137;3437.1922598189403;3439.570053743282;29493.967711130223;30321;31212.843935780176;32267.51598726021;32634.673653263402;3237.5143728440685;3262.559554460141;3264.55928449661;3272.9509057364157;3366.5286859839644;468863;471494;477054;479074.9999999965;479285;55453.722731386086;61959.69020154871;63751;64741;65052.34947650557;35295.29409411798;35797.46303805445;36163.73426930045;36782;68212;16740;16908.746368804477;16908.91545542289;16958.830411695973;16977.575560611083;11092.890710929005;12439;12720.300383478812;12861.742765144645;12913.966882649365;20494.590108198052;20509.897450512653;20697.482562935747;20760.169593216404;20772.79227207739;7307.634618269065;8731.60707768152;8784.824303513893;8813.779655508664;8833.69082082124;819.2913130142384;848.1772680499905;884.2793123604242;1017.4200705597887;1033.261218228955;26585.867070664524;28339.57490637642;28490.572641410396;29869.55195672067;30582;1009.7980403919246;1016.903394177552;1017.9565944906531;1021.1269364693229;1121.6466812953893;13433.664158395923;15349.539513814603;15431.38274469031;15685.686286274211;15794.447194348142;552465;653743;657850;662025;662109;55644.72177639167;55869;56370.87258254894;56669;56706.716466417405;1110.2394859521253;1172.8827117288204;1368.4594585138616;1385.1550554161954;1391.2139641102744;28150.71849281481;29219;29249.41501169922;29265.85367073151;29743.405131897674;48690.75654621704;48903.26645100255;48990.26514602355;49171.75414123049;49193;575665;576336;577407;578017;579099.1045044748;26688.19935401941;26892.327691807077;27001.729982699922;27010.594841077793;27149.72850271551;45896;46408.83977900445;46482;47459.52540474551;47545.762271188425;38607.6139238604;38775.03062423462;39063.02342441348;39383.80308098441;39398.60601393949;31695;36103.736369228005;37908.43137352887;38229.61770382261;42495.362569560864;30725.846370768002;32666.183345416553;37935.62064379321;37963.620363796006;38093.42859857049;33734;34124.82937585296;35002;35129.47305790364;35208.29583408266;20515.79484205139;25381;25716.228513143888;25848.353791155372;26007.609885852107 19:12:18 INFO - PROCESS | 1646 | |2;jquery.html;340.5011657921145;367.4910249304712;374.9445311448273;383.93856982882744;397.1560434077585;16446.83553164468;19431.41705748828;20434.795652043475;20661;20681.276155334566;16580.75128873068;19851.10670019849;20116.798832011675;20407.224663437275;20765.480862978435;1563.3355823774907;1618.668173024527;1618.8785841061967;1619.9190040497988;1839.4021942868587;5727.799527016556;5907.468327850493;5911.822645320647;6036.577439579231;6041.909371359433;5017.397912250535;5917.289925208959;5920.526357891358;5920.822375328747;5926.881462370771;5609.439056094398;5753.568482363798;5862.06207006879;5869.325027621835;5871.765129394819;5067.062593420229;5102.387713474407;5120.385553733576;5126.641135120543;5141.331626888518;19956.90021549883;23938.28185154448;25312.493750124893;25404;25659.74340256592;2182.4434769133927;2252.31304452142;2260.9660855087186;2377.9881100594384;2436.4639779248537;15161.696766064728;15509.5347139586;15623.687526249412;16107.355705771755;16360.509184724477;1773.671870703911;1810.809864964186;1862.32956135791;1877.6714075036814;1921.4331772127257;113566.43216783863;113754;114154.42922785332;114195.99999999917;114395.85604143936;10332.896671033344;10338.844917326247;10363.11913487347;10426.791464170674;10484.947575262075;12961.611151665276;13036.283004434657;13089.476420943247;13127.146735462164;13146.34268286601;8315.251627353446;8350.62422191003;8391.412601117985;8413.663453461915;8505;7830.765077047698;7962.283394494523;8324.209200125979;8545.401821872534;8734.825303493895;6391.808245752635;6440.54916155874;6441.0982462455295;6460.3216662250725;6535;1837.9816201838078;1875.924963001492;1877.1978900255635;1880.3512788088087;1911.5949776913958;836.4855613797471;876.2945828608032;915.8855143107112;923.5290002098978;959.4483172176027;18043.639127217644;20746;21970;21982.560348792933;22064;877.8463768840427;911.5852287209436;955.8279509688323;962.3644967945974;970.8543718442149;9088.909110908939;11124.221304508768;11210.775784484265;11687.590934317259;11776.05791536675;572667;574299;580611;582084;584402;113264.43367783108;114095;114636;117674.82325176639;118127.40936295263;1010.6210171185805;1017.1455976979225;1066.7546464313252;1093.33853018924;1100.998091736516;18621.534461638566;19399.2240310386;19452.61094778125;19461.61076778428;19539.902300488407;41932;53513.732431337594;53559.46440535701;54065.18902216391;54238.72880635571;57961;72712.63643681748;72856;73033.26966730478;73152.63423682848;5524.917126243029;6759.290274521104;7863.882041769492;7919;7958.801029974065;10580.312279701795;12942.899853512557;13359.332033398485;13385.799213011618;13790.586282411543;10456.47717614101;11262.549498019987;11276.830847537129;11427.771444570895;11560.537578496765;9432.669856554987;11037.448127593749;11206.551737930718;11303.830442543533;11607.245529040585;9680.661176858775;11414.543418263176;11575.942120289345;11847.348395838311;11921.284722916653;7086;7649.120351159688;7787.29914307715;7904.84190316202;8015.198480151938;10681.305715128492;11141.665750027512;11515.25150865191;11519.366434845828;11674.007709344802 19:12:18 INFO - PROCESS | 1646 | |3;mootools.html;1356.9796453053202;1535.6468012357157;1694.169856770183;1878.107898748094;1885.4814925895378;7834.255745704163;8348.91651083489;8478.364122690808;8893.421927574711;8931.419457735243;8523.105073967221;8702.173293537104;8744.956275218616;8747.956260218705;8776.956115219415;2372.5136347048856;2391.1391898916377;2398.8080953523763;2401.6277476991095;2407.1334319644916;2359.2214569192124;2373.8457000294925;2405.0259644843777;2408.385861605288;2409.4458274596855;2392.605220138675;2395.4610212702178;2401.8438801477937;2404.1224952892157;2408.0849277274697;1960.5000724815222;2367.7868991790733;2374.2995816234165;2383.30884043628;2393.4495066134714;2351.1418332308585;2361.4568649210605;2369.1707902234252;2375.596148654733;2375.6080246759266;8737.912620873836;8759.868601970975;8820.382573219877;8834.823303533958;8853.11468853116;4498.842540511099;4732.455767586703;5121.436641969381;5242.135047717122;5382.219578161184;3012.9849350753107;3583.3191693578137;3642.763220390693;3675.191457879262;3683.355412802749;1063.5905176507024;1072.0030371754265;1072.9913880951958;1083.5232497701065;1086.8804431512526;9642.614295428175;11518.136139789549;11520.135989800714;11566.669832969148;11576.421178941104;3394.405978953673;4606.355110284563;4776.952230477651;4787.569118779326;4802.375691160197;9773.706788796348;9806.264530160266;9813.607455701835;9850.605975761033;9876.456794876207;5324.787008519694;5328.0675881720545;5331.86670333237;5338.706371149624;5362.597805164628;4192.979035104805;4368.36658684489;4859.465458799529;4862.635302352338;4879.2925025871045;3868.400397938324;3916.3538016227003;4002.7598344099447;4007.7595344278852;4035.394690796404;1018.6129270877168;1135.920485566019;1307.744989727013;1322.7288405876773;1325.602319304205;780.6135962698501;793.9361255917099;887.4808237181321;907.1291560102244;940.2618944128826;5476.91784623231;5719.628224165416;5873.823785286449;5924.318703349084;5975.073863551156;904.4618452021037;928.8467402878584;941.9717408477757;967.4002118686459;1015.081351377005;3065.084708787199;3761.962380376161;3952.3676211805973;3972.867732696168;4026.3960405939342;8307.543085130375;10491.842622360671;10658.946705266424;10779.0058839115;10792.730181745519;10431.634892778717;10633.946830265799;10723.678289651323;10726.517306721216;10761.192910531741;7962.482438641469;8120.959395202986;8168.264856162974;8206.097329293772;8217.465864718773;5564.777408903598;5652.745626446737;5753.223314852441;5758.913616295675;5806.738696758741;9830;9837.557309920927;9927.553260103457;9939.503024848873;9945.552450139616;9789.265805064506;9932.801343972935;10003.699889003341;10203.591856325664;10241.027102425258;6799.456043516606;6809.285025072494;6917.412019978358;6943.79168624942;7002.144431725698;5929.911051334319;8080.757577272691;8166.101728809707;8178.46839955401;8183.140770219041;7293.562386256842;7382.480278756806;7429.702811887681;7452.254774522721;7544.735934242275;7173.689824645453;7254.419646428168;7273.3453989141235;7468.141163766237;7473.476856620202;6620.536562440776;6707.731690732316;6748.257691654012;6754.729810807513;6761.93238067613;4851;4888.2178851383605;4897.975510122569;4948.05986862495;5017.24741288795;3963.9495533684176;4371;4879.463259041574;4930.063287975273;4971.353724015855 19:12:18 INFO - PROCESS | 1646 | |4;prototype.html;300.66776212285436;304.18478477679815;309.8326903472126;312.78417891654755;313.56414583728605;4241.978790106045;4638.884027899304;5458.91811622825;5608.411116832735;5691.943080569193;4367.541408152146;4733.296013435161;5526.806561770339;5585.916211256835;5636.520895723858;3754.7371683982133;4249;4250.426192464024;4256.680748943826;4297.570242975708;3533.92932141357;3943.329633962219;4178.01816573105;4313.137372525507;4315.201687687788;3942.408638704203;4118.011677197466;4133.896652583694;4151.920500669818;4186.0581369192;4127.215828992496;4155.916881662365;4180.059486615518;4181.330987042059;4185.707000509966;3447.586489539302;3591.299696559172;3604.153024039375;3621.8732344367822;3662.633736626343;4111.97944010281;5458.808941687062;5472.534834539069;5503.174523821419;5517.36550296717;4838.806447742086;4839.322494850724;4842.706959267696;4850.611951043908;4854.223324268136;4922.876928076791;5243.737813109329;5494.5054945054735;5600.719964001783;5720.971395142998;3955.9011024724323;3981.646240278319;3987.5813039631003;3990.521137463494;3999.5400528938962;6241.65937773335;6264.718087686065;6278.372162783685;6310.686889383065;6312.684365781693;3585.354636165463;3706.4440333950124;3887.261420330128;3957.4763715969557;4025.77858217795;4072.6741860651077;4110.486189226347;4115.464989551339;4116.608922152391;4119.237940980928;4803.447603525569;4804.759762011886;4813.3983252093485;4821;4842.564169224786;5147.867469156779;5340.572754179734;5524.806631767869;6031.276246850318;6061.484773794189;1305.4517102816649;1357.7080927600416;1389.666480044782;1392.276016471429;1393.9512117075853;782.3154739602847;887.2103827593447;895.1987970766259;932.9486878221763;933.6825479337058;4873.439554451213;5118.872028199325;5469.726513674301;5554.1391084381985;5559;5612.579056570774;6062;6105.725242364104;6113.205283313141;6129.781864521162;4287.678424118204;4614.17739992701;4795.880102997453;4954.921721680354;5432.848947610377;2429.161939130997;2535.7844583210262;2679.3569543309463;2709.769669578108;2734.2754170144926;4733.431988161443;4985.2141943733695;5270.62679884874;5279.8152064678325;5367.543758780469;5121.180611102206;5272.499112584298;5322.520973112439;5836.766529338864;6422.325655806167;6164.969175154096;6400.487960963112;6442.48460123189;6453.51598630114;6455.965696122815;5953.791617293374;5991.191189189578;6154.230721159855;6291.307956124914;6294.622322660655;5058.443571207091;6121.3572574879;6137.355577664281;6188.55552056944;6195.597286176384;6044.15381846533;6409;6445.066480056058;6455.806325810233;6478.70845811949;3482.268723568079;4009.5389030260726;4038.0617303693803;4070.5346075413418;4086.816093275869;5044.697318160922;5081.983603279285;5923.512124907463;6201.937730786342;6235.844103897258;5868.2077919480325;6002.939970600238;6023.5181185505935;6034.818955431344;6180.845478863064;4807.471178170468;5199.792008319625;5449.591280654046;6033.637981721111;6036.6679832609625;5595.692236927008;5843.503302219359;5876.50248644333;5882.205902203148;5928.229330187048;5483.424240454695;6020.969895150496;6025.457708806229;6075.362086980804;6079.756809727738;5170.922436163385;5346.679199248058;5467.535259502828;5664.348599911063;5888.911666324922 19:12:18 INFO - PROCESS | 1646 | |5;yui.html;686.9072675188851;691.9446444284456;713.6217804563584;740.7592532426959;811.3103861717539;14946.327415266313;15111.546653600382;15441;15547.300371483283;15572.45496407626;13793.793093103586;13961.30193490324;14007.92996035021;14130.858691413083;14158.29208539574;850.0819115355432;865.5196366016858;865.9826803463927;867.0116067682847;868.8740132680757;683.0539702512007;838.3879767769532;855.170484629908;867.8871746672924;869.6521391443441;878.3236907581158;909.1045320359441;910.2399496420463;911.9785839859334;912.8493798523269;863.8920134983127;896.5651658945407;907.1971305394708;910.0944560162648;911.8632205169212;643.9259485159173;668.481926506961;671.7413795688695;672.9427998620123;673.4612310151908;12937.094403391766;13135.211887286794;13185.934070329587;13203.86796132036;13302.201867887958;14321.57035288943;14557.708845823025;14746.705065898623;14783.482578109715;14793.11241325524;11705.941470292679;11872.168948173632;11962.641120766391;12169.574064907685;12196;3769.189624230776;3853.2293541291856;3919.7648141111627;3971.940420893689;3972.6424621784176;113698.43150784276;120066;121080.39459802644;121100.1834972476;121575;9235.907640923639;11161.274517156358;11167.664970050912;11197.608083717194;11361.829572556418;18921.81078189228;22790.886045569998;22928.88535557345;22983.540329193656;23005.194818181284;7947.880781788278;8045.839083218303;8160.755177344689;8228.341732661373;8237.546934918517;4289.806958686867;4295.978520107379;4457.598816106566;4505.391772110788;4604.654650901196;3551.4672799080345;3639.3267245559655;3673.7428380013675;3700.870469533553;3706.2772759311674;558.4666643355586;663.0849427789619;663.4824836627438;672.9394354508117;677.021703638238;538.0879409401106;548.7322991585102;636.7484843486797;651.3519048546632;658.2989116590911;8667.869981950276;9040.63837446508;9272.582733776997;9319.953400233091;9366.531673416303;608.6743592178163;662.6984721951501;681.1077488490093;691.2707094015761;700.0758998122429;3799.848006079781;4117.8970525736495;4586.8165273388695;4652.930206046912;4671.6262698984;112866;115985;117770;148902.7664585032;149559.75660365104;118582;125333.37333313275;127059.72940270479;127603;130710;73819;83809.16190838136;87300;94903.52548237353;117300;9178.128077832594;9727.319087663796;9765.72650768577;9794.363366381447;9852.852207216754;42305.78847105745;47355.763221183675;57581.71209143927;59512.404875950684;61498;40478;58124.70937645285;58452.123218152614;76978.61510692634;81887.59056204681;12578.30819304947;13131.67170820699;13173.60479185626;13293;13317.467301308227;14754;14834.480793172619;14993.700125997637;15126.621834454227;15444.2277886103;11634.836516348298;11949.581764638197;13101.541446049343;14006.43974241019;14209.076410033314;10904.291221070605;13513.662158446117;13603.931980340036;13800.585982420544;14197.858021420067;12587.87412125867;13040.41318140705;13045.478180872658;13052.570779847867;13351.265680387673;11014.229003969645;11257.544521994054;11463.197576169921;11684.70788230301;11709.238899471507;10348.586056557653;10619.36283822973;11404.258723182966;11544.884551154717;11549.711257218636 19:12:18 INFO - PROCESS | 1646 | __end_tp_report 19:12:18 INFO - PROCESS | 1646 | __start_cc_report 19:12:18 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,383 19:12:18 INFO - PROCESS | 1646 | __end_cc_report 19:12:18 INFO - PROCESS | 1646 | __startTimestamp1473214338023__endTimestamp 19:12:18 INFO - PROCESS | 1646 | 19:12:18 INFO - PROCESS | 1646 | ------- Summary: start ------- 19:12:18 INFO - PROCESS | 1646 | Number of tests: 6 19:12:18 INFO - PROCESS | 1646 | 19:12:18 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5698.05 Median:5892.18 stddev:708.85 (12.0%) stddev-sans-first:679.71 19:12:18 INFO - PROCESS | 1646 | Values: 3042.2 3335.6 3356.4 3389.8 3400.9 4336.3 5595.9 5617.9 5618.3 5722.1 5705.3 5742.6 5746.2 5765.7 5774.2 5137.8 5228.8 5235.1 5283.3 5330.3 5242.2 5302.3 5307.3 5324.6 5341.3 3816.0 5126.5 5148.5 5214.5 5299.4 5212.2 5224.9 5245.5 5257.3 5259.1 5111.9 5141.6 5180.8 5193.5 5222.0 5401.3 5607.4 5638.4 5639.4 5696.3 5320.8 6161.8 6164.8 6186.1 6186.1 6161.5 6259.5 6274.9 6275.8 6279.5 5754.8 5795.8 5816.1 5816.5 5878.9 6256.1 6306.1 6306.2 6332.9 6346.1 5345.0 5764.7 6169.7 6177.8 6195.5 6137.2 6179.3 6196.3 6207.5 6228.5 6197.7 6220.5 6232.3 6260.7 6280.8 6169.3 6191.9 6252.2 6252.3 6260.4 4926.3 4942.0 4971.2 4988.9 5055.7 3648.4 3949.7 4114.1 4437.0 4453.2 4806.5 5544.2 5794.8 5802.8 5862.7 6132.5 6213.2 6244.3 6292.4 6328.3 4835.3 5773.9 5818.5 5824.7 5857.0 5439.8 5447.9 5452.3 5465.0 5465.3 6254.4 6270.7 6284.7 6362.3 6377.7 6303.8 6324.9 6333.9 6344.0 6352.7 6384.6 6391.9 6413.4 6430.3 6432.3 6047.4 6102.0 6123.6 6248.7 6252.1 6132.6 6256.2 6271.8 6328.0 6328.7 6144.8 6157.5 6160.2 6186.1 6194.7 5150.6 5503.0 6106.8 6113.9 6168.5 4916.6 5989.8 5999.2 6021.5 6122.2 6140.8 6158.4 6164.1 6171.1 6221.4 4858.9 5892.2 6011.0 6016.9 6047.6 19:12:18 INFO - PROCESS | 1646 | 19:12:18 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:69049.97 Median:39391.20 stddev:151827.46 (385.4%) stddev-sans-first:152166.05 19:12:18 INFO - PROCESS | 1646 | Values: 464.2 529.5 530.0 539.7 564.0 32810.3 40102.4 40105.6 40122.6 40257.8 40607.8 41080.6 41119.4 41218.0 41448.0 9566.8 9714.7 9749.3 9770.5 9783.3 9239.2 9655.8 9708.0 9729.0 9782.9 9777.6 9790.9 9800.5 9811.0 9823.5 9752.5 9790.0 9796.8 9800.5 9800.8 9593.7 9603.5 9636.7 9650.3 9674.0 39107.4 39436.8 39559.8 39749.0 39936.8 3253.5 3303.3 3346.9 3437.2 3439.6 29494.0 30321.0 31212.8 32267.5 32634.7 3237.5 3262.6 3264.6 3273.0 3366.5 468863.0 471494.0 477054.0 479075.0 479285.0 55453.7 61959.7 63751.0 64741.0 65052.3 35295.3 35797.5 36163.7 36782.0 68212.0 16740.0 16908.7 16908.9 16958.8 16977.6 11092.9 12439.0 12720.3 12861.7 12914.0 20494.6 20509.9 20697.5 20760.2 20772.8 7307.6 8731.6 8784.8 8813.8 8833.7 819.3 848.2 884.3 1017.4 1033.3 26585.9 28339.6 28490.6 29869.6 30582.0 1009.8 1016.9 1018.0 1021.1 1121.6 13433.7 15349.5 15431.4 15685.7 15794.4 552465.0 653743.0 657850.0 662025.0 662109.0 55644.7 55869.0 56370.9 56669.0 56706.7 1110.2 1172.9 1368.5 1385.2 1391.2 28150.7 29219.0 29249.4 29265.9 29743.4 48690.8 48903.3 48990.3 49171.8 49193.0 575665.0 576336.0 577407.0 578017.0 579099.1 26688.2 26892.3 27001.7 27010.6 27149.7 45896.0 46408.8 46482.0 47459.5 47545.8 38607.6 38775.0 39063.0 39383.8 39398.6 31695.0 36103.7 37908.4 38229.6 42495.4 30725.8 32666.2 37935.6 37963.6 38093.4 33734.0 34124.8 35002.0 35129.5 35208.3 20515.8 25381.0 25716.2 25848.4 26007.6 19:12:18 INFO - PROCESS | 1646 | 19:12:18 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:33678.59 Median:12076.22 stddev:96312.98 (797.5%) stddev-sans-first:96550.63 19:12:18 INFO - PROCESS | 1646 | Values: 340.5 367.5 374.9 383.9 397.2 16446.8 19431.4 20434.8 20661.0 20681.3 16580.8 19851.1 20116.8 20407.2 20765.5 1563.3 1618.7 1618.9 1619.9 1839.4 5727.8 5907.5 5911.8 6036.6 6041.9 5017.4 5917.3 5920.5 5920.8 5926.9 5609.4 5753.6 5862.1 5869.3 5871.8 5067.1 5102.4 5120.4 5126.6 5141.3 19956.9 23938.3 25312.5 25404.0 25659.7 2182.4 2252.3 2261.0 2378.0 2436.5 15161.7 15509.5 15623.7 16107.4 16360.5 1773.7 1810.8 1862.3 1877.7 1921.4 113566.4 113754.0 114154.4 114196.0 114395.9 10332.9 10338.8 10363.1 10426.8 10484.9 12961.6 13036.3 13089.5 13127.1 13146.3 8315.3 8350.6 8391.4 8413.7 8505.0 7830.8 7962.3 8324.2 8545.4 8734.8 6391.8 6440.5 6441.1 6460.3 6535.0 1838.0 1875.9 1877.2 1880.4 1911.6 836.5 876.3 915.9 923.5 959.4 18043.6 20746.0 21970.0 21982.6 22064.0 877.8 911.6 955.8 962.4 970.9 9088.9 11124.2 11210.8 11687.6 11776.1 572667.0 574299.0 580611.0 582084.0 584402.0 113264.4 114095.0 114636.0 117674.8 118127.4 1010.6 1017.1 1066.8 1093.3 1101.0 18621.5 19399.2 19452.6 19461.6 19539.9 41932.0 53513.7 53559.5 54065.2 54238.7 57961.0 72712.6 72856.0 73033.3 73152.6 5524.9 6759.3 7863.9 7919.0 7958.8 10580.3 12942.9 13359.3 13385.8 13790.6 10456.5 11262.5 11276.8 11427.8 11560.5 9432.7 11037.4 11206.6 11303.8 11607.2 9680.7 11414.5 11575.9 11847.3 11921.3 7086.0 7649.1 7787.3 7904.8 8015.2 10681.3 11141.7 11515.3 11519.4 11674.0 19:12:18 INFO - PROCESS | 1646 | 19:12:18 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5592.71 Median:4939.06 stddev:3132.92 (63.4%) stddev-sans-first:3125.53 19:12:18 INFO - PROCESS | 1646 | Values: 1357.0 1535.6 1694.2 1878.1 1885.5 7834.3 8348.9 8478.4 8893.4 8931.4 8523.1 8702.2 8745.0 8748.0 8777.0 2372.5 2391.1 2398.8 2401.6 2407.1 2359.2 2373.8 2405.0 2408.4 2409.4 2392.6 2395.5 2401.8 2404.1 2408.1 1960.5 2367.8 2374.3 2383.3 2393.4 2351.1 2361.5 2369.2 2375.6 2375.6 8737.9 8759.9 8820.4 8834.8 8853.1 4498.8 4732.5 5121.4 5242.1 5382.2 3013.0 3583.3 3642.8 3675.2 3683.4 1063.6 1072.0 1073.0 1083.5 1086.9 9642.6 11518.1 11520.1 11566.7 11576.4 3394.4 4606.4 4777.0 4787.6 4802.4 9773.7 9806.3 9813.6 9850.6 9876.5 5324.8 5328.1 5331.9 5338.7 5362.6 4193.0 4368.4 4859.5 4862.6 4879.3 3868.4 3916.4 4002.8 4007.8 4035.4 1018.6 1135.9 1307.7 1322.7 1325.6 780.6 793.9 887.5 907.1 940.3 5476.9 5719.6 5873.8 5924.3 5975.1 904.5 928.8 942.0 967.4 1015.1 3065.1 3762.0 3952.4 3972.9 4026.4 8307.5 10491.8 10658.9 10779.0 10792.7 10431.6 10633.9 10723.7 10726.5 10761.2 7962.5 8121.0 8168.3 8206.1 8217.5 5564.8 5652.7 5753.2 5758.9 5806.7 9830.0 9837.6 9927.6 9939.5 9945.6 9789.3 9932.8 10003.7 10203.6 10241.0 6799.5 6809.3 6917.4 6943.8 7002.1 5929.9 8080.8 8166.1 8178.5 8183.1 7293.6 7382.5 7429.7 7452.3 7544.7 7173.7 7254.4 7273.3 7468.1 7473.5 6620.5 6707.7 6748.3 6754.7 6761.9 4851.0 4888.2 4898.0 4948.1 5017.2 3963.9 4371.0 4879.5 4930.1 4971.4 19:12:18 INFO - PROCESS | 1646 | 19:12:18 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4715.84 Median:5185.36 stddev:1499.78 (28.9%) stddev-sans-first:1466.92 19:12:18 INFO - PROCESS | 1646 | Values: 300.7 304.2 309.8 312.8 313.6 4242.0 4638.9 5458.9 5608.4 5691.9 4367.5 4733.3 5526.8 5585.9 5636.5 3754.7 4249.0 4250.4 4256.7 4297.6 3533.9 3943.3 4178.0 4313.1 4315.2 3942.4 4118.0 4133.9 4151.9 4186.1 4127.2 4155.9 4180.1 4181.3 4185.7 3447.6 3591.3 3604.2 3621.9 3662.6 4112.0 5458.8 5472.5 5503.2 5517.4 4838.8 4839.3 4842.7 4850.6 4854.2 4922.9 5243.7 5494.5 5600.7 5721.0 3955.9 3981.6 3987.6 3990.5 3999.5 6241.7 6264.7 6278.4 6310.7 6312.7 3585.4 3706.4 3887.3 3957.5 4025.8 4072.7 4110.5 4115.5 4116.6 4119.2 4803.4 4804.8 4813.4 4821.0 4842.6 5147.9 5340.6 5524.8 6031.3 6061.5 1305.5 1357.7 1389.7 1392.3 1394.0 782.3 887.2 895.2 932.9 933.7 4873.4 5118.9 5469.7 5554.1 5559.0 5612.6 6062.0 6105.7 6113.2 6129.8 4287.7 4614.2 4795.9 4954.9 5432.8 2429.2 2535.8 2679.4 2709.8 2734.3 4733.4 4985.2 5270.6 5279.8 5367.5 5121.2 5272.5 5322.5 5836.8 6422.3 6165.0 6400.5 6442.5 6453.5 6456.0 5953.8 5991.2 6154.2 6291.3 6294.6 5058.4 6121.4 6137.4 6188.6 6195.6 6044.2 6409.0 6445.1 6455.8 6478.7 3482.3 4009.5 4038.1 4070.5 4086.8 5044.7 5082.0 5923.5 6201.9 6235.8 5868.2 6002.9 6023.5 6034.8 6180.8 4807.5 5199.8 5449.6 6033.6 6036.7 5595.7 5843.5 5876.5 5882.2 5928.2 5483.4 6021.0 6025.5 6075.4 6079.8 5170.9 5346.7 5467.5 5664.3 5888.9 19:12:18 INFO - PROCESS | 1646 | 19:12:18 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:22906.21 Median:3784.52 stddev:36214.36 (956.9%) stddev-sans-first:36277.52 19:12:18 INFO - PROCESS | 1646 | Values: 686.9 691.9 713.6 740.8 811.3 14946.3 15111.5 15441.0 15547.3 15572.5 13793.8 13961.3 14007.9 14130.9 14158.3 850.1 865.5 866.0 867.0 868.9 683.1 838.4 855.2 867.9 869.7 878.3 909.1 910.2 912.0 912.8 863.9 896.6 907.2 910.1 911.9 643.9 668.5 671.7 672.9 673.5 12937.1 13135.2 13185.9 13203.9 13302.2 14321.6 14557.7 14746.7 14783.5 14793.1 11705.9 11872.2 11962.6 12169.6 12196.0 3769.2 3853.2 3919.8 3971.9 3972.6 113698.4 120066.0 121080.4 121100.2 121575.0 9235.9 11161.3 11167.7 11197.6 11361.8 18921.8 22790.9 22928.9 22983.5 23005.2 7947.9 8045.8 8160.8 8228.3 8237.5 4289.8 4296.0 4457.6 4505.4 4604.7 3551.5 3639.3 3673.7 3700.9 3706.3 558.5 663.1 663.5 672.9 677.0 538.1 548.7 636.7 651.4 658.3 8667.9 9040.6 9272.6 9320.0 9366.5 608.7 662.7 681.1 691.3 700.1 3799.8 4117.9 4586.8 4652.9 4671.6 112866.0 115985.0 117770.0 148902.8 149559.8 118582.0 125333.4 127059.7 127603.0 130710.0 73819.0 83809.2 87300.0 94903.5 117300.0 9178.1 9727.3 9765.7 9794.4 9852.9 42305.8 47355.8 57581.7 59512.4 61498.0 40478.0 58124.7 58452.1 76978.6 81887.6 12578.3 13131.7 13173.6 13293.0 13317.5 14754.0 14834.5 14993.7 15126.6 15444.2 11634.8 11949.6 13101.5 14006.4 14209.1 10904.3 13513.7 13603.9 13800.6 14197.9 12587.9 13040.4 13045.5 13052.6 13351.3 11014.2 11257.5 11463.2 11684.7 11709.2 10348.6 10619.4 11404.3 11544.9 11549.7 19:12:18 INFO - PROCESS | 1646 | -------- Summary: end -------- 19:12:18 INFO - PROCESS | 1646 | 19:12:18 INFO - TEST-INFO | 1646: exit 0 19:12:18 INFO - TEST-OK | dromaeo_css | took 1114167ms 19:12:18 INFO - TEST-START | kraken 19:12:18 INFO - operating with platform_type : mac_ 19:12:18 INFO - Initialising browser for kraken test... 19:12:18 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmp2wbHEI/profile http://localhost:49272/getInfo.html) 19:12:21 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 19:12:21 INFO - PROCESS | 1658 | colorDepth:24 19:12:21 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 19:12:21 INFO - PROCESS | 1658 | __metrics 19:12:21 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 19:12:22 INFO - TEST-INFO | 1658: exit 0 19:12:22 INFO - Browser initialized. 19:12:22 INFO - Running cycle 1/1 for kraken test... 19:12:22 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/T/tmp2wbHEI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:12:34 INFO - PROCESS | 1660 | RSS: Main: 243941376 19:12:34 INFO - PROCESS | 1660 | 19:12:37 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 19:12:37 INFO - PROCESS | 1660 | RSS: Main: 256778240 19:12:37 INFO - PROCESS | 1660 | 19:12:40 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 19:12:41 INFO - PROCESS | 1660 | RSS: Main: 265547776 19:12:41 INFO - PROCESS | 1660 | 19:12:45 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 19:12:45 INFO - PROCESS | 1660 | RSS: Main: 262201344 19:12:45 INFO - PROCESS | 1660 | 19:12:47 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 19:12:48 INFO - PROCESS | 1660 | RSS: Main: 264495104 19:12:48 INFO - PROCESS | 1660 | 19:12:50 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:12:51 INFO - PROCESS | 1660 | RSS: Main: 265179136 19:12:51 INFO - PROCESS | 1660 | 19:12:54 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 19:12:54 INFO - PROCESS | 1660 | RSS: Main: 265211904 19:12:54 INFO - PROCESS | 1660 | 19:12:57 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 19:12:58 INFO - PROCESS | 1660 | RSS: Main: 265441280 19:12:58 INFO - PROCESS | 1660 | 19:13:01 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 19:13:02 INFO - PROCESS | 1660 | RSS: Main: 265576448 19:13:02 INFO - PROCESS | 1660 | 19:13:03 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:13:04 INFO - PROCESS | 1660 | RSS: Main: 265277440 19:13:04 INFO - PROCESS | 1660 | 19:13:06 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:13:07 INFO - PROCESS | 1660 | RSS: Main: 265318400 19:13:07 INFO - PROCESS | 1660 | 19:13:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:13:09 INFO - PROCESS | 1660 | RSS: Main: 265699328 19:13:09 INFO - PROCESS | 1660 | 19:13:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:13:13 INFO - PROCESS | 1660 | RSS: Main: 265895936 19:13:13 INFO - PROCESS | 1660 | 19:13:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:13:17 INFO - PROCESS | 1660 | RSS: Main: 265699328 19:13:17 INFO - PROCESS | 1660 | 19:13:18 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 19:13:19 INFO - PROCESS | 1660 | RSS: Main: 267616256 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | __start_tp_report 19:13:19 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 19:13:19 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 19:13:19 INFO - PROCESS | 1660 | |i|pagename|runs| 19:13:19 INFO - PROCESS | 1660 | |0;ai-astar;118;107;129;97;168;149;158;117;116;117 19:13:19 INFO - PROCESS | 1660 | |1;audio-beat-detection;142;111;135;186;145;130;130;115;190;114 19:13:19 INFO - PROCESS | 1660 | |2;audio-dft;195;219;272;263;287;240;198;215;283;230 19:13:19 INFO - PROCESS | 1660 | |3;audio-fft;65;81;74;176;86;65;59;58;138;60 19:13:19 INFO - PROCESS | 1660 | |4;audio-oscillator;69;71;129;96;101;108;116;70;72;114 19:13:19 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;91;88;93;74;136;79;136;132;124;88 19:13:19 INFO - PROCESS | 1660 | |6;imaging-darkroom;97;99;97;99;98;104;152;97;97;99 19:13:19 INFO - PROCESS | 1660 | |7;imaging-desaturate;145;99;146;146;78;143;145;134;100;101 19:13:19 INFO - PROCESS | 1660 | |8;json-parse-financial;57;59;60;58;57;59;60;57;57;56 19:13:19 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;65;74;80;66;60;65;95;91;106;103 19:13:19 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;86;88;86;88;92;86;87;79;92;94 19:13:19 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;135;150;172;127;159;161;155;160;218;163 19:13:19 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;176;172;168;166;173;173;170;172;171;171 19:13:19 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;70;66;70;68;68;69;68;68;68;70 19:13:19 INFO - PROCESS | 1660 | __end_tp_report 19:13:19 INFO - PROCESS | 1660 | __start_cc_report 19:13:19 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,954 19:13:19 INFO - PROCESS | 1660 | __end_cc_report 19:13:19 INFO - PROCESS | 1660 | __startTimestamp1473214399068__endTimestamp 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | ------- Summary: start ------- 19:13:19 INFO - PROCESS | 1660 | Number of tests: 14 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:127.60 Median:139.00 stddev:23.16 (16.7%) stddev-sans-first:24.31 19:13:19 INFO - PROCESS | 1660 | Values: 118.0 107.0 129.0 97.0 168.0 149.0 158.0 117.0 116.0 117.0 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:138.50 stddev:27.91 (20.2%) stddev-sans-first:29.59 19:13:19 INFO - PROCESS | 1660 | Values: 142.0 111.0 135.0 186.0 145.0 130.0 130.0 115.0 190.0 114.0 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:240.20 Median:251.50 stddev:34.26 (13.6%) stddev-sans-first:32.20 19:13:19 INFO - PROCESS | 1660 | Values: 195.0 219.0 272.0 263.0 287.0 240.0 198.0 215.0 283.0 230.0 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:86.20 Median:65.00 stddev:39.50 (60.8%) stddev-sans-first:41.15 19:13:19 INFO - PROCESS | 1660 | Values: 65.0 81.0 74.0 176.0 86.0 65.0 59.0 58.0 138.0 60.0 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:69.50 stddev:22.53 (32.4%) stddev-sans-first:21.91 19:13:19 INFO - PROCESS | 1660 | Values: 69.0 71.0 129.0 96.0 101.0 108.0 116.0 70.0 72.0 114.0 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:104.10 Median:83.50 stddev:24.85 (29.8%) stddev-sans-first:25.90 19:13:19 INFO - PROCESS | 1660 | Values: 91.0 88.0 93.0 74.0 136.0 79.0 136.0 132.0 124.0 88.0 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:103.90 Median:97.50 stddev:17.03 (17.5%) stddev-sans-first:17.88 19:13:19 INFO - PROCESS | 1660 | Values: 97.0 99.0 97.0 99.0 98.0 104.0 152.0 97.0 97.0 99.0 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:123.70 Median:145.50 stddev:26.15 (18.0%) stddev-sans-first:26.58 19:13:19 INFO - PROCESS | 1660 | Values: 145.0 99.0 146.0 146.0 78.0 143.0 145.0 134.0 100.0 101.0 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:58.50 stddev:1.41 (2.4%) stddev-sans-first:1.45 19:13:19 INFO - PROCESS | 1660 | Values: 57.0 59.0 60.0 58.0 57.0 59.0 60.0 57.0 57.0 56.0 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:80.50 Median:70.00 stddev:17.10 (24.4%) stddev-sans-first:17.19 19:13:19 INFO - PROCESS | 1660 | Values: 65.0 74.0 80.0 66.0 60.0 65.0 95.0 91.0 106.0 103.0 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:88.00 stddev:4.24 (4.8%) stddev-sans-first:4.44 19:13:19 INFO - PROCESS | 1660 | Values: 86.0 88.0 86.0 88.0 92.0 86.0 87.0 79.0 92.0 94.0 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:160.00 Median:160.50 stddev:24.40 (15.2%) stddev-sans-first:24.14 19:13:19 INFO - PROCESS | 1660 | Values: 135.0 150.0 172.0 127.0 159.0 161.0 155.0 160.0 218.0 163.0 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.20 Median:172.00 stddev:2.78 (1.6%) stddev-sans-first:2.35 19:13:19 INFO - PROCESS | 1660 | Values: 176.0 172.0 168.0 166.0 173.0 173.0 170.0 172.0 171.0 171.0 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.50 stddev:1.27 (1.9%) stddev-sans-first:1.22 19:13:19 INFO - PROCESS | 1660 | Values: 70.0 66.0 70.0 68.0 68.0 69.0 68.0 68.0 68.0 70.0 19:13:19 INFO - PROCESS | 1660 | -------- Summary: end -------- 19:13:19 INFO - PROCESS | 1660 | 19:13:19 INFO - TEST-INFO | 1660: exit 0 19:13:19 INFO - TEST-OK | kraken | took 61308ms 19:13:19 INFO - SUITE-END | took 1175s 19:13:20 INFO - Completed test suite (00:19:35) 19:13:20 INFO - javascript benchmark 19:13:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7284.942775873312, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3042.19381863806, 3335.616404113526, 3356.446186379247, 3389.779664321819, 3400.8639654413823, 4336.3495475678665, 5595.9160612590795, 5617.887642247153, 5618.325800903892, 5722.141678748179, 5705.343885453174, 5742.598018138732, 5746.195532625426, 5765.6828874411995, 5774.162746401769, 5137.794488220486, 5228.816991405301, 5235.11003129467, 5283.339582552181, 5330.333708286465, 5242.187460943541, 5302.337207849019, 5307.2835167252315, 5324.600654950894, 5341.252224688546, 3816.0, 5126.461721519243, 5148.459411761749, 5214.5306922376785, 5299.417064122943, 5212.244224587452, 5224.895502089937, 5245.527902488756, 5257.342832145981, 5259.079661059338, 5111.87220319495, 5141.640085194038, 5180.792768289264, 5193.454687257859, 5222.033923724085, 5401.270828438189, 5607.383187849374, 5638.379778224392, 5639.379668236491, 5696.316442026942, 5320.840374788762, 6161.84595385119, 6164.7534098636, 6186.10301506279, 6186.103015062836, 6161.476274516672, 6259.530535209832, 6274.937250627481, 6275.780347687809, 6279.466245369149, 5754.827355179351, 5795.826125216208, 5816.127580862904, 5816.505597024216, 5878.882422351529, 6256.124142620037, 6306.1171435998995, 6306.180196574508, 6332.936670633235, 6346.079818426302, 5345.0, 5764.74058667361, 6169.722362493699, 6177.7837775677635, 6195.504359651217, 6137.193127861533, 6179.28938172116, 6196.318404975449, 6207.503399728011, 6228.47058000066, 6197.721102550396, 6220.533459990519, 6232.283287422005, 6260.686965651699, 6280.842978925472, 6169.321374648785, 6191.876162476815, 6252.155958945576, 6252.343503932022, 6260.436560709558, 4926.261060840902, 4942.0, 4971.229459433794, 4988.8752781180765, 5055.671381360274, 3648.434492653643, 3949.6642785363565, 4114.115465175002, 4436.977815110904, 4453.22068637987, 4806.543378379119, 5544.168374743821, 5794.82615521535, 5802.767889284381, 5862.677552734641, 6132.539076726632, 6213.2233470816145, 6244.344343843922, 6292.370762923762, 6328.335524769925, 4835.323054772335, 5773.93182261283, 5818.4763371296785, 5824.708764561755, 5857.0, 5439.809606663748, 5447.863803404946, 5452.345718513804, 5465.016297066487, 5465.344158700902, 6254.437100660963, 6270.6551139685935, 6284.748610055546, 6362.331955144643, 6377.7448902044325, 6303.810885673437, 6324.873502529832, 6333.904991425225, 6343.9682801585695, 6352.650604216812, 6384.553081284265, 6391.93608063932, 6413.39072788103, 6430.324815894263, 6432.292447830641, 6047.425494578184, 6102.004547214632, 6123.557162997741, 6248.656323902229, 6252.124702541739, 6132.5707200495535, 6256.155419018374, 6271.811845644638, 6327.96836015817, 6328.7152078157515, 6144.846378840386, 6157.476614487819, 6160.16837726919, 6186.10301506288, 6194.721237544411, 5150.583589512914, 5503.0, 6106.7640410362355, 6113.871604717183, 6168.537359698131, 4916.557509824133, 5989.8203053908455, 5999.160117583455, 6021.518278537619, 6122.173506576555, 6140.754369825157, 6158.353372895961, 6164.106204600397, 6171.1360409543595, 6221.4089661482085, 4858.854234372974, 5892.175095486549, 6010.9699451502465, 6016.909746353721, 6047.637141771508], "value": 5651.746796362921, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.1506043939591, 529.5340100711373, 529.9814506492272, 539.7058603061331, 563.9887202255958, 32810.34379312412, 40102.39846402299, 40105.59894401055, 40122.59877401229, 40257.798711006406, 40607.796961015156, 41080.58919410805, 41119.383209251886, 41218.0, 41447.963800904916, 9566.760830979229, 9714.708558743248, 9749.31754777166, 9770.462624555646, 9783.315167938246, 9239.214666753334, 9655.75860603487, 9707.95146024269, 9729.0, 9782.853257201148, 9777.560009799576, 9790.853137202948, 9800.460974646392, 9811.018898110167, 9823.459709715962, 9752.512374381287, 9790.0, 9796.804063918682, 9800.509974501318, 9800.803983920354, 9593.664221752275, 9603.4718090505, 9636.662716804878, 9650.324477286593, 9674.0, 39107.41338879891, 39436.802815985735, 39559.81320560388, 39749.0, 39936.80031599823, 3253.4957081652383, 3303.3228188221356, 3346.8995930122137, 3437.1922598189403, 3439.570053743282, 29493.967711130223, 30321.0, 31212.843935780176, 32267.51598726021, 32634.673653263402, 3237.5143728440685, 3262.559554460141, 3264.55928449661, 3272.9509057364157, 3366.5286859839644, 468863.0, 471494.0, 477054.0, 479074.9999999965, 479285.0, 55453.722731386086, 61959.69020154871, 63751.0, 64741.0, 65052.34947650557, 35295.29409411798, 35797.46303805445, 36163.73426930045, 36782.0, 68212.0, 16740.0, 16908.746368804477, 16908.91545542289, 16958.830411695973, 16977.575560611083, 11092.890710929005, 12439.0, 12720.300383478812, 12861.742765144645, 12913.966882649365, 20494.590108198052, 20509.897450512653, 20697.482562935747, 20760.169593216404, 20772.79227207739, 7307.634618269065, 8731.60707768152, 8784.824303513893, 8813.779655508664, 8833.69082082124, 819.2913130142384, 848.1772680499905, 884.2793123604242, 1017.4200705597887, 1033.261218228955, 26585.867070664524, 28339.57490637642, 28490.572641410396, 29869.55195672067, 30582.0, 1009.7980403919246, 1016.903394177552, 1017.9565944906531, 1021.1269364693229, 1121.6466812953893, 13433.664158395923, 15349.539513814603, 15431.38274469031, 15685.686286274211, 15794.447194348142, 552465.0, 653743.0, 657850.0, 662025.0, 662109.0, 55644.72177639167, 55869.0, 56370.87258254894, 56669.0, 56706.716466417405, 1110.2394859521253, 1172.8827117288204, 1368.4594585138616, 1385.1550554161954, 1391.2139641102744, 28150.71849281481, 29219.0, 29249.41501169922, 29265.85367073151, 29743.405131897674, 48690.75654621704, 48903.26645100255, 48990.26514602355, 49171.75414123049, 49193.0, 575665.0, 576336.0, 577407.0, 578017.0, 579099.1045044748, 26688.19935401941, 26892.327691807077, 27001.729982699922, 27010.594841077793, 27149.72850271551, 45896.0, 46408.83977900445, 46482.0, 47459.52540474551, 47545.762271188425, 38607.6139238604, 38775.03062423462, 39063.02342441348, 39383.80308098441, 39398.60601393949, 31695.0, 36103.736369228005, 37908.43137352887, 38229.61770382261, 42495.362569560864, 30725.846370768002, 32666.183345416553, 37935.62064379321, 37963.620363796006, 38093.42859857049, 33734.0, 34124.82937585296, 35002.0, 35129.47305790364, 35208.29583408266, 20515.79484205139, 25381.0, 25716.228513143888, 25848.353791155372, 26007.609885852107], "value": 19701.09268155015, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.5011657921145, 367.4910249304712, 374.9445311448273, 383.93856982882744, 397.1560434077585, 16446.83553164468, 19431.41705748828, 20434.795652043475, 20661.0, 20681.276155334566, 16580.75128873068, 19851.10670019849, 20116.798832011675, 20407.224663437275, 20765.480862978435, 1563.3355823774907, 1618.668173024527, 1618.8785841061967, 1619.9190040497988, 1839.4021942868587, 5727.799527016556, 5907.468327850493, 5911.822645320647, 6036.577439579231, 6041.909371359433, 5017.397912250535, 5917.289925208959, 5920.526357891358, 5920.822375328747, 5926.881462370771, 5609.439056094398, 5753.568482363798, 5862.06207006879, 5869.325027621835, 5871.765129394819, 5067.062593420229, 5102.387713474407, 5120.385553733576, 5126.641135120543, 5141.331626888518, 19956.90021549883, 23938.28185154448, 25312.493750124893, 25404.0, 25659.74340256592, 2182.4434769133927, 2252.31304452142, 2260.9660855087186, 2377.9881100594384, 2436.4639779248537, 15161.696766064728, 15509.5347139586, 15623.687526249412, 16107.355705771755, 16360.509184724477, 1773.671870703911, 1810.809864964186, 1862.32956135791, 1877.6714075036814, 1921.4331772127257, 113566.43216783863, 113754.0, 114154.42922785332, 114195.99999999917, 114395.85604143936, 10332.896671033344, 10338.844917326247, 10363.11913487347, 10426.791464170674, 10484.947575262075, 12961.611151665276, 13036.283004434657, 13089.476420943247, 13127.146735462164, 13146.34268286601, 8315.251627353446, 8350.62422191003, 8391.412601117985, 8413.663453461915, 8505.0, 7830.765077047698, 7962.283394494523, 8324.209200125979, 8545.401821872534, 8734.825303493895, 6391.808245752635, 6440.54916155874, 6441.0982462455295, 6460.3216662250725, 6535.0, 1837.9816201838078, 1875.924963001492, 1877.1978900255635, 1880.3512788088087, 1911.5949776913958, 836.4855613797471, 876.2945828608032, 915.8855143107112, 923.5290002098978, 959.4483172176027, 18043.639127217644, 20746.0, 21970.0, 21982.560348792933, 22064.0, 877.8463768840427, 911.5852287209436, 955.8279509688323, 962.3644967945974, 970.8543718442149, 9088.909110908939, 11124.221304508768, 11210.775784484265, 11687.590934317259, 11776.05791536675, 572667.0, 574299.0, 580611.0, 582084.0, 584402.0, 113264.43367783108, 114095.0, 114636.0, 117674.82325176639, 118127.40936295263, 1010.6210171185805, 1017.1455976979225, 1066.7546464313252, 1093.33853018924, 1100.998091736516, 18621.534461638566, 19399.2240310386, 19452.61094778125, 19461.61076778428, 19539.902300488407, 41932.0, 53513.732431337594, 53559.46440535701, 54065.18902216391, 54238.72880635571, 57961.0, 72712.63643681748, 72856.0, 73033.26966730478, 73152.63423682848, 5524.917126243029, 6759.290274521104, 7863.882041769492, 7919.0, 7958.801029974065, 10580.312279701795, 12942.899853512557, 13359.332033398485, 13385.799213011618, 13790.586282411543, 10456.47717614101, 11262.549498019987, 11276.830847537129, 11427.771444570895, 11560.537578496765, 9432.669856554987, 11037.448127593749, 11206.551737930718, 11303.830442543533, 11607.245529040585, 9680.661176858775, 11414.543418263176, 11575.942120289345, 11847.348395838311, 11921.284722916653, 7086.0, 7649.120351159688, 7787.29914307715, 7904.84190316202, 8015.198480151938, 10681.305715128492, 11141.665750027512, 11515.25150865191, 11519.366434845828, 11674.007709344802], "value": 9216.279900676722, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.9796453053202, 1535.6468012357157, 1694.169856770183, 1878.107898748094, 1885.4814925895378, 7834.255745704163, 8348.91651083489, 8478.364122690808, 8893.421927574711, 8931.419457735243, 8523.105073967221, 8702.173293537104, 8744.956275218616, 8747.956260218705, 8776.956115219415, 2372.5136347048856, 2391.1391898916377, 2398.8080953523763, 2401.6277476991095, 2407.1334319644916, 2359.2214569192124, 2373.8457000294925, 2405.0259644843777, 2408.385861605288, 2409.4458274596855, 2392.605220138675, 2395.4610212702178, 2401.8438801477937, 2404.1224952892157, 2408.0849277274697, 1960.5000724815222, 2367.7868991790733, 2374.2995816234165, 2383.30884043628, 2393.4495066134714, 2351.1418332308585, 2361.4568649210605, 2369.1707902234252, 2375.596148654733, 2375.6080246759266, 8737.912620873836, 8759.868601970975, 8820.382573219877, 8834.823303533958, 8853.11468853116, 4498.842540511099, 4732.455767586703, 5121.436641969381, 5242.135047717122, 5382.219578161184, 3012.9849350753107, 3583.3191693578137, 3642.763220390693, 3675.191457879262, 3683.355412802749, 1063.5905176507024, 1072.0030371754265, 1072.9913880951958, 1083.5232497701065, 1086.8804431512526, 9642.614295428175, 11518.136139789549, 11520.135989800714, 11566.669832969148, 11576.421178941104, 3394.405978953673, 4606.355110284563, 4776.952230477651, 4787.569118779326, 4802.375691160197, 9773.706788796348, 9806.264530160266, 9813.607455701835, 9850.605975761033, 9876.456794876207, 5324.787008519694, 5328.0675881720545, 5331.86670333237, 5338.706371149624, 5362.597805164628, 4192.979035104805, 4368.36658684489, 4859.465458799529, 4862.635302352338, 4879.2925025871045, 3868.400397938324, 3916.3538016227003, 4002.7598344099447, 4007.7595344278852, 4035.394690796404, 1018.6129270877168, 1135.920485566019, 1307.744989727013, 1322.7288405876773, 1325.602319304205, 780.6135962698501, 793.9361255917099, 887.4808237181321, 907.1291560102244, 940.2618944128826, 5476.91784623231, 5719.628224165416, 5873.823785286449, 5924.318703349084, 5975.073863551156, 904.4618452021037, 928.8467402878584, 941.9717408477757, 967.4002118686459, 1015.081351377005, 3065.084708787199, 3761.962380376161, 3952.3676211805973, 3972.867732696168, 4026.3960405939342, 8307.543085130375, 10491.842622360671, 10658.946705266424, 10779.0058839115, 10792.730181745519, 10431.634892778717, 10633.946830265799, 10723.678289651323, 10726.517306721216, 10761.192910531741, 7962.482438641469, 8120.959395202986, 8168.264856162974, 8206.097329293772, 8217.465864718773, 5564.777408903598, 5652.745626446737, 5753.223314852441, 5758.913616295675, 5806.738696758741, 9830.0, 9837.557309920927, 9927.553260103457, 9939.503024848873, 9945.552450139616, 9789.265805064506, 9932.801343972935, 10003.699889003341, 10203.591856325664, 10241.027102425258, 6799.456043516606, 6809.285025072494, 6917.412019978358, 6943.79168624942, 7002.144431725698, 5929.911051334319, 8080.757577272691, 8166.101728809707, 8178.46839955401, 8183.140770219041, 7293.562386256842, 7382.480278756806, 7429.702811887681, 7452.254774522721, 7544.735934242275, 7173.689824645453, 7254.419646428168, 7273.3453989141235, 7468.141163766237, 7473.476856620202, 6620.536562440776, 6707.731690732316, 6748.257691654012, 6754.729810807513, 6761.93238067613, 4851.0, 4888.2178851383605, 4897.975510122569, 4948.05986862495, 5017.24741288795, 3963.9495533684176, 4371.0, 4879.463259041574, 4930.063287975273, 4971.353724015855], "value": 4510.881513361086, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.66776212285436, 304.18478477679815, 309.8326903472126, 312.78417891654755, 313.56414583728605, 4241.978790106045, 4638.884027899304, 5458.91811622825, 5608.411116832735, 5691.943080569193, 4367.541408152146, 4733.296013435161, 5526.806561770339, 5585.916211256835, 5636.520895723858, 3754.7371683982133, 4249.0, 4250.426192464024, 4256.680748943826, 4297.570242975708, 3533.92932141357, 3943.329633962219, 4178.01816573105, 4313.137372525507, 4315.201687687788, 3942.408638704203, 4118.011677197466, 4133.896652583694, 4151.920500669818, 4186.0581369192, 4127.215828992496, 4155.916881662365, 4180.059486615518, 4181.330987042059, 4185.707000509966, 3447.586489539302, 3591.299696559172, 3604.153024039375, 3621.8732344367822, 3662.633736626343, 4111.97944010281, 5458.808941687062, 5472.534834539069, 5503.174523821419, 5517.36550296717, 4838.806447742086, 4839.322494850724, 4842.706959267696, 4850.611951043908, 4854.223324268136, 4922.876928076791, 5243.737813109329, 5494.5054945054735, 5600.719964001783, 5720.971395142998, 3955.9011024724323, 3981.646240278319, 3987.5813039631003, 3990.521137463494, 3999.5400528938962, 6241.65937773335, 6264.718087686065, 6278.372162783685, 6310.686889383065, 6312.684365781693, 3585.354636165463, 3706.4440333950124, 3887.261420330128, 3957.4763715969557, 4025.77858217795, 4072.6741860651077, 4110.486189226347, 4115.464989551339, 4116.608922152391, 4119.237940980928, 4803.447603525569, 4804.759762011886, 4813.3983252093485, 4821.0, 4842.564169224786, 5147.867469156779, 5340.572754179734, 5524.806631767869, 6031.276246850318, 6061.484773794189, 1305.4517102816649, 1357.7080927600416, 1389.666480044782, 1392.276016471429, 1393.9512117075853, 782.3154739602847, 887.2103827593447, 895.1987970766259, 932.9486878221763, 933.6825479337058, 4873.439554451213, 5118.872028199325, 5469.726513674301, 5554.1391084381985, 5559.0, 5612.579056570774, 6062.0, 6105.725242364104, 6113.205283313141, 6129.781864521162, 4287.678424118204, 4614.17739992701, 4795.880102997453, 4954.921721680354, 5432.848947610377, 2429.161939130997, 2535.7844583210262, 2679.3569543309463, 2709.769669578108, 2734.2754170144926, 4733.431988161443, 4985.2141943733695, 5270.62679884874, 5279.8152064678325, 5367.543758780469, 5121.180611102206, 5272.499112584298, 5322.520973112439, 5836.766529338864, 6422.325655806167, 6164.969175154096, 6400.487960963112, 6442.48460123189, 6453.51598630114, 6455.965696122815, 5953.791617293374, 5991.191189189578, 6154.230721159855, 6291.307956124914, 6294.622322660655, 5058.443571207091, 6121.3572574879, 6137.355577664281, 6188.55552056944, 6195.597286176384, 6044.15381846533, 6409.0, 6445.066480056058, 6455.806325810233, 6478.70845811949, 3482.268723568079, 4009.5389030260726, 4038.0617303693803, 4070.5346075413418, 4086.816093275869, 5044.697318160922, 5081.983603279285, 5923.512124907463, 6201.937730786342, 6235.844103897258, 5868.2077919480325, 6002.939970600238, 6023.5181185505935, 6034.818955431344, 6180.845478863064, 4807.471178170468, 5199.792008319625, 5449.591280654046, 6033.637981721111, 6036.6679832609625, 5595.692236927008, 5843.503302219359, 5876.50248644333, 5882.205902203148, 5928.229330187048, 5483.424240454695, 6020.969895150496, 6025.457708806229, 6075.362086980804, 6079.756809727738, 5170.922436163385, 5346.679199248058, 5467.535259502828, 5664.348599911063, 5888.911666324922], "value": 4232.994134156322, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.9072675188851, 691.9446444284456, 713.6217804563584, 740.7592532426959, 811.3103861717539, 14946.327415266313, 15111.546653600382, 15441.0, 15547.300371483283, 15572.45496407626, 13793.793093103586, 13961.30193490324, 14007.92996035021, 14130.858691413083, 14158.29208539574, 850.0819115355432, 865.5196366016858, 865.9826803463927, 867.0116067682847, 868.8740132680757, 683.0539702512007, 838.3879767769532, 855.170484629908, 867.8871746672924, 869.6521391443441, 878.3236907581158, 909.1045320359441, 910.2399496420463, 911.9785839859334, 912.8493798523269, 863.8920134983127, 896.5651658945407, 907.1971305394708, 910.0944560162648, 911.8632205169212, 643.9259485159173, 668.481926506961, 671.7413795688695, 672.9427998620123, 673.4612310151908, 12937.094403391766, 13135.211887286794, 13185.934070329587, 13203.86796132036, 13302.201867887958, 14321.57035288943, 14557.708845823025, 14746.705065898623, 14783.482578109715, 14793.11241325524, 11705.941470292679, 11872.168948173632, 11962.641120766391, 12169.574064907685, 12196.0, 3769.189624230776, 3853.2293541291856, 3919.7648141111627, 3971.940420893689, 3972.6424621784176, 113698.43150784276, 120066.0, 121080.39459802644, 121100.1834972476, 121575.0, 9235.907640923639, 11161.274517156358, 11167.664970050912, 11197.608083717194, 11361.829572556418, 18921.81078189228, 22790.886045569998, 22928.88535557345, 22983.540329193656, 23005.194818181284, 7947.880781788278, 8045.839083218303, 8160.755177344689, 8228.341732661373, 8237.546934918517, 4289.806958686867, 4295.978520107379, 4457.598816106566, 4505.391772110788, 4604.654650901196, 3551.4672799080345, 3639.3267245559655, 3673.7428380013675, 3700.870469533553, 3706.2772759311674, 558.4666643355586, 663.0849427789619, 663.4824836627438, 672.9394354508117, 677.021703638238, 538.0879409401106, 548.7322991585102, 636.7484843486797, 651.3519048546632, 658.2989116590911, 8667.869981950276, 9040.63837446508, 9272.582733776997, 9319.953400233091, 9366.531673416303, 608.6743592178163, 662.6984721951501, 681.1077488490093, 691.2707094015761, 700.0758998122429, 3799.848006079781, 4117.8970525736495, 4586.8165273388695, 4652.930206046912, 4671.6262698984, 112866.0, 115985.0, 117770.0, 148902.7664585032, 149559.75660365104, 118582.0, 125333.37333313275, 127059.72940270479, 127603.0, 130710.0, 73819.0, 83809.16190838136, 87300.0, 94903.52548237353, 117300.0, 9178.128077832594, 9727.319087663796, 9765.72650768577, 9794.363366381447, 9852.852207216754, 42305.78847105745, 47355.763221183675, 57581.71209143927, 59512.404875950684, 61498.0, 40478.0, 58124.70937645285, 58452.123218152614, 76978.61510692634, 81887.59056204681, 12578.30819304947, 13131.67170820699, 13173.60479185626, 13293.0, 13317.467301308227, 14754.0, 14834.480793172619, 14993.700125997637, 15126.621834454227, 15444.2277886103, 11634.836516348298, 11949.581764638197, 13101.541446049343, 14006.43974241019, 14209.076410033314, 10904.291221070605, 13513.662158446117, 13603.931980340036, 13800.585982420544, 14197.858021420067, 12587.87412125867, 13040.41318140705, 13045.478180872658, 13052.570779847867, 13351.265680387673, 11014.229003969645, 11257.544521994054, 11463.197576169921, 11684.70788230301, 11709.238899471507, 10348.586056557653, 10619.36283822973, 11404.258723182966, 11544.884551154717, 11549.711257218636], "value": 7627.3731952634125, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1646.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 107.0, 129.0, 97.0, 168.0, 149.0, 158.0, 117.0, 116.0, 117.0], "value": 127.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 135.0, 186.0, 145.0, 130.0, 130.0, 115.0, 190.0, 114.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 219.0, 272.0, 263.0, 287.0, 240.0, 198.0, 215.0, 283.0, 230.0], "value": 240.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 81.0, 74.0, 176.0, 86.0, 65.0, 59.0, 58.0, 138.0, 60.0], "value": 86.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 129.0, 96.0, 101.0, 108.0, 116.0, 70.0, 72.0, 114.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 93.0, 74.0, 136.0, 79.0, 136.0, 132.0, 124.0, 88.0], "value": 104.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 97.0, 99.0, 98.0, 104.0, 152.0, 97.0, 97.0, 99.0], "value": 103.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 99.0, 146.0, 146.0, 78.0, 143.0, 145.0, 134.0, 100.0, 101.0], "value": 123.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 60.0, 58.0, 57.0, 59.0, 60.0, 57.0, 57.0, 56.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 80.0, 66.0, 60.0, 65.0, 95.0, 91.0, 106.0, 103.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 86.0, 88.0, 92.0, 86.0, 87.0, 79.0, 92.0, 94.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 150.0, 172.0, 127.0, 159.0, 161.0, 155.0, 160.0, 218.0, 163.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 172.0, 168.0, 166.0, 173.0, 173.0, 170.0, 172.0, 171.0, 171.0], "value": 171.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 70.0, 68.0, 68.0, 69.0, 68.0, 68.0, 68.0, 70.0], "value": 68.5, "unit": "score"}]}]} 19:13:20 INFO - Return code: 0 19:13:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:13:20 ERROR - # TBPL SUCCESS # 19:13:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:13:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:13:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:13:20 INFO - 2016-09-06 19:13:20.393 screenresolution[1665:10463] starting screenresolution argv=screenresolution get 19:13:20 INFO - 2016-09-06 19:13:20.395 screenresolution[1665:10463] Display 0: 1600x1200x32@60 19:13:20 INFO - 2016-09-06 19:13:20.401 screenresolution[1666:10466] starting screenresolution argv=screenresolution list 19:13:20 INFO - Available Modes on Display 0 19:13:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:13:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:13:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:13:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:13:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:13:20 INFO - Intel Iris: 19:13:20 INFO - Chipset Model: Intel Iris 19:13:20 INFO - Type: GPU 19:13:20 INFO - Bus: Built-In 19:13:20 INFO - VRAM (Dynamic, Max): 1536 MB 19:13:20 INFO - Vendor: Intel (0x8086) 19:13:20 INFO - Device ID: 0x0a2e 19:13:20 INFO - Revision ID: 0x0009 19:13:20 INFO - Displays: 19:13:20 INFO - Display: 19:13:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:13:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:13:20 INFO - Display Serial Number: 1600x1200 60 19:13:20 INFO - Main Display: Yes 19:13:20 INFO - Mirror: Off 19:13:20 INFO - Online: Yes 19:13:20 INFO - Rotation: Supported 19:13:20 INFO - Return code: 0 19:13:20 INFO - Running post-action listener: _resource_record_post_action 19:13:20 INFO - [mozharness: 2016-09-07 02:13:20.636240Z] Finished run-tests step (success) 19:13:20 INFO - Running post-run listener: _resource_record_post_run 19:13:20 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38323200; Write bytes: 707227136; Read time: 381; Write time: 2010 19:13:20 INFO - TinderboxPrint: CPU usage
54.4% 19:13:20 INFO - TinderboxPrint: I/O read bytes / time
38,323,200 / 381 19:13:20 INFO - TinderboxPrint: I/O write bytes / time
707,227,136 / 2,010 19:13:20 INFO - TinderboxPrint: CPU idle
2,209.8 (45.6%) 19:13:20 INFO - TinderboxPrint: CPU system
982.0 (20.3%) 19:13:20 INFO - TinderboxPrint: CPU user
1,653.8 (34.1%) 19:13:20 INFO - TinderboxPrint: Swap in / out
444,137,472 / 0 19:13:20 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 385741312; Write bytes: 379880960; Read time: 29048; Write time: 742 19:13:20 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 37360640; Write bytes: 298977280; Read time: 366; Write time: 1214 19:13:21 INFO - Running post-run listener: _upload_blobber_files 19:13:21 INFO - Blob upload gear active. 19:13:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:13:21 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 19:13:21 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:13:21 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:13:21 INFO - (blobuploader) - INFO - Open directory for files ... 19:13:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:13:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:13:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:13:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:13:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:13:22 INFO - (blobuploader) - INFO - Done attempting. 19:13:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:13:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:13:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:13:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:13:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:13:23 INFO - (blobuploader) - INFO - Done attempting. 19:13:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:13:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:13:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:13:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:13:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:13:26 INFO - (blobuploader) - INFO - Done attempting. 19:13:26 INFO - (blobuploader) - INFO - Iteration through files over. 19:13:26 INFO - Return code: 0 19:13:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:13:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:13:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/482228b03bdf37b41430c3e07c99eb8501c9ec3852b256b898bcbe45f5fa34019046c8179c9dce42f6a480d38f3c0e3859e73cfae32b739b755a2c773b3a1f7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/032766895c9b50d090cd44b2a30e965f853e9b98daeb15f0d52d537db0f58f2d6f0c349c135777b53315c64aab2f18edb6dc1555bb631286133ba45de1713b64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4bd7e1501b8dd83a71f6dd33580aa99d5b7494fc0ea76b8970d07408d2954e192b215c4da876b1c7bc0c01d14ca6ea23bdfd0be6432ad39b6ad5d8d183414eb1"} 19:13:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:13:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:13:26 INFO - Contents: 19:13:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/482228b03bdf37b41430c3e07c99eb8501c9ec3852b256b898bcbe45f5fa34019046c8179c9dce42f6a480d38f3c0e3859e73cfae32b739b755a2c773b3a1f7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/032766895c9b50d090cd44b2a30e965f853e9b98daeb15f0d52d537db0f58f2d6f0c349c135777b53315c64aab2f18edb6dc1555bb631286133ba45de1713b64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4bd7e1501b8dd83a71f6dd33580aa99d5b7494fc0ea76b8970d07408d2954e192b215c4da876b1c7bc0c01d14ca6ea23bdfd0be6432ad39b6ad5d8d183414eb1"} 19:13:26 INFO - Running post-run listener: copy_logs_to_upload_dir 19:13:26 INFO - Copying logs to upload dir... 19:13:26 INFO - mkdir: /builds/slave/test/build/upload/logs 19:13:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.701643 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-06 19:13:26.261044) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 19:13:26.265012) ========= 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.xabwqh0DlY/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.WNYgvToalZ/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/482228b03bdf37b41430c3e07c99eb8501c9ec3852b256b898bcbe45f5fa34019046c8179c9dce42f6a480d38f3c0e3859e73cfae32b739b755a2c773b3a1f7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/032766895c9b50d090cd44b2a30e965f853e9b98daeb15f0d52d537db0f58f2d6f0c349c135777b53315c64aab2f18edb6dc1555bb631286133ba45de1713b64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4bd7e1501b8dd83a71f6dd33580aa99d5b7494fc0ea76b8970d07408d2954e192b215c4da876b1c7bc0c01d14ca6ea23bdfd0be6432ad39b6ad5d8d183414eb1"} build_url:https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011338 build_url: 'https://queue.taskcluster.net/v1/task/LN8hK4dsSqC-ImEHXgXFXg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/482228b03bdf37b41430c3e07c99eb8501c9ec3852b256b898bcbe45f5fa34019046c8179c9dce42f6a480d38f3c0e3859e73cfae32b739b755a2c773b3a1f7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/032766895c9b50d090cd44b2a30e965f853e9b98daeb15f0d52d537db0f58f2d6f0c349c135777b53315c64aab2f18edb6dc1555bb631286133ba45de1713b64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4bd7e1501b8dd83a71f6dd33580aa99d5b7494fc0ea76b8970d07408d2954e192b215c4da876b1c7bc0c01d14ca6ea23bdfd0be6432ad39b6ad5d8d183414eb1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 19:13:26.300348) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 19:13:26.301080) ========= 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.xabwqh0DlY/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.WNYgvToalZ/Listeners TMPDIR=/var/folders/y0/2qrs5c4s7zx7vn2wp253cdm000000w/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.005086 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 19:13:26.355976) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 19:13:26.356711) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 19:13:26.357377) ========= ========= Total master_lag: 1.05 =========