builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0002 starttime: 1448370845.04 results: success (0) buildid: 20151124030553 builduid: 02d4a55c39f24d7aa7b615644e0e2224 revision: 45273bbed8efaface6f5ec56d984cb9faf4fbb6a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.036884) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.037325) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.037608) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.v9gSj4c60S/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.ZwxRnBYXV1/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 /builds/slave/test program finished with exit code 0 elapsedTime=0.005944 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.058614) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.058936) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.073688) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.074010) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.v9gSj4c60S/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.ZwxRnBYXV1/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.005244 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.122453) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.122762) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.123156) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.123443) ========= 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.v9gSj4c60S/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.ZwxRnBYXV1/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 --2015-11-24 05:14:05-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 1.41G=0s 2015-11-24 05:14:05 (1.41 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.189471 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.326152) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.326484) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.v9gSj4c60S/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.ZwxRnBYXV1/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.019681 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:05.375313) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-11-24 05:14:05.375686) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 45273bbed8efaface6f5ec56d984cb9faf4fbb6a --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 45273bbed8efaface6f5ec56d984cb9faf4fbb6a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.v9gSj4c60S/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.ZwxRnBYXV1/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 2015-11-24 05:14:05,450 truncating revision to first 12 chars 2015-11-24 05:14:05,451 Setting DEBUG logging. 2015-11-24 05:14:05,451 attempt 1/10 2015-11-24 05:14:05,451 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/45273bbed8ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 05:14:07,385 unpacking tar archive at: mozilla-central-45273bbed8ef/testing/mozharness/ program finished with exit code 0 elapsedTime=2.176655 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-11-24 05:14:07.566097) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:07.566437) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:07.577661) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:07.578005) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 05:14:07.578476) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2015-11-24 05:14:07.578769) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--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.v9gSj4c60S/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.ZwxRnBYXV1/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 05:14:07 INFO - MultiFileLogger online at 20151124 05:14:07 in /builds/slave/test 05:14:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:14:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:14:07 INFO - {'append_to_log': False, 05:14:07 INFO - 'base_work_dir': '/builds/slave/test', 05:14:07 INFO - 'blob_upload_branch': 'Firefox', 05:14:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:14:07 INFO - 'branch': 'Firefox', 05:14:07 INFO - 'buildbot_json_path': 'buildprops.json', 05:14:07 INFO - 'config_files': ('talos/mac_config.py',), 05:14:07 INFO - 'default_actions': ('clobber', 05:14:07 INFO - 'read-buildbot-config', 05:14:07 INFO - 'download-and-extract', 05:14:07 INFO - 'populate-webroot', 05:14:07 INFO - 'create-virtualenv', 05:14:07 INFO - 'install', 05:14:07 INFO - 'run-tests'), 05:14:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:14:07 INFO - 'download_minidump_stackwalk': True, 05:14:07 INFO - 'download_symbols': 'ondemand', 05:14:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:14:07 INFO - 'tooltool.py': '/tools/tooltool.py', 05:14:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:14:07 INFO - '/tools/misc-python/virtualenv.py')}, 05:14:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:14:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:14:07 INFO - 'installer_path': 'installer.exe', 05:14:07 INFO - 'log_level': 'info', 05:14:07 INFO - 'log_name': 'talos', 05:14:07 INFO - 'log_to_console': True, 05:14:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:14:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:14:07 INFO - 'opt_config_files': (), 05:14:07 INFO - 'pip_index': False, 05:14:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:14:07 INFO - 'cmd': ('bash', 05:14:07 INFO - '-c', 05:14:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:14:07 INFO - 'enabled': True, 05:14:07 INFO - 'halt_on_failure': False, 05:14:07 INFO - 'name': 'check_screen_resolution'},), 05:14:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:14:07 INFO - 'cmd': ('bash', 05:14:07 INFO - '-c', 05:14:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:14:07 INFO - 'enabled': True, 05:14:07 INFO - 'halt_on_failure': False, 05:14:07 INFO - 'name': 'check_screen_resolution'},), 05:14:07 INFO - 'run_cmd_checks_enabled': True, 05:14:07 INFO - 'sps_profile': False, 05:14:07 INFO - 'sps_profile_interval': 0, 05:14:07 INFO - 'suite': 'dromaeojs', 05:14:07 INFO - 'system_bits': '64', 05:14:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:14:07 INFO - 'title': 't-yosemite-r7-0002', 05:14:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:14:07 INFO - 'use_talos_json': True, 05:14:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:14:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:14:07 INFO - 'work_dir': 'build'} 05:14:07 INFO - ##### 05:14:07 INFO - ##### Running clobber step. 05:14:07 INFO - ##### 05:14:07 INFO - Running pre-action listener: _resource_record_pre_action 05:14:07 INFO - Running main action method: clobber 05:14:07 INFO - rmtree: /builds/slave/test/build 05:14:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:14:08 INFO - Running post-action listener: _resource_record_post_action 05:14:08 INFO - ##### 05:14:08 INFO - ##### Running read-buildbot-config step. 05:14:08 INFO - ##### 05:14:08 INFO - Running pre-action listener: _resource_record_pre_action 05:14:08 INFO - Running main action method: read_buildbot_config 05:14:08 INFO - Using buildbot properties: 05:14:08 INFO - { 05:14:08 INFO - "properties": { 05:14:08 INFO - "buildnumber": 10, 05:14:08 INFO - "product": "firefox", 05:14:08 INFO - "script_repo_revision": "production", 05:14:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:14:08 INFO - "repository": "", 05:14:08 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:14:08 INFO - "buildid": "20151124030553", 05:14:08 INFO - "slavename": "t-yosemite-r7-0002", 05:14:08 INFO - "stage_platform": "macosx64", 05:14:08 INFO - "basedir": "/builds/slave/test", 05:14:08 INFO - "nightly_build": "True", 05:14:08 INFO - "project": "", 05:14:08 INFO - "platform": "yosemite_r7", 05:14:08 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:14:08 INFO - "slavebuilddir": "test", 05:14:08 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:14:08 INFO - "branch": "mozilla-central", 05:14:08 INFO - "repo_path": "mozilla-central", 05:14:08 INFO - "pgo_build": "False", 05:14:08 INFO - "builduid": "02d4a55c39f24d7aa7b615644e0e2224", 05:14:08 INFO - "revision": "45273bbed8efaface6f5ec56d984cb9faf4fbb6a" 05:14:08 INFO - }, 05:14:08 INFO - "sourcestamp": { 05:14:08 INFO - "repository": "", 05:14:08 INFO - "hasPatch": false, 05:14:08 INFO - "project": "", 05:14:08 INFO - "branch": "mozilla-central-macosx64-talos", 05:14:08 INFO - "changes": [ 05:14:08 INFO - { 05:14:08 INFO - "category": null, 05:14:08 INFO - "files": [ 05:14:08 INFO - { 05:14:08 INFO - "url": null, 05:14:08 INFO - "name": "https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 05:14:08 INFO - } 05:14:08 INFO - ], 05:14:08 INFO - "repository": "", 05:14:08 INFO - "rev": "45273bbed8efaface6f5ec56d984cb9faf4fbb6a", 05:14:08 INFO - "who": "sendchange", 05:14:08 INFO - "when": 1448370773, 05:14:08 INFO - "number": 6700074, 05:14:08 INFO - "comments": "", 05:14:08 INFO - "project": "", 05:14:08 INFO - "at": "Tue 24 Nov 2015 05:12:53", 05:14:08 INFO - "branch": "mozilla-central-macosx64-talos", 05:14:08 INFO - "revlink": "", 05:14:08 INFO - "properties": [ 05:14:08 INFO - [ 05:14:08 INFO - "buildid", 05:14:08 INFO - "20151124030553", 05:14:08 INFO - "Change" 05:14:08 INFO - ], 05:14:08 INFO - [ 05:14:08 INFO - "builduid", 05:14:08 INFO - "02d4a55c39f24d7aa7b615644e0e2224", 05:14:08 INFO - "Change" 05:14:08 INFO - ], 05:14:08 INFO - [ 05:14:08 INFO - "nightly_build", 05:14:08 INFO - "True", 05:14:08 INFO - "Change" 05:14:08 INFO - ], 05:14:08 INFO - [ 05:14:08 INFO - "pgo_build", 05:14:08 INFO - "False", 05:14:08 INFO - "Change" 05:14:08 INFO - ] 05:14:08 INFO - ], 05:14:08 INFO - "revision": "45273bbed8efaface6f5ec56d984cb9faf4fbb6a" 05:14:08 INFO - } 05:14:08 INFO - ], 05:14:08 INFO - "revision": "45273bbed8efaface6f5ec56d984cb9faf4fbb6a" 05:14:08 INFO - } 05:14:08 INFO - } 05:14:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 05:14:08 INFO - Running post-action listener: _resource_record_post_action 05:14:08 INFO - ##### 05:14:08 INFO - ##### Running download-and-extract step. 05:14:08 INFO - ##### 05:14:08 INFO - Running pre-action listener: _resource_record_pre_action 05:14:08 INFO - Running main action method: download_and_extract 05:14:08 INFO - mkdir: /builds/slave/test/build/tests 05:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:08 INFO - https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:14:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/test_packages.json 05:14:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/test_packages.json 05:14:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 05:14:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 05:14:10 INFO - Downloaded 1149 bytes. 05:14:10 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:14:10 INFO - Using the following test package requirements: 05:14:10 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 05:14:10 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:14:10 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 05:14:10 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:14:10 INFO - u'jsshell-mac.zip'], 05:14:10 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:14:10 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 05:14:10 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 05:14:10 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:14:10 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 05:14:10 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:14:10 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 05:14:10 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:14:10 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 05:14:10 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 05:14:10 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:14:10 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 05:14:10 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:10 INFO - https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:14:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:14:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:14:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:14:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:14:11 INFO - Downloaded 17527044 bytes. 05:14:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] in /builds/slave/test/build/tests 05:14:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:14:11 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 05:14:13 INFO - Return code: 0 05:14:13 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip', u'firefox-45.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:13 INFO - https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:14:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:14:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:14:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:14:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:14:14 INFO - Downloaded 17527044 bytes. 05:14:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] in /builds/slave/test/build/tests 05:14:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:14:14 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 05:14:18 INFO - Return code: 0 05:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:18 INFO - https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:14:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 05:14:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 05:14:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 05:14:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:14:19 INFO - Downloaded 11130997 bytes. 05:14:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] in /builds/slave/test/build/tests 05:14:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 05:14:19 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 05:14:19 INFO - Return code: 0 05:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:19 INFO - https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:14:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 05:14:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 05:14:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg 05:14:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg'}, attempt #1 05:14:22 INFO - Downloaded 110029462 bytes. 05:14:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 05:14:22 INFO - mkdir: /builds/slave/test/properties 05:14:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:14:22 INFO - Writing to file /builds/slave/test/properties/build_url 05:14:22 INFO - Contents: 05:14:22 INFO - build_url:https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 05:14:22 INFO - Running post-action listener: _resource_record_post_action 05:14:22 INFO - Running post-action listener: set_extra_try_arguments 05:14:22 INFO - ##### 05:14:22 INFO - ##### Running populate-webroot step. 05:14:22 INFO - ##### 05:14:22 INFO - Running pre-action listener: _resource_record_pre_action 05:14:22 INFO - Running main action method: populate_webroot 05:14:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:14:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:14:22 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:14:22 INFO - '../fennec_ids.txt'], 05:14:22 INFO - 'tests': ['tcheck2']}, 05:14:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:14:22 INFO - '--tppagecycles', 05:14:22 INFO - '7'], 05:14:22 INFO - 'tests': ['tsvgm']}}, 05:14:22 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:14:22 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:14:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:14:22 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:14:22 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:14:22 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:14:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:14:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:22 INFO - 'talos_options': ['--e10s'], 05:14:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:14:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:22 INFO - 'tests': ['damp', 'tps']}, 05:14:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:22 INFO - 'talos_options': ['--e10s'], 05:14:22 INFO - 'tests': ['damp', 'tps']}, 05:14:22 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:14:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:14:22 INFO - 'other': {'tests': ['a11yr', 05:14:22 INFO - 'ts_paint', 05:14:22 INFO - 'tpaint', 05:14:22 INFO - 'sessionrestore', 05:14:22 INFO - 'sessionrestore_no_auto_restore']}, 05:14:22 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:14:22 INFO - 'tests': ['a11yr', 05:14:22 INFO - 'ts_paint', 05:14:22 INFO - 'tpaint', 05:14:22 INFO - 'sessionrestore', 05:14:22 INFO - 'sessionrestore_no_auto_restore']}, 05:14:22 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:14:22 INFO - 'tests': ['a11yr', 05:14:22 INFO - 'ts_paint', 05:14:22 INFO - 'tpaint', 05:14:22 INFO - 'sessionrestore', 05:14:22 INFO - 'sessionrestore_no_auto_restore']}, 05:14:22 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:14:22 INFO - 'tests': ['a11yr', 05:14:22 INFO - 'ts_paint', 05:14:22 INFO - 'tpaint', 05:14:22 INFO - 'sessionrestore', 05:14:22 INFO - 'sessionrestore_no_auto_restore']}, 05:14:22 INFO - 'other_l64': {'tests': ['a11yr', 05:14:22 INFO - 'ts_paint', 05:14:22 INFO - 'tpaint', 05:14:22 INFO - 'sessionrestore', 05:14:22 INFO - 'sessionrestore_no_auto_restore']}, 05:14:22 INFO - 'other_nol64': {'tests': ['a11yr', 05:14:22 INFO - 'ts_paint', 05:14:22 INFO - 'tpaint', 05:14:22 INFO - 'sessionrestore', 05:14:22 INFO - 'sessionrestore_no_auto_restore']}, 05:14:22 INFO - 'svgr': {'tests': ['tsvgx', 05:14:22 INFO - 'tsvgr_opacity', 05:14:22 INFO - 'tart', 05:14:22 INFO - 'tscrollx', 05:14:22 INFO - 'cart']}, 05:14:22 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:14:22 INFO - 'tests': ['tsvgx', 05:14:22 INFO - 'tsvgr_opacity', 05:14:22 INFO - 'tart', 05:14:22 INFO - 'tscrollx', 05:14:22 INFO - 'cart']}, 05:14:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:22 INFO - 'tests': ['tp5o']}, 05:14:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:22 INFO - 'talos_options': ['--e10s'], 05:14:22 INFO - 'tests': ['tp5o']}, 05:14:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:22 INFO - 'talos_options': ['--xperf_path', 05:14:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:14:22 INFO - 'tests': ['tp5n']}, 05:14:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:22 INFO - 'talos_options': ['--e10s', 05:14:22 INFO - '--xperf_path', 05:14:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:14:22 INFO - 'tests': ['tp5n']}}, 05:14:22 INFO - 'talos.zip': {'path': '', 05:14:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:14:22 INFO - Running post-action listener: _resource_record_post_action 05:14:22 INFO - ##### 05:14:22 INFO - ##### Running create-virtualenv step. 05:14:22 INFO - ##### 05:14:22 INFO - Running pre-action listener: _resource_record_pre_action 05:14:22 INFO - Running main action method: create_virtualenv 05:14:22 INFO - Creating virtualenv /builds/slave/test/build/venv 05:14:22 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 05:14:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:14:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:14:22 INFO - Using real prefix '/tools/python27' 05:14:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:14:23 INFO - Installing distribute.............................................................................................................................................................................................done. 05:14:26 INFO - Installing pip.................done. 05:14:26 INFO - Return code: 0 05:14:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x1076fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076da228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8384c77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076219f0>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076148c8>, '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.v9gSj4c60S/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.ZwxRnBYXV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 05:14:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 05:14:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 05:14:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9gSj4c60S/Render', 05:14:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:26 INFO - 'HOME': '/Users/cltbld', 05:14:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:26 INFO - 'LOGNAME': 'cltbld', 05:14:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:26 INFO - 'MOZ_NO_REMOTE': '1', 05:14:26 INFO - 'NO_EM_RESTART': '1', 05:14:26 INFO - 'PAGER': '/bin/cat', 05:14:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:26 INFO - 'PWD': '/builds/slave/test', 05:14:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:26 INFO - 'SHELL': '/bin/bash', 05:14:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwxRnBYXV1/Listeners', 05:14:26 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 05:14:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:26 INFO - 'USER': 'cltbld', 05:14:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:26 INFO - 'XPC_FLAGS': '0x0', 05:14:26 INFO - 'XPC_SERVICE_NAME': '0', 05:14:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:26 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 05:14:26 INFO - Cleaning up... 05:14:26 INFO - Return code: 0 05:14:26 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1076fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076da228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8384c77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076219f0>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076148c8>, '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.v9gSj4c60S/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.ZwxRnBYXV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 05:14:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1'] in /builds/slave/test/build 05:14:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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>=0.7.1 05:14:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9gSj4c60S/Render', 05:14:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:26 INFO - 'HOME': '/Users/cltbld', 05:14:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:26 INFO - 'LOGNAME': 'cltbld', 05:14:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:26 INFO - 'MOZ_NO_REMOTE': '1', 05:14:26 INFO - 'NO_EM_RESTART': '1', 05:14:26 INFO - 'PAGER': '/bin/cat', 05:14:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:26 INFO - 'PWD': '/builds/slave/test', 05:14:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:26 INFO - 'SHELL': '/bin/bash', 05:14:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwxRnBYXV1/Listeners', 05:14:26 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 05:14:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:26 INFO - 'USER': 'cltbld', 05:14:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:26 INFO - 'XPC_FLAGS': '0x0', 05:14:26 INFO - 'XPC_SERVICE_NAME': '0', 05:14:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:27 INFO - Downloading/unpacking psutil>=0.7.1 05:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:29 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 05:14:29 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:14:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:14:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:14:29 INFO - Installing collected packages: psutil 05:14:29 INFO - Running setup.py install for psutil 05:14:29 INFO - building 'psutil._psutil_osx' extension 05:14:29 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 05:14:30 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 05:14:30 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 05:14:30 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 05:14:30 INFO - building 'psutil._psutil_posix' extension 05:14:30 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 05:14:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:14:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:14:30 INFO - ^ 05:14:30 INFO - 1 warning generated. 05:14:30 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 05:14:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:14:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:14:30 INFO - Successfully installed psutil 05:14:30 INFO - Cleaning up... 05:14:30 INFO - Return code: 0 05:14:30 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1076fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076da228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8384c77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076219f0>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076148c8>, '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.v9gSj4c60S/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.ZwxRnBYXV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 05:14:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0'] in /builds/slave/test/build 05:14:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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.0.0 05:14:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9gSj4c60S/Render', 05:14:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:30 INFO - 'HOME': '/Users/cltbld', 05:14:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:30 INFO - 'LOGNAME': 'cltbld', 05:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:30 INFO - 'MOZ_NO_REMOTE': '1', 05:14:30 INFO - 'NO_EM_RESTART': '1', 05:14:30 INFO - 'PAGER': '/bin/cat', 05:14:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:30 INFO - 'PWD': '/builds/slave/test', 05:14:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:30 INFO - 'SHELL': '/bin/bash', 05:14:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwxRnBYXV1/Listeners', 05:14:30 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 05:14:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:30 INFO - 'USER': 'cltbld', 05:14:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:30 INFO - 'XPC_FLAGS': '0x0', 05:14:30 INFO - 'XPC_SERVICE_NAME': '0', 05:14:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:30 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:14:30 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 05:14:30 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 05:14:30 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 05:14:30 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 05:14:30 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 05:14:30 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 05:14:33 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:14:33 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:14:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:14:33 INFO - Installing collected packages: mozsystemmonitor 05:14:33 INFO - Running setup.py install for mozsystemmonitor 05:14:33 INFO - Successfully installed mozsystemmonitor 05:14:33 INFO - Cleaning up... 05:14:33 INFO - Return code: 0 05:14:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:14:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x1076fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076da228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8384c77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076219f0>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076148c8>, '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.v9gSj4c60S/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.ZwxRnBYXV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 05:14:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 05:14:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 05:14:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9gSj4c60S/Render', 05:14:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:33 INFO - 'HOME': '/Users/cltbld', 05:14:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:33 INFO - 'LOGNAME': 'cltbld', 05:14:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:33 INFO - 'MOZ_NO_REMOTE': '1', 05:14:33 INFO - 'NO_EM_RESTART': '1', 05:14:33 INFO - 'PAGER': '/bin/cat', 05:14:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:33 INFO - 'PWD': '/builds/slave/test', 05:14:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:33 INFO - 'SHELL': '/bin/bash', 05:14:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwxRnBYXV1/Listeners', 05:14:33 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 05:14:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:33 INFO - 'USER': 'cltbld', 05:14:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:33 INFO - 'XPC_FLAGS': '0x0', 05:14:33 INFO - 'XPC_SERVICE_NAME': '0', 05:14:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:33 INFO - Downloading/unpacking blobuploader==1.2.4 05:14:33 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 05:14:33 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 05:14:33 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 05:14:33 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 05:14:33 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 05:14:33 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 05:14:35 INFO - Downloading blobuploader-1.2.4.tar.gz 05:14:35 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:14:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:14:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:14:35 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 05:14:35 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 05:14:35 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 05:14:35 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 05:14:35 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 05:14:35 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 05:14:36 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:14:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:14:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:14:36 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 05:14:36 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 05:14:36 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 05:14:36 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 05:14:36 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 05:14:36 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 05:14:36 INFO - Downloading docopt-0.6.1.tar.gz 05:14:36 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:14:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:14:37 INFO - Installing collected packages: blobuploader, requests, docopt 05:14:37 INFO - Running setup.py install for blobuploader 05:14:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:14:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:14:37 INFO - Running setup.py install for requests 05:14:37 INFO - Running setup.py install for docopt 05:14:37 INFO - Successfully installed blobuploader requests docopt 05:14:37 INFO - Cleaning up... 05:14:37 INFO - Return code: 0 05:14:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x1076fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076da228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8384c77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076219f0>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076148c8>, '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.v9gSj4c60S/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.ZwxRnBYXV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 05:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 05:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --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 05:14:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9gSj4c60S/Render', 05:14:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:37 INFO - 'HOME': '/Users/cltbld', 05:14:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:37 INFO - 'LOGNAME': 'cltbld', 05:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:37 INFO - 'MOZ_NO_REMOTE': '1', 05:14:37 INFO - 'NO_EM_RESTART': '1', 05:14:37 INFO - 'PAGER': '/bin/cat', 05:14:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:37 INFO - 'PWD': '/builds/slave/test', 05:14:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:37 INFO - 'SHELL': '/bin/bash', 05:14:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwxRnBYXV1/Listeners', 05:14:37 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 05:14:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:37 INFO - 'USER': 'cltbld', 05:14:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:37 INFO - 'XPC_FLAGS': '0x0', 05:14:37 INFO - 'XPC_SERVICE_NAME': '0', 05:14:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:38 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-w5Y5i2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:38 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-3ySnTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:38 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-oiQwnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:38 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-ctUE2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:38 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-gcMMzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:38 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-67gNuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:38 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-biFYUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:38 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-PYzMLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:39 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Ltq_ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:39 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-hmdWHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:39 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-yc6kyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:39 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-7ICBzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:39 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-txhPRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:39 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-SDdRDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:39 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-VGRmRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:39 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-QHNkd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:39 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-cu0pkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:14:39 INFO - Running setup.py install for manifestparser 05:14:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:14:40 INFO - Running setup.py install for mozcrash 05:14:40 INFO - Running setup.py install for mozdebug 05:14:40 INFO - Running setup.py install for mozdevice 05:14:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:14:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:14:40 INFO - Running setup.py install for mozfile 05:14:40 INFO - Running setup.py install for mozhttpd 05:14:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:14:40 INFO - Running setup.py install for mozinfo 05:14:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:14:41 INFO - Running setup.py install for mozInstall 05:14:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:14:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:14:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:14:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:14:41 INFO - Running setup.py install for mozleak 05:14:41 INFO - Running setup.py install for mozlog 05:14:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:14:41 INFO - Running setup.py install for moznetwork 05:14:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:14:41 INFO - Running setup.py install for mozprocess 05:14:41 INFO - Running setup.py install for mozprofile 05:14:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:14:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:14:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:14:41 INFO - Running setup.py install for mozrunner 05:14:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:14:42 INFO - Running setup.py install for mozscreenshot 05:14:42 INFO - Running setup.py install for moztest 05:14:42 INFO - Running setup.py install for mozversion 05:14:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:14:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:14:42 INFO - Cleaning up... 05:14:42 INFO - Return code: 0 05:14:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x1076fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076da228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8384c77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076219f0>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076148c8>, '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.v9gSj4c60S/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.ZwxRnBYXV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 05:14:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 05:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 05:14:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9gSj4c60S/Render', 05:14:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:42 INFO - 'HOME': '/Users/cltbld', 05:14:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:42 INFO - 'LOGNAME': 'cltbld', 05:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:42 INFO - 'MOZ_NO_REMOTE': '1', 05:14:42 INFO - 'NO_EM_RESTART': '1', 05:14:42 INFO - 'PAGER': '/bin/cat', 05:14:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:42 INFO - 'PWD': '/builds/slave/test', 05:14:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:42 INFO - 'SHELL': '/bin/bash', 05:14:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwxRnBYXV1/Listeners', 05:14:42 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 05:14:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:42 INFO - 'USER': 'cltbld', 05:14:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:42 INFO - 'XPC_FLAGS': '0x0', 05:14:42 INFO - 'XPC_SERVICE_NAME': '0', 05:14:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:42 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Vyu8Dj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:42 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)) 05:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:42 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-gqR6QV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 05:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:43 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-QcHX0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:43 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)) 05:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:43 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-s4QipE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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)) 05:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:43 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-tH1tz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:43 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)) 05:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:43 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-wDl2XQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:43 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)) 05:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:43 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-32KPIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:43 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)) 05:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:43 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-zktEXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:43 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)) 05:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:43 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-DJUQjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:43 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)) 05:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:43 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-KJ7es2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 05:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:43 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-I68r_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:44 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)) 05:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:44 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-bbEzGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 05:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:44 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-NlVnT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 05:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:44 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Oxz8Zm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 05:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:44 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-_04cS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:44 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)) 05:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:44 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-CpqwmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:44 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)) 05:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:44 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Vtpvf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:44 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)) 05:14:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:44 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)) 05:14:44 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:44 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14:44 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 05:14:44 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 05:14:44 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 05:14:44 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 05:14:44 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 05:14:44 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 05:14:46 INFO - Downloading blessings-1.5.1.tar.gz 05:14:46 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 05:14:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:14:46 INFO - Installing collected packages: blessings 05:14:46 INFO - Running setup.py install for blessings 05:14:47 INFO - Successfully installed blessings 05:14:47 INFO - Cleaning up... 05:14:47 INFO - Return code: 0 05:14:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:14:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:47 INFO - Reading from file tmpfile_stdout 05:14:47 INFO - Current package versions: 05:14:47 INFO - blessings == 1.5.1 05:14:47 INFO - blobuploader == 1.2.4 05:14:47 INFO - docopt == 0.6.1 05:14:47 INFO - manifestparser == 1.1 05:14:47 INFO - mozInstall == 1.12 05:14:47 INFO - mozcrash == 0.16 05:14:47 INFO - mozdebug == 0.1 05:14:47 INFO - mozdevice == 0.47 05:14:47 INFO - mozfile == 1.2 05:14:47 INFO - mozhttpd == 0.7 05:14:47 INFO - mozinfo == 0.9 05:14:47 INFO - mozleak == 0.1 05:14:47 INFO - mozlog == 3.1 05:14:47 INFO - moznetwork == 0.27 05:14:47 INFO - mozprocess == 0.22 05:14:47 INFO - mozprofile == 0.27 05:14:47 INFO - mozrunner == 6.11 05:14:47 INFO - mozscreenshot == 0.1 05:14:47 INFO - mozsystemmonitor == 0.0 05:14:47 INFO - moztest == 0.7 05:14:47 INFO - mozversion == 1.4 05:14:47 INFO - psutil == 3.1.1 05:14:47 INFO - requests == 1.2.3 05:14:47 INFO - wsgiref == 0.1.2 05:14:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x1076fd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076da228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8384c77b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076219f0>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076148c8>, '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.v9gSj4c60S/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.ZwxRnBYXV1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 05:14:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 05:14:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 05:14:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9gSj4c60S/Render', 05:14:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:47 INFO - 'HOME': '/Users/cltbld', 05:14:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:47 INFO - 'LOGNAME': 'cltbld', 05:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:47 INFO - 'MOZ_NO_REMOTE': '1', 05:14:47 INFO - 'NO_EM_RESTART': '1', 05:14:47 INFO - 'PAGER': '/bin/cat', 05:14:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:47 INFO - 'PWD': '/builds/slave/test', 05:14:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:47 INFO - 'SHELL': '/bin/bash', 05:14:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwxRnBYXV1/Listeners', 05:14:47 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 05:14:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:47 INFO - 'USER': 'cltbld', 05:14:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:47 INFO - 'XPC_FLAGS': '0x0', 05:14:47 INFO - 'XPC_SERVICE_NAME': '0', 05:14:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:47 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 1)) 05:14:47 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 2)) 05:14:47 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 3)) 05:14:47 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 4)) 05:14:47 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 5)) 05:14:47 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 6)) 05:14:47 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 7)) 05:14:47 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 8)) 05:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:14:47 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 3)) 05:14:47 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.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:14:47 INFO - Cleaning up... 05:14:47 INFO - Return code: 0 05:14:47 INFO - Running post-action listener: _resource_record_post_action 05:14:47 INFO - Running post-action listener: _start_resource_monitoring 05:14:47 INFO - Starting resource monitoring. 05:14:47 INFO - ##### 05:14:47 INFO - ##### Running install step. 05:14:47 INFO - ##### 05:14:47 INFO - Running pre-action listener: _resource_record_pre_action 05:14:47 INFO - Running main action method: install 05:14:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:47 INFO - Reading from file tmpfile_stdout 05:14:47 INFO - Detecting whether we're running mozinstall >=1.0... 05:14:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:14:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:14:48 INFO - Reading from file tmpfile_stdout 05:14:48 INFO - Output received: 05:14:48 INFO - Usage: mozinstall [options] installer 05:14:48 INFO - Options: 05:14:48 INFO - -h, --help show this help message and exit 05:14:48 INFO - -d DEST, --destination=DEST 05:14:48 INFO - Directory to install application into. [default: 05:14:48 INFO - "/builds/slave/test"] 05:14:48 INFO - --app=APP Application being installed. [default: firefox] 05:14:48 INFO - mkdir: /builds/slave/test/build/application 05:14:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:15:18 INFO - Reading from file tmpfile_stdout 05:15:18 INFO - Output received: 05:15:18 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:15:18 INFO - Running post-action listener: _resource_record_post_action 05:15:18 INFO - ##### 05:15:18 INFO - ##### Running run-tests step. 05:15:18 INFO - ##### 05:15:18 INFO - Running pre-action listener: _resource_record_pre_action 05:15:18 INFO - Running main action method: run_tests 05:15:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:15:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:15:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:15:18 INFO - 2015-11-24 05:15:18.545 screenresolution[1966:16334] starting screenresolution argv=screenresolution get 05:15:18 INFO - 2015-11-24 05:15:18.548 screenresolution[1966:16334] Display 0: 1600x1200x32@60 05:15:18 INFO - 2015-11-24 05:15:18.553 screenresolution[1967:16337] starting screenresolution argv=screenresolution list 05:15:18 INFO - Available Modes on Display 0 05:15:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:15:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:15:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:15:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:15:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:15:18 INFO - Intel Iris: 05:15:18 INFO - Chipset Model: Intel Iris 05:15:18 INFO - Type: GPU 05:15:18 INFO - Bus: Built-In 05:15:18 INFO - VRAM (Dynamic, Max): 1536 MB 05:15:18 INFO - Vendor: Intel (0x8086) 05:15:18 INFO - Device ID: 0x0a2e 05:15:18 INFO - Revision ID: 0x0009 05:15:18 INFO - Displays: 05:15:18 INFO - Display: 05:15:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:15:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:15:18 INFO - Display Serial Number: 1600x1200 60 05:15:18 INFO - Main Display: Yes 05:15:18 INFO - Mirror: Off 05:15:18 INFO - Online: Yes 05:15:18 INFO - Rotation: Supported 05:15:18 INFO - Return code: 0 05:15:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:15:18 INFO - Python 2.7.3 05:15:18 INFO - Return code: 0 05:15:18 INFO - grabbing minidump binary from tooltool 05:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:18 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076219f0>, '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 0x107380db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076148c8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:15:18 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 05:15:18 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 05:15:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:15:18 INFO - Return code: 0 05:15:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:15:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:15:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:15:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:15:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:15:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--webServer', 'localhost'] in /builds/slave/test/build 05:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0002 --webServer localhost 05:15:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9gSj4c60S/Render', 05:15:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:18 INFO - 'HOME': '/Users/cltbld', 05:15:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:18 INFO - 'LOGNAME': 'cltbld', 05:15:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:15:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:18 INFO - 'MOZ_NO_REMOTE': '1', 05:15:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:15:18 INFO - 'NO_EM_RESTART': '1', 05:15:18 INFO - 'PAGER': '/bin/cat', 05:15:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:18 INFO - 'PWD': '/builds/slave/test', 05:15:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:15:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:18 INFO - 'SHELL': '/bin/bash', 05:15:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwxRnBYXV1/Listeners', 05:15:18 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 05:15:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:18 INFO - 'USER': 'cltbld', 05:15:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:18 INFO - 'XPC_FLAGS': '0x0', 05:15:18 INFO - 'XPC_SERVICE_NAME': '0', 05:15:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--webServer', 'localhost'] with output_timeout 3600 05:15:19 INFO - mozversion INFO | application_buildid: 20151124030553 05:15:19 INFO - mozversion INFO | application_changeset: 45273bbed8efaface6f5ec56d984cb9faf4fbb6a 05:15:19 INFO - mozversion INFO | application_display_name: Nightly 05:15:19 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:15:19 INFO - mozversion INFO | application_name: Firefox 05:15:19 INFO - mozversion INFO | application_remotingname: firefox 05:15:19 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 05:15:19 INFO - mozversion INFO | application_vendor: Mozilla 05:15:19 INFO - mozversion INFO | application_version: 45.0a1 05:15:19 INFO - mozversion INFO | platform_buildid: 20151124030553 05:15:19 INFO - mozversion INFO | platform_changeset: 45273bbed8efaface6f5ec56d984cb9faf4fbb6a 05:15:19 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 05:15:19 INFO - mozversion INFO | platform_version: 45.0a1 05:15:19 INFO - 2015-11-24 05:15:19,102 DEBUG : using testdate: 1448370919 05:15:19 INFO - 2015-11-24 05:15:19,102 DEBUG : actual date: 1448370919 05:15:19 INFO - 2015-11-24 05:15:19,190 INFO : starting webserver on 'localhost:51258' 05:15:19 INFO - 2015-11-24 05:15:19,191 INFO : Starting test suite t-yosemite-r7-0002 05:15:19 INFO - 2015-11-24 05:15:19,191 INFO : Starting test dromaeo_css 05:15:19 INFO - 2015-11-24 05:15:19,191 DEBUG : operating with platform_type : mac_ 05:15:19 INFO - 2015-11-24 05:15:19,192 INFO : Initialising browser for dromaeo_css test... 05:15:19 INFO - 2015-11-24 05:15:19,204 DEBUG : command line: /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpVAA3Sb/profile http://localhost:51258/getInfo.html 05:15:21 INFO - 2015-11-24 05:15:21,523 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:15:21 INFO - 2015-11-24 05:15:21,523 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:15:21 INFO - 2015-11-24 05:15:21,523 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 05:15:21 INFO - 2015-11-24 05:15:21,523 DEBUG : BROWSER_OUTPUT: __metrics 05:15:21 INFO - 2015-11-24 05:15:21,541 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:15:22 INFO - 2015-11-24 05:15:22,384 INFO : Browser initialized. 05:15:22 INFO - 2015-11-24 05:15:22,385 INFO : Running cycle 1/1 for dromaeo_css test... 05:15:22 INFO - 2015-11-24 05:15:22,385 DEBUG : command line: /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpVAA3Sb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:15:34 INFO - 2015-11-24 05:15:34,204 DEBUG : BROWSER_OUTPUT: RSS: Main: 226144256 05:15:34 INFO - 2015-11-24 05:15:34,204 DEBUG : BROWSER_OUTPUT: 05:16:25 INFO - 2015-11-24 05:16:25,970 DEBUG : BROWSER_OUTPUT: 1448370985967 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] 05:18:36 INFO - 2015-11-24 05:18:36,492 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51258/tests/dromaeo/cssquery-ext.html) 05:18:37 INFO - 2015-11-24 05:18:37,806 DEBUG : BROWSER_OUTPUT: RSS: Main: 336846848 05:18:37 INFO - 2015-11-24 05:18:37,807 DEBUG : BROWSER_OUTPUT: 05:21:40 INFO - 2015-11-24 05:21:40,305 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/dromaeo/cssquery-ext.html (next: http://localhost:51258/tests/dromaeo/cssquery-jquery.html) 05:21:40 INFO - 2015-11-24 05:21:40,898 DEBUG : BROWSER_OUTPUT: RSS: Main: 359698432 05:21:40 INFO - 2015-11-24 05:21:40,898 DEBUG : BROWSER_OUTPUT: 05:24:43 INFO - 2015-11-24 05:24:43,041 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51258/tests/dromaeo/cssquery-mootools.html) 05:24:43 INFO - 2015-11-24 05:24:43,624 DEBUG : BROWSER_OUTPUT: RSS: Main: 344645632 05:24:43 INFO - 2015-11-24 05:24:43,624 DEBUG : BROWSER_OUTPUT: 05:27:45 INFO - 2015-11-24 05:27:45,683 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51258/tests/dromaeo/cssquery-prototype.html) 05:27:46 INFO - 2015-11-24 05:27:46,289 DEBUG : BROWSER_OUTPUT: RSS: Main: 317345792 05:27:46 INFO - 2015-11-24 05:27:46,290 DEBUG : BROWSER_OUTPUT: 05:30:49 INFO - 2015-11-24 05:30:49,533 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51258/tests/dromaeo/cssquery-yui.html) 05:30:50 INFO - 2015-11-24 05:30:50,141 DEBUG : BROWSER_OUTPUT: RSS: Main: 323489792 05:30:50 INFO - 2015-11-24 05:30:50,141 DEBUG : BROWSER_OUTPUT: 05:33:53 INFO - 2015-11-24 05:33:53,080 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/dromaeo/cssquery-yui.html (next: http://localhost:51258/tests/dromaeo/cssquery-dojo.html) 05:33:53 INFO - 2015-11-24 05:33:53,343 DEBUG : BROWSER_OUTPUT: RSS: Main: 418713600 05:33:53 INFO - 2015-11-24 05:33:53,343 DEBUG : BROWSER_OUTPUT: 05:33:53 INFO - 2015-11-24 05:33:53,343 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:33:53 INFO - 2015-11-24 05:33:53,343 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:33:53 INFO - 2015-11-24 05:33:53,343 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:33:53 INFO - 2015-11-24 05:33:53,343 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:33:53 INFO - 2015-11-24 05:33:53,344 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3139.7959132656374;3498.8775392861253;3512.8946131616062;3526.964730352696;3573.4639804029403;5664.801731939383;5697.344805347381;5732.254806875106;5757.596968212217;5774.711264436772;4858.659893807435;5267.499587539187;5743.483086522212;5791.623544469607;5871.76512939483;4766.880827979311;5156.355455568054;5198.428172900978;5242.266082748418;5300.469953004707;5322.148456247001;5333.626646134772;5340.118880384752;5343.332083489564;5408.296921400212;5093.083245015896;5201.687898726089;5293.073712100367;5300.390455097675;5326.573874090082;5212.478752124795;5240.109181439165;5242.501962313574;5249.842504724865;5258.027264955957;5161.406438259611;5250.65870718402;5255.947440525584;5257.158854583248;5257.290265814105;5727.369989301215;5732.22614946981;5733.913991290134;5749.223854779593;5776.913346299809;6205.720742566596;6253.3433989430905;6257;6266.49868010558;6270.122182894398;6256.87486250277;6311.905321420182;6347.682615869188;6354.110424540568;6355.777547785851;5943.940560594382;5951.434613711691;5957.76168953237;5958.493528050121;5971.611845230046;6177.69111544421;6312.936870631327;6320.652364119926;6337.714802833883;6403.615783053032;6079.148919151322;6217.875642487215;6220.066989951543;6234.719437625317;6235.220597425322;6314.431701146918;6317.6209427434505;6318.273398559132;6323.715432805535;6330.3669633036325;6094.634321940698;6188;6311.526635502356;6315.715792789336;6323.336049714806;6138.878900475427;6220.502359811204;6244.687765611701;6281.466075383643;6292.590981586274;5002.374703162105;5006.973570418056;5022.096022715946;5027.974860125676;5047.570956468668;4334.718243314174;4486.147631949919;4491.214037543416;4494.5505449454795;4509;5692.82921512347;5762.798302059407;5769.68266745333;5775.971120144372;5781.075027995585;6229.470495007975;6278.843028924313;6288.119663247149;6290.276618188876;6293.3391993840905;5728.77084916607;5734.569907256972;5742.052561327334;5748.48263656273;5845.678487683133;5437.429069947678;5451.127819548853;5473.124300111963;5478.287822583039;5513.145462453326;6358.427741503195;6358.982562789932;6361.141245931833;6368.968155159288;6381.8723625528155;5901.616394934487;6044.758209671564;6299.338569450142;6309;6312.368763123651;6236.875262494816;6302.1492098566105;6345.46063584582;6356.586821856565;6361.61830290184;6252.343503932022;6258;6267.5299352549655;6278.874422511615;6288.591241569284;6138.600990935575;6233.40782625668;6311.337309582428;6328.841778955563;6334.714937827716;5859.472647461749;6112.816615501542;6121.462011337575;6124.683193113502;6129.793274421294;6146.293176284785;6162.445379915829;6175;6178.814635560941;6203.47270481996;5008.84973450797;5902.5277977762535;6000.549958753199;6048.60684055535;6052.6973651316375;5956.523478121827;6099.725512351866;6126.203593532812;6142.447179753844;6143.740533190775;6058.454739073504;6083.300420451527;6085.817425477243;6086.69566521681;6094.78668246627 05:33:53 INFO - 2015-11-24 05:33:53,344 DEBUG : BROWSER_OUTPUT: |1;ext.html;583.8715482593827;584.1325631437317;591.3229352391509;603.9154518367428;637.0794202377566;32229.355412891757;32422.513662295085;40831;41368.793156034255;41397.79301103498;43616.90957726059;43983.78008109955;44103;44114.55885441144;44156;8864.645414183426;9772.462514561696;9956.352837065568;9977.700668979905;9996.500174991257;8786.6924657637;8835.690750823722;12028.45871935765;12144.757104857941;12184.208026478294;11875.287482751019;11946.820797688042;11963.641090767247;11965.641030769091;11999.160058795844;8879.200871921557;9011.188992990597;11940.820887686692;11971.521139154424;11979.221350612226;8853.114688531095;11928.284302941851;12097.092718046093;12117.939410302892;12119.697007574792;40712.57505987306;40969.79515102405;40995.590044099474;41120.56078037254;41136.38295425541;3160.7787454878166;3186.5857438533076;3202.151429871086;3542.875999340011;3728.6084961079237;28446.43107137866;28596;28637.427251454854;28804.279893002633;28829.135125946254;2763.2539214411995;2793.6507936507937;2805.3968396794785;2878.9923526765465;2912.3156058326276;399133;475635;476695;478050;478137;58243.4175658238;59339;67632.6618366905;68044.97932531017;68131;59980.70009649924;60051.69974150101;60239.09641355383;71704.64147679228;72844.90732639015;15653.217339132998;15839.604009899846;15839.604009899846;15844.683106337809;15866.52400427989;9596.520173991272;11917.046636269077;11926.463309150935;11951.163418560618;11958.282503049672;17189.914050429918;20813.959302034837;20981.475463113544;21255;21266.36200913975;9191.667208254765;9336.019717929656;9349.439033658002;9365.391249568756;9459.858102128474;777.9611019449005;993.8906720260766;1011.549860312154;1047.5648361744459;1163.5462169753803;29034.709652903624;29334.559981600294;29670.851645741634;29860;29900;1035.1098055672117;1070.6306324318098;1080.3949788118678;1083.712816103733;1177.364223319398;14600.860814924843;14639.780403293958;14677.339519721638;14991;15031.022983506036;517636;523167;523498;523500;523807;46968;57080.714596427584;57324.42675573274;57326.14010789841;57328.42671573231;1262.5959692898368;1437.7412065828041;1450.1371683848095;1474.9115053096848;1475.4172102019427;22974.310770676908;27732.44535109327;28308.71691283061;28359.291017723895;30325.241868953453;49323.26015109848;49594.50405495899;49661.006779864925;50149.49850501448;52822.73588632032;544648;551485;551777;552248;554724;26610;29417.411651766415;30008.099757007323;30607;30685.693143068285;42666;42790.35814462848;42846.78576607097;43496.56503434925;44106.11787764291;37336;37692.246155076195;38555.22889542249;38679.41980870345;38827;34324.828375857964;36619.26761464809;37776;37857.62142378541;37910.62089379071;36741.79549227055;36794.816025919696;37550.624493754716;37576.81211593925;37837.24325513529;32319.51520727144;33267.334653306316;33609.966803983676;33759;33810;24979.750202497744;25110.874445627654;25194;25310.74689253084;25378.481106997944 05:33:53 INFO - 2015-11-24 05:33:53,345 DEBUG : BROWSER_OUTPUT: |2;jquery.html;355.563523365603;357.10366978883;360.17519879476004;362.6120051544848;362.94555816627496;15985.440509582138;17226.82773172268;19603.313884014064;20617.38147855565;21490.462738431528;16595;17047.659046819055;17049.91475042623;20675.276365327216;21088;5123.615728820335;5334.413214546396;5343.385510666284;5489.533389661883;5669.09294512878;3876.7673939563574;4007.2185923744883;5637.182608521762;5666.405027472118;5676.091825307952;4011.83952641894;4343.522212556616;5761.567882408815;5772.855678608026;5776.364599894008;5404.189371594253;5551.77792888284;5713.800016999385;5772.6536407815665;5780.2196703444915;5254.159334506498;5325.227841962933;5340.172273297606;5342.305500284977;5342.786288548454;23978.400539986465;24232.757672423402;24319.02723891042;24585.38536536583;25156.11953581616;2043.673012318022;2052.9486762830898;2162.524244666171;2223.466368071667;2230.4423894026495;9436.150746432786;9727.805443891082;9772.95113524435;12777.10560260782;13379.86271166952;1568.8390590962606;1598.5603958911393;1697.8098253253288;1743.4072415378705;1769.8053214146435;96824;97861.51069244609;99650.00349996479;101210;103545.96454035438;10566.207534434949;10946.343219406861;11083.556657733601;11096.445177741081;11099.389533575732;13169.407376668072;13197.538086166938;13206.801897971538;13242.470301188037;13266.668333291746;8036.236557527026;8144.638054964113;8147.185281471805;8151.633176507072;8193.344532437392;8088.433809633387;8091.716789912325;8111;8155.551444670481;8160.755177344689;4343.69594128408;4360.648199058302;4362.8909277267685;4371.693981421334;4399.076193999296;1719.974200386995;1766.143420441085;1768.4164225805453;1772.6188869393143;1827.360423851668;891.3047822698305;908.7637214324191;932.8554074118523;1027.9434631095212;1036.2073014144187;14529.31207726574;18850.81149188518;19391;20588.588228235352;20656.690149647766;804.6218277409608;833.6332013913858;917.6512925088424;961.6730311694058;968.4576637083256;10321.690349289533;10519.053285204369;10523.26337156407;10533.787904539979;10550.683479495627;744456;752371.3814309372;753991;769712;770669;116404;120418;121077;122209.77790222161;122474.77525224634;1257.2079589858513;1257.2142410993129;1258.79229927061;1261.4701825233424;1282.5062350994747;15625.453109141581;17101.48695539136;17902.46292611224;18172.636547268718;18191.09044547741;49115;49152.65236034369;49436.258456123905;49751.253731193334;50529.73675658138;70376.64811675908;71220.64389678018;71452.64273678599;73167;74304.62847685727;9604.375715578464;9751.853722194031;9850.113489785954;9854.753631159278;10010.249231307535;11672.941635291769;11735.178537502634;11746.001589864958;18502.98233597165;19725.802741972395;15924.124173170123;16066.518004459886;16341.67316653684;16420.83579164193;16542.751858721887;15561.84438155604;15578.766318505459;15666.76499852526;15794.526164215093;16445.91777041107;12787.680307992374;13793.172409655454;16501.669966600843;16920.238589263758;16955.830441695918;7692.153863075169;8468.915310846813;8828.823423531621;9672.41965482045;9709.320347575602;15350.618444340063;15540;15763.448279310169;15783.921080394524;15881 05:33:53 INFO - 2015-11-24 05:33:53,346 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1420.2827572076103;1604.1898841085253;1685.7050016247154;1870.728744332072;2042.7038079478484;7538.773836784899;7694.345980591649;8210.671573137068;8520.318374530038;8542;8222.712205072823;8374.455660382071;8426.199511046456;8441.240288374045;8446.704365347214;2389.1876761900994;2412.626042963335;2413.9517209655796;2414.625733011386;2417.2627348658652;2402.6876506054277;2430.3195105370523;2437.731849496554;2443.2059580636273;2444.40112172518;2364.4561750797325;2393.2580899920995;2394.8682822444757;2399.7360290368047;2407.9518409631796;2349.1660460536605;2372.4543355028354;2389.653500242464;2391.1869964212165;2393.210240620591;2384.6899903012672;2402.3994001499627;2410.5660981023407;2412.6139817629096;2419.588669926117;7065.43476521877;8276.586170691442;8324.542150181738;8335.749927502124;8337.249647531751;4782.976085119587;4831.275308703687;4970.6520543561965;5144;5163.457836927144;3138.686131386866;3448.586169659657;3524.1542029912634;3538.2569660371355;3597.8740744074094;1045.8326667733127;1051.4059556350637;1053.9051485366276;1071.571371451418;1079.611339917629;10913;11161.330320180816;11192.44037798107;11195.60815371458;11200.775984480264;4494.16857881293;4501.077279157765;4566.566176213254;4583.358329833826;4610.792514336863;8977.461352318882;8982.685606003759;8984.23633991105;8989.55052247385;8998.820023599623;5096.821611243588;5102.489751024942;5107.208382700687;5109.8722531937;5116;4720.268358404453;4733.526647335308;4738.09976104546;4738.857834264978;4741.478437371887;3513.982430087833;3613.512175856226;3648.4527320902075;3661.487391765155;3673.577538583044;1134.28540019787;1150.045462266317;1156.4044517073714;1161.7618388230392;1162.7674465107013;1371.6433727230992;1391.1583491987403;1408.6407965968613;1411.1039489923971;1423.011007349882;7916.218533626182;7927.445078844541;8172.305354044854;8176.83646327082;8212.876806847902;1124.983125253122;1161.6689243565543;1217.5251651855783;1217.7686239614445;1231.8829711177423;6070.271567411851;6192.659403732748;6280.340564240781;6344.984802431589;6352.205974253218;9462.952685236529;10309.07218350352;10334.896651033543;10369.844452333222;10378;9903.80192396148;10041.648542300984;10184.490775461198;10237.078662920372;10267.075963163352;7830.686772529149;7936.16670249627;8016.55908925003;8017.158198389086;8039.517628942165;7836;7918.762437126896;7955.204479551999;7973.760787176394;7980.760577182694;9136.95431522838;9171.495567743572;9245.907540924505;9248.768780780534;9297.30270229749;9076.591553380245;9329.9270583881;9425.952870235878;9435.764105897408;9499.715008549754;7447.329740323397;7450.850982980419;7468.327850493264;7501.88747168782;7505.362044226302;8097.473664211808;8135.79660508492;8137.8372432552205;8138.9186108138165;8140.063892652184;7460.440466965108;7561.4706970513735;7635.618219089134;7685.654145563351;7693.692252309845;7399.8150046249275;7410.481266311306;7421;7552.169261381134;7580.317771400601;7538.849223015619;7590.734324298623;7601.7719468416035;7606.581638009962;7619.314261716472;5740.196372507936;6831.11195544576;6883.208431030495;6903.206131294764;6920.653967301715;6984.16190057176;7010.158780946319;7021.332973367522;7022.157341119098;7024.4029257513685 05:33:53 INFO - 2015-11-24 05:33:53,350 DEBUG : BROWSER_OUTPUT: |4;prototype.html;324.7499425442409;330.69741186814065;335.11362446329457;335.43981550810145;338.96271410144897;6637.502187335957;6858.279880612538;6896.344847239504;6955.234924158339;6967.721291148354;6671.733130674767;6724.529282950195;6764.492663050279;6794.830129246771;6835.316468353162;4986.077575648498;5053.747312634372;5056.367954005749;5066.898662026757;5073.568746656539;4289.528151903288;4635.165670179366;4746.738929358884;5061.898762024757;5074.873128171788;4929.827456039039;4979.601631869442;4992.975035124819;5000.274960130779;5020.523050310233;4012.478377810895;4950.752462376885;5001.09980203565;5010.348654674905;5021;3581.176329444216;4259.446271984653;4285.807138678767;4300.870973870758;4301.2902871026245;6367.681615919232;6555.737770489175;6578.736850525973;6601.768938087144;6636.701348439332;5978.581499295051;5988.652553175544;6000.279966404016;6003.459688628044;6003.8198854034445;7313.670884810196;7326.706931722725;7330.376917961979;7348.853022939565;7353.742619008309;4574.08518296342;4708.058388322315;4717.174494463455;4727.101850648401;4852.126617208901;7596.886046709304;7812.68749250035;7824.608769561556;7837.333826624744;7852.17552157021;4540.977295113504;4643.674942753974;4776.188048031843;4782.497837727058;4786.736729479842;4488.798004089824;4520.344550040226;4677.883052923636;4728.3143944127905;4804.8318308859025;4747.786349614275;4758.714477131383;5848.356680765198;5870.970645146833;5885.705714714247;6347.301796802348;6660.167479065117;7370.115586129698;7371.189169191492;7383.778486645409;1632.7061128996895;1694.9576260593583;1696.5588946873654;1698.456493921933;1701.4045084220672;976.4434272464769;989.9158571521501;1050.2700623067049;1076.6339444588878;1082.0748260237542;6837.453003759687;6846.315368463114;6864.897026544506;6871.209810871814;6882.311768823178;7630.160682324939;7639.121501027342;7644.503107298008;7652.923470765332;7659.425543084291;6652.970843892659;6808.38724514796;6825.317468253135;6834.931650683529;6899;4024.678025757991;4071.2875246831686;4075.612816782401;4076.3274059780397;4078.816453259551;7958.6816527339415;7984.041914970125;7987.960060199662;7991.72028978983;7996.280334769898;7679.808004799925;7965.203479651988;7967.880481792778;8005.279524842792;8005.799855003672;7950.204979502004;7963.044434667877;7972.36221102322;7980.241877021674;7982.640781164745;7440.925590744023;7710.575918324546;7722.806929826799;7724.884126737991;7743.148253692202;7538.509996850187;7541.057367829021;7561.546307221584;7607.4674772765375;7653.617319134132;6922.788512010277;7601.353884919844;7995.680172793023;8010.239027292398;8013.19868013194;3748.8313025914445;4481.820727170877;4689.437267527782;4699.3420921071765;4709.058188362273;7507.286807753255;7509.9249007509225;7676.80807979805;7728.884066738891;7735.497192682457;6050.969745151246;7395.630218488946;7412.518186317872;7424.628768561658;7437.219091995479;6038.4867286279405;7149.928500715135;7386.261373862786;7393.44549158826;7440.516366436164;7242.818929526593;7243.782686519413;7254.782356529313;7256.600886951268;7268;7141.107361579802;7426.777196684108;7439.479236453612;7448.776536703907;7455.217202193909;7221.241769614325;7244.601546914971;7255.637218139177;7260.237675044044;7260.528065675714 05:33:53 INFO - 2015-11-24 05:33:53,351 DEBUG : BROWSER_OUTPUT: |5;yui.html;697.1947400752132;784.8548018616557;797.780610332159;822.4612878564535;823.8681810910253;14782.852171478282;15129;15154.848451515481;15160.696786064273;15174.165420901845;14115.505957291496;14255.71488570228;14271.357788899499;14278.643033924158;14289.928255380863;758.5221310574344;760.7299408709912;761.8514389694012;767.769669099268;956.9473678947655;947.6872632031448;957.248559880494;957.301170145794;957.5595226195924;959.3092973059386;796.2913007423398;1002.2533212756507;1003.6938733686222;1006.8187726209279;1009.1271050541266;789.8973133492609;791.7149826062613;990.1237404896647;992.2905122837134;992.5682328187262;750.1860481377657;751.8909758085047;753.7399597138979;753.9858889793219;755.69016703152;14976.400943962228;15112.39550417982;15149.772753408708;15153.696926061417;15172;12457.377131143498;12487.75024499505;12735.108542402035;12795.104342696015;12855.807162892565;10076.89923100767;10260.435676037814;10265.486725663686;10274.383536987805;10295.794084118275;3509.31568344173;3534.2224710563637;3538.6461353864665;3539.628339024417;3542.6988705960025;75640.62179689121;77083.8437423439;77345.83981240286;77515;77817.61091194564;9677.322587418954;9720.562574684156;9771.902280977241;9878.506074696237;10440.37357758537;21685.915704214727;22343.32970010902;22400.439989000406;22406;22446.55106897853;7147.606881621561;7160.606166660779;7280.271972802678;7380.852382952311;7417.629118544051;3946.309395855714;4028.8187031583648;4032.2540330039037;4035.9798201008807;4042.43405923171;3542.3446662367546;3587.28254349126;3593.083763640255;3593.2813437312643;3608.4767708682093;655.4592461219513;663.280340830195;664.7839452178061;672.4183581202225;674.1775034457953;486.888015756378;487.35425561131643;492.1412135823012;508.506748453999;524.9658772179796;8617.61220745068;8662.350323725746;8701.216890479887;8818.955905220433;8948.8657670135;527.7994362142362;568.7497501099585;616.3220457496718;631.516889579472;636.745301879252;3877.0418210588427;3937.1731936293127;4157.625813676784;4251.59609837065;4266.445362102907;96653;96913.5154324238;99311.51032734515;99745;100395;79329;80611.19388806155;86347.13652863516;86576.56711716401;86823.26353472895;55540;73570.63214683892;73907.63046184843;74022.62988685022;74539.25460745323;8788.2529984952;9026;9065.365424420228;9091.227245684191;9145.588448519966;53010.73494632502;54397.91204175974;54596.72701636466;54685;56610;73714.26285737289;76754;76914.61542692251;77203;78151.21848781667;9330.720078397659;10895.945520272347;10909.509072091616;10947.45262736844;11629.941850290694;11520.654380368602;11617.6514704559;12179.269243845398;12458.127931045103;12496.25022498653;11474.541018359172;11764.411779410824;11866.40667966581;11892.405379730806;12431.689207769516;11566.132540059698;11847.407629618658;11922.642320730392;11933;12430.316332601793;11486;11693.415329233334;11704.648860534198;11704.946554810109;11718.824217636571;9456.33805633599;9655.324127311022;9985;10005.599776008878;10580.259381843505;9094.545272736468;9268.814623707623;9406.952965235403;9831.360961537477;9978.25163112784 05:33:53 INFO - 2015-11-24 05:33:53,351 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:33:53 INFO - 2015-11-24 05:33:53,351 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:33:53 INFO - 2015-11-24 05:33:53,351 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1120 05:33:53 INFO - 2015-11-24 05:33:53,351 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:33:53 INFO - 2015-11-24 05:33:53,352 DEBUG : BROWSER_OUTPUT: __startTimestamp1448372033337__endTimestamp 05:33:53 INFO - 2015-11-24 05:33:53,352 DEBUG : BROWSER_OUTPUT: 05:33:53 INFO - 2015-11-24 05:33:53,352 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:33:53 INFO - 2015-11-24 05:33:53,352 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:33:53 INFO - 2015-11-24 05:33:53,352 DEBUG : BROWSER_OUTPUT: 05:33:53 INFO - 2015-11-24 05:33:53,352 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5783.00 Median:6048.61 stddev:642.13 (10.6%) stddev-sans-first:609.71 05:33:53 INFO - 2015-11-24 05:33:53,353 DEBUG : BROWSER_OUTPUT: Values: 3139.8 3498.9 3512.9 3527.0 3573.5 5664.8 5697.3 5732.3 5757.6 5774.7 4858.7 5267.5 5743.5 5791.6 5871.8 4766.9 5156.4 5198.4 5242.3 5300.5 5322.1 5333.6 5340.1 5343.3 5408.3 5093.1 5201.7 5293.1 5300.4 5326.6 5212.5 5240.1 5242.5 5249.8 5258.0 5161.4 5250.7 5255.9 5257.2 5257.3 5727.4 5732.2 5733.9 5749.2 5776.9 6205.7 6253.3 6257.0 6266.5 6270.1 6256.9 6311.9 6347.7 6354.1 6355.8 5943.9 5951.4 5957.8 5958.5 5971.6 6177.7 6312.9 6320.7 6337.7 6403.6 6079.1 6217.9 6220.1 6234.7 6235.2 6314.4 6317.6 6318.3 6323.7 6330.4 6094.6 6188.0 6311.5 6315.7 6323.3 6138.9 6220.5 6244.7 6281.5 6292.6 5002.4 5007.0 5022.1 5028.0 5047.6 4334.7 4486.1 4491.2 4494.6 4509.0 5692.8 5762.8 5769.7 5776.0 5781.1 6229.5 6278.8 6288.1 6290.3 6293.3 5728.8 5734.6 5742.1 5748.5 5845.7 5437.4 5451.1 5473.1 5478.3 5513.1 6358.4 6359.0 6361.1 6369.0 6381.9 5901.6 6044.8 6299.3 6309.0 6312.4 6236.9 6302.1 6345.5 6356.6 6361.6 6252.3 6258.0 6267.5 6278.9 6288.6 6138.6 6233.4 6311.3 6328.8 6334.7 5859.5 6112.8 6121.5 6124.7 6129.8 6146.3 6162.4 6175.0 6178.8 6203.5 5008.8 5902.5 6000.5 6048.6 6052.7 5956.5 6099.7 6126.2 6142.4 6143.7 6058.5 6083.3 6085.8 6086.7 6094.8 05:33:53 INFO - 2015-11-24 05:33:53,354 DEBUG : BROWSER_OUTPUT: 05:33:53 INFO - 2015-11-24 05:33:53,354 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:65506.73 Median:36768.31 stddev:136584.90 (371.5%) stddev-sans-first:136881.08 05:33:53 INFO - 2015-11-24 05:33:53,354 DEBUG : BROWSER_OUTPUT: Values: 583.9 584.1 591.3 603.9 637.1 32229.4 32422.5 40831.0 41368.8 41397.8 43616.9 43983.8 44103.0 44114.6 44156.0 8864.6 9772.5 9956.4 9977.7 9996.5 8786.7 8835.7 12028.5 12144.8 12184.2 11875.3 11946.8 11963.6 11965.6 11999.2 8879.2 9011.2 11940.8 11971.5 11979.2 8853.1 11928.3 12097.1 12117.9 12119.7 40712.6 40969.8 40995.6 41120.6 41136.4 3160.8 3186.6 3202.2 3542.9 3728.6 28446.4 28596.0 28637.4 28804.3 28829.1 2763.3 2793.7 2805.4 2879.0 2912.3 399133.0 475635.0 476695.0 478050.0 478137.0 58243.4 59339.0 67632.7 68045.0 68131.0 59980.7 60051.7 60239.1 71704.6 72844.9 15653.2 15839.6 15839.6 15844.7 15866.5 9596.5 11917.0 11926.5 11951.2 11958.3 17189.9 20814.0 20981.5 21255.0 21266.4 9191.7 9336.0 9349.4 9365.4 9459.9 778.0 993.9 1011.5 1047.6 1163.5 29034.7 29334.6 29670.9 29860.0 29900.0 1035.1 1070.6 1080.4 1083.7 1177.4 14600.9 14639.8 14677.3 14991.0 15031.0 517636.0 523167.0 523498.0 523500.0 523807.0 46968.0 57080.7 57324.4 57326.1 57328.4 1262.6 1437.7 1450.1 1474.9 1475.4 22974.3 27732.4 28308.7 28359.3 30325.2 49323.3 49594.5 49661.0 50149.5 52822.7 544648.0 551485.0 551777.0 552248.0 554724.0 26610.0 29417.4 30008.1 30607.0 30685.7 42666.0 42790.4 42846.8 43496.6 44106.1 37336.0 37692.2 38555.2 38679.4 38827.0 34324.8 36619.3 37776.0 37857.6 37910.6 36741.8 36794.8 37550.6 37576.8 37837.2 32319.5 33267.3 33610.0 33759.0 33810.0 24979.8 25110.9 25194.0 25310.7 25378.5 05:33:53 INFO - 2015-11-24 05:33:53,354 DEBUG : BROWSER_OUTPUT: 05:33:53 INFO - 2015-11-24 05:33:53,354 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:38798.26 Median:2226.95 stddev:124758.24 (5602.2%) stddev-sans-first:125074.82 05:33:53 INFO - 2015-11-24 05:33:53,355 DEBUG : BROWSER_OUTPUT: Values: 355.6 357.1 360.2 362.6 362.9 15985.4 17226.8 19603.3 20617.4 21490.5 16595.0 17047.7 17049.9 20675.3 21088.0 5123.6 5334.4 5343.4 5489.5 5669.1 3876.8 4007.2 5637.2 5666.4 5676.1 4011.8 4343.5 5761.6 5772.9 5776.4 5404.2 5551.8 5713.8 5772.7 5780.2 5254.2 5325.2 5340.2 5342.3 5342.8 23978.4 24232.8 24319.0 24585.4 25156.1 2043.7 2052.9 2162.5 2223.5 2230.4 9436.2 9727.8 9773.0 12777.1 13379.9 1568.8 1598.6 1697.8 1743.4 1769.8 96824.0 97861.5 99650.0 101210.0 103546.0 10566.2 10946.3 11083.6 11096.4 11099.4 13169.4 13197.5 13206.8 13242.5 13266.7 8036.2 8144.6 8147.2 8151.6 8193.3 8088.4 8091.7 8111.0 8155.6 8160.8 4343.7 4360.6 4362.9 4371.7 4399.1 1720.0 1766.1 1768.4 1772.6 1827.4 891.3 908.8 932.9 1027.9 1036.2 14529.3 18850.8 19391.0 20588.6 20656.7 804.6 833.6 917.7 961.7 968.5 10321.7 10519.1 10523.3 10533.8 10550.7 744456.0 752371.4 753991.0 769712.0 770669.0 116404.0 120418.0 121077.0 122209.8 122474.8 1257.2 1257.2 1258.8 1261.5 1282.5 15625.5 17101.5 17902.5 18172.6 18191.1 49115.0 49152.7 49436.3 49751.3 50529.7 70376.6 71220.6 71452.6 73167.0 74304.6 9604.4 9751.9 9850.1 9854.8 10010.2 11672.9 11735.2 11746.0 18503.0 19725.8 15924.1 16066.5 16341.7 16420.8 16542.8 15561.8 15578.8 15666.8 15794.5 16445.9 12787.7 13793.2 16501.7 16920.2 16955.8 7692.2 8468.9 8828.8 9672.4 9709.3 15350.6 15540.0 15763.4 15783.9 15881.0 05:33:53 INFO - 2015-11-24 05:33:53,356 DEBUG : BROWSER_OUTPUT: 05:33:53 INFO - 2015-11-24 05:33:53,356 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5852.98 Median:5130.00 stddev:3018.34 (58.8%) stddev-sans-first:3008.41 05:33:53 INFO - 2015-11-24 05:33:53,357 DEBUG : BROWSER_OUTPUT: Values: 1420.3 1604.2 1685.7 1870.7 2042.7 7538.8 7694.3 8210.7 8520.3 8542.0 8222.7 8374.5 8426.2 8441.2 8446.7 2389.2 2412.6 2414.0 2414.6 2417.3 2402.7 2430.3 2437.7 2443.2 2444.4 2364.5 2393.3 2394.9 2399.7 2408.0 2349.2 2372.5 2389.7 2391.2 2393.2 2384.7 2402.4 2410.6 2412.6 2419.6 7065.4 8276.6 8324.5 8335.7 8337.2 4783.0 4831.3 4970.7 5144.0 5163.5 3138.7 3448.6 3524.2 3538.3 3597.9 1045.8 1051.4 1053.9 1071.6 1079.6 10913.0 11161.3 11192.4 11195.6 11200.8 4494.2 4501.1 4566.6 4583.4 4610.8 8977.5 8982.7 8984.2 8989.6 8998.8 5096.8 5102.5 5107.2 5109.9 5116.0 4720.3 4733.5 4738.1 4738.9 4741.5 3514.0 3613.5 3648.5 3661.5 3673.6 1134.3 1150.0 1156.4 1161.8 1162.8 1371.6 1391.2 1408.6 1411.1 1423.0 7916.2 7927.4 8172.3 8176.8 8212.9 1125.0 1161.7 1217.5 1217.8 1231.9 6070.3 6192.7 6280.3 6345.0 6352.2 9463.0 10309.1 10334.9 10369.8 10378.0 9903.8 10041.6 10184.5 10237.1 10267.1 7830.7 7936.2 8016.6 8017.2 8039.5 7836.0 7918.8 7955.2 7973.8 7980.8 9137.0 9171.5 9245.9 9248.8 9297.3 9076.6 9329.9 9426.0 9435.8 9499.7 7447.3 7450.9 7468.3 7501.9 7505.4 8097.5 8135.8 8137.8 8138.9 8140.1 7460.4 7561.5 7635.6 7685.7 7693.7 7399.8 7410.5 7421.0 7552.2 7580.3 7538.8 7590.7 7601.8 7606.6 7619.3 5740.2 6831.1 6883.2 6903.2 6920.7 6984.2 7010.2 7021.3 7022.2 7024.4 05:33:53 INFO - 2015-11-24 05:33:53,357 DEBUG : BROWSER_OUTPUT: 05:33:53 INFO - 2015-11-24 05:33:53,357 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5972.44 Median:6835.12 stddev:1968.92 (28.8%) stddev-sans-first:1928.27 05:33:53 INFO - 2015-11-24 05:33:53,358 DEBUG : BROWSER_OUTPUT: Values: 324.7 330.7 335.1 335.4 339.0 6637.5 6858.3 6896.3 6955.2 6967.7 6671.7 6724.5 6764.5 6794.8 6835.3 4986.1 5053.7 5056.4 5066.9 5073.6 4289.5 4635.2 4746.7 5061.9 5074.9 4929.8 4979.6 4993.0 5000.3 5020.5 4012.5 4950.8 5001.1 5010.3 5021.0 3581.2 4259.4 4285.8 4300.9 4301.3 6367.7 6555.7 6578.7 6601.8 6636.7 5978.6 5988.7 6000.3 6003.5 6003.8 7313.7 7326.7 7330.4 7348.9 7353.7 4574.1 4708.1 4717.2 4727.1 4852.1 7596.9 7812.7 7824.6 7837.3 7852.2 4541.0 4643.7 4776.2 4782.5 4786.7 4488.8 4520.3 4677.9 4728.3 4804.8 4747.8 4758.7 5848.4 5871.0 5885.7 6347.3 6660.2 7370.1 7371.2 7383.8 1632.7 1695.0 1696.6 1698.5 1701.4 976.4 989.9 1050.3 1076.6 1082.1 6837.5 6846.3 6864.9 6871.2 6882.3 7630.2 7639.1 7644.5 7652.9 7659.4 6653.0 6808.4 6825.3 6834.9 6899.0 4024.7 4071.3 4075.6 4076.3 4078.8 7958.7 7984.0 7988.0 7991.7 7996.3 7679.8 7965.2 7967.9 8005.3 8005.8 7950.2 7963.0 7972.4 7980.2 7982.6 7440.9 7710.6 7722.8 7724.9 7743.1 7538.5 7541.1 7561.5 7607.5 7653.6 6922.8 7601.4 7995.7 8010.2 8013.2 3748.8 4481.8 4689.4 4699.3 4709.1 7507.3 7509.9 7676.8 7728.9 7735.5 6051.0 7395.6 7412.5 7424.6 7437.2 6038.5 7149.9 7386.3 7393.4 7440.5 7242.8 7243.8 7254.8 7256.6 7268.0 7141.1 7426.8 7439.5 7448.8 7455.2 7221.2 7244.6 7255.6 7260.2 7260.5 05:33:53 INFO - 2015-11-24 05:33:53,358 DEBUG : BROWSER_OUTPUT: 05:33:53 INFO - 2015-11-24 05:33:53,358 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:19044.12 Median:54640.86 stddev:27042.17 (49.5%) stddev-sans-first:27082.94 05:33:53 INFO - 2015-11-24 05:33:53,360 DEBUG : BROWSER_OUTPUT: Values: 697.2 784.9 797.8 822.5 823.9 14782.9 15129.0 15154.8 15160.7 15174.2 14115.5 14255.7 14271.4 14278.6 14289.9 758.5 760.7 761.9 767.8 956.9 947.7 957.2 957.3 957.6 959.3 796.3 1002.3 1003.7 1006.8 1009.1 789.9 791.7 990.1 992.3 992.6 750.2 751.9 753.7 754.0 755.7 14976.4 15112.4 15149.8 15153.7 15172.0 12457.4 12487.8 12735.1 12795.1 12855.8 10076.9 10260.4 10265.5 10274.4 10295.8 3509.3 3534.2 3538.6 3539.6 3542.7 75640.6 77083.8 77345.8 77515.0 77817.6 9677.3 9720.6 9771.9 9878.5 10440.4 21685.9 22343.3 22400.4 22406.0 22446.6 7147.6 7160.6 7280.3 7380.9 7417.6 3946.3 4028.8 4032.3 4036.0 4042.4 3542.3 3587.3 3593.1 3593.3 3608.5 655.5 663.3 664.8 672.4 674.2 486.9 487.4 492.1 508.5 525.0 8617.6 8662.4 8701.2 8819.0 8948.9 527.8 568.7 616.3 631.5 636.7 3877.0 3937.2 4157.6 4251.6 4266.4 96653.0 96913.5 99311.5 99745.0 100395.0 79329.0 80611.2 86347.1 86576.6 86823.3 55540.0 73570.6 73907.6 74022.6 74539.3 8788.3 9026.0 9065.4 9091.2 9145.6 53010.7 54397.9 54596.7 54685.0 56610.0 73714.3 76754.0 76914.6 77203.0 78151.2 9330.7 10895.9 10909.5 10947.5 11629.9 11520.7 11617.7 12179.3 12458.1 12496.3 11474.5 11764.4 11866.4 11892.4 12431.7 11566.1 11847.4 11922.6 11933.0 12430.3 11486.0 11693.4 11704.6 11704.9 11718.8 9456.3 9655.3 9985.0 10005.6 10580.3 9094.5 9268.8 9407.0 9831.4 9978.3 05:33:53 INFO - 2015-11-24 05:33:53,360 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:33:53 INFO - 2015-11-24 05:33:53,360 DEBUG : BROWSER_OUTPUT: 05:33:53 INFO - 2015-11-24 05:33:53,805 INFO : Browser exited with error code: 0 05:33:53 INFO - 2015-11-24 05:33:53,830 INFO : Completed test dromaeo_css (00:18:34) 05:33:53 INFO - 2015-11-24 05:33:53,830 INFO : Starting test kraken 05:33:53 INFO - 2015-11-24 05:33:53,830 DEBUG : operating with platform_type : mac_ 05:33:53 INFO - 2015-11-24 05:33:53,830 INFO : Initialising browser for kraken test... 05:33:53 INFO - 2015-11-24 05:33:53,842 DEBUG : command line: /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpexhHqX/profile http://localhost:51258/getInfo.html 05:33:55 INFO - 2015-11-24 05:33:55,972 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:33:55 INFO - 2015-11-24 05:33:55,973 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:33:55 INFO - 2015-11-24 05:33:55,973 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 05:33:55 INFO - 2015-11-24 05:33:55,973 DEBUG : BROWSER_OUTPUT: __metrics 05:33:55 INFO - 2015-11-24 05:33:55,990 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:33:56 INFO - 2015-11-24 05:33:56,649 INFO : Browser initialized. 05:33:56 INFO - 2015-11-24 05:33:56,650 INFO : Running cycle 1/1 for kraken test... 05:33:56 INFO - 2015-11-24 05:33:56,650 DEBUG : command line: /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpexhHqX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:34:08 INFO - 2015-11-24 05:34:08,565 DEBUG : BROWSER_OUTPUT: RSS: Main: 224894976 05:34:08 INFO - 2015-11-24 05:34:08,565 DEBUG : BROWSER_OUTPUT: 05:34:11 INFO - 2015-11-24 05:34:11,068 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51258/tests/kraken/driver.html?testName=audio-beat-detection) 05:34:11 INFO - 2015-11-24 05:34:11,660 DEBUG : BROWSER_OUTPUT: RSS: Main: 274784256 05:34:11 INFO - 2015-11-24 05:34:11,661 DEBUG : BROWSER_OUTPUT: 05:34:14 INFO - 2015-11-24 05:34:14,253 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51258/tests/kraken/driver.html?testName=audio-dft) 05:34:14 INFO - 2015-11-24 05:34:14,846 DEBUG : BROWSER_OUTPUT: RSS: Main: 290263040 05:34:14 INFO - 2015-11-24 05:34:14,846 DEBUG : BROWSER_OUTPUT: 05:34:18 INFO - 2015-11-24 05:34:18,281 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51258/tests/kraken/driver.html?testName=audio-fft) 05:34:18 INFO - 2015-11-24 05:34:18,862 DEBUG : BROWSER_OUTPUT: RSS: Main: 277602304 05:34:18 INFO - 2015-11-24 05:34:18,862 DEBUG : BROWSER_OUTPUT: 05:34:20 INFO - 2015-11-24 05:34:20,698 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51258/tests/kraken/driver.html?testName=audio-oscillator) 05:34:21 INFO - 2015-11-24 05:34:21,290 DEBUG : BROWSER_OUTPUT: RSS: Main: 280088576 05:34:21 INFO - 2015-11-24 05:34:21,290 DEBUG : BROWSER_OUTPUT: 05:34:23 INFO - 2015-11-24 05:34:23,416 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51258/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:34:24 INFO - 2015-11-24 05:34:24,000 DEBUG : BROWSER_OUTPUT: RSS: Main: 280743936 05:34:24 INFO - 2015-11-24 05:34:24,000 DEBUG : BROWSER_OUTPUT: 05:34:26 INFO - 2015-11-24 05:34:26,656 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51258/tests/kraken/driver.html?testName=imaging-darkroom) 05:34:27 INFO - 2015-11-24 05:34:27,246 DEBUG : BROWSER_OUTPUT: RSS: Main: 282091520 05:34:27 INFO - 2015-11-24 05:34:27,246 DEBUG : BROWSER_OUTPUT: 05:34:30 INFO - 2015-11-24 05:34:30,126 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51258/tests/kraken/driver.html?testName=imaging-desaturate) 05:34:30 INFO - 2015-11-24 05:34:30,708 DEBUG : BROWSER_OUTPUT: RSS: Main: 281141248 05:34:30 INFO - 2015-11-24 05:34:30,709 DEBUG : BROWSER_OUTPUT: 05:34:33 INFO - 2015-11-24 05:34:33,376 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51258/tests/kraken/driver.html?testName=json-parse-financial) 05:34:33 INFO - 2015-11-24 05:34:33,975 DEBUG : BROWSER_OUTPUT: RSS: Main: 281300992 05:34:33 INFO - 2015-11-24 05:34:33,975 DEBUG : BROWSER_OUTPUT: 05:34:35 INFO - 2015-11-24 05:34:35,468 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51258/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:34:36 INFO - 2015-11-24 05:34:36,045 DEBUG : BROWSER_OUTPUT: RSS: Main: 316624896 05:34:36 INFO - 2015-11-24 05:34:36,046 DEBUG : BROWSER_OUTPUT: 05:34:37 INFO - 2015-11-24 05:34:37,941 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51258/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:34:38 INFO - 2015-11-24 05:34:38,554 DEBUG : BROWSER_OUTPUT: RSS: Main: 317792256 05:34:38 INFO - 2015-11-24 05:34:38,555 DEBUG : BROWSER_OUTPUT: 05:34:40 INFO - 2015-11-24 05:34:40,582 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51258/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:34:41 INFO - 2015-11-24 05:34:41,183 DEBUG : BROWSER_OUTPUT: RSS: Main: 323166208 05:34:41 INFO - 2015-11-24 05:34:41,183 DEBUG : BROWSER_OUTPUT: 05:34:43 INFO - 2015-11-24 05:34:43,649 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:34:44 INFO - 2015-11-24 05:34:44,249 DEBUG : BROWSER_OUTPUT: RSS: Main: 325775360 05:34:44 INFO - 2015-11-24 05:34:44,250 DEBUG : BROWSER_OUTPUT: 05:34:46 INFO - 2015-11-24 05:34:46,615 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:34:47 INFO - 2015-11-24 05:34:47,206 DEBUG : BROWSER_OUTPUT: RSS: Main: 323739648 05:34:47 INFO - 2015-11-24 05:34:47,206 DEBUG : BROWSER_OUTPUT: 05:34:48 INFO - 2015-11-24 05:34:48,875 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51258/tests/kraken/driver.html?testName=ai-astar) 05:34:49 INFO - 2015-11-24 05:34:49,133 DEBUG : BROWSER_OUTPUT: RSS: Main: 383655936 05:34:49 INFO - 2015-11-24 05:34:49,133 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,133 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:34:49 INFO - 2015-11-24 05:34:49,133 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:34:49 INFO - 2015-11-24 05:34:49,133 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:34:49 INFO - 2015-11-24 05:34:49,133 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;110;103;109;111;110;100;111;111;100 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;122;141;162;151;147;139;119;155;118 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |2;audio-dft;181;183;200;254;185;182;196;264;188;179 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |3;audio-fft;59;70;77;73;55;57;56;68;71;55 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;84;82;118;74;76;75;112;105;109;141 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;85;86;85;85;86;88;88;85;86;85 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;93;92;93;93;93;109;150;102;93;92 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;86;85;86;84;87;86;85;83;84;84 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;56;55;53;54;54;54;63;53;52 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;62;61;67;98;96;71;66;68;68;68 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;80;81;83;81;81;80;83;83;83 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;129;118;128;119;120;124;118;120;123;126 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;133;123;135;135;133;123;133;126;138;123 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;63;66;62;64;64;64;64;67;82 05:34:49 INFO - 2015-11-24 05:34:49,134 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1031 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: __startTimestamp1448372089127__endTimestamp 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.50 Median:110.00 stddev:4.60 (4.2%) stddev-sans-first:4.79 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 103.0 109.0 111.0 110.0 100.0 111.0 111.0 100.0 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.30 Median:148.00 stddev:15.67 (10.6%) stddev-sans-first:16.30 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: Values: 149.0 122.0 141.0 162.0 151.0 147.0 139.0 119.0 155.0 118.0 05:34:49 INFO - 2015-11-24 05:34:49,135 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:201.20 Median:192.00 stddev:31.27 (16.3%) stddev-sans-first:32.30 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: Values: 181.0 183.0 200.0 254.0 185.0 182.0 196.0 264.0 188.0 179.0 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:64.10 Median:69.00 stddev:8.50 (12.3%) stddev-sans-first:8.82 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: Values: 59.0 70.0 77.0 73.0 55.0 57.0 56.0 68.0 71.0 55.0 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:97.60 Median:74.50 stddev:22.74 (30.5%) stddev-sans-first:23.58 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: Values: 84.0 82.0 118.0 74.0 76.0 75.0 112.0 105.0 109.0 141.0 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:86.00 stddev:1.20 (1.4%) stddev-sans-first:1.22 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: Values: 85.0 86.0 85.0 85.0 86.0 88.0 88.0 85.0 86.0 85.0 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:101.00 Median:93.00 stddev:18.10 (19.5%) stddev-sans-first:18.96 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: Values: 93.0 92.0 93.0 93.0 93.0 109.0 150.0 102.0 93.0 92.0 05:34:49 INFO - 2015-11-24 05:34:49,136 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.50 stddev:1.25 (1.5%) stddev-sans-first:1.27 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: Values: 86.0 85.0 86.0 84.0 87.0 86.0 85.0 83.0 84.0 84.0 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.80 Median:54.00 stddev:3.08 (5.7%) stddev-sans-first:3.26 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: Values: 54.0 56.0 55.0 53.0 54.0 54.0 54.0 63.0 53.0 52.0 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:68.00 stddev:13.25 (19.5%) stddev-sans-first:13.50 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: Values: 62.0 61.0 67.0 98.0 96.0 71.0 66.0 68.0 68.0 68.0 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:81.70 Median:82.50 stddev:1.25 (1.5%) stddev-sans-first:1.32 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: Values: 82.0 80.0 81.0 83.0 81.0 81.0 80.0 83.0 83.0 83.0 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:122.50 Median:123.50 stddev:4.12 (3.3%) stddev-sans-first:3.63 05:34:49 INFO - 2015-11-24 05:34:49,137 DEBUG : BROWSER_OUTPUT: Values: 129.0 118.0 128.0 119.0 120.0 124.0 118.0 120.0 123.0 126.0 05:34:49 INFO - 2015-11-24 05:34:49,138 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,138 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:130.20 Median:133.00 stddev:5.81 (4.4%) stddev-sans-first:6.07 05:34:49 INFO - 2015-11-24 05:34:49,138 DEBUG : BROWSER_OUTPUT: Values: 133.0 123.0 135.0 135.0 133.0 123.0 133.0 126.0 138.0 123.0 05:34:49 INFO - 2015-11-24 05:34:49,138 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,138 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:64.00 stddev:5.79 (9.1%) stddev-sans-first:6.10 05:34:49 INFO - 2015-11-24 05:34:49,138 DEBUG : BROWSER_OUTPUT: Values: 64.0 63.0 66.0 62.0 64.0 64.0 64.0 64.0 67.0 82.0 05:34:49 INFO - 2015-11-24 05:34:49,138 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:34:49 INFO - 2015-11-24 05:34:49,138 DEBUG : BROWSER_OUTPUT: 05:34:49 INFO - 2015-11-24 05:34:49,757 INFO : Browser exited with error code: 0 05:34:49 INFO - 2015-11-24 05:34:49,803 INFO : Completed test kraken (00:00:55) 05:34:49 INFO - 2015-11-24 05:34:49,804 INFO : Starting test v8_7 05:34:49 INFO - 2015-11-24 05:34:49,804 DEBUG : operating with platform_type : mac_ 05:34:49 INFO - 2015-11-24 05:34:49,804 INFO : Initialising browser for v8_7 test... 05:34:49 INFO - 2015-11-24 05:34:49,816 DEBUG : command line: /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpNAv2dF/profile http://localhost:51258/getInfo.html 05:34:51 INFO - 2015-11-24 05:34:51,829 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:34:51 INFO - 2015-11-24 05:34:51,829 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:34:51 INFO - 2015-11-24 05:34:51,829 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 05:34:51 INFO - 2015-11-24 05:34:51,829 DEBUG : BROWSER_OUTPUT: __metrics 05:34:51 INFO - 2015-11-24 05:34:51,847 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:34:52 INFO - 2015-11-24 05:34:52,487 INFO : Browser initialized. 05:34:52 INFO - 2015-11-24 05:34:52,487 INFO : Running cycle 1/1 for v8_7 test... 05:34:52 INFO - 2015-11-24 05:34:52,487 DEBUG : command line: /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpNAv2dF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:35:04 INFO - 2015-11-24 05:35:04,384 DEBUG : BROWSER_OUTPUT: RSS: Main: 229408768 05:35:04 INFO - 2015-11-24 05:35:04,384 DEBUG : BROWSER_OUTPUT: 05:35:26 INFO - 2015-11-24 05:35:26,930 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51258/tests/v8_7/run.html (next: http://localhost:51258/tests/v8_7/run.html) 05:35:27 INFO - 2015-11-24 05:35:27,179 DEBUG : BROWSER_OUTPUT: RSS: Main: 678563840 05:35:27 INFO - 2015-11-24 05:35:27,179 DEBUG : BROWSER_OUTPUT: 05:35:27 INFO - 2015-11-24 05:35:27,179 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:35:27 INFO - 2015-11-24 05:35:27,179 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:35:27 INFO - 2015-11-24 05:35:27,179 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: |0;Richards;173.91304347826087 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;162.813415825464 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.0336040324839 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4699.530516431925 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1753.0647985989492 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: |5;Earley;791.1392405063291 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: |6;Boyer;12587.5 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: |7;RegExp;30044.117647058825 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: |8;Splay;745.7121551081283 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4554.545454545455 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: __startTimestamp1448372127178__endTimestamp 05:35:27 INFO - 2015-11-24 05:35:27,180 DEBUG : BROWSER_OUTPUT: 05:35:27 INFO - 2015-11-24 05:35:27,182 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:35:27 INFO - 2015-11-24 05:35:27,182 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:35:27 INFO - 2015-11-24 05:35:27,182 DEBUG : BROWSER_OUTPUT: 05:35:27 INFO - 2015-11-24 05:35:27,182 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:173.91 Median:173.91 stddev:0.00 (0.0%) 05:35:27 INFO - 2015-11-24 05:35:27,182 DEBUG : BROWSER_OUTPUT: Values: 173.9 05:35:27 INFO - 2015-11-24 05:35:27,182 DEBUG : BROWSER_OUTPUT: 05:35:27 INFO - 2015-11-24 05:35:27,182 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:162.81 Median:162.81 stddev:0.00 (0.0%) 05:35:27 INFO - 2015-11-24 05:35:27,182 DEBUG : BROWSER_OUTPUT: Values: 162.8 05:35:27 INFO - 2015-11-24 05:35:27,182 DEBUG : BROWSER_OUTPUT: 05:35:27 INFO - 2015-11-24 05:35:27,182 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.03 Median:280.03 stddev:0.00 (0.0%) 05:35:27 INFO - 2015-11-24 05:35:27,182 DEBUG : BROWSER_OUTPUT: Values: 280.0 05:35:27 INFO - 2015-11-24 05:35:27,182 DEBUG : BROWSER_OUTPUT: 05:35:27 INFO - 2015-11-24 05:35:27,183 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4699.53 Median:4699.53 stddev:0.00 (0.0%) 05:35:27 INFO - 2015-11-24 05:35:27,183 DEBUG : BROWSER_OUTPUT: Values: 4699.5 05:35:27 INFO - 2015-11-24 05:35:27,183 DEBUG : BROWSER_OUTPUT: 05:35:27 INFO - 2015-11-24 05:35:27,183 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1753.06 Median:1753.06 stddev:0.00 (0.0%) 05:35:27 INFO - 2015-11-24 05:35:27,183 DEBUG : BROWSER_OUTPUT: Values: 1753.1 05:35:27 INFO - 2015-11-24 05:35:27,183 DEBUG : BROWSER_OUTPUT: 05:35:27 INFO - 2015-11-24 05:35:27,183 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:791.14 Median:791.14 stddev:0.00 (0.0%) 05:35:27 INFO - 2015-11-24 05:35:27,183 DEBUG : BROWSER_OUTPUT: Values: 791.1 05:35:27 INFO - 2015-11-24 05:35:27,183 DEBUG : BROWSER_OUTPUT: 05:35:27 INFO - 2015-11-24 05:35:27,183 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12587.50 Median:12587.50 stddev:0.00 (0.0%) 05:35:27 INFO - 2015-11-24 05:35:27,183 DEBUG : BROWSER_OUTPUT: Values: 12587.5 05:35:27 INFO - 2015-11-24 05:35:27,183 DEBUG : BROWSER_OUTPUT: 05:35:27 INFO - 2015-11-24 05:35:27,183 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:30044.12 Median:30044.12 stddev:0.00 (0.0%) 05:35:27 INFO - 2015-11-24 05:35:27,184 DEBUG : BROWSER_OUTPUT: Values: 30044.1 05:35:27 INFO - 2015-11-24 05:35:27,184 DEBUG : BROWSER_OUTPUT: 05:35:27 INFO - 2015-11-24 05:35:27,184 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:745.71 Median:745.71 stddev:0.00 (0.0%) 05:35:27 INFO - 2015-11-24 05:35:27,184 DEBUG : BROWSER_OUTPUT: Values: 745.7 05:35:27 INFO - 2015-11-24 05:35:27,184 DEBUG : BROWSER_OUTPUT: 05:35:27 INFO - 2015-11-24 05:35:27,184 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4554.55 Median:4554.55 stddev:0.00 (0.0%) 05:35:27 INFO - 2015-11-24 05:35:27,184 DEBUG : BROWSER_OUTPUT: Values: 4554.5 05:35:27 INFO - 2015-11-24 05:35:27,184 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:35:27 INFO - 2015-11-24 05:35:27,184 DEBUG : BROWSER_OUTPUT: 05:35:27 INFO - 2015-11-24 05:35:27,826 INFO : Browser exited with error code: 0 05:35:27 INFO - 2015-11-24 05:35:27,843 INFO : Completed test v8_7 (00:00:38) 05:35:28 INFO - 2015-11-24 05:35:28,112 INFO : Completed test suite (00:20:08) 05:35:28 INFO - 2015-11-24 05:35:28,112 DEBUG : Working with test: dromaeo_css 05:35:28 INFO - 2015-11-24 05:35:28,112 DEBUG : Generating results file: dromaeo_css 05:35:28 INFO - 2015-11-24 05:35:28,113 DEBUG : Working with test: kraken 05:35:28 INFO - 2015-11-24 05:35:28,113 DEBUG : Generating results file: kraken 05:35:28 INFO - 2015-11-24 05:35:28,114 INFO : javascript benchmark 05:35:28 INFO - 2015-11-24 05:35:28,114 DEBUG : Working with test: v8_7 05:35:28 INFO - 2015-11-24 05:35:28,114 DEBUG : Generating results file: v8_7 05:35:28 INFO - 2015-11-24 05:35:28,114 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:35:28 INFO - 2015-11-24 05:35:28,642 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,59]] 05:35:28 INFO - 2015-11-24 05:35:28,642 DEBUG : process_Request line: dromaeo_css 7665.12 graph.html#tests=[[72,1,59]] 05:35:28 INFO - 2015-11-24 05:35:28,643 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:35:28 INFO - 2015-11-24 05:35:28,817 DEBUG : process_Request line: kraken 1410.30 graph.html#tests=[[232,1,59]] 05:35:28 INFO - 2015-11-24 05:35:28,817 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:35:28 INFO - 2015-11-24 05:35:28,924 DEBUG : process_Request line: v8_7 19832.23 graph.html#tests=[[230,1,59]] 05:35:28 INFO - 2015-11-24 05:35:28,925 INFO : javascript benchmark 05:35:28 INFO - 2015-11-24 05:35:28,927 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3139.7959132656374, 3498.8775392861253, 3512.8946131616062, 3526.964730352696, 3573.4639804029403, 5664.801731939383, 5697.344805347381, 5732.254806875106, 5757.596968212217, 5774.711264436772, 4858.659893807435, 5267.499587539187, 5743.483086522212, 5791.623544469607, 5871.76512939483, 4766.880827979311, 5156.355455568054, 5198.428172900978, 5242.266082748418, 5300.469953004707, 5322.148456247001, 5333.626646134772, 5340.118880384752, 5343.332083489564, 5408.296921400212, 5093.083245015896, 5201.687898726089, 5293.073712100367, 5300.390455097675, 5326.573874090082, 5212.478752124795, 5240.109181439165, 5242.501962313574, 5249.842504724865, 5258.027264955957, 5161.406438259611, 5250.65870718402, 5255.947440525584, 5257.158854583248, 5257.290265814105, 5727.369989301215, 5732.22614946981, 5733.913991290134, 5749.223854779593, 5776.913346299809, 6205.720742566596, 6253.3433989430905, 6257.0, 6266.49868010558, 6270.122182894398, 6256.87486250277, 6311.905321420182, 6347.682615869188, 6354.110424540568, 6355.777547785851, 5943.940560594382, 5951.434613711691, 5957.76168953237, 5958.493528050121, 5971.611845230046, 6177.69111544421, 6312.936870631327, 6320.652364119926, 6337.714802833883, 6403.615783053032, 6079.148919151322, 6217.875642487215, 6220.066989951543, 6234.719437625317, 6235.220597425322, 6314.431701146918, 6317.6209427434505, 6318.273398559132, 6323.715432805535, 6330.3669633036325, 6094.634321940698, 6188.0, 6311.526635502356, 6315.715792789336, 6323.336049714806, 6138.878900475427, 6220.502359811204, 6244.687765611701, 6281.466075383643, 6292.590981586274, 5002.374703162105, 5006.973570418056, 5022.096022715946, 5027.974860125676, 5047.570956468668, 4334.718243314174, 4486.147631949919, 4491.214037543416, 4494.5505449454795, 4509.0, 5692.82921512347, 5762.798302059407, 5769.68266745333, 5775.971120144372, 5781.075027995585, 6229.470495007975, 6278.843028924313, 6288.119663247149, 6290.276618188876, 6293.3391993840905, 5728.77084916607, 5734.569907256972, 5742.052561327334, 5748.48263656273, 5845.678487683133, 5437.429069947678, 5451.127819548853, 5473.124300111963, 5478.287822583039, 5513.145462453326, 6358.427741503195, 6358.982562789932, 6361.141245931833, 6368.968155159288, 6381.8723625528155, 5901.616394934487, 6044.758209671564, 6299.338569450142, 6309.0, 6312.368763123651, 6236.875262494816, 6302.1492098566105, 6345.46063584582, 6356.586821856565, 6361.61830290184, 6252.343503932022, 6258.0, 6267.5299352549655, 6278.874422511615, 6288.591241569284, 6138.600990935575, 6233.40782625668, 6311.337309582428, 6328.841778955563, 6334.714937827716, 5859.472647461749, 6112.816615501542, 6121.462011337575, 6124.683193113502, 6129.793274421294, 6146.293176284785, 6162.445379915829, 6175.0, 6178.814635560941, 6203.47270481996, 5008.84973450797, 5902.5277977762535, 6000.549958753199, 6048.60684055535, 6052.6973651316375, 5956.523478121827, 6099.725512351866, 6126.203593532812, 6142.447179753844, 6143.740533190775, 6058.454739073504, 6083.300420451527, 6085.817425477243, 6086.69566521681, 6094.78668246627], "unit": "ms", "name": "dojo.html", "value": 5742.596244805285}, {"lowerIsBetter": false, "replicates": [583.8715482593827, 584.1325631437317, 591.3229352391509, 603.9154518367428, 637.0794202377566, 32229.355412891757, 32422.513662295085, 40831.0, 41368.793156034255, 41397.79301103498, 43616.90957726059, 43983.78008109955, 44103.0, 44114.55885441144, 44156.0, 8864.645414183426, 9772.462514561696, 9956.352837065568, 9977.700668979905, 9996.500174991257, 8786.6924657637, 8835.690750823722, 12028.45871935765, 12144.757104857941, 12184.208026478294, 11875.287482751019, 11946.820797688042, 11963.641090767247, 11965.641030769091, 11999.160058795844, 8879.200871921557, 9011.188992990597, 11940.820887686692, 11971.521139154424, 11979.221350612226, 8853.114688531095, 11928.284302941851, 12097.092718046093, 12117.939410302892, 12119.697007574792, 40712.57505987306, 40969.79515102405, 40995.590044099474, 41120.56078037254, 41136.38295425541, 3160.7787454878166, 3186.5857438533076, 3202.151429871086, 3542.875999340011, 3728.6084961079237, 28446.43107137866, 28596.0, 28637.427251454854, 28804.279893002633, 28829.135125946254, 2763.2539214411995, 2793.6507936507937, 2805.3968396794785, 2878.9923526765465, 2912.3156058326276, 399133.0, 475635.0, 476695.0, 478050.0, 478137.0, 58243.4175658238, 59339.0, 67632.6618366905, 68044.97932531017, 68131.0, 59980.70009649924, 60051.69974150101, 60239.09641355383, 71704.64147679228, 72844.90732639015, 15653.217339132998, 15839.604009899846, 15839.604009899846, 15844.683106337809, 15866.52400427989, 9596.520173991272, 11917.046636269077, 11926.463309150935, 11951.163418560618, 11958.282503049672, 17189.914050429918, 20813.959302034837, 20981.475463113544, 21255.0, 21266.36200913975, 9191.667208254765, 9336.019717929656, 9349.439033658002, 9365.391249568756, 9459.858102128474, 777.9611019449005, 993.8906720260766, 1011.549860312154, 1047.5648361744459, 1163.5462169753803, 29034.709652903624, 29334.559981600294, 29670.851645741634, 29860.0, 29900.0, 1035.1098055672117, 1070.6306324318098, 1080.3949788118678, 1083.712816103733, 1177.364223319398, 14600.860814924843, 14639.780403293958, 14677.339519721638, 14991.0, 15031.022983506036, 517636.0, 523167.0, 523498.0, 523500.0, 523807.0, 46968.0, 57080.714596427584, 57324.42675573274, 57326.14010789841, 57328.42671573231, 1262.5959692898368, 1437.7412065828041, 1450.1371683848095, 1474.9115053096848, 1475.4172102019427, 22974.310770676908, 27732.44535109327, 28308.71691283061, 28359.291017723895, 30325.241868953453, 49323.26015109848, 49594.50405495899, 49661.006779864925, 50149.49850501448, 52822.73588632032, 544648.0, 551485.0, 551777.0, 552248.0, 554724.0, 26610.0, 29417.411651766415, 30008.099757007323, 30607.0, 30685.693143068285, 42666.0, 42790.35814462848, 42846.78576607097, 43496.56503434925, 44106.11787764291, 37336.0, 37692.246155076195, 38555.22889542249, 38679.41980870345, 38827.0, 34324.828375857964, 36619.26761464809, 37776.0, 37857.62142378541, 37910.62089379071, 36741.79549227055, 36794.816025919696, 37550.624493754716, 37576.81211593925, 37837.24325513529, 32319.51520727144, 33267.334653306316, 33609.966803983676, 33759.0, 33810.0, 24979.750202497744, 25110.874445627654, 25194.0, 25310.74689253084, 25378.481106997944], "unit": "ms", "name": "ext.html", "value": 20182.60416008256}, {"lowerIsBetter": false, "replicates": [355.563523365603, 357.10366978883, 360.17519879476004, 362.6120051544848, 362.94555816627496, 15985.440509582138, 17226.82773172268, 19603.313884014064, 20617.38147855565, 21490.462738431528, 16595.0, 17047.659046819055, 17049.91475042623, 20675.276365327216, 21088.0, 5123.615728820335, 5334.413214546396, 5343.385510666284, 5489.533389661883, 5669.09294512878, 3876.7673939563574, 4007.2185923744883, 5637.182608521762, 5666.405027472118, 5676.091825307952, 4011.83952641894, 4343.522212556616, 5761.567882408815, 5772.855678608026, 5776.364599894008, 5404.189371594253, 5551.77792888284, 5713.800016999385, 5772.6536407815665, 5780.2196703444915, 5254.159334506498, 5325.227841962933, 5340.172273297606, 5342.305500284977, 5342.786288548454, 23978.400539986465, 24232.757672423402, 24319.02723891042, 24585.38536536583, 25156.11953581616, 2043.673012318022, 2052.9486762830898, 2162.524244666171, 2223.466368071667, 2230.4423894026495, 9436.150746432786, 9727.805443891082, 9772.95113524435, 12777.10560260782, 13379.86271166952, 1568.8390590962606, 1598.5603958911393, 1697.8098253253288, 1743.4072415378705, 1769.8053214146435, 96824.0, 97861.51069244609, 99650.00349996479, 101210.0, 103545.96454035438, 10566.207534434949, 10946.343219406861, 11083.556657733601, 11096.445177741081, 11099.389533575732, 13169.407376668072, 13197.538086166938, 13206.801897971538, 13242.470301188037, 13266.668333291746, 8036.236557527026, 8144.638054964113, 8147.185281471805, 8151.633176507072, 8193.344532437392, 8088.433809633387, 8091.716789912325, 8111.0, 8155.551444670481, 8160.755177344689, 4343.69594128408, 4360.648199058302, 4362.8909277267685, 4371.693981421334, 4399.076193999296, 1719.974200386995, 1766.143420441085, 1768.4164225805453, 1772.6188869393143, 1827.360423851668, 891.3047822698305, 908.7637214324191, 932.8554074118523, 1027.9434631095212, 1036.2073014144187, 14529.31207726574, 18850.81149188518, 19391.0, 20588.588228235352, 20656.690149647766, 804.6218277409608, 833.6332013913858, 917.6512925088424, 961.6730311694058, 968.4576637083256, 10321.690349289533, 10519.053285204369, 10523.26337156407, 10533.787904539979, 10550.683479495627, 744456.0, 752371.3814309372, 753991.0, 769712.0, 770669.0, 116404.0, 120418.0, 121077.0, 122209.77790222161, 122474.77525224634, 1257.2079589858513, 1257.2142410993129, 1258.79229927061, 1261.4701825233424, 1282.5062350994747, 15625.453109141581, 17101.48695539136, 17902.46292611224, 18172.636547268718, 18191.09044547741, 49115.0, 49152.65236034369, 49436.258456123905, 49751.253731193334, 50529.73675658138, 70376.64811675908, 71220.64389678018, 71452.64273678599, 73167.0, 74304.62847685727, 9604.375715578464, 9751.853722194031, 9850.113489785954, 9854.753631159278, 10010.249231307535, 11672.941635291769, 11735.178537502634, 11746.001589864958, 18502.98233597165, 19725.802741972395, 15924.124173170123, 16066.518004459886, 16341.67316653684, 16420.83579164193, 16542.751858721887, 15561.84438155604, 15578.766318505459, 15666.76499852526, 15794.526164215093, 16445.91777041107, 12787.680307992374, 13793.172409655454, 16501.669966600843, 16920.238589263758, 16955.830441695918, 7692.153863075169, 8468.915310846813, 8828.823423531621, 9672.41965482045, 9709.320347575602, 15350.618444340063, 15540.0, 15763.448279310169, 15783.921080394524, 15881.0], "unit": "ms", "name": "jquery.html", "value": 9722.201287686432}, {"lowerIsBetter": false, "replicates": [1420.2827572076103, 1604.1898841085253, 1685.7050016247154, 1870.728744332072, 2042.7038079478484, 7538.773836784899, 7694.345980591649, 8210.671573137068, 8520.318374530038, 8542.0, 8222.712205072823, 8374.455660382071, 8426.199511046456, 8441.240288374045, 8446.704365347214, 2389.1876761900994, 2412.626042963335, 2413.9517209655796, 2414.625733011386, 2417.2627348658652, 2402.6876506054277, 2430.3195105370523, 2437.731849496554, 2443.2059580636273, 2444.40112172518, 2364.4561750797325, 2393.2580899920995, 2394.8682822444757, 2399.7360290368047, 2407.9518409631796, 2349.1660460536605, 2372.4543355028354, 2389.653500242464, 2391.1869964212165, 2393.210240620591, 2384.6899903012672, 2402.3994001499627, 2410.5660981023407, 2412.6139817629096, 2419.588669926117, 7065.43476521877, 8276.586170691442, 8324.542150181738, 8335.749927502124, 8337.249647531751, 4782.976085119587, 4831.275308703687, 4970.6520543561965, 5144.0, 5163.457836927144, 3138.686131386866, 3448.586169659657, 3524.1542029912634, 3538.2569660371355, 3597.8740744074094, 1045.8326667733127, 1051.4059556350637, 1053.9051485366276, 1071.571371451418, 1079.611339917629, 10913.0, 11161.330320180816, 11192.44037798107, 11195.60815371458, 11200.775984480264, 4494.16857881293, 4501.077279157765, 4566.566176213254, 4583.358329833826, 4610.792514336863, 8977.461352318882, 8982.685606003759, 8984.23633991105, 8989.55052247385, 8998.820023599623, 5096.821611243588, 5102.489751024942, 5107.208382700687, 5109.8722531937, 5116.0, 4720.268358404453, 4733.526647335308, 4738.09976104546, 4738.857834264978, 4741.478437371887, 3513.982430087833, 3613.512175856226, 3648.4527320902075, 3661.487391765155, 3673.577538583044, 1134.28540019787, 1150.045462266317, 1156.4044517073714, 1161.7618388230392, 1162.7674465107013, 1371.6433727230992, 1391.1583491987403, 1408.6407965968613, 1411.1039489923971, 1423.011007349882, 7916.218533626182, 7927.445078844541, 8172.305354044854, 8176.83646327082, 8212.876806847902, 1124.983125253122, 1161.6689243565543, 1217.5251651855783, 1217.7686239614445, 1231.8829711177423, 6070.271567411851, 6192.659403732748, 6280.340564240781, 6344.984802431589, 6352.205974253218, 9462.952685236529, 10309.07218350352, 10334.896651033543, 10369.844452333222, 10378.0, 9903.80192396148, 10041.648542300984, 10184.490775461198, 10237.078662920372, 10267.075963163352, 7830.686772529149, 7936.16670249627, 8016.55908925003, 8017.158198389086, 8039.517628942165, 7836.0, 7918.762437126896, 7955.204479551999, 7973.760787176394, 7980.760577182694, 9136.95431522838, 9171.495567743572, 9245.907540924505, 9248.768780780534, 9297.30270229749, 9076.591553380245, 9329.9270583881, 9425.952870235878, 9435.764105897408, 9499.715008549754, 7447.329740323397, 7450.850982980419, 7468.327850493264, 7501.88747168782, 7505.362044226302, 8097.473664211808, 8135.79660508492, 8137.8372432552205, 8138.9186108138165, 8140.063892652184, 7460.440466965108, 7561.4706970513735, 7635.618219089134, 7685.654145563351, 7693.692252309845, 7399.8150046249275, 7410.481266311306, 7421.0, 7552.169261381134, 7580.317771400601, 7538.849223015619, 7590.734324298623, 7601.7719468416035, 7606.581638009962, 7619.314261716472, 5740.196372507936, 6831.11195544576, 6883.208431030495, 6903.206131294764, 6920.653967301715, 6984.16190057176, 7010.158780946319, 7021.332973367522, 7022.157341119098, 7024.4029257513685], "unit": "ms", "name": "mootools.html", "value": 4812.783897845426}, {"lowerIsBetter": false, "replicates": [324.7499425442409, 330.69741186814065, 335.11362446329457, 335.43981550810145, 338.96271410144897, 6637.502187335957, 6858.279880612538, 6896.344847239504, 6955.234924158339, 6967.721291148354, 6671.733130674767, 6724.529282950195, 6764.492663050279, 6794.830129246771, 6835.316468353162, 4986.077575648498, 5053.747312634372, 5056.367954005749, 5066.898662026757, 5073.568746656539, 4289.528151903288, 4635.165670179366, 4746.738929358884, 5061.898762024757, 5074.873128171788, 4929.827456039039, 4979.601631869442, 4992.975035124819, 5000.274960130779, 5020.523050310233, 4012.478377810895, 4950.752462376885, 5001.09980203565, 5010.348654674905, 5021.0, 3581.176329444216, 4259.446271984653, 4285.807138678767, 4300.870973870758, 4301.2902871026245, 6367.681615919232, 6555.737770489175, 6578.736850525973, 6601.768938087144, 6636.701348439332, 5978.581499295051, 5988.652553175544, 6000.279966404016, 6003.459688628044, 6003.8198854034445, 7313.670884810196, 7326.706931722725, 7330.376917961979, 7348.853022939565, 7353.742619008309, 4574.08518296342, 4708.058388322315, 4717.174494463455, 4727.101850648401, 4852.126617208901, 7596.886046709304, 7812.68749250035, 7824.608769561556, 7837.333826624744, 7852.17552157021, 4540.977295113504, 4643.674942753974, 4776.188048031843, 4782.497837727058, 4786.736729479842, 4488.798004089824, 4520.344550040226, 4677.883052923636, 4728.3143944127905, 4804.8318308859025, 4747.786349614275, 4758.714477131383, 5848.356680765198, 5870.970645146833, 5885.705714714247, 6347.301796802348, 6660.167479065117, 7370.115586129698, 7371.189169191492, 7383.778486645409, 1632.7061128996895, 1694.9576260593583, 1696.5588946873654, 1698.456493921933, 1701.4045084220672, 976.4434272464769, 989.9158571521501, 1050.2700623067049, 1076.6339444588878, 1082.0748260237542, 6837.453003759687, 6846.315368463114, 6864.897026544506, 6871.209810871814, 6882.311768823178, 7630.160682324939, 7639.121501027342, 7644.503107298008, 7652.923470765332, 7659.425543084291, 6652.970843892659, 6808.38724514796, 6825.317468253135, 6834.931650683529, 6899.0, 4024.678025757991, 4071.2875246831686, 4075.612816782401, 4076.3274059780397, 4078.816453259551, 7958.6816527339415, 7984.041914970125, 7987.960060199662, 7991.72028978983, 7996.280334769898, 7679.808004799925, 7965.203479651988, 7967.880481792778, 8005.279524842792, 8005.799855003672, 7950.204979502004, 7963.044434667877, 7972.36221102322, 7980.241877021674, 7982.640781164745, 7440.925590744023, 7710.575918324546, 7722.806929826799, 7724.884126737991, 7743.148253692202, 7538.509996850187, 7541.057367829021, 7561.546307221584, 7607.4674772765375, 7653.617319134132, 6922.788512010277, 7601.353884919844, 7995.680172793023, 8010.239027292398, 8013.19868013194, 3748.8313025914445, 4481.820727170877, 4689.437267527782, 4699.3420921071765, 4709.058188362273, 7507.286807753255, 7509.9249007509225, 7676.80807979805, 7728.884066738891, 7735.497192682457, 6050.969745151246, 7395.630218488946, 7412.518186317872, 7424.628768561658, 7437.219091995479, 6038.4867286279405, 7149.928500715135, 7386.261373862786, 7393.44549158826, 7440.516366436164, 7242.818929526593, 7243.782686519413, 7254.782356529313, 7256.600886951268, 7268.0, 7141.107361579802, 7426.777196684108, 7439.479236453612, 7448.776536703907, 7455.217202193909, 7221.241769614325, 7244.601546914971, 7255.637218139177, 7260.237675044044, 7260.528065675714], "unit": "ms", "name": "prototype.html", "value": 5307.022299204669}, {"lowerIsBetter": false, "replicates": [697.1947400752132, 784.8548018616557, 797.780610332159, 822.4612878564535, 823.8681810910253, 14782.852171478282, 15129.0, 15154.848451515481, 15160.696786064273, 15174.165420901845, 14115.505957291496, 14255.71488570228, 14271.357788899499, 14278.643033924158, 14289.928255380863, 758.5221310574344, 760.7299408709912, 761.8514389694012, 767.769669099268, 956.9473678947655, 947.6872632031448, 957.248559880494, 957.301170145794, 957.5595226195924, 959.3092973059386, 796.2913007423398, 1002.2533212756507, 1003.6938733686222, 1006.8187726209279, 1009.1271050541266, 789.8973133492609, 791.7149826062613, 990.1237404896647, 992.2905122837134, 992.5682328187262, 750.1860481377657, 751.8909758085047, 753.7399597138979, 753.9858889793219, 755.69016703152, 14976.400943962228, 15112.39550417982, 15149.772753408708, 15153.696926061417, 15172.0, 12457.377131143498, 12487.75024499505, 12735.108542402035, 12795.104342696015, 12855.807162892565, 10076.89923100767, 10260.435676037814, 10265.486725663686, 10274.383536987805, 10295.794084118275, 3509.31568344173, 3534.2224710563637, 3538.6461353864665, 3539.628339024417, 3542.6988705960025, 75640.62179689121, 77083.8437423439, 77345.83981240286, 77515.0, 77817.61091194564, 9677.322587418954, 9720.562574684156, 9771.902280977241, 9878.506074696237, 10440.37357758537, 21685.915704214727, 22343.32970010902, 22400.439989000406, 22406.0, 22446.55106897853, 7147.606881621561, 7160.606166660779, 7280.271972802678, 7380.852382952311, 7417.629118544051, 3946.309395855714, 4028.8187031583648, 4032.2540330039037, 4035.9798201008807, 4042.43405923171, 3542.3446662367546, 3587.28254349126, 3593.083763640255, 3593.2813437312643, 3608.4767708682093, 655.4592461219513, 663.280340830195, 664.7839452178061, 672.4183581202225, 674.1775034457953, 486.888015756378, 487.35425561131643, 492.1412135823012, 508.506748453999, 524.9658772179796, 8617.61220745068, 8662.350323725746, 8701.216890479887, 8818.955905220433, 8948.8657670135, 527.7994362142362, 568.7497501099585, 616.3220457496718, 631.516889579472, 636.745301879252, 3877.0418210588427, 3937.1731936293127, 4157.625813676784, 4251.59609837065, 4266.445362102907, 96653.0, 96913.5154324238, 99311.51032734515, 99745.0, 100395.0, 79329.0, 80611.19388806155, 86347.13652863516, 86576.56711716401, 86823.26353472895, 55540.0, 73570.63214683892, 73907.63046184843, 74022.62988685022, 74539.25460745323, 8788.2529984952, 9026.0, 9065.365424420228, 9091.227245684191, 9145.588448519966, 53010.73494632502, 54397.91204175974, 54596.72701636466, 54685.0, 56610.0, 73714.26285737289, 76754.0, 76914.61542692251, 77203.0, 78151.21848781667, 9330.720078397659, 10895.945520272347, 10909.509072091616, 10947.45262736844, 11629.941850290694, 11520.654380368602, 11617.6514704559, 12179.269243845398, 12458.127931045103, 12496.25022498653, 11474.541018359172, 11764.411779410824, 11866.40667966581, 11892.405379730806, 12431.689207769516, 11566.132540059698, 11847.407629618658, 11922.642320730392, 11933.0, 12430.316332601793, 11486.0, 11693.415329233334, 11704.648860534198, 11704.946554810109, 11718.824217636571, 9456.33805633599, 9655.324127311022, 9985.0, 10005.599776008878, 10580.259381843505, 9094.545272736468, 9268.814623707623, 9406.952965235403, 9831.360961537477, 9978.25163112784], "unit": "ms", "name": "yui.html", "value": 7046.604870548189}], "name": "dromaeo_css", "value": 7665.117342109546}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 103.0, 109.0, 111.0, 110.0, 100.0, 111.0, 111.0, 100.0], "unit": "ms", "name": "ai-astar", "value": 107.5}, {"lowerIsBetter": true, "replicates": [149.0, 122.0, 141.0, 162.0, 151.0, 147.0, 139.0, 119.0, 155.0, 118.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.3}, {"lowerIsBetter": true, "replicates": [181.0, 183.0, 200.0, 254.0, 185.0, 182.0, 196.0, 264.0, 188.0, 179.0], "unit": "ms", "name": "audio-dft", "value": 201.2}, {"lowerIsBetter": true, "replicates": [59.0, 70.0, 77.0, 73.0, 55.0, 57.0, 56.0, 68.0, 71.0, 55.0], "unit": "ms", "name": "audio-fft", "value": 64.1}, {"lowerIsBetter": true, "replicates": [84.0, 82.0, 118.0, 74.0, 76.0, 75.0, 112.0, 105.0, 109.0, 141.0], "unit": "ms", "name": "audio-oscillator", "value": 97.6}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 85.0, 85.0, 86.0, 88.0, 88.0, 85.0, 86.0, 85.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 85.9}, {"lowerIsBetter": true, "replicates": [93.0, 92.0, 93.0, 93.0, 93.0, 109.0, 150.0, 102.0, 93.0, 92.0], "unit": "ms", "name": "imaging-darkroom", "value": 101.0}, {"lowerIsBetter": true, "replicates": [86.0, 85.0, 86.0, 84.0, 87.0, 86.0, 85.0, 83.0, 84.0, 84.0], "unit": "ms", "name": "imaging-desaturate", "value": 85.0}, {"lowerIsBetter": true, "replicates": [54.0, 56.0, 55.0, 53.0, 54.0, 54.0, 54.0, 63.0, 53.0, 52.0], "unit": "ms", "name": "json-parse-financial", "value": 54.8}, {"lowerIsBetter": true, "replicates": [62.0, 61.0, 67.0, 98.0, 96.0, 71.0, 66.0, 68.0, 68.0, 68.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 72.5}, {"lowerIsBetter": true, "replicates": [82.0, 80.0, 81.0, 83.0, 81.0, 81.0, 80.0, 83.0, 83.0, 83.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 81.7}, {"lowerIsBetter": true, "replicates": [129.0, 118.0, 128.0, 119.0, 120.0, 124.0, 118.0, 120.0, 123.0, 126.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 122.5}, {"lowerIsBetter": true, "replicates": [133.0, 123.0, 135.0, 135.0, 133.0, 123.0, 133.0, 126.0, 138.0, 123.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 130.2}, {"lowerIsBetter": true, "replicates": [64.0, 63.0, 66.0, 62.0, 64.0, 64.0, 64.0, 64.0, 67.0, 82.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.0}], "name": "kraken", "value": 1410.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [173.91304347826087], "unit": "score", "name": "Richards", "value": 202.98649999999995}, {"lowerIsBetter": false, "replicates": [162.813415825464], "unit": "score", "name": "DeltaBlue", "value": 406.09675599999997}, {"lowerIsBetter": false, "replicates": [280.0336040324839], "unit": "score", "name": "Encrypt", "value": 950.5323509999997}, {"lowerIsBetter": false, "replicates": [4699.530516431925], "unit": "score", "name": "Decrypt", "value": 56.63991308691312}, {"lowerIsBetter": false, "replicates": [1753.0647985989492], "unit": "score", "name": "RayTrace", "value": 422.11160739260737}, {"lowerIsBetter": false, "replicates": [791.1392405063291], "unit": "score", "name": "Earley", "value": 842.4092320000002}, {"lowerIsBetter": false, "replicates": [12587.5], "unit": "score", "name": "Boyer", "value": 52.946415094339656}, {"lowerIsBetter": false, "replicates": [30044.117647058825], "unit": "score", "name": "RegExp", "value": 30.3215761135585}, {"lowerIsBetter": false, "replicates": [745.7121551081283], "unit": "score", "name": "Splay", "value": 109.27943099999997}, {"lowerIsBetter": false, "replicates": [4554.545454545455], "unit": "score", "name": "NavierStokes", "value": 325.8283433133734}], "name": "v8_7", "value": 19832.233495865214}]} 05:35:28 INFO - RETURN: dromaeo_css: 7665.12 05:35:28 INFO - RETURN: kraken: 1410.30 05:35:28 INFO - RETURN: v8_7: 19832.23 05:35:28 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,59]]", "result": "19832.23"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,59]]", "result": "1410.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,59]]", "result": "7665.12"}}} 05:35:29 INFO - Return code: 0 05:35:29 INFO - # TBPL SUCCESS # 05:35:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:35:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:35:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:35:29 INFO - 2015-11-24 05:35:29.023 screenresolution[1995:18640] starting screenresolution argv=screenresolution get 05:35:29 INFO - 2015-11-24 05:35:29.026 screenresolution[1995:18640] Display 0: 1600x1200x32@60 05:35:29 INFO - 2015-11-24 05:35:29.032 screenresolution[1996:18644] starting screenresolution argv=screenresolution list 05:35:29 INFO - Available Modes on Display 0 05:35:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:35:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:35:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:35:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:35:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:35:29 INFO - Intel Iris: 05:35:29 INFO - Chipset Model: Intel Iris 05:35:29 INFO - Type: GPU 05:35:29 INFO - Bus: Built-In 05:35:29 INFO - VRAM (Dynamic, Max): 1536 MB 05:35:29 INFO - Vendor: Intel (0x8086) 05:35:29 INFO - Device ID: 0x0a2e 05:35:29 INFO - Revision ID: 0x0009 05:35:29 INFO - Displays: 05:35:29 INFO - Display: 05:35:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:35:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:35:29 INFO - Display Serial Number: 1600x1200 60 05:35:29 INFO - Main Display: Yes 05:35:29 INFO - Mirror: Off 05:35:29 INFO - Online: Yes 05:35:29 INFO - Rotation: Supported 05:35:29 INFO - Return code: 0 05:35:29 INFO - Running post-action listener: _resource_record_post_action 05:35:29 INFO - Running post-run listener: _resource_record_post_run 05:35:30 INFO - Total resource usage - Wall time: 1241s; CPU: 53.0%; Read bytes: 2707456; Write bytes: 687549440; Read time: 43; Write time: 2269 05:35:30 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 335354368; Write bytes: 336558080; Read time: 26266; Write time: 635 05:35:30 INFO - run-tests - Wall time: 1211s; CPU: 52.0%; Read bytes: 2707456; Write bytes: 340554752; Read time: 43; Write time: 1600 05:35:30 INFO - Running post-run listener: _upload_blobber_files 05:35:30 INFO - Blob upload gear active. 05:35:30 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:35:30 INFO - Copying logs to upload dir... 05:35:30 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1282.773063 ========= master_lag: 0.52 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2015-11-24 05:35:30.867968) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:35:30.873732) ========= 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.v9gSj4c60S/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.ZwxRnBYXV1/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 build_url:https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009826 build_url: 'https://queue.taskcluster.net/v1/task/TcTxA_EXSA-WMqQzmw_HhA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:35:30.905797) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:35:30.906107) ========= 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.v9gSj4c60S/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.ZwxRnBYXV1/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.004785 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:35:30.953821) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 05:35:30.954116) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 05:35:30.954457) ========= ========= Total master_lag: 0.70 =========