builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0215 starttime: 1482164387.17 results: success (0) buildid: 20161219070438 builduid: 0f955e9ab14746ee9cbcc4218ead8e09 revision: d4b3146a5567a7ddbcdfa5244945db55616cb8d1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:47.168721) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:47.180582) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:47.181295) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:47.289455) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:47.289882) ========= 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.KAf200UK7t/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.oNIhjIRvad/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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-12-19 08:19:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.9M=0.001s 2016-12-19 08:19:47 (17.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.585568 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:47.899626) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:47.899941) ========= 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.KAf200UK7t/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.oNIhjIRvad/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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.024905 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:47.950132) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:47.950586) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d4b3146a5567a7ddbcdfa5244945db55616cb8d1 --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 d4b3146a5567a7ddbcdfa5244945db55616cb8d1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KAf200UK7t/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.oNIhjIRvad/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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-12-19 08:19:48,051 truncating revision to first 12 chars 2016-12-19 08:19:48,051 Setting DEBUG logging. 2016-12-19 08:19:48,051 attempt 1/10 2016-12-19 08:19:48,051 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d4b3146a5567?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-19 08:19:48,715 unpacking tar archive at: mozilla-central-d4b3146a5567/testing/mozharness/ program finished with exit code 0 elapsedTime=0.930788 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:48.907372) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:48.907860) ========= script_repo_revision: d4b3146a5567a7ddbcdfa5244945db55616cb8d1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:48.908472) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:48.908884) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 08:19:48.932152) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-19 08:19:48.932599) ========= /tools/buildbot/bin/python -u 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', '-u', '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.KAf200UK7t/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.oNIhjIRvad/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 08:19:49 INFO - MultiFileLogger online at 20161219 08:19:49 in /builds/slave/test 08:19:49 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 08:19:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:19:49 INFO - {'append_to_log': False, 08:19:49 INFO - 'base_work_dir': '/builds/slave/test', 08:19:49 INFO - 'blob_upload_branch': 'Firefox', 08:19:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:19:49 INFO - 'branch': 'Firefox', 08:19:49 INFO - 'buildbot_json_path': 'buildprops.json', 08:19:49 INFO - 'config_files': ('talos/mac_config.py',), 08:19:49 INFO - 'default_actions': ('clobber', 08:19:49 INFO - 'read-buildbot-config', 08:19:49 INFO - 'download-and-extract', 08:19:49 INFO - 'populate-webroot', 08:19:49 INFO - 'create-virtualenv', 08:19:49 INFO - 'install', 08:19:49 INFO - 'run-tests'), 08:19:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:19:49 INFO - 'download_minidump_stackwalk': True, 08:19:49 INFO - 'download_symbols': 'ondemand', 08:19:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:19:49 INFO - 'tooltool.py': '/tools/tooltool.py', 08:19:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:19:49 INFO - '/tools/misc-python/virtualenv.py')}, 08:19:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:19:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:19:49 INFO - 'installer_path': 'installer.exe', 08:19:49 INFO - 'log_level': 'info', 08:19:49 INFO - 'log_name': 'talos', 08:19:49 INFO - 'log_to_console': True, 08:19:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:19:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:19:49 INFO - 'opt_config_files': (), 08:19:49 INFO - 'pip_index': False, 08:19:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:19:49 INFO - 'cmd': ('bash', 08:19:49 INFO - '-c', 08:19:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:19:49 INFO - 'enabled': True, 08:19:49 INFO - 'halt_on_failure': False, 08:19:49 INFO - 'name': 'check_screen_resolution'},), 08:19:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:19:49 INFO - 'cmd': ('bash', 08:19:49 INFO - '-c', 08:19:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:19:49 INFO - 'enabled': True, 08:19:49 INFO - 'halt_on_failure': False, 08:19:49 INFO - 'name': 'check_screen_resolution'},), 08:19:49 INFO - 'run_cmd_checks_enabled': True, 08:19:49 INFO - 'sps_profile': False, 08:19:49 INFO - 'sps_profile_interval': 0, 08:19:49 INFO - 'suite': 'dromaeojs-e10s', 08:19:49 INFO - 'system_bits': '32', 08:19:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:19:49 INFO - 'title': 't-yosemite-r7-0215', 08:19:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:19:49 INFO - 'use_talos_json': True, 08:19:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:19:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:19:49 INFO - 'work_dir': 'build'} 08:19:49 INFO - [mozharness: 2016-12-19 16:19:49.141403Z] Running clobber step. 08:19:49 INFO - Running pre-action listener: _resource_record_pre_action 08:19:49 INFO - Running main action method: clobber 08:19:49 INFO - rmtree: /builds/slave/test/build 08:19:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:19:51 INFO - Running post-action listener: _resource_record_post_action 08:19:51 INFO - [mozharness: 2016-12-19 16:19:51.615242Z] Finished clobber step (success) 08:19:51 INFO - [mozharness: 2016-12-19 16:19:51.615371Z] Running read-buildbot-config step. 08:19:51 INFO - Running pre-action listener: _resource_record_pre_action 08:19:51 INFO - Running main action method: read_buildbot_config 08:19:51 INFO - Using buildbot properties: 08:19:51 INFO - { 08:19:51 INFO - "project": "", 08:19:51 INFO - "product": "firefox", 08:19:51 INFO - "script_repo_revision": "production", 08:19:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:19:51 INFO - "repository": "", 08:19:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:19:51 INFO - "buildid": "20161219070438", 08:19:51 INFO - "pgo_build": "False", 08:19:51 INFO - "basedir": "/builds/slave/test", 08:19:51 INFO - "buildnumber": 108, 08:19:51 INFO - "slavename": "t-yosemite-r7-0215", 08:19:51 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:19:51 INFO - "platform": "yosemite_r7", 08:19:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:19:51 INFO - "branch": "mozilla-central", 08:19:51 INFO - "repo_path": "mozilla-central", 08:19:51 INFO - "revision": "d4b3146a5567a7ddbcdfa5244945db55616cb8d1", 08:19:51 INFO - "stage_platform": "macosx64", 08:19:51 INFO - "builduid": "0f955e9ab14746ee9cbcc4218ead8e09", 08:19:51 INFO - "slavebuilddir": "test" 08:19:51 INFO - } 08:19:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:19:51 INFO - Running post-action listener: _resource_record_post_action 08:19:51 INFO - [mozharness: 2016-12-19 16:19:51.618627Z] Finished read-buildbot-config step (success) 08:19:51 INFO - [mozharness: 2016-12-19 16:19:51.618719Z] Running download-and-extract step. 08:19:51 INFO - Running pre-action listener: _resource_record_pre_action 08:19:51 INFO - Running main action method: download_and_extract 08:19:51 INFO - mkdir: /builds/slave/test/build/tests 08:19:51 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')]} 08:19:51 INFO - trying https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:19:51 INFO - Downloading https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:19:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:19:52 INFO - Downloaded 1199 bytes. 08:19:52 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:19:52 INFO - Using the following test package requirements: 08:19:52 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:19:52 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:19:52 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:19:52 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:19:52 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:19:52 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:19:52 INFO - u'jsshell-mac.zip'], 08:19:52 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:19:52 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:19:52 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:19:52 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:19:52 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:19:52 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:19:52 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:19:52 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:19:52 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:19:52 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:19:52 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:19:52 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:19:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:19:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:19:52 INFO - Fetch https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:19:53 INFO - Expected file size: 16276089 08:19:53 INFO - Obtained file size: 16276089 08:20:00 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:20:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:20:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:20:00 INFO - Fetch https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:20:01 INFO - Expected file size: 16276089 08:20:01 INFO - Obtained file size: 16276089 08:20:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:20:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:20:07 INFO - Fetch https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:20:08 INFO - Expected file size: 14409734 08:20:08 INFO - Obtained file size: 14409734 08:20:09 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')]} 08:20:09 INFO - trying https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:20:09 INFO - Downloading https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:20:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:20:12 INFO - Downloaded 69556187 bytes. 08:20:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:20:12 INFO - mkdir: /builds/slave/test/properties 08:20:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:20:12 INFO - Writing to file /builds/slave/test/properties/build_url 08:20:12 INFO - Contents: 08:20:12 INFO - build_url:https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:20:12 INFO - Running post-action listener: _resource_record_post_action 08:20:12 INFO - Running post-action listener: set_extra_try_arguments 08:20:12 INFO - [mozharness: 2016-12-19 16:20:12.626121Z] Finished download-and-extract step (success) 08:20:12 INFO - [mozharness: 2016-12-19 16:20:12.626264Z] Running populate-webroot step. 08:20:12 INFO - Running pre-action listener: _resource_record_pre_action 08:20:12 INFO - Running main action method: populate_webroot 08:20:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:20:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:20:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:20:12 INFO - '--tppagecycles', 08:20:12 INFO - '7'], 08:20:12 INFO - 'tests': ['tsvgm']}}, 08:20:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:20:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:20:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:20:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:20:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:20:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:20:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:20:12 INFO - 'talos_options': ['--disable-e10s'], 08:20:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:20:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:20:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:20:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:20:12 INFO - 'talos_options': ['--disable-e10s'], 08:20:12 INFO - 'tests': ['damp', 'tps']}, 08:20:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:20:12 INFO - 'tests': ['damp', 'tps']}, 08:20:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:20:12 INFO - 'tests': ['dromaeo_dom']}, 08:20:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:20:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:20:12 INFO - 'tests': ['basic_compositor_video']}, 08:20:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:20:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:20:12 INFO - 'tests': ['a11yr', 08:20:12 INFO - 'ts_paint', 08:20:12 INFO - 'tpaint', 08:20:12 INFO - 'sessionrestore', 08:20:12 INFO - 'sessionrestore_no_auto_restore', 08:20:12 INFO - 'tabpaint']}, 08:20:12 INFO - 'other-e10s': {'tests': ['a11yr', 08:20:12 INFO - 'ts_paint', 08:20:12 INFO - 'tpaint', 08:20:12 INFO - 'sessionrestore', 08:20:12 INFO - 'sessionrestore_no_auto_restore', 08:20:12 INFO - 'tabpaint']}, 08:20:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:20:12 INFO - 'ts_paint', 08:20:12 INFO - 'tpaint', 08:20:12 INFO - 'sessionrestore', 08:20:12 INFO - 'sessionrestore_no_auto_restore', 08:20:12 INFO - 'tabpaint']}, 08:20:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:20:12 INFO - 'ts_paint', 08:20:12 INFO - 'tpaint', 08:20:12 INFO - 'sessionrestore', 08:20:12 INFO - 'sessionrestore_no_auto_restore', 08:20:12 INFO - 'tabpaint']}, 08:20:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:20:12 INFO - 'tests': ['a11yr', 08:20:12 INFO - 'ts_paint', 08:20:12 INFO - 'tpaint', 08:20:12 INFO - 'sessionrestore', 08:20:12 INFO - 'sessionrestore_no_auto_restore', 08:20:12 INFO - 'tabpaint']}, 08:20:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:20:12 INFO - 'tests': ['a11yr', 08:20:12 INFO - 'ts_paint', 08:20:12 INFO - 'tpaint', 08:20:12 INFO - 'sessionrestore', 08:20:12 INFO - 'sessionrestore_no_auto_restore', 08:20:12 INFO - 'tabpaint']}, 08:20:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:20:12 INFO - 'tests': ['tsvgx', 08:20:12 INFO - 'tsvgr_opacity', 08:20:12 INFO - 'tart', 08:20:12 INFO - 'tscrollx', 08:20:12 INFO - 'cart', 08:20:12 INFO - 'tsvg_static']}, 08:20:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:20:12 INFO - 'tsvgr_opacity', 08:20:12 INFO - 'tart', 08:20:12 INFO - 'tscrollx', 08:20:12 INFO - 'cart', 08:20:12 INFO - 'tsvg_static']}, 08:20:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:20:12 INFO - 'talos_options': ['--disable-e10s'], 08:20:12 INFO - 'tests': ['tp5o']}, 08:20:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:20:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:20:12 INFO - 'talos_options': ['--disable-e10s', 08:20:12 INFO - '--xperf_path', 08:20:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:20:12 INFO - 'tests': ['tp5n']}, 08:20:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:20:12 INFO - 'talos_options': ['--xperf_path', 08:20:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:20:12 INFO - 'tests': ['tp5n']}}, 08:20:12 INFO - 'talos.zip': {'path': '', 08:20:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:20:12 INFO - Running post-action listener: _resource_record_post_action 08:20:12 INFO - [mozharness: 2016-12-19 16:20:12.644782Z] Finished populate-webroot step (success) 08:20:12 INFO - [mozharness: 2016-12-19 16:20:12.644859Z] Running create-virtualenv step. 08:20:12 INFO - Running pre-action listener: _resource_record_pre_action 08:20:12 INFO - Running main action method: create_virtualenv 08:20:12 INFO - Creating virtualenv /builds/slave/test/build/venv 08:20:12 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 08:20:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:20:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:20:12 INFO - Using real prefix '/tools/python27' 08:20:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:20:13 INFO - Installing distribute.............................................................................................................................................................................................done. 08:20:16 INFO - Installing pip.................done. 08:20:16 INFO - Return code: 0 08:20:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:20:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:20:16 INFO - Reading from file tmpfile_stdout 08:20:16 INFO - Output received: 08:20:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:20:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:20: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')]} 08:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20: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')]} 08:20:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:16 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 0x104da3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8135c9270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81d30>, '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 0x104aa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b76be8>, '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.KAf200UK7t/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.oNIhjIRvad/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 08:20:16 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 08:20:16 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 08:20:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAf200UK7t/Render', 08:20:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:16 INFO - 'HOME': '/Users/cltbld', 08:20:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:16 INFO - 'LOGNAME': 'cltbld', 08:20:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:16 INFO - 'MOZ_NO_REMOTE': '1', 08:20:16 INFO - 'NO_EM_RESTART': '1', 08:20:16 INFO - 'PAGER': '/bin/cat', 08:20:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:16 INFO - 'PWD': '/builds/slave/test', 08:20:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:16 INFO - 'SHELL': '/bin/bash', 08:20:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oNIhjIRvad/Listeners', 08:20:16 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:20:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:16 INFO - 'USER': 'cltbld', 08:20:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:16 INFO - 'XPC_FLAGS': '0x0', 08:20:16 INFO - 'XPC_SERVICE_NAME': '0', 08:20:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:16 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 08:20:16 INFO - Cleaning up... 08:20:16 INFO - Return code: 0 08:20:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:20: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')]} 08:20:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20: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')]} 08:20:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:16 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 0x104da3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8135c9270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81d30>, '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 0x104aa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b76be8>, '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.KAf200UK7t/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.oNIhjIRvad/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 08:20:16 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 08:20:16 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 08:20:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAf200UK7t/Render', 08:20:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:16 INFO - 'HOME': '/Users/cltbld', 08:20:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:16 INFO - 'LOGNAME': 'cltbld', 08:20:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:16 INFO - 'MOZ_NO_REMOTE': '1', 08:20:16 INFO - 'NO_EM_RESTART': '1', 08:20:16 INFO - 'PAGER': '/bin/cat', 08:20:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:16 INFO - 'PWD': '/builds/slave/test', 08:20:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:16 INFO - 'SHELL': '/bin/bash', 08:20:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oNIhjIRvad/Listeners', 08:20:16 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:20:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:16 INFO - 'USER': 'cltbld', 08:20:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:16 INFO - 'XPC_FLAGS': '0x0', 08:20:16 INFO - 'XPC_SERVICE_NAME': '0', 08:20:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:17 INFO - Downloading/unpacking psutil>=3.1.1 08:20:17 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 08:20:17 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 08:20:17 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 08:20:17 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 08:20:17 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 08:20:17 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 08:20:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:20:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:20:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:20:20 INFO - Installing collected packages: psutil 08:20:20 INFO - Running setup.py install for psutil 08:20:20 INFO - building 'psutil._psutil_osx' extension 08:20:20 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 08:20:21 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 08:20:21 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 08:20:21 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 08:20:21 INFO - building 'psutil._psutil_posix' extension 08:20:21 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 08:20:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:20:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:20:21 INFO - ^ 08:20:21 INFO - 1 warning generated. 08:20:21 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 08:20:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:20:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:20:22 INFO - Successfully installed psutil 08:20:22 INFO - Cleaning up... 08:20:22 INFO - Return code: 0 08:20:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:20:22 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')]} 08:20:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:22 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')]} 08:20:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:22 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 0x104da3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8135c9270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81d30>, '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 0x104aa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b76be8>, '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.KAf200UK7t/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.oNIhjIRvad/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 08:20:22 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 08:20:22 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 08:20:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAf200UK7t/Render', 08:20:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:22 INFO - 'HOME': '/Users/cltbld', 08:20:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:22 INFO - 'LOGNAME': 'cltbld', 08:20:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:22 INFO - 'MOZ_NO_REMOTE': '1', 08:20:22 INFO - 'NO_EM_RESTART': '1', 08:20:22 INFO - 'PAGER': '/bin/cat', 08:20:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:22 INFO - 'PWD': '/builds/slave/test', 08:20:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:22 INFO - 'SHELL': '/bin/bash', 08:20:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oNIhjIRvad/Listeners', 08:20:22 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:20:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:22 INFO - 'USER': 'cltbld', 08:20:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:22 INFO - 'XPC_FLAGS': '0x0', 08:20:22 INFO - 'XPC_SERVICE_NAME': '0', 08:20:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:20:22 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 08:20:22 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 08:20:22 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 08:20:22 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 08:20:22 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 08:20:22 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 08:20:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:20:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:20:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:20:24 INFO - Installing collected packages: mozsystemmonitor 08:20:24 INFO - Running setup.py install for mozsystemmonitor 08:20:25 INFO - Successfully installed mozsystemmonitor 08:20:25 INFO - Cleaning up... 08:20:25 INFO - Return code: 0 08:20:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:20:25 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')]} 08:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:25 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')]} 08:20:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:25 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 0x104da3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8135c9270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81d30>, '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 0x104aa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b76be8>, '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.KAf200UK7t/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.oNIhjIRvad/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 08:20:25 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 08:20:25 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 08:20:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAf200UK7t/Render', 08:20:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:25 INFO - 'HOME': '/Users/cltbld', 08:20:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:25 INFO - 'LOGNAME': 'cltbld', 08:20:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:25 INFO - 'MOZ_NO_REMOTE': '1', 08:20:25 INFO - 'NO_EM_RESTART': '1', 08:20:25 INFO - 'PAGER': '/bin/cat', 08:20:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:25 INFO - 'PWD': '/builds/slave/test', 08:20:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:25 INFO - 'SHELL': '/bin/bash', 08:20:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oNIhjIRvad/Listeners', 08:20:25 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:20:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:25 INFO - 'USER': 'cltbld', 08:20:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:25 INFO - 'XPC_FLAGS': '0x0', 08:20:25 INFO - 'XPC_SERVICE_NAME': '0', 08:20:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:25 INFO - Downloading/unpacking jsonschema==2.5.1 08:20:25 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 08:20:25 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 08:20:25 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 08:20:25 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 08:20:25 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 08:20:25 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 08:20:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:20:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:20:28 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 08:20:28 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 08:20:28 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 08:20:28 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 08:20:28 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 08:20:28 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 08:20:28 INFO - Downloading functools32-3.2.3-2.tar.gz 08:20:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:20:28 INFO - warning: no files found matching '*.txt' 08:20:28 INFO - no previously-included directories found matching 'build' 08:20:28 INFO - no previously-included directories found matching 'dist' 08:20:28 INFO - no previously-included directories found matching '.git*' 08:20:28 INFO - Installing collected packages: jsonschema, functools32 08:20:28 INFO - Running setup.py install for functools32 08:20:28 INFO - warning: no files found matching '*.txt' 08:20:28 INFO - no previously-included directories found matching 'build' 08:20:28 INFO - no previously-included directories found matching 'dist' 08:20:28 INFO - no previously-included directories found matching '.git*' 08:20:28 INFO - Successfully installed jsonschema functools32 08:20:28 INFO - Cleaning up... 08:20:28 INFO - Return code: 0 08:20:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:28 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 0x104da3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8135c9270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81d30>, '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 0x104aa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b76be8>, '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.KAf200UK7t/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.oNIhjIRvad/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 08:20:28 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 08:20:28 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 08:20:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAf200UK7t/Render', 08:20:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:28 INFO - 'HOME': '/Users/cltbld', 08:20:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:28 INFO - 'LOGNAME': 'cltbld', 08:20:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:28 INFO - 'MOZ_NO_REMOTE': '1', 08:20:28 INFO - 'NO_EM_RESTART': '1', 08:20:28 INFO - 'PAGER': '/bin/cat', 08:20:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:28 INFO - 'PWD': '/builds/slave/test', 08:20:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:28 INFO - 'SHELL': '/bin/bash', 08:20:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oNIhjIRvad/Listeners', 08:20:28 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:20:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:28 INFO - 'USER': 'cltbld', 08:20:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:28 INFO - 'XPC_FLAGS': '0x0', 08:20:28 INFO - 'XPC_SERVICE_NAME': '0', 08:20:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:20:29 INFO - Cleaning up... 08:20:29 INFO - Return code: 0 08:20:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104da3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8135c9270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81d30>, '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 0x104aa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b76be8>, '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.KAf200UK7t/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.oNIhjIRvad/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 08:20:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:20:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAf200UK7t/Render', 08:20:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:29 INFO - 'HOME': '/Users/cltbld', 08:20:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:29 INFO - 'LOGNAME': 'cltbld', 08:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:29 INFO - 'MOZ_NO_REMOTE': '1', 08:20:29 INFO - 'NO_EM_RESTART': '1', 08:20:29 INFO - 'PAGER': '/bin/cat', 08:20:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:29 INFO - 'PWD': '/builds/slave/test', 08:20:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:29 INFO - 'SHELL': '/bin/bash', 08:20:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oNIhjIRvad/Listeners', 08:20:29 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:20:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:29 INFO - 'USER': 'cltbld', 08:20:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:29 INFO - 'XPC_FLAGS': '0x0', 08:20:29 INFO - 'XPC_SERVICE_NAME': '0', 08:20:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:29 INFO - Downloading/unpacking blobuploader==1.2.4 08:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:31 INFO - Downloading blobuploader-1.2.4.tar.gz 08:20:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:20:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:20:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:33 INFO - Downloading docopt-0.6.1.tar.gz 08:20:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:20:33 INFO - Installing collected packages: blobuploader, requests, docopt 08:20:33 INFO - Running setup.py install for blobuploader 08:20:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:20:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:20:33 INFO - Running setup.py install for requests 08:20:33 INFO - Running setup.py install for docopt 08:20:34 INFO - Successfully installed blobuploader requests docopt 08:20:34 INFO - Cleaning up... 08:20:34 INFO - Return code: 0 08:20:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:34 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 0x104da3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8135c9270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81d30>, '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 0x104aa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b76be8>, '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.KAf200UK7t/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.oNIhjIRvad/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 08:20:34 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 08:20:34 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 08:20:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAf200UK7t/Render', 08:20:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:34 INFO - 'HOME': '/Users/cltbld', 08:20:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:34 INFO - 'LOGNAME': 'cltbld', 08:20:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:34 INFO - 'MOZ_NO_REMOTE': '1', 08:20:34 INFO - 'NO_EM_RESTART': '1', 08:20:34 INFO - 'PAGER': '/bin/cat', 08:20:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:34 INFO - 'PWD': '/builds/slave/test', 08:20:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:34 INFO - 'SHELL': '/bin/bash', 08:20:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oNIhjIRvad/Listeners', 08:20:34 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:20:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:34 INFO - 'USER': 'cltbld', 08:20:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:34 INFO - 'XPC_FLAGS': '0x0', 08:20:34 INFO - 'XPC_SERVICE_NAME': '0', 08:20:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:20:34 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-wcsz68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:20:34 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-L9ruYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:20:34 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-hJmvGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:20:34 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-yaqGJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:20:34 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-B5FWjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:20:34 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-t6_cCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:20:35 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-ZsJoPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:20:35 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-RBqXZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:20:35 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-GpUhkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:20:35 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-nCx4KC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:20:35 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-D4BZv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:20:35 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-SNoOT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:20:35 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-qjad_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:20:35 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-AWeleG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:35 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-1QcRd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:20:35 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-SU2YAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:20:36 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-QVqe83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:20:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:20:36 INFO - Running setup.py install for manifestparser 08:20:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:20:36 INFO - Running setup.py install for mozcrash 08:20:36 INFO - Running setup.py install for mozdebug 08:20:36 INFO - Running setup.py install for mozdevice 08:20:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:20:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:20:36 INFO - Running setup.py install for mozfile 08:20:36 INFO - Running setup.py install for mozhttpd 08:20:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:20:37 INFO - Running setup.py install for mozinfo 08:20:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:20:37 INFO - Running setup.py install for mozInstall 08:20:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:20:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:20:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:20:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:20:37 INFO - Running setup.py install for mozleak 08:20:37 INFO - Running setup.py install for mozlog 08:20:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:20:37 INFO - Running setup.py install for moznetwork 08:20:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:20:37 INFO - Running setup.py install for mozprocess 08:20:38 INFO - Running setup.py install for mozprofile 08:20:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:20:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:20:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:20:38 INFO - Running setup.py install for mozrunner 08:20:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:20:38 INFO - Running setup.py install for mozscreenshot 08:20:38 INFO - Running setup.py install for moztest 08:20:38 INFO - Running setup.py install for mozversion 08:20:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:20:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:20:38 INFO - Cleaning up... 08:20:38 INFO - Return code: 0 08:20:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:38 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 0x104da3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8135c9270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81d30>, '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 0x104aa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b76be8>, '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.KAf200UK7t/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.oNIhjIRvad/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 08:20:38 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 08:20:38 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 08:20:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAf200UK7t/Render', 08:20:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:38 INFO - 'HOME': '/Users/cltbld', 08:20:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:38 INFO - 'LOGNAME': 'cltbld', 08:20:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:38 INFO - 'MOZ_NO_REMOTE': '1', 08:20:38 INFO - 'NO_EM_RESTART': '1', 08:20:38 INFO - 'PAGER': '/bin/cat', 08:20:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:38 INFO - 'PWD': '/builds/slave/test', 08:20:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:38 INFO - 'SHELL': '/bin/bash', 08:20:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oNIhjIRvad/Listeners', 08:20:38 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:20:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:38 INFO - 'USER': 'cltbld', 08:20:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:38 INFO - 'XPC_FLAGS': '0x0', 08:20:38 INFO - 'XPC_SERVICE_NAME': '0', 08:20:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:20:39 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-PBB3sC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:20:39 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-_YmgSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 08:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:20:39 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-KgMpV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:20:39 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-ATa01y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 08:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:20:39 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-WUxzsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:20:39 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-r8drh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:20:39 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-c2jga8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:20:39 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-g4CHNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:20:39 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-QGGPNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:20:40 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)) 08:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:20:40 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-2S8fQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 08:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:20:40 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-lpXUXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:20:40 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-Lji1Xw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:20:40 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-PCdIJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:20:40 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-8styXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 08:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:40 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-NxV4y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:20:40 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-zMwM4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:20:40 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-kCKzJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:20:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:43 INFO - Downloading blessings-1.6.tar.gz 08:20:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:20:43 INFO - Installing collected packages: blessings 08:20:43 INFO - Running setup.py install for blessings 08:20:43 INFO - Successfully installed blessings 08:20:43 INFO - Cleaning up... 08:20:43 INFO - Return code: 0 08:20:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:20:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:20:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:20:44 INFO - Reading from file tmpfile_stdout 08:20:44 INFO - Current package versions: 08:20:44 INFO - blessings == 1.6 08:20:44 INFO - blobuploader == 1.2.4 08:20:44 INFO - docopt == 0.6.1 08:20:44 INFO - functools32 == 3.2.3-2 08:20:44 INFO - jsonschema == 2.5.1 08:20:44 INFO - manifestparser == 1.1 08:20:44 INFO - mozInstall == 1.12 08:20:44 INFO - mozcrash == 1.0 08:20:44 INFO - mozdebug == 0.1 08:20:44 INFO - mozdevice == 0.49 08:20:44 INFO - mozfile == 1.2 08:20:44 INFO - mozhttpd == 0.7 08:20:44 INFO - mozinfo == 0.9 08:20:44 INFO - mozleak == 0.1 08:20:44 INFO - mozlog == 3.4 08:20:44 INFO - moznetwork == 0.27 08:20:44 INFO - mozprocess == 0.23 08:20:44 INFO - mozprofile == 0.28 08:20:44 INFO - mozrunner == 6.13 08:20:44 INFO - mozscreenshot == 0.1 08:20:44 INFO - mozsystemmonitor == 0.3 08:20:44 INFO - moztest == 0.7 08:20:44 INFO - mozversion == 1.4 08:20:44 INFO - psutil == 3.1.1 08:20:44 INFO - requests == 1.2.3 08:20:44 INFO - wsgiref == 0.1.2 08:20:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20: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')]} 08:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20: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')]} 08:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:44 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 0x104da3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8135c9270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81d30>, '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 0x104aa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b76be8>, '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.KAf200UK7t/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.oNIhjIRvad/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 08:20:44 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 08:20:44 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 08:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAf200UK7t/Render', 08:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:44 INFO - 'HOME': '/Users/cltbld', 08:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:44 INFO - 'LOGNAME': 'cltbld', 08:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:44 INFO - 'MOZ_NO_REMOTE': '1', 08:20:44 INFO - 'NO_EM_RESTART': '1', 08:20:44 INFO - 'PAGER': '/bin/cat', 08:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:44 INFO - 'PWD': '/builds/slave/test', 08:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:44 INFO - 'SHELL': '/bin/bash', 08:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oNIhjIRvad/Listeners', 08:20:44 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:44 INFO - 'USER': 'cltbld', 08:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:44 INFO - 'XPC_FLAGS': '0x0', 08:20:44 INFO - 'XPC_SERVICE_NAME': '0', 08:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:44 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)) 08:20:44 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)) 08:20:44 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)) 08:20:44 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)) 08:20:44 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)) 08:20:44 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)) 08:20:44 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)) 08:20:44 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)) 08:20:44 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)) 08:20:44 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)) 08:20:44 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)) 08:20:44 INFO - Cleaning up... 08:20:44 INFO - Return code: 0 08:20:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:20: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')]} 08:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20: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')]} 08:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:44 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 0x104da3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8135c9270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81d30>, '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 0x104aa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b76be8>, '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.KAf200UK7t/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.oNIhjIRvad/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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 08:20:44 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 08:20:44 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 08:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAf200UK7t/Render', 08:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:44 INFO - 'HOME': '/Users/cltbld', 08:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:44 INFO - 'LOGNAME': 'cltbld', 08:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:44 INFO - 'MOZ_NO_REMOTE': '1', 08:20:44 INFO - 'NO_EM_RESTART': '1', 08:20:44 INFO - 'PAGER': '/bin/cat', 08:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:44 INFO - 'PWD': '/builds/slave/test', 08:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:44 INFO - 'SHELL': '/bin/bash', 08:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oNIhjIRvad/Listeners', 08:20:44 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:44 INFO - 'USER': 'cltbld', 08:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:44 INFO - 'XPC_FLAGS': '0x0', 08:20:44 INFO - 'XPC_SERVICE_NAME': '0', 08:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:20:44 INFO - Cleaning up... 08:20:44 INFO - Return code: 0 08:20:44 INFO - Running post-action listener: _resource_record_post_action 08:20:44 INFO - Running post-action listener: _start_resource_monitoring 08:20:44 INFO - Starting resource monitoring. 08:20:44 INFO - [mozharness: 2016-12-19 16:20:44.738692Z] Finished create-virtualenv step (success) 08:20:44 INFO - [mozharness: 2016-12-19 16:20:44.739592Z] Running install step. 08:20:44 INFO - Running pre-action listener: _resource_record_pre_action 08:20:44 INFO - Running main action method: install 08:20:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:20:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:20:44 INFO - Reading from file tmpfile_stdout 08:20:44 INFO - Detecting whether we're running mozinstall >=1.0... 08:20:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:20:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:20:45 INFO - Reading from file tmpfile_stdout 08:20:45 INFO - Output received: 08:20:45 INFO - Usage: mozinstall [options] installer 08:20:45 INFO - Options: 08:20:45 INFO - -h, --help show this help message and exit 08:20:45 INFO - -d DEST, --destination=DEST 08:20:45 INFO - Directory to install application into. [default: 08:20:45 INFO - "/builds/slave/test"] 08:20:45 INFO - --app=APP Application being installed. [default: firefox] 08:20:45 INFO - mkdir: /builds/slave/test/build/application 08:20:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:21:06 INFO - Reading from file tmpfile_stdout 08:21:06 INFO - Output received: 08:21:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:21:06 INFO - Running post-action listener: _resource_record_post_action 08:21:06 INFO - [mozharness: 2016-12-19 16:21:06.767522Z] Finished install step (success) 08:21:06 INFO - [mozharness: 2016-12-19 16:21:06.767651Z] Running run-tests step. 08:21:06 INFO - Running pre-action listener: _resource_record_pre_action 08:21:06 INFO - Running main action method: run_tests 08:21:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:21:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:21:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:21:06 INFO - 2016-12-19 08:21:06.782 screenresolution[1632:8060] starting screenresolution argv=screenresolution get 08:21:06 INFO - 2016-12-19 08:21:06.784 screenresolution[1632:8060] Display 0: 1600x1200x32@60 08:21:06 INFO - 2016-12-19 08:21:06.790 screenresolution[1633:8063] starting screenresolution argv=screenresolution list 08:21:06 INFO - Available Modes on Display 0 08:21:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:21:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:21:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:21:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:21:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:21:07 INFO - Intel Iris: 08:21:07 INFO - Chipset Model: Intel Iris 08:21:07 INFO - Type: GPU 08:21:07 INFO - Bus: Built-In 08:21:07 INFO - VRAM (Dynamic, Max): 1536 MB 08:21:07 INFO - Vendor: Intel (0x8086) 08:21:07 INFO - Device ID: 0x0a2e 08:21:07 INFO - Revision ID: 0x0009 08:21:07 INFO - Displays: 08:21:07 INFO - Display: 08:21:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:21:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:21:07 INFO - Display Serial Number: 1600x1200 60 08:21:07 INFO - Main Display: Yes 08:21:07 INFO - Mirror: Off 08:21:07 INFO - Online: Yes 08:21:07 INFO - Rotation: Supported 08:21:07 INFO - Return code: 0 08:21:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:21:07 INFO - Python 2.7.3 08:21:07 INFO - Return code: 0 08:21:07 INFO - grabbing minidump binary from tooltool 08:21: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')]} 08:21:07 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 0x104b81d30>, '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 0x104aa8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b76be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:21:07 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 08:21:07 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 08:21:07 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 08:21:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:21:07 INFO - Return code: 0 08:21:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:21:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:21:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:21:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:21:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:21:07 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/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0215', '--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 08:21:07 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/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0215 --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 08:21:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KAf200UK7t/Render', 08:21:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:07 INFO - 'HOME': '/Users/cltbld', 08:21:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:07 INFO - 'LOGNAME': 'cltbld', 08:21:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:21:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:07 INFO - 'MOZ_NO_REMOTE': '1', 08:21:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:21:07 INFO - 'NO_EM_RESTART': '1', 08:21:07 INFO - 'PAGER': '/bin/cat', 08:21:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:07 INFO - 'PWD': '/builds/slave/test', 08:21:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:21:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:07 INFO - 'SHELL': '/bin/bash', 08:21:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oNIhjIRvad/Listeners', 08:21:07 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:21:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:07 INFO - 'USER': 'cltbld', 08:21:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:07 INFO - 'XPC_FLAGS': '0x0', 08:21:07 INFO - 'XPC_SERVICE_NAME': '0', 08:21:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:07 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/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0215', '--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 08:21:07 INFO - mozversion application_buildid: 20161219070438 08:21:07 INFO - mozversion application_changeset: d4b3146a5567a7ddbcdfa5244945db55616cb8d1 08:21:07 INFO - mozversion application_display_name: Nightly 08:21:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:21:07 INFO - mozversion application_name: Firefox 08:21:07 INFO - mozversion application_remotingname: firefox 08:21:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:21:07 INFO - mozversion application_vendor: Mozilla 08:21:07 INFO - mozversion application_version: 53.0a1 08:21:07 INFO - mozversion platform_buildid: 20161219070438 08:21:07 INFO - mozversion platform_changeset: d4b3146a5567a7ddbcdfa5244945db55616cb8d1 08:21:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:21:07 INFO - mozversion platform_version: 53.0a1 08:21:07 INFO - using testdate: 1482164467 08:21:07 INFO - actual date: 1482164467 08:21:07 INFO - starting webserver on 'localhost:49242' 08:21:07 INFO - SUITE-START | Running 2 tests 08:21:07 INFO - TEST-START | dromaeo_css 08:21:07 INFO - operating with platform_type : mac_ 08:21:07 INFO - Initialising browser for dromaeo_css test... 08:21:07 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpLCyt6r/profile http://localhost:49242/getInfo.html) 08:21:10 INFO - PROCESS | 1643 | 2016-12-19 08:21:10.409 plugin-container[1645:8236] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 08:21:10 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:10 INFO - PROCESS | 1643 | 2016-12-19 08:21:10.412 plugin-container[1645:8236] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9217, name = 'com.apple.CFPasteboardClient' 08:21:10 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:10 INFO - PROCESS | 1643 | 2016-12-19 08:21:10.412 plugin-container[1645:8236] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:21:10 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 08:21:10 INFO - PROCESS | 1643 | colorDepth:24 08:21:10 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 08:21:10 INFO - PROCESS | 1643 | __metrics 08:21:10 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 08:21:11 INFO - TEST-INFO | 1643: exit 0 08:21:11 INFO - Browser initialized. 08:21:11 INFO - Running cycle 1/1 for dromaeo_css test... 08:21:11 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpLCyt6r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:21:13 INFO - PROCESS | 1647 | 2016-12-19 08:21:13.629 plugin-container[1648:8360] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 08:21:13 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:13 INFO - PROCESS | 1647 | 2016-12-19 08:21:13.629 plugin-container[1648:8360] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9013, name = 'com.apple.CFPasteboardClient' 08:21:13 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:13 INFO - PROCESS | 1647 | 2016-12-19 08:21:13.629 plugin-container[1648:8360] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:21:23 INFO - PROCESS | 1647 | RSS: Main: 236916736 08:21:23 INFO - PROCESS | 1647 | 08:22:12 INFO - PROCESS | 1647 | 1482164532943 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] 08:24:26 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 08:24:28 INFO - PROCESS | 1647 | RSS: Main: 250204160 08:24:28 INFO - PROCESS | 1647 | 08:27:31 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 08:27:31 INFO - PROCESS | 1647 | RSS: Main: 264404992 08:27:31 INFO - PROCESS | 1647 | 08:30:33 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 08:30:34 INFO - PROCESS | 1647 | RSS: Main: 269430784 08:30:34 INFO - PROCESS | 1647 | 08:33:36 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 08:33:37 INFO - PROCESS | 1647 | RSS: Main: 268369920 08:33:37 INFO - PROCESS | 1647 | 08:36:39 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 08:36:40 INFO - PROCESS | 1647 | RSS: Main: 272797696 08:36:40 INFO - PROCESS | 1647 | 08:39:43 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 08:39:44 INFO - PROCESS | 1647 | RSS: Main: 276017152 08:39:44 INFO - PROCESS | 1647 | 08:39:44 INFO - PROCESS | 1647 | __start_tp_report 08:39:44 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 08:39:44 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 08:39:44 INFO - PROCESS | 1647 | |i|pagename|runs| 08:39:44 INFO - PROCESS | 1647 | |0;dojo.html;2961.3633068890185;3398.6091599466054;3413.761036727427;3422.8459719312627;3471.080163756605;4602.700824446409;5223.216517522374;5501.174823776436;5657.547396208303;5670.68811215383;4029.214303210875;5482.259894914186;5557.166425036246;5602.1036634138545;5715.9428405715935;5195.948040519594;5233.842984710464;5235.685858848462;5272.578193744491;5280.973595132019;5176.404713457944;5223.503767142115;5263.684178949256;5295.655782374152;5301.098813201765;5230.398504172031;5256.710880901549;5282.3925248596515;5286.10136276832;5325.733713314338;5077.593792496591;5112.054269960032;5150.9742451287875;5189.195674670432;5211.869703257449;4082.7346222495444;5038.899222015576;5152.948470515322;5169.043726910533;5174.689518628857;5485.588580856452;5661.490465858052;5668.631538949955;5688.744006519716;5717;6091.42131497507;6173.074038894157;6204.751809927598;6220.03589443637;6225.750969961241;6085.604435711664;6112.6638034908065;6163.660998645073;6221.3156552779155;6241.843953901143;5769.221155144086;5771.163181338682;5777;5778.653280803207;5790.7973220937065;6188.845278867974;6224.06639004153;6245.250569931637;6258.874822503524;6273.780417685451;5970.373110823388;6024.216851809237;6034.155218269446;6076.939230607726;6086.878262434815;6164.4760195383;6170.56806023583;6171.413715697002;6180.042093475428;6184.938150618526;6043.7280322385595;6056;6059.424354686298;6071.635701857903;6089.908651370233;6119.167450728587;6132.7532529736445;6150.231221097363;6179.134921111048;6210.223722034746;4847.720399457768;4891.804327826918;4894.902101957941;4923.261510773413;4926.8768280793265;4432.3351497275025;4436.245838207513;4438.911221775611;4440.555944405533;4449.555044495524;5652.858678532987;5722.856928576735;5728.1407788831175;5744.885102297931;5798.855028624318;6231.255248530419;6249.9062514062325;6250.74997000124;6259.593126446857;6280.371962803774;5786.537077033827;5800.88398232033;5817.331006934172;5818.709064546756;5851.64890106595;5192.7403629818355;5281.12861377876;5281.207818827206;5441.673499590037;5452.727363631802;6200.782972595937;6236.251649802053;6238.812835614939;6241.531885108726;6268.1851359323;6245.594036387621;6247.5939063960695;6256.624602523863;6270.717817698123;6311.842203944754;6291.937080629135;6347.492200623847;6348.587341822767;6358.046293056079;6379.170707807955;4571.8570357410645;4658.487566367765;5199.584033277253;5514.648911016827;6031.60794548353;5836.912446313224;6165.907511387243;6172;6185.010398336244;6186.1030150627;5992.580519363777;6033.245844269466;6063.848403789941;6096.29892562361;6114.296855861633;6061.121137435135;6096.542759292982;6097.207363042776;6106.297775755845;6145.139680444652;5132.691163753219;5398.379186393615;5766.481016708516;5834.241548598655;5972.910406343822;6017.969910150421;6033.396660333931;6058.939410605837;6062.333143354139;6093.299270583762;5820.126980952891;5919.171316015675;5920.082387229986;5932;5937.406259374028 08:39:44 INFO - PROCESS | 1647 | |1;ext.html;483.7291116974494;492.28864291099376;495.3708789836907;506.48338694531566;523.8795077132262;29476.557851632217;40018;40298.79850600743;40304.79847600765;40340.596594034054;41193.58806411935;41791.37312940308;41810;41873;41914.95212619686;8399.454035487674;8609.053004169537;9317.860232096524;9565.139137477625;9580.90419095807;11242.213045086848;11558.595449159282;11594.188406811527;11756.05951523876;11767.940885320362;11400.543978240861;11432.885671143265;11582.710432239177;11595.710107247301;11651.359175245358;11435.371054591995;11504.769904601944;11542.36516991565;11549.826752598718;11552.595659151973;11412.600558980395;11415.0297224736;11496.540138394454;11497.540098396054;11498.367589782558;40004;40270.79864600688;40353.99115022118;40483.7975810122;40734;3274.2305558193807;3412.436947903593;3525.347810655037;3590.120420496969;3623.7825730455993;27241.863790681116;28578;28948.27629309263;29014;29027.709722902713;2674.291312802109;2795.0636536760207;2859.2137162280333;2861.2560734209046;2879.7840161987724;459612;463710.628937107;464636;464884;467368;49338;55587;55703.72148139314;55836.16245756317;55875.72062139744;56750.43249567534;57650.13524797132;58196.418035819945;58271;58430.70784646049;15067.849321506865;15338.15640139803;15345.6163595911;15375.538733838002;15444.5366639001;10727.731806704895;10957.780844383227;11521.905418985183;11696.88303116958;11944.522219111312;17261.91369043172;17402.39091631787;17458.301667933396;17479.38822141219;17584.384546541067;6234.812955611339;6637.004449332639;6996.965015174892;7557.068761261895;7604.0114785077585;846.4286606540561;858.8319884956454;909.8043920556979;1076.5263284154946;1142.9142814288962;23999;26927;28362;29856.701432985825;30545.541816872767;1034.9236793734583;1068.7488440216543;1070.218740319571;1099.1756182862853;1118.4351902289454;15278.541643750705;15691.215439228221;15695.764563531557;15759.29083191259;16043.839561604467;501123;514565.7086858242;562897.3710262927;635582;636076;59416.40583594195;59610;59738.70130649319;60047.79904401887;60153;1346.6566025663394;1376.421902800826;1378.7862881253423;1393.7282229965263;1400.5238219005587;27814.3046423841;29321.85339073291;29543.409131817672;29632.703672962994;30824.69175308218;40971;46777.76611116923;49838.50161498338;50233;50565;581520;589380.0619937972;590620;591777;591829;24670.13654522083;25415.618765718158;25765.742342576334;32075.35849283048;38573.035674108374;41759.164816704106;42752;43179.78410107929;44180;45711.54288457073;35763.284734305686;36283;36454;37120.25759484741;37154.07114822151;35628.28743425169;36132.81933590315;36441.802240332;36779.63220367869;36899.446508302935;34310.313793723486;34782.9565113047;35743.64256357403;36225.27549448943;36557.63442365542;31598.52602210923;32346.561057156767;32396.83801580977;32703.836480817445;32780.672193277765;23906.043758249474;24141.15505957283;24274.514509709355;24747.62878556787;24806.37984050341 08:39:44 INFO - PROCESS | 1647 | |2;jquery.html;372.49340896380915;378.0322374720715;378.249175386857;388.3320688415924;401.43397809089174;14101.506447274347;14806.111633301982;18074.277028918845;20322.491937701565;20536.6651167674;16115;16125.677486450264;18058;18716.43850684478;20423.693644595307;5788.42115788422;5900.1739756434135;5956.463918247357;6026.63840169589;6030.547708921827;4709.317149013408;5917.792877249297;5957.404259574051;6022.819315420545;6024.759009639609;4915.901681966358;5933.673647949361;5951.434613711691;5952.375000624937;5961.463468287853;4983.950160498385;5706.429357064302;5831.533477321826;5839.0949402842625;5841.503472204868;4391.890202744957;4973.701577905337;5045.268436076785;5103.953689493645;5115.2327150927285;19515.512112197168;23985.520289594286;24763.009479620792;25302.746972530407;25337.239882803544;2256.142665787007;2260.626996545568;2287.3023727763025;2481.367251350912;2702.337927207827;13972.93013534936;14962.625934351729;15372.769408458882;15450.304736286782;15651;1734.2022669572011;1821.1076572479521;1842.4012196036342;1908.6755251607121;1912.4358314297315;86113.56943215306;91795;111577;112304;113665;9049.909500904907;11386.487608057658;11470.655880323604;11519.424028798527;11596.94201528987;14303.78544321836;14389.496367627084;14405.639859003608;14437.85562144365;14446.927765361106;6839.897401538981;8070.2333278338465;8301.045379781282;8342.708005219789;8460.450070745383;8128.9593552031865;8145.063317718538;8154.796130096795;8292.875606865902;8329.791755206168;4453.955460445419;4529.6376289896725;4559.817607295738;4572.314152877028;4607.58531732139;1739.2347367158663;1817.1005352350671;1931.121339790392;1966.9901650491943;1979.8317143042714;823.7940514871282;849.426637020009;882.4131952251809;951.509972364233;958.2334132693817;17444.563885902953;19604.607907841764;19808.801911980696;21009.894950525457;21314.680279795815;756.7540549321493;841.5203334099505;902.1880307723102;920.5489310238069;986.2356673578034;8671.176238257356;10571;10758.623448179433;11287.830682539769;11293.37886416255;474630;482405;487349;488508;493066;118538.66576004101;124239;124774;125057;129445;994.8858675314956;1039.9964034706545;1055.2613170780485;1093.578972095749;1100.7688385438987;14244.928775356471;15544.367841376945;19050.21293453534;19755.80244197597;19900.004999749664;48476.272855906485;51951.48048519466;53220;53672.46327536675;55664;79644.20355796367;80125.19874801411;80264;80862;81329.59335203285;7872.606369681378;7930;7979.760607181562;8360.665573376997;8389.244967952913;9180.862287065565;11776.52893884235;12589.685257868627;12803.487860485477;12900.83892449684;9825.9017409825;10190;10835.837462438227;10980.286281391684;11203.663890083311;9044.638214471348;10436.530356134144;10712.35725856451;10821.891781082404;10949.507272172612;9163.35856490066;10957.671269861918;11237;11245.775084498428;11267.774644507228;7220.530665506725;7903.0516338037505;8145.470544414831;8268.338532917473;8381.65893457044;6837.863242735217;8662.436941598777;8912.33157513202;10930.672079837617;10975.945120274348 08:39:44 INFO - PROCESS | 1647 | |3;mootools.html;1397.2594524901801;1504.9548513544594;1635.4766474728085;1713.4431309824301;1760.7622970898924;7573.053368328959;7718.614069296534;7912.802179945505;8132.26809587137;8644.783880402993;7172.852343625021;7987.361011119111;8013.799655008627;8414.663413463453;8422.031466381368;1896.7344571759966;2287.5196208796087;2309.8036666883336;2319.5708793873187;2326.569584626841;2108.968365474519;2285.7828506291876;2341.8711970841596;2371.5019845832394;2386.7016622922133;2307.157887371106;2325.546518428907;2333.3466629343725;2348.4011577047827;2357.599208134621;2306.6655334976417;2325.5000174962297;2330.5105927755276;2348.025569388719;2349.1543044503965;2294.4608017115966;2340.625499920022;2348.5068135691527;2355.823313251496;2370.751071137523;8497.830043399097;8512.233898949124;8529.402941794076;8554.657813687507;8594.5702714864;5061.16490779021;5223.320968274138;5226;5246.7638956247065;5250.65870718402;3126.7498600111935;3201.3117179806454;3378.763486555942;3381.746369022333;3401.234722187513;949.7890190007727;968.3947532792005;978.7063880835792;984.8621193032909;984.8670429491998;9563.139317461462;10872.728181795519;11011.504482298316;11014.06380457662;11054.170937179662;4368.973291276548;4476.328550717419;4512.278035514368;4514;4521.027978984468;8721.956390218009;8811.867821982676;8818.955905220433;8825.117488251251;8841.867371989296;5116.641835071509;5140.794368225229;5175.0167468180925;5180.274761533388;5186.92219616706;4679.532046795293;4819.49395313494;4835.492273311252;4871.14754917888;4887.34020907173;3641.326354624403;3728.832202550892;3744.382176940774;3778.471014058034;3789.6020917803235;1178.1105265524475;1179.8702142764462;1186.1874615888144;1187.7208855919025;1189.5301355964345;823.5717426937958;887.1704955866286;890.844102282098;893.6559424621438;912.1631725334205;5493.560515158778;5603.691796951125;5649.661020338793;5672.901026620177;5800.477956983892;434.79347310027487;435.15579775235943;449.93814597549755;452.09580838323353;454.2436842657005;3665.5051568037975;3837.002379381381;3908.8241029153755;3939.783311917872;3968.2857085724268;10011.949940250252;10255.179585633132;10370.429626370471;10402.27184097121;10428.634997775041;10285.537150828231;10314.43270620123;10374.792504150026;10412.583496660049;10431.009054139844;7829.942957700751;7916.802079948047;7941.563214023284;8115;8135.999999999882;5528.7235638218735;5587.0502014656795;5618.747156378055;5682.77268909239;5771.307443106854;7880.936073629987;8174.959125204336;9175.587098580714;9307.97612262637;9370.39092458988;8385.825984362073;8596.957015214884;9464.810703786023;9683.612655493984;9686;7214.170370407261;7220.927790722236;7258.386581469606;7334.449916256409;7339.266073392618;7897.091834438883;7921.128675845767;7948.165442628672;7976;8000.719974800854;5953.493953014043;7164.856702866018;7226.7109315626785;7237.529560578546;7363.77908662741;7145.249748776304;7155;7160;7195.388391986739;7221;6735.663216839041;6736.124303840666;6775.728970841308;6827.761028363983;6848.691808868513;4024.5371782245415;4769.618430525619;4849.393825771778;4891.315215869852;4972.950270497248;4569.47451043121;4721;4794.808207671654;4963.454020057863;5024.0956627807345 08:39:44 INFO - PROCESS | 1647 | |4;prototype.html;350.1718435899098;356.8412056634795;357.53877498027543;359.6421560547256;365.83537408166313;4272.359146128082;4631.698939568926;4830.951690483099;4832.864276894927;5257.789688412459;4531.818727250914;4759.357486739289;5095.394950590561;5446.2919820423385;5458.536024437927;3835;3969.7693714948387;4159.043420013383;4207.789610519461;4248.596383343593;3590.3178396104786;4181.640966685824;4182.64447521961;4190.982492605334;4255.851045213418;3889.436031775391;4204.705670603059;4240.957590424087;4270.829166833323;4277.668670574529;4039.8890305166315;4251.298535741599;4252.532221455637;4285.764282964436;4291.806868690916;3689.05928988109;3712.3874560697454;3719.5865571083086;3729.9440508392127;3747.138158223624;4189.706720529564;4636.820694273686;4956.727379994099;5263.815766448194;5280.973595132038;4208.85269015583;4339.175556644227;4472.993576445274;4817.61459083276;4861.52062448154;5360.839174824761;5656.604037717361;5680.188306788125;5725.2843394575675;5738.540916726652;3084.121025507742;3556.8795829313685;3574.7855128692363;3633.2551826875692;3904.941425878614;5112.667676601009;5684.516816070639;5873.647581145145;5925.970370148121;6051.485623721989;3748.5314335708035;3893.377059670439;3921.941170882439;3934.409838524187;3940.8817735467983;3329.6004479462617;3832.2718683450057;3969.186316805048;3977.602239776057;3991.501062367204;4051.3591995241973;4630.467496237909;4653.488116307203;4657.184992626345;4681.110588988081;5137.691738495702;5291.497307755757;5601.243832082698;5671.9716401418555;5674.801381951612;1210.1468464732343;1271.9364031798373;1286.8777466140702;1291.8901893338984;1295.8509771376223;754.9584772837546;798.3014861995754;812.2486699802777;871.1506281375734;876.9561521924006;4312.5903039211225;4324.610520321876;4571.748553829571;4798.184308667535;4839;4333.891652708645;4910.631702622246;5169.896602067937;5288.3918349390315;5596.888062238814;4607.516210797818;4686.695364801277;5064.164412871834;5074.746262686851;5110.795568177231;2502.724700282967;2611.479921875399;2793.3156376687843;2914.863203350695;2962.3482833776534;5414.810481633124;5479.671219726829;5505.256790333334;5778.7688492460675;5826;4530.948080061593;5112.412072611697;5503.422140675171;5714.5999780015;5839.795607153729;4831.685940413862;5207.5833933286;5763.711814409263;5806.419358064244;5875.6474611523445;5176.997341541972;5263.736813159403;5281.498257665516;5676.388861123929;5686.914696279477;4771.95228047729;4826.948546867638;5012.523810238022;5071.721055341844;5831.154482599914;4944.703317800943;4984.426790918946;5290.470952904832;5622.746976385991;5975.521958243417;3314.7016768490953;3474.228143646708;3718.665320121202;3935.2916475034767;3970.533150206018;4977.900441991213;5917.497012753965;5937.703114844327;5993.321869876441;6017.308009578955;4652.441706995182;5122.410922743783;5204.713740744144;5241.9516096780035;5604.551635869039;5678.971605141948;5682.096497673326;5702.2016917630735;5721.456461636138;5736.368999409978;5457.224928187836;5625.690587017753;5631.7184140793615;5634.23937768396;5682.374938756816;4815.855524334275;5200.921986170286;5667.574931880043;5673.602847800615;5680.68756218412;5384.488473595002;5607.990561698934;5637.985162670759;5668.234788303526;5692.943070569241 08:39:44 INFO - PROCESS | 1647 | |5;yui.html;716.8745469542829;718.5975853522028;752.555991964741;767.7581561808026;808.5027707959603;11667.299962002286;15464.768028479553;15492.535223943272;15506.302216400261;15509.379624814994;13948.930255348734;14130.293485325717;14148.929255353734;14149.71700565988;14177.291135443238;555.7804667156471;666.4302021771389;843.8565443874526;844.7170197983683;864.9308055355557;861.3798065392939;863.896332440108;868.1882439918696;869.0744357259498;869.1829680100717;919.1451949686789;925.9166674999249;926.3885835348672;926.9860952085725;929.2008872369756;910.8588168833808;911.2573252798948;916.8303863785154;917.2203626917134;919.4207649181006;684.6782012454139;685.4721864164616;685.9348361905611;686.6875571614903;689.0869597782957;14970.401183952628;15095.773563396559;15259.771103433457;15315.693686126326;15552.844471555252;13977.440902363893;14214.92892535541;14306.64233394163;14359.066660667128;14482.782758258634;11772.764544709058;11858.3477908715;11881.821772673416;11920.10599205063;11934.820977685342;3643.599204087575;3654.6162652921325;3697.815109244527;3705.7220708446976;3804.771713697187;83793;86158.70761938576;91329.54335228281;114875.4256228722;115327.27009094786;10884.292520986111;10915.290506117235;10963.287386319864;11184.384858832842;11224.27042242268;18305.90847045756;22582.887085564795;23379.766202338098;23424.414389640395;23446.765532344798;7798;7818.569978651229;7830.138684744674;7830.647620857075;7969.442139050211;4217.936730948977;4281.12236991416;4303.4190384298345;4304.18220538103;4337.631301339421;3422.57217847769;3537.2218112015316;3540.8937731868086;3555.4311310190246;3558.0749005258817;639.5267502048544;641.7433026789322;652.3476523476523;653.183520599251;660.7819419591523;585.672023666748;604.694629212245;613.6318209074609;657.5857209957792;659.6042374575217;6951.304869513109;8783.165599268059;9071.727848164433;9584.28117891161;10033.397996120257;482.25250861165756;582.8775957048982;646.0384248967971;662.8839953008204;678.9486222504551;4495.649850046036;4882.31204687658;4912.312276281324;4959.097404440301;5051.898962020739;78180;105283;107142;107208;112833.43583282031;91621;95193;96384.51807740917;96401;96427.66075526619;82333.54255711258;83916.58041709752;84457;84634.5768271167;84755;9758.80482390334;9904.653337133166;10051.849222261819;10071.84892226632;10189.23580731463;51134.74432627813;51234.74382628063;51889.48110518847;52241;54495;74651;77237;77390.2260977383;77881;78776.21223787688;12531.561395351118;12744.6813829655;12978.286194259406;13095.41070651804;13139.277339746406;14433.422663093359;14624.780628290797;14717.762092339668;14735.778963315774;14921.104733716013;13436.664083397993;13711.108777929403;13753.656158596115;13927;14241.287935602972;11610.013148882439;13407;13837.515686950908;13838.377273022537;13938.372773225432;11445.198836081727;12422.565210217599;13351.465941362238;13527.391267392793;13537.323133843465;10252.897471025493;10264.846027309446;10717.571297148026;11320.773584528099;11865.644030679094;11267.26822646302;11461.217972706501;11601.18791684574;11636;11662 08:39:44 INFO - PROCESS | 1647 | __end_tp_report 08:39:44 INFO - PROCESS | 1647 | __start_cc_report 08:39:44 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,365 08:39:44 INFO - PROCESS | 1647 | __end_cc_report 08:39:44 INFO - PROCESS | 1647 | __startTimestamp1482165584050__endTimestamp 08:39:44 INFO - PROCESS | 1647 | 08:39:44 INFO - PROCESS | 1647 | ------- Summary: start ------- 08:39:44 INFO - PROCESS | 1647 | Number of tests: 6 08:39:44 INFO - PROCESS | 1647 | 08:39:44 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5654.20 Median:5834.24 stddev:667.13 (11.4%) stddev-sans-first:634.85 08:39:44 INFO - PROCESS | 1647 | Values: 2961.4 3398.6 3413.8 3422.8 3471.1 4602.7 5223.2 5501.2 5657.5 5670.7 4029.2 5482.3 5557.2 5602.1 5715.9 5195.9 5233.8 5235.7 5272.6 5281.0 5176.4 5223.5 5263.7 5295.7 5301.1 5230.4 5256.7 5282.4 5286.1 5325.7 5077.6 5112.1 5151.0 5189.2 5211.9 4082.7 5038.9 5152.9 5169.0 5174.7 5485.6 5661.5 5668.6 5688.7 5717.0 6091.4 6173.1 6204.8 6220.0 6225.8 6085.6 6112.7 6163.7 6221.3 6241.8 5769.2 5771.2 5777.0 5778.7 5790.8 6188.8 6224.1 6245.3 6258.9 6273.8 5970.4 6024.2 6034.2 6076.9 6086.9 6164.5 6170.6 6171.4 6180.0 6184.9 6043.7 6056.0 6059.4 6071.6 6089.9 6119.2 6132.8 6150.2 6179.1 6210.2 4847.7 4891.8 4894.9 4923.3 4926.9 4432.3 4436.2 4438.9 4440.6 4449.6 5652.9 5722.9 5728.1 5744.9 5798.9 6231.3 6249.9 6250.7 6259.6 6280.4 5786.5 5800.9 5817.3 5818.7 5851.6 5192.7 5281.1 5281.2 5441.7 5452.7 6200.8 6236.3 6238.8 6241.5 6268.2 6245.6 6247.6 6256.6 6270.7 6311.8 6291.9 6347.5 6348.6 6358.0 6379.2 4571.9 4658.5 5199.6 5514.6 6031.6 5836.9 6165.9 6172.0 6185.0 6186.1 5992.6 6033.2 6063.8 6096.3 6114.3 6061.1 6096.5 6097.2 6106.3 6145.1 5132.7 5398.4 5766.5 5834.2 5972.9 6018.0 6033.4 6058.9 6062.3 6093.3 5820.1 5919.2 5920.1 5932.0 5937.4 08:39:44 INFO - PROCESS | 1647 | 08:39:44 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:67201.79 Median:19154.15 stddev:145455.52 (759.4%) stddev-sans-first:145777.31 08:39:44 INFO - PROCESS | 1647 | Values: 483.7 492.3 495.4 506.5 523.9 29476.6 40018.0 40298.8 40304.8 40340.6 41193.6 41791.4 41810.0 41873.0 41915.0 8399.5 8609.1 9317.9 9565.1 9580.9 11242.2 11558.6 11594.2 11756.1 11767.9 11400.5 11432.9 11582.7 11595.7 11651.4 11435.4 11504.8 11542.4 11549.8 11552.6 11412.6 11415.0 11496.5 11497.5 11498.4 40004.0 40270.8 40354.0 40483.8 40734.0 3274.2 3412.4 3525.3 3590.1 3623.8 27241.9 28578.0 28948.3 29014.0 29027.7 2674.3 2795.1 2859.2 2861.3 2879.8 459612.0 463710.6 464636.0 464884.0 467368.0 49338.0 55587.0 55703.7 55836.2 55875.7 56750.4 57650.1 58196.4 58271.0 58430.7 15067.8 15338.2 15345.6 15375.5 15444.5 10727.7 10957.8 11521.9 11696.9 11944.5 17261.9 17402.4 17458.3 17479.4 17584.4 6234.8 6637.0 6997.0 7557.1 7604.0 846.4 858.8 909.8 1076.5 1142.9 23999.0 26927.0 28362.0 29856.7 30545.5 1034.9 1068.7 1070.2 1099.2 1118.4 15278.5 15691.2 15695.8 15759.3 16043.8 501123.0 514565.7 562897.4 635582.0 636076.0 59416.4 59610.0 59738.7 60047.8 60153.0 1346.7 1376.4 1378.8 1393.7 1400.5 27814.3 29321.9 29543.4 29632.7 30824.7 40971.0 46777.8 49838.5 50233.0 50565.0 581520.0 589380.1 590620.0 591777.0 591829.0 24670.1 25415.6 25765.7 32075.4 38573.0 41759.2 42752.0 43179.8 44180.0 45711.5 35763.3 36283.0 36454.0 37120.3 37154.1 35628.3 36132.8 36441.8 36779.6 36899.4 34310.3 34783.0 35743.6 36225.3 36557.6 31598.5 32346.6 32396.8 32703.8 32780.7 23906.0 24141.2 24274.5 24747.6 24806.4 08:39:44 INFO - PROCESS | 1647 | 08:39:44 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:31126.76 Median:383.29 stddev:81823.69 (21347.7%) stddev-sans-first:82020.64 08:39:44 INFO - PROCESS | 1647 | Values: 372.5 378.0 378.2 388.3 401.4 14101.5 14806.1 18074.3 20322.5 20536.7 16115.0 16125.7 18058.0 18716.4 20423.7 5788.4 5900.2 5956.5 6026.6 6030.5 4709.3 5917.8 5957.4 6022.8 6024.8 4915.9 5933.7 5951.4 5952.4 5961.5 4984.0 5706.4 5831.5 5839.1 5841.5 4391.9 4973.7 5045.3 5104.0 5115.2 19515.5 23985.5 24763.0 25302.7 25337.2 2256.1 2260.6 2287.3 2481.4 2702.3 13972.9 14962.6 15372.8 15450.3 15651.0 1734.2 1821.1 1842.4 1908.7 1912.4 86113.6 91795.0 111577.0 112304.0 113665.0 9049.9 11386.5 11470.7 11519.4 11596.9 14303.8 14389.5 14405.6 14437.9 14446.9 6839.9 8070.2 8301.0 8342.7 8460.5 8129.0 8145.1 8154.8 8292.9 8329.8 4454.0 4529.6 4559.8 4572.3 4607.6 1739.2 1817.1 1931.1 1967.0 1979.8 823.8 849.4 882.4 951.5 958.2 17444.6 19604.6 19808.8 21009.9 21314.7 756.8 841.5 902.2 920.5 986.2 8671.2 10571.0 10758.6 11287.8 11293.4 474630.0 482405.0 487349.0 488508.0 493066.0 118538.7 124239.0 124774.0 125057.0 129445.0 994.9 1040.0 1055.3 1093.6 1100.8 14244.9 15544.4 19050.2 19755.8 19900.0 48476.3 51951.5 53220.0 53672.5 55664.0 79644.2 80125.2 80264.0 80862.0 81329.6 7872.6 7930.0 7979.8 8360.7 8389.2 9180.9 11776.5 12589.7 12803.5 12900.8 9825.9 10190.0 10835.8 10980.3 11203.7 9044.6 10436.5 10712.4 10821.9 10949.5 9163.4 10957.7 11237.0 11245.8 11267.8 7220.5 7903.1 8145.5 8268.3 8381.7 6837.9 8662.4 8912.3 10930.7 10975.9 08:39:44 INFO - PROCESS | 1647 | 08:39:44 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5410.62 Median:5128.72 stddev:2989.21 (58.3%) stddev-sans-first:2982.37 08:39:44 INFO - PROCESS | 1647 | Values: 1397.3 1505.0 1635.5 1713.4 1760.8 7573.1 7718.6 7912.8 8132.3 8644.8 7172.9 7987.4 8013.8 8414.7 8422.0 1896.7 2287.5 2309.8 2319.6 2326.6 2109.0 2285.8 2341.9 2371.5 2386.7 2307.2 2325.5 2333.3 2348.4 2357.6 2306.7 2325.5 2330.5 2348.0 2349.2 2294.5 2340.6 2348.5 2355.8 2370.8 8497.8 8512.2 8529.4 8554.7 8594.6 5061.2 5223.3 5226.0 5246.8 5250.7 3126.7 3201.3 3378.8 3381.7 3401.2 949.8 968.4 978.7 984.9 984.9 9563.1 10872.7 11011.5 11014.1 11054.2 4369.0 4476.3 4512.3 4514.0 4521.0 8722.0 8811.9 8819.0 8825.1 8841.9 5116.6 5140.8 5175.0 5180.3 5186.9 4679.5 4819.5 4835.5 4871.1 4887.3 3641.3 3728.8 3744.4 3778.5 3789.6 1178.1 1179.9 1186.2 1187.7 1189.5 823.6 887.2 890.8 893.7 912.2 5493.6 5603.7 5649.7 5672.9 5800.5 434.8 435.2 449.9 452.1 454.2 3665.5 3837.0 3908.8 3939.8 3968.3 10011.9 10255.2 10370.4 10402.3 10428.6 10285.5 10314.4 10374.8 10412.6 10431.0 7829.9 7916.8 7941.6 8115.0 8136.0 5528.7 5587.1 5618.7 5682.8 5771.3 7880.9 8175.0 9175.6 9308.0 9370.4 8385.8 8597.0 9464.8 9683.6 9686.0 7214.2 7220.9 7258.4 7334.4 7339.3 7897.1 7921.1 7948.2 7976.0 8000.7 5953.5 7164.9 7226.7 7237.5 7363.8 7145.2 7155.0 7160.0 7195.4 7221.0 6735.7 6736.1 6775.7 6827.8 6848.7 4024.5 4769.6 4849.4 4891.3 4973.0 4569.5 4721.0 4794.8 4963.5 5024.1 08:39:44 INFO - PROCESS | 1647 | 08:39:44 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4476.29 Median:4850.26 stddev:1363.25 (28.1%) stddev-sans-first:1331.43 08:39:44 INFO - PROCESS | 1647 | Values: 350.2 356.8 357.5 359.6 365.8 4272.4 4631.7 4831.0 4832.9 5257.8 4531.8 4759.4 5095.4 5446.3 5458.5 3835.0 3969.8 4159.0 4207.8 4248.6 3590.3 4181.6 4182.6 4191.0 4255.9 3889.4 4204.7 4241.0 4270.8 4277.7 4039.9 4251.3 4252.5 4285.8 4291.8 3689.1 3712.4 3719.6 3729.9 3747.1 4189.7 4636.8 4956.7 5263.8 5281.0 4208.9 4339.2 4473.0 4817.6 4861.5 5360.8 5656.6 5680.2 5725.3 5738.5 3084.1 3556.9 3574.8 3633.3 3904.9 5112.7 5684.5 5873.6 5926.0 6051.5 3748.5 3893.4 3921.9 3934.4 3940.9 3329.6 3832.3 3969.2 3977.6 3991.5 4051.4 4630.5 4653.5 4657.2 4681.1 5137.7 5291.5 5601.2 5672.0 5674.8 1210.1 1271.9 1286.9 1291.9 1295.9 755.0 798.3 812.2 871.2 877.0 4312.6 4324.6 4571.7 4798.2 4839.0 4333.9 4910.6 5169.9 5288.4 5596.9 4607.5 4686.7 5064.2 5074.7 5110.8 2502.7 2611.5 2793.3 2914.9 2962.3 5414.8 5479.7 5505.3 5778.8 5826.0 4530.9 5112.4 5503.4 5714.6 5839.8 4831.7 5207.6 5763.7 5806.4 5875.6 5177.0 5263.7 5281.5 5676.4 5686.9 4772.0 4826.9 5012.5 5071.7 5831.2 4944.7 4984.4 5290.5 5622.7 5975.5 3314.7 3474.2 3718.7 3935.3 3970.5 4977.9 5917.5 5937.7 5993.3 6017.3 4652.4 5122.4 5204.7 5242.0 5604.6 5679.0 5682.1 5702.2 5721.5 5736.4 5457.2 5625.7 5631.7 5634.2 5682.4 4815.9 5200.9 5667.6 5673.6 5680.7 5384.5 5608.0 5638.0 5668.2 5692.9 08:39:44 INFO - PROCESS | 1647 | 08:39:44 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20899.96 Median:4249.53 stddev:30187.61 (710.4%) stddev-sans-first:30234.25 08:39:44 INFO - PROCESS | 1647 | Values: 716.9 718.6 752.6 767.8 808.5 11667.3 15464.8 15492.5 15506.3 15509.4 13948.9 14130.3 14148.9 14149.7 14177.3 555.8 666.4 843.9 844.7 864.9 861.4 863.9 868.2 869.1 869.2 919.1 925.9 926.4 927.0 929.2 910.9 911.3 916.8 917.2 919.4 684.7 685.5 685.9 686.7 689.1 14970.4 15095.8 15259.8 15315.7 15552.8 13977.4 14214.9 14306.6 14359.1 14482.8 11772.8 11858.3 11881.8 11920.1 11934.8 3643.6 3654.6 3697.8 3705.7 3804.8 83793.0 86158.7 91329.5 114875.4 115327.3 10884.3 10915.3 10963.3 11184.4 11224.3 18305.9 22582.9 23379.8 23424.4 23446.8 7798.0 7818.6 7830.1 7830.6 7969.4 4217.9 4281.1 4303.4 4304.2 4337.6 3422.6 3537.2 3540.9 3555.4 3558.1 639.5 641.7 652.3 653.2 660.8 585.7 604.7 613.6 657.6 659.6 6951.3 8783.2 9071.7 9584.3 10033.4 482.3 582.9 646.0 662.9 678.9 4495.6 4882.3 4912.3 4959.1 5051.9 78180.0 105283.0 107142.0 107208.0 112833.4 91621.0 95193.0 96384.5 96401.0 96427.7 82333.5 83916.6 84457.0 84634.6 84755.0 9758.8 9904.7 10051.8 10071.8 10189.2 51134.7 51234.7 51889.5 52241.0 54495.0 74651.0 77237.0 77390.2 77881.0 78776.2 12531.6 12744.7 12978.3 13095.4 13139.3 14433.4 14624.8 14717.8 14735.8 14921.1 13436.7 13711.1 13753.7 13927.0 14241.3 11610.0 13407.0 13837.5 13838.4 13938.4 11445.2 12422.6 13351.5 13527.4 13537.3 10252.9 10264.8 10717.6 11320.8 11865.6 11267.3 11461.2 11601.2 11636.0 11662.0 08:39:44 INFO - PROCESS | 1647 | -------- Summary: end -------- 08:39:44 INFO - PROCESS | 1647 | 08:39:44 INFO - TEST-INFO | 1647: exit 0 08:39:44 INFO - TEST-OK | dromaeo_css | took 1117317ms 08:39:44 INFO - TEST-START | kraken 08:39:44 INFO - operating with platform_type : mac_ 08:39:44 INFO - Initialising browser for kraken test... 08:39:44 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpon6AFC/profile http://localhost:49242/getInfo.html) 08:39:47 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 08:39:47 INFO - PROCESS | 1655 | colorDepth:24 08:39:47 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 08:39:47 INFO - PROCESS | 1655 | __metrics 08:39:47 INFO - PROCESS | 1655 | 2016-12-19 08:39:47.765 plugin-container[1656:10588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 08:39:47 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:47 INFO - PROCESS | 1655 | 2016-12-19 08:39:47.770 plugin-container[1656:10588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 08:39:47 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:47 INFO - PROCESS | 1655 | 2016-12-19 08:39:47.770 plugin-container[1656:10588] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:39:47 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 08:39:48 INFO - TEST-INFO | 1655: exit 0 08:39:48 INFO - Browser initialized. 08:39:48 INFO - Running cycle 1/1 for kraken test... 08:39:48 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpon6AFC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:39:50 INFO - PROCESS | 1657 | 2016-12-19 08:39:50.744 plugin-container[1658:10706] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 08:39:50 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:50 INFO - PROCESS | 1657 | 2016-12-19 08:39:50.745 plugin-container[1658:10706] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430f, name = 'com.apple.CFPasteboardClient' 08:39:50 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:50 INFO - PROCESS | 1657 | 2016-12-19 08:39:50.745 plugin-container[1658:10706] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:40:00 INFO - PROCESS | 1657 | RSS: Main: 236978176 08:40:00 INFO - PROCESS | 1657 | 08:40:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 08:40:04 INFO - PROCESS | 1657 | RSS: Main: 249446400 08:40:04 INFO - PROCESS | 1657 | 08:40:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 08:40:07 INFO - PROCESS | 1657 | RSS: Main: 256966656 08:40:07 INFO - PROCESS | 1657 | 08:40:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 08:40:11 INFO - PROCESS | 1657 | RSS: Main: 259670016 08:40:11 INFO - PROCESS | 1657 | 08:40:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 08:40:14 INFO - PROCESS | 1657 | RSS: Main: 261824512 08:40:14 INFO - PROCESS | 1657 | 08:40:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:40:16 INFO - PROCESS | 1657 | RSS: Main: 262463488 08:40:16 INFO - PROCESS | 1657 | 08:40:20 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 08:40:20 INFO - PROCESS | 1657 | RSS: Main: 266440704 08:40:20 INFO - PROCESS | 1657 | 08:40:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 08:40:24 INFO - PROCESS | 1657 | RSS: Main: 266493952 08:40:24 INFO - PROCESS | 1657 | 08:40:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 08:40:28 INFO - PROCESS | 1657 | RSS: Main: 266932224 08:40:28 INFO - PROCESS | 1657 | 08:40:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:40:30 INFO - PROCESS | 1657 | RSS: Main: 266235904 08:40:30 INFO - PROCESS | 1657 | 08:40:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:40:33 INFO - PROCESS | 1657 | RSS: Main: 266244096 08:40:33 INFO - PROCESS | 1657 | 08:40:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:40:36 INFO - PROCESS | 1657 | RSS: Main: 266350592 08:40:36 INFO - PROCESS | 1657 | 08:40:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:40:40 INFO - PROCESS | 1657 | RSS: Main: 266637312 08:40:40 INFO - PROCESS | 1657 | 08:40:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:40:43 INFO - PROCESS | 1657 | RSS: Main: 266477568 08:40:43 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 08:40:45 INFO - PROCESS | 1657 | RSS: Main: 267640832 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | __start_tp_report 08:40:45 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 08:40:45 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 08:40:45 INFO - PROCESS | 1657 | |i|pagename|runs| 08:40:45 INFO - PROCESS | 1657 | |0;ai-astar;107;118;119;120;117;119;120;120;117;119 08:40:45 INFO - PROCESS | 1657 | |1;audio-beat-detection;137;110;110;143;111;112;113;109;148;142 08:40:45 INFO - PROCESS | 1657 | |2;audio-dft;199;238;206;217;203;274;233;200;221;181 08:40:45 INFO - PROCESS | 1657 | |3;audio-fft;79;60;60;82;72;68;59;55;72;59 08:40:45 INFO - PROCESS | 1657 | |4;audio-oscillator;91;73;86;70;71;74;117;106;101;85 08:40:45 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;126;110;96;166;94;176;168;117;169;159 08:40:45 INFO - PROCESS | 1657 | |6;imaging-darkroom;156;97;96;130;99;146;156;95;97;98 08:40:45 INFO - PROCESS | 1657 | |7;imaging-desaturate;91;97;115;106;86;147;158;110;154;120 08:40:45 INFO - PROCESS | 1657 | |8;json-parse-financial;58;56;59;56;58;58;57;58;57;58 08:40:45 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;76;69;81;70;71;62;87;110;112;109 08:40:45 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;89;85;91;92;91;88;90;86;80;114 08:40:45 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;143;169;154;162;159;152;161;143;195;146 08:40:45 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;174;177;175;173;174;174;173;176;177;171 08:40:45 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;68;69;67;72;87;90;74;69;68;68 08:40:45 INFO - PROCESS | 1657 | __end_tp_report 08:40:45 INFO - PROCESS | 1657 | __start_cc_report 08:40:45 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,939 08:40:45 INFO - PROCESS | 1657 | __end_cc_report 08:40:45 INFO - PROCESS | 1657 | __startTimestamp1482165645613__endTimestamp 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | ------- Summary: start ------- 08:40:45 INFO - PROCESS | 1657 | Number of tests: 14 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:117.60 Median:119.00 stddev:3.89 (3.3%) stddev-sans-first:1.20 08:40:45 INFO - PROCESS | 1657 | Values: 107.0 118.0 119.0 120.0 117.0 119.0 120.0 120.0 117.0 119.0 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:125.00 stddev:16.59 (13.3%) stddev-sans-first:16.87 08:40:45 INFO - PROCESS | 1657 | Values: 137.0 110.0 110.0 143.0 111.0 112.0 113.0 109.0 148.0 142.0 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:217.20 Median:219.00 stddev:26.22 (12.0%) stddev-sans-first:26.97 08:40:45 INFO - PROCESS | 1657 | Values: 199.0 238.0 206.0 217.0 203.0 274.0 233.0 200.0 221.0 181.0 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:66.60 Median:70.00 stddev:9.36 (13.4%) stddev-sans-first:8.79 08:40:45 INFO - PROCESS | 1657 | Values: 79.0 60.0 60.0 82.0 72.0 68.0 59.0 55.0 72.0 59.0 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:87.40 Median:73.50 stddev:16.28 (22.2%) stddev-sans-first:17.22 08:40:45 INFO - PROCESS | 1657 | Values: 91.0 73.0 86.0 70.0 71.0 74.0 117.0 106.0 101.0 85.0 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:138.10 Median:168.50 stddev:32.66 (19.4%) stddev-sans-first:34.34 08:40:45 INFO - PROCESS | 1657 | Values: 126.0 110.0 96.0 166.0 94.0 176.0 168.0 117.0 169.0 159.0 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:117.00 Median:96.50 stddev:26.80 (27.8%) stddev-sans-first:24.42 08:40:45 INFO - PROCESS | 1657 | Values: 156.0 97.0 96.0 130.0 99.0 146.0 156.0 95.0 97.0 98.0 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:118.40 Median:156.00 stddev:26.14 (16.8%) stddev-sans-first:25.78 08:40:45 INFO - PROCESS | 1657 | Values: 91.0 97.0 115.0 106.0 86.0 147.0 158.0 110.0 154.0 120.0 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:58.00 stddev:0.97 (1.7%) stddev-sans-first:1.01 08:40:45 INFO - PROCESS | 1657 | Values: 58.0 56.0 59.0 56.0 58.0 58.0 57.0 58.0 57.0 58.0 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:84.70 Median:70.50 stddev:18.96 (26.9%) stddev-sans-first:19.85 08:40:45 INFO - PROCESS | 1657 | Values: 76.0 69.0 81.0 70.0 71.0 62.0 87.0 110.0 112.0 109.0 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:89.50 stddev:8.97 (10.0%) stddev-sans-first:9.50 08:40:45 INFO - PROCESS | 1657 | Values: 89.0 85.0 91.0 92.0 91.0 88.0 90.0 86.0 80.0 114.0 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:158.40 Median:160.00 stddev:15.49 (9.7%) stddev-sans-first:15.40 08:40:45 INFO - PROCESS | 1657 | Values: 143.0 169.0 154.0 162.0 159.0 152.0 161.0 143.0 195.0 146.0 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:174.50 stddev:1.90 (1.1%) stddev-sans-first:2.01 08:40:45 INFO - PROCESS | 1657 | Values: 174.0 177.0 175.0 173.0 174.0 174.0 173.0 176.0 177.0 171.0 08:40:45 INFO - PROCESS | 1657 | 08:40:45 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:70.50 stddev:8.36 (11.9%) stddev-sans-first:8.66 08:40:45 INFO - PROCESS | 1657 | Values: 68.0 69.0 67.0 72.0 87.0 90.0 74.0 69.0 68.0 68.0 08:40:45 INFO - PROCESS | 1657 | -------- Summary: end -------- 08:40:45 INFO - PROCESS | 1657 | 08:40:46 INFO - TEST-INFO | 1657: exit 0 08:40:46 INFO - TEST-OK | kraken | took 61986ms 08:40:46 INFO - SUITE-END | took 1179s 08:40:46 INFO - Completed test suite (00:19:39) 08:40:46 INFO - javascript benchmark 08:40:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7152.0565909187735, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2961.3633068890185, 3398.6091599466054, 3413.761036727427, 3422.8459719312627, 3471.080163756605, 4602.700824446409, 5223.216517522374, 5501.174823776436, 5657.547396208303, 5670.68811215383, 4029.214303210875, 5482.259894914186, 5557.166425036246, 5602.1036634138545, 5715.9428405715935, 5195.948040519594, 5233.842984710464, 5235.685858848462, 5272.578193744491, 5280.973595132019, 5176.404713457944, 5223.503767142115, 5263.684178949256, 5295.655782374152, 5301.098813201765, 5230.398504172031, 5256.710880901549, 5282.3925248596515, 5286.10136276832, 5325.733713314338, 5077.593792496591, 5112.054269960032, 5150.9742451287875, 5189.195674670432, 5211.869703257449, 4082.7346222495444, 5038.899222015576, 5152.948470515322, 5169.043726910533, 5174.689518628857, 5485.588580856452, 5661.490465858052, 5668.631538949955, 5688.744006519716, 5717.0, 6091.42131497507, 6173.074038894157, 6204.751809927598, 6220.03589443637, 6225.750969961241, 6085.604435711664, 6112.6638034908065, 6163.660998645073, 6221.3156552779155, 6241.843953901143, 5769.221155144086, 5771.163181338682, 5777.0, 5778.653280803207, 5790.7973220937065, 6188.845278867974, 6224.06639004153, 6245.250569931637, 6258.874822503524, 6273.780417685451, 5970.373110823388, 6024.216851809237, 6034.155218269446, 6076.939230607726, 6086.878262434815, 6164.4760195383, 6170.56806023583, 6171.413715697002, 6180.042093475428, 6184.938150618526, 6043.7280322385595, 6056.0, 6059.424354686298, 6071.635701857903, 6089.908651370233, 6119.167450728587, 6132.7532529736445, 6150.231221097363, 6179.134921111048, 6210.223722034746, 4847.720399457768, 4891.804327826918, 4894.902101957941, 4923.261510773413, 4926.8768280793265, 4432.3351497275025, 4436.245838207513, 4438.911221775611, 4440.555944405533, 4449.555044495524, 5652.858678532987, 5722.856928576735, 5728.1407788831175, 5744.885102297931, 5798.855028624318, 6231.255248530419, 6249.9062514062325, 6250.74997000124, 6259.593126446857, 6280.371962803774, 5786.537077033827, 5800.88398232033, 5817.331006934172, 5818.709064546756, 5851.64890106595, 5192.7403629818355, 5281.12861377876, 5281.207818827206, 5441.673499590037, 5452.727363631802, 6200.782972595937, 6236.251649802053, 6238.812835614939, 6241.531885108726, 6268.1851359323, 6245.594036387621, 6247.5939063960695, 6256.624602523863, 6270.717817698123, 6311.842203944754, 6291.937080629135, 6347.492200623847, 6348.587341822767, 6358.046293056079, 6379.170707807955, 4571.8570357410645, 4658.487566367765, 5199.584033277253, 5514.648911016827, 6031.60794548353, 5836.912446313224, 6165.907511387243, 6172.0, 6185.010398336244, 6186.1030150627, 5992.580519363777, 6033.245844269466, 6063.848403789941, 6096.29892562361, 6114.296855861633, 6061.121137435135, 6096.542759292982, 6097.207363042776, 6106.297775755845, 6145.139680444652, 5132.691163753219, 5398.379186393615, 5766.481016708516, 5834.241548598655, 5972.910406343822, 6017.969910150421, 6033.396660333931, 6058.939410605837, 6062.333143354139, 6093.299270583762, 5820.126980952891, 5919.171316015675, 5920.082387229986, 5932.0, 5937.406259374028], "value": 5612.249041041985, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.7291116974494, 492.28864291099376, 495.3708789836907, 506.48338694531566, 523.8795077132262, 29476.557851632217, 40018.0, 40298.79850600743, 40304.79847600765, 40340.596594034054, 41193.58806411935, 41791.37312940308, 41810.0, 41873.0, 41914.95212619686, 8399.454035487674, 8609.053004169537, 9317.860232096524, 9565.139137477625, 9580.90419095807, 11242.213045086848, 11558.595449159282, 11594.188406811527, 11756.05951523876, 11767.940885320362, 11400.543978240861, 11432.885671143265, 11582.710432239177, 11595.710107247301, 11651.359175245358, 11435.371054591995, 11504.769904601944, 11542.36516991565, 11549.826752598718, 11552.595659151973, 11412.600558980395, 11415.0297224736, 11496.540138394454, 11497.540098396054, 11498.367589782558, 40004.0, 40270.79864600688, 40353.99115022118, 40483.7975810122, 40734.0, 3274.2305558193807, 3412.436947903593, 3525.347810655037, 3590.120420496969, 3623.7825730455993, 27241.863790681116, 28578.0, 28948.27629309263, 29014.0, 29027.709722902713, 2674.291312802109, 2795.0636536760207, 2859.2137162280333, 2861.2560734209046, 2879.7840161987724, 459612.0, 463710.628937107, 464636.0, 464884.0, 467368.0, 49338.0, 55587.0, 55703.72148139314, 55836.16245756317, 55875.72062139744, 56750.43249567534, 57650.13524797132, 58196.418035819945, 58271.0, 58430.70784646049, 15067.849321506865, 15338.15640139803, 15345.6163595911, 15375.538733838002, 15444.5366639001, 10727.731806704895, 10957.780844383227, 11521.905418985183, 11696.88303116958, 11944.522219111312, 17261.91369043172, 17402.39091631787, 17458.301667933396, 17479.38822141219, 17584.384546541067, 6234.812955611339, 6637.004449332639, 6996.965015174892, 7557.068761261895, 7604.0114785077585, 846.4286606540561, 858.8319884956454, 909.8043920556979, 1076.5263284154946, 1142.9142814288962, 23999.0, 26927.0, 28362.0, 29856.701432985825, 30545.541816872767, 1034.9236793734583, 1068.7488440216543, 1070.218740319571, 1099.1756182862853, 1118.4351902289454, 15278.541643750705, 15691.215439228221, 15695.764563531557, 15759.29083191259, 16043.839561604467, 501123.0, 514565.7086858242, 562897.3710262927, 635582.0, 636076.0, 59416.40583594195, 59610.0, 59738.70130649319, 60047.79904401887, 60153.0, 1346.6566025663394, 1376.421902800826, 1378.7862881253423, 1393.7282229965263, 1400.5238219005587, 27814.3046423841, 29321.85339073291, 29543.409131817672, 29632.703672962994, 30824.69175308218, 40971.0, 46777.76611116923, 49838.50161498338, 50233.0, 50565.0, 581520.0, 589380.0619937972, 590620.0, 591777.0, 591829.0, 24670.13654522083, 25415.618765718158, 25765.742342576334, 32075.35849283048, 38573.035674108374, 41759.164816704106, 42752.0, 43179.78410107929, 44180.0, 45711.54288457073, 35763.284734305686, 36283.0, 36454.0, 37120.25759484741, 37154.07114822151, 35628.28743425169, 36132.81933590315, 36441.802240332, 36779.63220367869, 36899.446508302935, 34310.313793723486, 34782.9565113047, 35743.64256357403, 36225.27549448943, 36557.63442365542, 31598.52602210923, 32346.561057156767, 32396.83801580977, 32703.836480817445, 32780.672193277765, 23906.043758249474, 24141.15505957283, 24274.514509709355, 24747.62878556787, 24806.37984050341], "value": 19674.113239901584, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.49340896380915, 378.0322374720715, 378.249175386857, 388.3320688415924, 401.43397809089174, 14101.506447274347, 14806.111633301982, 18074.277028918845, 20322.491937701565, 20536.6651167674, 16115.0, 16125.677486450264, 18058.0, 18716.43850684478, 20423.693644595307, 5788.42115788422, 5900.1739756434135, 5956.463918247357, 6026.63840169589, 6030.547708921827, 4709.317149013408, 5917.792877249297, 5957.404259574051, 6022.819315420545, 6024.759009639609, 4915.901681966358, 5933.673647949361, 5951.434613711691, 5952.375000624937, 5961.463468287853, 4983.950160498385, 5706.429357064302, 5831.533477321826, 5839.0949402842625, 5841.503472204868, 4391.890202744957, 4973.701577905337, 5045.268436076785, 5103.953689493645, 5115.2327150927285, 19515.512112197168, 23985.520289594286, 24763.009479620792, 25302.746972530407, 25337.239882803544, 2256.142665787007, 2260.626996545568, 2287.3023727763025, 2481.367251350912, 2702.337927207827, 13972.93013534936, 14962.625934351729, 15372.769408458882, 15450.304736286782, 15651.0, 1734.2022669572011, 1821.1076572479521, 1842.4012196036342, 1908.6755251607121, 1912.4358314297315, 86113.56943215306, 91795.0, 111577.0, 112304.0, 113665.0, 9049.909500904907, 11386.487608057658, 11470.655880323604, 11519.424028798527, 11596.94201528987, 14303.78544321836, 14389.496367627084, 14405.639859003608, 14437.85562144365, 14446.927765361106, 6839.897401538981, 8070.2333278338465, 8301.045379781282, 8342.708005219789, 8460.450070745383, 8128.9593552031865, 8145.063317718538, 8154.796130096795, 8292.875606865902, 8329.791755206168, 4453.955460445419, 4529.6376289896725, 4559.817607295738, 4572.314152877028, 4607.58531732139, 1739.2347367158663, 1817.1005352350671, 1931.121339790392, 1966.9901650491943, 1979.8317143042714, 823.7940514871282, 849.426637020009, 882.4131952251809, 951.509972364233, 958.2334132693817, 17444.563885902953, 19604.607907841764, 19808.801911980696, 21009.894950525457, 21314.680279795815, 756.7540549321493, 841.5203334099505, 902.1880307723102, 920.5489310238069, 986.2356673578034, 8671.176238257356, 10571.0, 10758.623448179433, 11287.830682539769, 11293.37886416255, 474630.0, 482405.0, 487349.0, 488508.0, 493066.0, 118538.66576004101, 124239.0, 124774.0, 125057.0, 129445.0, 994.8858675314956, 1039.9964034706545, 1055.2613170780485, 1093.578972095749, 1100.7688385438987, 14244.928775356471, 15544.367841376945, 19050.21293453534, 19755.80244197597, 19900.004999749664, 48476.272855906485, 51951.48048519466, 53220.0, 53672.46327536675, 55664.0, 79644.20355796367, 80125.19874801411, 80264.0, 80862.0, 81329.59335203285, 7872.606369681378, 7930.0, 7979.760607181562, 8360.665573376997, 8389.244967952913, 9180.862287065565, 11776.52893884235, 12589.685257868627, 12803.487860485477, 12900.83892449684, 9825.9017409825, 10190.0, 10835.837462438227, 10980.286281391684, 11203.663890083311, 9044.638214471348, 10436.530356134144, 10712.35725856451, 10821.891781082404, 10949.507272172612, 9163.35856490066, 10957.671269861918, 11237.0, 11245.775084498428, 11267.774644507228, 7220.530665506725, 7903.0516338037505, 8145.470544414831, 8268.338532917473, 8381.65893457044, 6837.863242735217, 8662.436941598777, 8912.33157513202, 10930.672079837617, 10975.945120274348], "value": 9263.309814581762, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.2594524901801, 1504.9548513544594, 1635.4766474728085, 1713.4431309824301, 1760.7622970898924, 7573.053368328959, 7718.614069296534, 7912.802179945505, 8132.26809587137, 8644.783880402993, 7172.852343625021, 7987.361011119111, 8013.799655008627, 8414.663413463453, 8422.031466381368, 1896.7344571759966, 2287.5196208796087, 2309.8036666883336, 2319.5708793873187, 2326.569584626841, 2108.968365474519, 2285.7828506291876, 2341.8711970841596, 2371.5019845832394, 2386.7016622922133, 2307.157887371106, 2325.546518428907, 2333.3466629343725, 2348.4011577047827, 2357.599208134621, 2306.6655334976417, 2325.5000174962297, 2330.5105927755276, 2348.025569388719, 2349.1543044503965, 2294.4608017115966, 2340.625499920022, 2348.5068135691527, 2355.823313251496, 2370.751071137523, 8497.830043399097, 8512.233898949124, 8529.402941794076, 8554.657813687507, 8594.5702714864, 5061.16490779021, 5223.320968274138, 5226.0, 5246.7638956247065, 5250.65870718402, 3126.7498600111935, 3201.3117179806454, 3378.763486555942, 3381.746369022333, 3401.234722187513, 949.7890190007727, 968.3947532792005, 978.7063880835792, 984.8621193032909, 984.8670429491998, 9563.139317461462, 10872.728181795519, 11011.504482298316, 11014.06380457662, 11054.170937179662, 4368.973291276548, 4476.328550717419, 4512.278035514368, 4514.0, 4521.027978984468, 8721.956390218009, 8811.867821982676, 8818.955905220433, 8825.117488251251, 8841.867371989296, 5116.641835071509, 5140.794368225229, 5175.0167468180925, 5180.274761533388, 5186.92219616706, 4679.532046795293, 4819.49395313494, 4835.492273311252, 4871.14754917888, 4887.34020907173, 3641.326354624403, 3728.832202550892, 3744.382176940774, 3778.471014058034, 3789.6020917803235, 1178.1105265524475, 1179.8702142764462, 1186.1874615888144, 1187.7208855919025, 1189.5301355964345, 823.5717426937958, 887.1704955866286, 890.844102282098, 893.6559424621438, 912.1631725334205, 5493.560515158778, 5603.691796951125, 5649.661020338793, 5672.901026620177, 5800.477956983892, 434.79347310027487, 435.15579775235943, 449.93814597549755, 452.09580838323353, 454.2436842657005, 3665.5051568037975, 3837.002379381381, 3908.8241029153755, 3939.783311917872, 3968.2857085724268, 10011.949940250252, 10255.179585633132, 10370.429626370471, 10402.27184097121, 10428.634997775041, 10285.537150828231, 10314.43270620123, 10374.792504150026, 10412.583496660049, 10431.009054139844, 7829.942957700751, 7916.802079948047, 7941.563214023284, 8115.0, 8135.999999999882, 5528.7235638218735, 5587.0502014656795, 5618.747156378055, 5682.77268909239, 5771.307443106854, 7880.936073629987, 8174.959125204336, 9175.587098580714, 9307.97612262637, 9370.39092458988, 8385.825984362073, 8596.957015214884, 9464.810703786023, 9683.612655493984, 9686.0, 7214.170370407261, 7220.927790722236, 7258.386581469606, 7334.449916256409, 7339.266073392618, 7897.091834438883, 7921.128675845767, 7948.165442628672, 7976.0, 8000.719974800854, 5953.493953014043, 7164.856702866018, 7226.7109315626785, 7237.529560578546, 7363.77908662741, 7145.249748776304, 7155.0, 7160.0, 7195.388391986739, 7221.0, 6735.663216839041, 6736.124303840666, 6775.728970841308, 6827.761028363983, 6848.691808868513, 4024.5371782245415, 4769.618430525619, 4849.393825771778, 4891.315215869852, 4972.950270497248, 4569.47451043121, 4721.0, 4794.808207671654, 4963.454020057863, 5024.0956627807345], "value": 4303.238886517279, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.1718435899098, 356.8412056634795, 357.53877498027543, 359.6421560547256, 365.83537408166313, 4272.359146128082, 4631.698939568926, 4830.951690483099, 4832.864276894927, 5257.789688412459, 4531.818727250914, 4759.357486739289, 5095.394950590561, 5446.2919820423385, 5458.536024437927, 3835.0, 3969.7693714948387, 4159.043420013383, 4207.789610519461, 4248.596383343593, 3590.3178396104786, 4181.640966685824, 4182.64447521961, 4190.982492605334, 4255.851045213418, 3889.436031775391, 4204.705670603059, 4240.957590424087, 4270.829166833323, 4277.668670574529, 4039.8890305166315, 4251.298535741599, 4252.532221455637, 4285.764282964436, 4291.806868690916, 3689.05928988109, 3712.3874560697454, 3719.5865571083086, 3729.9440508392127, 3747.138158223624, 4189.706720529564, 4636.820694273686, 4956.727379994099, 5263.815766448194, 5280.973595132038, 4208.85269015583, 4339.175556644227, 4472.993576445274, 4817.61459083276, 4861.52062448154, 5360.839174824761, 5656.604037717361, 5680.188306788125, 5725.2843394575675, 5738.540916726652, 3084.121025507742, 3556.8795829313685, 3574.7855128692363, 3633.2551826875692, 3904.941425878614, 5112.667676601009, 5684.516816070639, 5873.647581145145, 5925.970370148121, 6051.485623721989, 3748.5314335708035, 3893.377059670439, 3921.941170882439, 3934.409838524187, 3940.8817735467983, 3329.6004479462617, 3832.2718683450057, 3969.186316805048, 3977.602239776057, 3991.501062367204, 4051.3591995241973, 4630.467496237909, 4653.488116307203, 4657.184992626345, 4681.110588988081, 5137.691738495702, 5291.497307755757, 5601.243832082698, 5671.9716401418555, 5674.801381951612, 1210.1468464732343, 1271.9364031798373, 1286.8777466140702, 1291.8901893338984, 1295.8509771376223, 754.9584772837546, 798.3014861995754, 812.2486699802777, 871.1506281375734, 876.9561521924006, 4312.5903039211225, 4324.610520321876, 4571.748553829571, 4798.184308667535, 4839.0, 4333.891652708645, 4910.631702622246, 5169.896602067937, 5288.3918349390315, 5596.888062238814, 4607.516210797818, 4686.695364801277, 5064.164412871834, 5074.746262686851, 5110.795568177231, 2502.724700282967, 2611.479921875399, 2793.3156376687843, 2914.863203350695, 2962.3482833776534, 5414.810481633124, 5479.671219726829, 5505.256790333334, 5778.7688492460675, 5826.0, 4530.948080061593, 5112.412072611697, 5503.422140675171, 5714.5999780015, 5839.795607153729, 4831.685940413862, 5207.5833933286, 5763.711814409263, 5806.419358064244, 5875.6474611523445, 5176.997341541972, 5263.736813159403, 5281.498257665516, 5676.388861123929, 5686.914696279477, 4771.95228047729, 4826.948546867638, 5012.523810238022, 5071.721055341844, 5831.154482599914, 4944.703317800943, 4984.426790918946, 5290.470952904832, 5622.746976385991, 5975.521958243417, 3314.7016768490953, 3474.228143646708, 3718.665320121202, 3935.2916475034767, 3970.533150206018, 4977.900441991213, 5917.497012753965, 5937.703114844327, 5993.321869876441, 6017.308009578955, 4652.441706995182, 5122.410922743783, 5204.713740744144, 5241.9516096780035, 5604.551635869039, 5678.971605141948, 5682.096497673326, 5702.2016917630735, 5721.456461636138, 5736.368999409978, 5457.224928187836, 5625.690587017753, 5631.7184140793615, 5634.23937768396, 5682.374938756816, 4815.855524334275, 5200.921986170286, 5667.574931880043, 5673.602847800615, 5680.68756218412, 5384.488473595002, 5607.990561698934, 5637.985162670759, 5668.234788303526, 5692.943070569241], "value": 4051.551836981225, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.8745469542829, 718.5975853522028, 752.555991964741, 767.7581561808026, 808.5027707959603, 11667.299962002286, 15464.768028479553, 15492.535223943272, 15506.302216400261, 15509.379624814994, 13948.930255348734, 14130.293485325717, 14148.929255353734, 14149.71700565988, 14177.291135443238, 555.7804667156471, 666.4302021771389, 843.8565443874526, 844.7170197983683, 864.9308055355557, 861.3798065392939, 863.896332440108, 868.1882439918696, 869.0744357259498, 869.1829680100717, 919.1451949686789, 925.9166674999249, 926.3885835348672, 926.9860952085725, 929.2008872369756, 910.8588168833808, 911.2573252798948, 916.8303863785154, 917.2203626917134, 919.4207649181006, 684.6782012454139, 685.4721864164616, 685.9348361905611, 686.6875571614903, 689.0869597782957, 14970.401183952628, 15095.773563396559, 15259.771103433457, 15315.693686126326, 15552.844471555252, 13977.440902363893, 14214.92892535541, 14306.64233394163, 14359.066660667128, 14482.782758258634, 11772.764544709058, 11858.3477908715, 11881.821772673416, 11920.10599205063, 11934.820977685342, 3643.599204087575, 3654.6162652921325, 3697.815109244527, 3705.7220708446976, 3804.771713697187, 83793.0, 86158.70761938576, 91329.54335228281, 114875.4256228722, 115327.27009094786, 10884.292520986111, 10915.290506117235, 10963.287386319864, 11184.384858832842, 11224.27042242268, 18305.90847045756, 22582.887085564795, 23379.766202338098, 23424.414389640395, 23446.765532344798, 7798.0, 7818.569978651229, 7830.138684744674, 7830.647620857075, 7969.442139050211, 4217.936730948977, 4281.12236991416, 4303.4190384298345, 4304.18220538103, 4337.631301339421, 3422.57217847769, 3537.2218112015316, 3540.8937731868086, 3555.4311310190246, 3558.0749005258817, 639.5267502048544, 641.7433026789322, 652.3476523476523, 653.183520599251, 660.7819419591523, 585.672023666748, 604.694629212245, 613.6318209074609, 657.5857209957792, 659.6042374575217, 6951.304869513109, 8783.165599268059, 9071.727848164433, 9584.28117891161, 10033.397996120257, 482.25250861165756, 582.8775957048982, 646.0384248967971, 662.8839953008204, 678.9486222504551, 4495.649850046036, 4882.31204687658, 4912.312276281324, 4959.097404440301, 5051.898962020739, 78180.0, 105283.0, 107142.0, 107208.0, 112833.43583282031, 91621.0, 95193.0, 96384.51807740917, 96401.0, 96427.66075526619, 82333.54255711258, 83916.58041709752, 84457.0, 84634.5768271167, 84755.0, 9758.80482390334, 9904.653337133166, 10051.849222261819, 10071.84892226632, 10189.23580731463, 51134.74432627813, 51234.74382628063, 51889.48110518847, 52241.0, 54495.0, 74651.0, 77237.0, 77390.2260977383, 77881.0, 78776.21223787688, 12531.561395351118, 12744.6813829655, 12978.286194259406, 13095.41070651804, 13139.277339746406, 14433.422663093359, 14624.780628290797, 14717.762092339668, 14735.778963315774, 14921.104733716013, 13436.664083397993, 13711.108777929403, 13753.656158596115, 13927.0, 14241.287935602972, 11610.013148882439, 13407.0, 13837.515686950908, 13838.377273022537, 13938.372773225432, 11445.198836081727, 12422.565210217599, 13351.465941362238, 13527.391267392793, 13537.323133843465, 10252.897471025493, 10264.846027309446, 10717.571297148026, 11320.773584528099, 11865.644030679094, 11267.26822646302, 11461.217972706501, 11601.18791684574, 11636.0, 11662.0], "value": 7504.513294474017, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1624.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 119.0, 120.0, 117.0, 119.0, 120.0, 120.0, 117.0, 119.0], "value": 117.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 110.0, 110.0, 143.0, 111.0, 112.0, 113.0, 109.0, 148.0, 142.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 238.0, 206.0, 217.0, 203.0, 274.0, 233.0, 200.0, 221.0, 181.0], "value": 217.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 60.0, 82.0, 72.0, 68.0, 59.0, 55.0, 72.0, 59.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 73.0, 86.0, 70.0, 71.0, 74.0, 117.0, 106.0, 101.0, 85.0], "value": 87.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 110.0, 96.0, 166.0, 94.0, 176.0, 168.0, 117.0, 169.0, 159.0], "value": 138.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 97.0, 96.0, 130.0, 99.0, 146.0, 156.0, 95.0, 97.0, 98.0], "value": 117.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 97.0, 115.0, 106.0, 86.0, 147.0, 158.0, 110.0, 154.0, 120.0], "value": 118.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 59.0, 56.0, 58.0, 58.0, 57.0, 58.0, 57.0, 58.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 69.0, 81.0, 70.0, 71.0, 62.0, 87.0, 110.0, 112.0, 109.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 91.0, 92.0, 91.0, 88.0, 90.0, 86.0, 80.0, 114.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 169.0, 154.0, 162.0, 159.0, 152.0, 161.0, 143.0, 195.0, 146.0], "value": 158.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 177.0, 175.0, 173.0, 174.0, 174.0, 173.0, 176.0, 177.0, 171.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 72.0, 87.0, 90.0, 74.0, 69.0, 68.0, 68.0], "value": 73.2, "unit": "score"}]}]} 08:40:46 INFO - Return code: 0 08:40:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:40:46 ERROR - # TBPL SUCCESS # 08:40:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:40:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:40:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:40:46 INFO - 2016-12-19 08:40:46.920 screenresolution[1662:11104] starting screenresolution argv=screenresolution get 08:40:46 INFO - 2016-12-19 08:40:46.923 screenresolution[1662:11104] Display 0: 1600x1200x32@60 08:40:46 INFO - 2016-12-19 08:40:46.929 screenresolution[1663:11107] starting screenresolution argv=screenresolution list 08:40:46 INFO - Available Modes on Display 0 08:40:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:40:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:40:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:40:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:40:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:40:47 INFO - Intel Iris: 08:40:47 INFO - Chipset Model: Intel Iris 08:40:47 INFO - Type: GPU 08:40:47 INFO - Bus: Built-In 08:40:47 INFO - VRAM (Dynamic, Max): 1536 MB 08:40:47 INFO - Vendor: Intel (0x8086) 08:40:47 INFO - Device ID: 0x0a2e 08:40:47 INFO - Revision ID: 0x0009 08:40:47 INFO - Displays: 08:40:47 INFO - Display: 08:40:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:40:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:40:47 INFO - Display Serial Number: 1600x1200 60 08:40:47 INFO - Main Display: Yes 08:40:47 INFO - Mirror: Off 08:40:47 INFO - Online: Yes 08:40:47 INFO - Rotation: Supported 08:40:47 INFO - Return code: 0 08:40:47 INFO - Running post-action listener: _resource_record_post_action 08:40:47 INFO - [mozharness: 2016-12-19 16:40:47.178461Z] Finished run-tests step (success) 08:40:47 INFO - Running post-run listener: _resource_record_post_run 08:40:47 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 33149440; Write bytes: 552568320; Read time: 202; Write time: 1709 08:40:47 INFO - TinderboxPrint: CPU usage
54.5% 08:40:47 INFO - TinderboxPrint: I/O read bytes / time
33,149,440 / 202 08:40:47 INFO - TinderboxPrint: I/O write bytes / time
552,568,320 / 1,709 08:40:47 INFO - TinderboxPrint: CPU idle
2,187.4 (45.5%) 08:40:47 INFO - TinderboxPrint: CPU system
970.0 (20.2%) 08:40:47 INFO - TinderboxPrint: CPU user
1,648.3 (34.3%) 08:40:47 INFO - TinderboxPrint: Swap in / out
278,638,592 / 0 08:40:47 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250548736; Write bytes: 251611136; Read time: 18154; Write time: 567 08:40:47 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29942272; Write bytes: 299380224; Read time: 179; Write time: 1133 08:40:47 INFO - Running post-run listener: _upload_blobber_files 08:40:47 INFO - Blob upload gear active. 08:40:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:40:47 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 08:40:47 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'] 08:40:47 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 08:40:48 INFO - (blobuploader) - INFO - Open directory for files ... 08:40:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:40:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:40:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:40:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:40:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:40:49 INFO - (blobuploader) - INFO - Done attempting. 08:40:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:40:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:40:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:40:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:40:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:40:50 INFO - (blobuploader) - INFO - Done attempting. 08:40:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:40:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:40:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:40:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:40:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:40:51 INFO - (blobuploader) - INFO - Done attempting. 08:40:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:40:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:40:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:40:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:40:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:40:54 INFO - (blobuploader) - INFO - Done attempting. 08:40:54 INFO - (blobuploader) - INFO - Iteration through files over. 08:40:54 INFO - Return code: 0 08:40:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:40:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:40:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41489814e9775da5c2ac4e459655a57151e082b7b88e1668ee357390a17073f92de51ed0c90c147a95a0fa32e29ee86f3327ecc82cf8bda29b7792d535fe0f78", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a4c6d211c3d49c7e3e41a0580ae8c6f975cdfb68441454e569415aef093144fde3b36d7adbbcc8f13126a6a366a393d58a78d9a27b830d06967602270acf240", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8291d3e35f82bbf184026f7500296525256e7a3d3ab3ec46a2e210b039d3701ae001ab2e11cdee984ace591092bb073ea0c79b3a4870e0c3c989211a30ea96a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b903edb8673f02f39294a850488aab6f2019a0a9eaa5d9b999058c15c39d1175806ad07e9bc18da81af72078dee1504b2e1fe799fb85a738a28325add7b12994"} 08:40:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:40:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:40:54 INFO - Contents: 08:40:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41489814e9775da5c2ac4e459655a57151e082b7b88e1668ee357390a17073f92de51ed0c90c147a95a0fa32e29ee86f3327ecc82cf8bda29b7792d535fe0f78", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a4c6d211c3d49c7e3e41a0580ae8c6f975cdfb68441454e569415aef093144fde3b36d7adbbcc8f13126a6a366a393d58a78d9a27b830d06967602270acf240", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8291d3e35f82bbf184026f7500296525256e7a3d3ab3ec46a2e210b039d3701ae001ab2e11cdee984ace591092bb073ea0c79b3a4870e0c3c989211a30ea96a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b903edb8673f02f39294a850488aab6f2019a0a9eaa5d9b999058c15c39d1175806ad07e9bc18da81af72078dee1504b2e1fe799fb85a738a28325add7b12994"} 08:40:54 INFO - Running post-run listener: copy_logs_to_upload_dir 08:40:54 INFO - Copying logs to upload dir... 08:40:54 INFO - mkdir: /builds/slave/test/build/upload/logs 08:40:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.222714 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-19 08:40:54.195449) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 08:40:54.198913) ========= 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.KAf200UK7t/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.oNIhjIRvad/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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/41489814e9775da5c2ac4e459655a57151e082b7b88e1668ee357390a17073f92de51ed0c90c147a95a0fa32e29ee86f3327ecc82cf8bda29b7792d535fe0f78", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a4c6d211c3d49c7e3e41a0580ae8c6f975cdfb68441454e569415aef093144fde3b36d7adbbcc8f13126a6a366a393d58a78d9a27b830d06967602270acf240", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8291d3e35f82bbf184026f7500296525256e7a3d3ab3ec46a2e210b039d3701ae001ab2e11cdee984ace591092bb073ea0c79b3a4870e0c3c989211a30ea96a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b903edb8673f02f39294a850488aab6f2019a0a9eaa5d9b999058c15c39d1175806ad07e9bc18da81af72078dee1504b2e1fe799fb85a738a28325add7b12994"} build_url:https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011344 build_url: 'https://queue.taskcluster.net/v1/task/YU_5Wz-ZRxqhJfdP-IwjMg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41489814e9775da5c2ac4e459655a57151e082b7b88e1668ee357390a17073f92de51ed0c90c147a95a0fa32e29ee86f3327ecc82cf8bda29b7792d535fe0f78", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a4c6d211c3d49c7e3e41a0580ae8c6f975cdfb68441454e569415aef093144fde3b36d7adbbcc8f13126a6a366a393d58a78d9a27b830d06967602270acf240", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8291d3e35f82bbf184026f7500296525256e7a3d3ab3ec46a2e210b039d3701ae001ab2e11cdee984ace591092bb073ea0c79b3a4870e0c3c989211a30ea96a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b903edb8673f02f39294a850488aab6f2019a0a9eaa5d9b999058c15c39d1175806ad07e9bc18da81af72078dee1504b2e1fe799fb85a738a28325add7b12994"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 08:40:54.233930) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:40:54.234237) ========= 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.KAf200UK7t/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.oNIhjIRvad/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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.005045 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 08:40:54.282234) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 08:40:54.282618) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 08:40:54.282909) ========= ========= Total master_lag: 0.18 =========