builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0019 starttime: 1447897013.13 results: success (0) buildid: 20151118143539 builduid: e296ea047c3d421a9e9a3326cfd3b08b revision: 8e22c8aac02322264ab50695d88901ac807fd96e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.126698) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.127409) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.127858) ========= 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.StM05ynPSB/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.dTwNFQQAuY/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.005275 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.150953) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.151470) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.167053) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.167535) ========= 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.StM05ynPSB/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.dTwNFQQAuY/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.005232 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.217844) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.218216) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.218790) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.219294) ========= 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.StM05ynPSB/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.dTwNFQQAuY/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-18 17:36:53-- 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-18 17:36:53 (1.41 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.144655 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.378525) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.378939) ========= 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.StM05ynPSB/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.dTwNFQQAuY/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.019045 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.429487) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:53.430208) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8e22c8aac02322264ab50695d88901ac807fd96e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 8e22c8aac02322264ab50695d88901ac807fd96e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.StM05ynPSB/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.dTwNFQQAuY/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-18 17:36:53,506 truncating revision to first 12 chars 2015-11-18 17:36:53,506 Setting DEBUG logging. 2015-11-18 17:36:53,506 attempt 1/10 2015-11-18 17:36:53,506 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8e22c8aac023?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-18 17:36:54,126 unpacking tar archive at: fx-team-8e22c8aac023/testing/mozharness/ program finished with exit code 0 elapsedTime=0.855967 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:54.302937) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:54.303342) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:54.315429) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:54.315868) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 17:36:54.316366) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2015-11-18 17:36:54.316692) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.StM05ynPSB/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.dTwNFQQAuY/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 17:36:54 INFO - MultiFileLogger online at 20151118 17:36:54 in /builds/slave/test 17:36:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 17:36:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:36:54 INFO - {'append_to_log': False, 17:36:54 INFO - 'base_work_dir': '/builds/slave/test', 17:36:54 INFO - 'blob_upload_branch': 'Fx-Team', 17:36:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:36:54 INFO - 'branch': 'Fx-Team', 17:36:54 INFO - 'buildbot_json_path': 'buildprops.json', 17:36:54 INFO - 'config_files': ('talos/mac_config.py',), 17:36:54 INFO - 'default_actions': ('clobber', 17:36:54 INFO - 'read-buildbot-config', 17:36:54 INFO - 'download-and-extract', 17:36:54 INFO - 'populate-webroot', 17:36:54 INFO - 'create-virtualenv', 17:36:54 INFO - 'install', 17:36:54 INFO - 'run-tests'), 17:36:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:36:54 INFO - 'download_minidump_stackwalk': True, 17:36:54 INFO - 'download_symbols': 'ondemand', 17:36:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:36:54 INFO - 'tooltool.py': '/tools/tooltool.py', 17:36:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:36:54 INFO - '/tools/misc-python/virtualenv.py')}, 17:36:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:36:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:36:54 INFO - 'installer_path': 'installer.exe', 17:36:54 INFO - 'log_level': 'info', 17:36:54 INFO - 'log_name': 'talos', 17:36:54 INFO - 'log_to_console': True, 17:36:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:36:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:36:54 INFO - 'opt_config_files': (), 17:36:54 INFO - 'pip_index': False, 17:36:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:36:54 INFO - 'cmd': ('bash', 17:36:54 INFO - '-c', 17:36:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:36:54 INFO - 'enabled': True, 17:36:54 INFO - 'halt_on_failure': False, 17:36:54 INFO - 'name': 'check_screen_resolution'},), 17:36:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:36:54 INFO - 'cmd': ('bash', 17:36:54 INFO - '-c', 17:36:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:36:54 INFO - 'enabled': True, 17:36:54 INFO - 'halt_on_failure': False, 17:36:54 INFO - 'name': 'check_screen_resolution'},), 17:36:54 INFO - 'run_cmd_checks_enabled': True, 17:36:54 INFO - 'sps_profile': False, 17:36:54 INFO - 'sps_profile_interval': 0, 17:36:54 INFO - 'suite': 'dromaeojs', 17:36:54 INFO - 'system_bits': '64', 17:36:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:36:54 INFO - 'title': 't-yosemite-r7-0019', 17:36:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:36:54 INFO - 'use_talos_json': True, 17:36:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:36:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:36:54 INFO - 'work_dir': 'build'} 17:36:54 INFO - ##### 17:36:54 INFO - ##### Running clobber step. 17:36:54 INFO - ##### 17:36:54 INFO - Running pre-action listener: _resource_record_pre_action 17:36:54 INFO - Running main action method: clobber 17:36:54 INFO - rmtree: /builds/slave/test/build 17:36:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:36:55 INFO - Running post-action listener: _resource_record_post_action 17:36:55 INFO - ##### 17:36:55 INFO - ##### Running read-buildbot-config step. 17:36:55 INFO - ##### 17:36:55 INFO - Running pre-action listener: _resource_record_pre_action 17:36:55 INFO - Running main action method: read_buildbot_config 17:36:55 INFO - Using buildbot properties: 17:36:55 INFO - { 17:36:55 INFO - "properties": { 17:36:55 INFO - "buildnumber": 4, 17:36:55 INFO - "product": "firefox", 17:36:55 INFO - "script_repo_revision": "production", 17:36:55 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 17:36:55 INFO - "repository": "", 17:36:55 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 17:36:55 INFO - "buildid": "20151118143539", 17:36:55 INFO - "slavename": "t-yosemite-r7-0019", 17:36:55 INFO - "pgo_build": "False", 17:36:55 INFO - "basedir": "/builds/slave/test", 17:36:55 INFO - "project": "", 17:36:55 INFO - "platform": "yosemite_r7", 17:36:55 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:36:55 INFO - "slavebuilddir": "test", 17:36:55 INFO - "scheduler": "tests-fx-team-macosx64-talos", 17:36:55 INFO - "branch": "fx-team", 17:36:55 INFO - "repo_path": "integration/fx-team", 17:36:55 INFO - "stage_platform": "macosx64", 17:36:55 INFO - "builduid": "e296ea047c3d421a9e9a3326cfd3b08b", 17:36:55 INFO - "revision": "8e22c8aac02322264ab50695d88901ac807fd96e" 17:36:55 INFO - }, 17:36:55 INFO - "sourcestamp": { 17:36:55 INFO - "repository": "", 17:36:55 INFO - "hasPatch": false, 17:36:55 INFO - "project": "", 17:36:55 INFO - "branch": "fx-team-macosx64-talos", 17:36:55 INFO - "changes": [ 17:36:55 INFO - { 17:36:55 INFO - "category": null, 17:36:55 INFO - "files": [ 17:36:55 INFO - { 17:36:55 INFO - "url": null, 17:36:55 INFO - "name": "https://queue.taskcluster.net/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 17:36:55 INFO - } 17:36:55 INFO - ], 17:36:55 INFO - "repository": "", 17:36:55 INFO - "rev": "8e22c8aac02322264ab50695d88901ac807fd96e", 17:36:55 INFO - "who": "kwierso@gmail.com", 17:36:55 INFO - "when": 1447892660, 17:36:55 INFO - "number": 6679698, 17:36:55 INFO - "comments": "Backed out changeset e8c43ec095e3 (bug 1132501) for Gu permafail CLOSED TREEx", 17:36:55 INFO - "project": "", 17:36:55 INFO - "at": "Wed 18 Nov 2015 16:24:20", 17:36:55 INFO - "branch": "fx-team-macosx64-talos", 17:36:55 INFO - "revlink": "", 17:36:55 INFO - "properties": [ 17:36:55 INFO - [ 17:36:55 INFO - "buildid", 17:36:55 INFO - "20151118143539", 17:36:55 INFO - "Change" 17:36:55 INFO - ], 17:36:55 INFO - [ 17:36:55 INFO - "builduid", 17:36:55 INFO - "e296ea047c3d421a9e9a3326cfd3b08b", 17:36:55 INFO - "Change" 17:36:55 INFO - ], 17:36:55 INFO - [ 17:36:55 INFO - "pgo_build", 17:36:55 INFO - "False", 17:36:55 INFO - "Change" 17:36:55 INFO - ] 17:36:55 INFO - ], 17:36:55 INFO - "revision": "8e22c8aac02322264ab50695d88901ac807fd96e" 17:36:55 INFO - } 17:36:55 INFO - ], 17:36:55 INFO - "revision": "8e22c8aac02322264ab50695d88901ac807fd96e" 17:36:55 INFO - } 17:36:55 INFO - } 17:36:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 17:36:55 INFO - Running post-action listener: _resource_record_post_action 17:36:55 INFO - ##### 17:36:55 INFO - ##### Running download-and-extract step. 17:36:55 INFO - ##### 17:36:55 INFO - Running pre-action listener: _resource_record_pre_action 17:36:55 INFO - Running main action method: download_and_extract 17:36:55 INFO - mkdir: /builds/slave/test/build/tests 17:36:55 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')]} 17:36:55 INFO - https://queue.taskcluster.net/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 17:36:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/test_packages.json 17:36:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/test_packages.json 17:36:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 17:36:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 17:36:56 INFO - Downloaded 1149 bytes. 17:36:56 INFO - Reading from file /builds/slave/test/build/test_packages.json 17:36:56 INFO - Using the following test package requirements: 17:36:56 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 17:36:56 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 17:36:56 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 17:36:56 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 17:36:56 INFO - u'jsshell-mac.zip'], 17:36:56 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 17:36:56 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 17:36:56 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 17:36:56 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 17:36:56 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 17:36:56 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 17:36:56 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 17:36:56 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 17:36:56 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 17:36:56 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 17:36:56 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 17:36:56 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 17:36:56 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:36:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:36:56 INFO - https://queue.taskcluster.net/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:36:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 17:36:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 17:36:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/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 17:36:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/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 17:36:57 INFO - Downloaded 17477426 bytes. 17:36:57 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 17:36:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 17:36:57 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 17:37:00 INFO - Return code: 0 17:37:00 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 17:37:00 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')]} 17:37:00 INFO - https://queue.taskcluster.net/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:37:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 17:37:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 17:37:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/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 17:37:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/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 17:37:01 INFO - Downloaded 17477426 bytes. 17:37:01 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 17:37:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 17:37:01 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 17:37:05 INFO - Return code: 0 17:37:05 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')]} 17:37:05 INFO - https://queue.taskcluster.net/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:37:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 17:37:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 17:37:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/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 17:37:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/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 17:37:05 INFO - Downloaded 11130997 bytes. 17:37:05 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 17:37:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 17:37:05 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 17:37:06 INFO - Return code: 0 17:37:06 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')]} 17:37:06 INFO - https://queue.taskcluster.net/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:37:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 17:37:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 17:37:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg 17:37:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YVIpQFebT4uW0P4Up-x0jQ/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 17:37:09 INFO - Downloaded 108853978 bytes. 17:37:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 17:37:09 INFO - mkdir: /builds/slave/test/properties 17:37:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:37:09 INFO - Writing to file /builds/slave/test/properties/build_url 17:37:09 INFO - Contents: 17:37:09 INFO - build_url:https://queue.taskcluster.net/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 17:37:09 INFO - Running post-action listener: _resource_record_post_action 17:37:09 INFO - Running post-action listener: set_extra_try_arguments 17:37:09 INFO - ##### 17:37:09 INFO - ##### Running populate-webroot step. 17:37:09 INFO - ##### 17:37:09 INFO - Running pre-action listener: _resource_record_pre_action 17:37:09 INFO - Running main action method: populate_webroot 17:37:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:37:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:37:09 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 17:37:09 INFO - '../fennec_ids.txt'], 17:37:09 INFO - 'tests': ['tcheck2']}, 17:37:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:37:09 INFO - '--tppagecycles', 17:37:09 INFO - '7'], 17:37:09 INFO - 'tests': ['tsvgm']}}, 17:37:09 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 17:37:09 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 17:37:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:37:09 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:37:09 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 17:37:09 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:37:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:37:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:09 INFO - 'talos_options': ['--e10s'], 17:37:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:37:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:09 INFO - 'tests': ['damp', 'tps']}, 17:37:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:09 INFO - 'talos_options': ['--e10s'], 17:37:09 INFO - 'tests': ['damp', 'tps']}, 17:37:09 INFO - 'g3': {'tests': ['dromaeo_dom']}, 17:37:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:37:09 INFO - 'other': {'tests': ['a11yr', 17:37:09 INFO - 'ts_paint', 17:37:09 INFO - 'tpaint', 17:37:09 INFO - 'sessionrestore', 17:37:09 INFO - 'sessionrestore_no_auto_restore']}, 17:37:09 INFO - 'other-e10s': {'talos_options': ['--e10s'], 17:37:09 INFO - 'tests': ['a11yr', 17:37:09 INFO - 'ts_paint', 17:37:09 INFO - 'tpaint', 17:37:09 INFO - 'sessionrestore', 17:37:09 INFO - 'sessionrestore_no_auto_restore']}, 17:37:09 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 17:37:09 INFO - 'tests': ['a11yr', 17:37:09 INFO - 'ts_paint', 17:37:09 INFO - 'tpaint', 17:37:09 INFO - 'sessionrestore', 17:37:09 INFO - 'sessionrestore_no_auto_restore']}, 17:37:09 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 17:37:09 INFO - 'tests': ['a11yr', 17:37:09 INFO - 'ts_paint', 17:37:09 INFO - 'tpaint', 17:37:09 INFO - 'sessionrestore', 17:37:09 INFO - 'sessionrestore_no_auto_restore']}, 17:37:09 INFO - 'other_l64': {'tests': ['a11yr', 17:37:09 INFO - 'ts_paint', 17:37:09 INFO - 'tpaint', 17:37:09 INFO - 'sessionrestore', 17:37:09 INFO - 'sessionrestore_no_auto_restore']}, 17:37:09 INFO - 'other_nol64': {'tests': ['a11yr', 17:37:09 INFO - 'ts_paint', 17:37:09 INFO - 'tpaint', 17:37:09 INFO - 'sessionrestore', 17:37:09 INFO - 'sessionrestore_no_auto_restore']}, 17:37:09 INFO - 'svgr': {'tests': ['tsvgx', 17:37:09 INFO - 'tsvgr_opacity', 17:37:09 INFO - 'tart', 17:37:09 INFO - 'tscrollx', 17:37:09 INFO - 'cart']}, 17:37:09 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 17:37:09 INFO - 'tests': ['tsvgx', 17:37:09 INFO - 'tsvgr_opacity', 17:37:09 INFO - 'tart', 17:37:09 INFO - 'tscrollx', 17:37:09 INFO - 'cart']}, 17:37:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:09 INFO - 'tests': ['tp5o']}, 17:37:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:09 INFO - 'talos_options': ['--e10s'], 17:37:09 INFO - 'tests': ['tp5o']}, 17:37:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:09 INFO - 'talos_options': ['--xperf_path', 17:37:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:37:09 INFO - 'tests': ['tp5n']}, 17:37:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:09 INFO - 'talos_options': ['--e10s', 17:37:09 INFO - '--xperf_path', 17:37:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:37:09 INFO - 'tests': ['tp5n']}}, 17:37:09 INFO - 'talos.zip': {'path': '', 17:37:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:37:09 INFO - Running post-action listener: _resource_record_post_action 17:37:09 INFO - ##### 17:37:09 INFO - ##### Running create-virtualenv step. 17:37:09 INFO - ##### 17:37:09 INFO - Running pre-action listener: _resource_record_pre_action 17:37:09 INFO - Running main action method: create_virtualenv 17:37:09 INFO - Creating virtualenv /builds/slave/test/build/venv 17:37:09 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 17:37:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:37:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:37:09 INFO - Using real prefix '/tools/python27' 17:37:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:37:10 INFO - Installing distribute.............................................................................................................................................................................................done. 17:37:12 INFO - Installing pip.................done. 17:37:12 INFO - Return code: 0 17:37:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:37:12 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')]} 17:37:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:37:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:12 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')]} 17:37:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:37:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:12 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 0x105877490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65b48b560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10579b9f0>, '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 0x1054fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10578e8c8>, '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.StM05ynPSB/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.dTwNFQQAuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 17:37:12 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 17:37:12 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 17:37:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StM05ynPSB/Render', 17:37:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:37:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:37:12 INFO - 'HOME': '/Users/cltbld', 17:37:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:37:12 INFO - 'LOGNAME': 'cltbld', 17:37:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:37:12 INFO - 'MOZ_NO_REMOTE': '1', 17:37:12 INFO - 'NO_EM_RESTART': '1', 17:37:12 INFO - 'PAGER': '/bin/cat', 17:37:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:37:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:37:12 INFO - 'PWD': '/builds/slave/test', 17:37:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:37:12 INFO - 'SHELL': '/bin/bash', 17:37:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dTwNFQQAuY/Listeners', 17:37:12 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 17:37:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:37:12 INFO - 'USER': 'cltbld', 17:37:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:37:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:37:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:37:12 INFO - 'XPC_FLAGS': '0x0', 17:37:12 INFO - 'XPC_SERVICE_NAME': '0', 17:37:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:37:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:37:13 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 17:37:13 INFO - Cleaning up... 17:37:13 INFO - Return code: 0 17:37:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:37: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')]} 17:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:37:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37: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')]} 17:37:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:37:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:13 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 0x105877490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65b48b560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10579b9f0>, '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 0x1054fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10578e8c8>, '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.StM05ynPSB/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.dTwNFQQAuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 17:37:13 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 17:37:13 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 17:37:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StM05ynPSB/Render', 17:37:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:37:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:37:13 INFO - 'HOME': '/Users/cltbld', 17:37:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:37:13 INFO - 'LOGNAME': 'cltbld', 17:37:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:37:13 INFO - 'MOZ_NO_REMOTE': '1', 17:37:13 INFO - 'NO_EM_RESTART': '1', 17:37:13 INFO - 'PAGER': '/bin/cat', 17:37:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:37:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:37:13 INFO - 'PWD': '/builds/slave/test', 17:37:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:37:13 INFO - 'SHELL': '/bin/bash', 17:37:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dTwNFQQAuY/Listeners', 17:37:13 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 17:37:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:37:13 INFO - 'USER': 'cltbld', 17:37:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:37:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:37:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:37:13 INFO - 'XPC_FLAGS': '0x0', 17:37:13 INFO - 'XPC_SERVICE_NAME': '0', 17:37:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:37:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:37:13 INFO - Downloading/unpacking psutil>=0.7.1 17:37:13 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 17:37:13 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 17:37:13 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 17:37:13 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 17:37:13 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 17:37:13 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 17:37:15 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 17:37:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 17:37:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:37:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:37:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:37:15 INFO - Installing collected packages: psutil 17:37:15 INFO - Running setup.py install for psutil 17:37:16 INFO - building 'psutil._psutil_osx' extension 17:37:16 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 17:37:16 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 17:37:16 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 17:37:16 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 17:37:16 INFO - building 'psutil._psutil_posix' extension 17:37:16 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 17:37:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:37:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:37:16 INFO - ^ 17:37:16 INFO - 1 warning generated. 17:37:16 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 17:37:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:37:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:37:16 INFO - Successfully installed psutil 17:37:16 INFO - Cleaning up... 17:37:16 INFO - Return code: 0 17:37:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:37:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:37:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:37:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:16 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 0x105877490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65b48b560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10579b9f0>, '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 0x1054fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10578e8c8>, '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.StM05ynPSB/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.dTwNFQQAuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 17:37:16 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 17:37:16 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 17:37:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StM05ynPSB/Render', 17:37:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:37:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:37:16 INFO - 'HOME': '/Users/cltbld', 17:37:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:37:16 INFO - 'LOGNAME': 'cltbld', 17:37:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:37:16 INFO - 'MOZ_NO_REMOTE': '1', 17:37:16 INFO - 'NO_EM_RESTART': '1', 17:37:16 INFO - 'PAGER': '/bin/cat', 17:37:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:37:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:37:16 INFO - 'PWD': '/builds/slave/test', 17:37:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:37:16 INFO - 'SHELL': '/bin/bash', 17:37:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dTwNFQQAuY/Listeners', 17:37:16 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 17:37:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:37:16 INFO - 'USER': 'cltbld', 17:37:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:37:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:37:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:37:16 INFO - 'XPC_FLAGS': '0x0', 17:37:16 INFO - 'XPC_SERVICE_NAME': '0', 17:37:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:37:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:37:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:37:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:37:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:37:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:37:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:37:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 17:37:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:37:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:37:19 INFO - Installing collected packages: mozsystemmonitor 17:37:19 INFO - Running setup.py install for mozsystemmonitor 17:37:19 INFO - Successfully installed mozsystemmonitor 17:37:19 INFO - Cleaning up... 17:37:19 INFO - Return code: 0 17:37:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:37: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')]} 17:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:37:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37: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')]} 17:37:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:37:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:19 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 0x105877490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65b48b560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10579b9f0>, '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 0x1054fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10578e8c8>, '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.StM05ynPSB/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.dTwNFQQAuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 17:37:19 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 17:37:19 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 17:37:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StM05ynPSB/Render', 17:37:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:37:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:37:19 INFO - 'HOME': '/Users/cltbld', 17:37:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:37:19 INFO - 'LOGNAME': 'cltbld', 17:37:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:37:19 INFO - 'MOZ_NO_REMOTE': '1', 17:37:19 INFO - 'NO_EM_RESTART': '1', 17:37:19 INFO - 'PAGER': '/bin/cat', 17:37:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:37:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:37:19 INFO - 'PWD': '/builds/slave/test', 17:37:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:37:19 INFO - 'SHELL': '/bin/bash', 17:37:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dTwNFQQAuY/Listeners', 17:37:19 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 17:37:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:37:19 INFO - 'USER': 'cltbld', 17:37:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:37:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:37:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:37:19 INFO - 'XPC_FLAGS': '0x0', 17:37:19 INFO - 'XPC_SERVICE_NAME': '0', 17:37:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:37:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:37:19 INFO - Downloading/unpacking blobuploader==1.2.4 17:37:19 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 17:37:19 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 17:37:19 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 17:37:19 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 17:37:19 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 17:37:19 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 17:37:21 INFO - Downloading blobuploader-1.2.4.tar.gz 17:37:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 17:37:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:37:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:37:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:37:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:37:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:37:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:37:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 17:37:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:37:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:37:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:37:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:37:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:37:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:37:22 INFO - Downloading docopt-0.6.1.tar.gz 17:37:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 17:37:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:37:23 INFO - Installing collected packages: blobuploader, requests, docopt 17:37:23 INFO - Running setup.py install for blobuploader 17:37:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:37:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:37:23 INFO - Running setup.py install for requests 17:37:23 INFO - Running setup.py install for docopt 17:37:23 INFO - Successfully installed blobuploader requests docopt 17:37:23 INFO - Cleaning up... 17:37:23 INFO - Return code: 0 17:37:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:37:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:37:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:37:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:23 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 0x105877490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65b48b560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10579b9f0>, '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 0x1054fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10578e8c8>, '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.StM05ynPSB/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.dTwNFQQAuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 17:37:23 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 17:37:23 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 17:37:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StM05ynPSB/Render', 17:37:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:37:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:37:23 INFO - 'HOME': '/Users/cltbld', 17:37:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:37:23 INFO - 'LOGNAME': 'cltbld', 17:37:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:37:23 INFO - 'MOZ_NO_REMOTE': '1', 17:37:23 INFO - 'NO_EM_RESTART': '1', 17:37:23 INFO - 'PAGER': '/bin/cat', 17:37:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:37:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:37:23 INFO - 'PWD': '/builds/slave/test', 17:37:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:37:23 INFO - 'SHELL': '/bin/bash', 17:37:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dTwNFQQAuY/Listeners', 17:37:23 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 17:37:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:37:23 INFO - 'USER': 'cltbld', 17:37:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:37:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:37:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:37:23 INFO - 'XPC_FLAGS': '0x0', 17:37:23 INFO - 'XPC_SERVICE_NAME': '0', 17:37:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:37:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:37:24 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-BeOg5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:37:24 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-xXTVzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:37:24 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-1JEdvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:37:24 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-_DoUwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:37:24 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-voPucR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:37:24 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-nApItu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:37:24 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-S4vUcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:37:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:37:24 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-0mEOXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:37:25 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-MdHOD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:37:25 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-FIZ_oH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:37:25 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-uTd_bj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:37:25 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-3v2wrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:37:25 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-ghE3Su-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:37:25 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-hcfi5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:37:25 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-l1jb1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:37:25 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-T0iSme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:37:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:37:25 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-YWg9m0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:37:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:37:25 INFO - Running setup.py install for manifestparser 17:37:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:37:26 INFO - Running setup.py install for mozcrash 17:37:26 INFO - Running setup.py install for mozdebug 17:37:26 INFO - Running setup.py install for mozdevice 17:37:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:37:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:37:26 INFO - Running setup.py install for mozfile 17:37:26 INFO - Running setup.py install for mozhttpd 17:37:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:37:26 INFO - Running setup.py install for mozinfo 17:37:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:37:27 INFO - Running setup.py install for mozInstall 17:37:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:37:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:37:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:37:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:37:27 INFO - Running setup.py install for mozleak 17:37:27 INFO - Running setup.py install for mozlog 17:37:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:37:27 INFO - Running setup.py install for moznetwork 17:37:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:37:27 INFO - Running setup.py install for mozprocess 17:37:27 INFO - Running setup.py install for mozprofile 17:37:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:37:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:37:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:37:27 INFO - Running setup.py install for mozrunner 17:37:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:37:28 INFO - Running setup.py install for mozscreenshot 17:37:28 INFO - Running setup.py install for moztest 17:37:28 INFO - Running setup.py install for mozversion 17:37:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:37:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:37:28 INFO - Cleaning up... 17:37:28 INFO - Return code: 0 17:37:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:28 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 0x105877490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65b48b560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10579b9f0>, '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 0x1054fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10578e8c8>, '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.StM05ynPSB/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.dTwNFQQAuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 17:37:28 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 17:37:28 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 17:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StM05ynPSB/Render', 17:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:37:28 INFO - 'HOME': '/Users/cltbld', 17:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:37:28 INFO - 'LOGNAME': 'cltbld', 17:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:37:28 INFO - 'MOZ_NO_REMOTE': '1', 17:37:28 INFO - 'NO_EM_RESTART': '1', 17:37:28 INFO - 'PAGER': '/bin/cat', 17:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:37:28 INFO - 'PWD': '/builds/slave/test', 17:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:37:28 INFO - 'SHELL': '/bin/bash', 17:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dTwNFQQAuY/Listeners', 17:37:28 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 17:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:37:28 INFO - 'USER': 'cltbld', 17:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:37:28 INFO - 'XPC_FLAGS': '0x0', 17:37:28 INFO - 'XPC_SERVICE_NAME': '0', 17:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:37:28 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-wwgjYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:37:28 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)) 17:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:37:28 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-0LmEJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:37:29 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)) 17:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:37:29 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-2lO3QY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:37:29 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)) 17:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:37:29 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-aLeK2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:37:29 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)) 17:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:37:29 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-WBImIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:37:29 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)) 17:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:37:29 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-XFacWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:37:29 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)) 17:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:37:29 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-mkNb04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:37:29 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)) 17:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:37:29 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-XTUqlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:37:29 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)) 17:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:37:29 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-22gV1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:37:29 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)) 17:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:37:29 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-JkkIGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:37:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 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)) 17:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:37:29 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-4VrIlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:37:30 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)) 17:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:37:30 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-k3anB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:37:30 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)) 17:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:37:30 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-ViQGfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:37:30 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)) 17:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:37:30 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-f8hAs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:37:30 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)) 17:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:37:30 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-xO8epJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:37:30 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)) 17:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:37:30 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-wtfhNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:37:30 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)) 17:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:37:30 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-YW2O2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:37:30 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)) 17:37:30 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)) 17:37:30 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)) 17:37:30 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)) 17:37:30 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)) 17:37:30 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)) 17:37:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:37: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 17:37: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 17:37: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 17:37: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 17:37: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 17:37: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 17:37:32 INFO - Downloading blessings-1.5.1.tar.gz 17:37:32 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 17:37:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:37:32 INFO - Installing collected packages: blessings 17:37:32 INFO - Running setup.py install for blessings 17:37:33 INFO - Successfully installed blessings 17:37:33 INFO - Cleaning up... 17:37:33 INFO - Return code: 0 17:37:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:37:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:37:33 INFO - Reading from file tmpfile_stdout 17:37:33 INFO - Current package versions: 17:37:33 INFO - blessings == 1.5.1 17:37:33 INFO - blobuploader == 1.2.4 17:37:33 INFO - docopt == 0.6.1 17:37:33 INFO - manifestparser == 1.1 17:37:33 INFO - mozInstall == 1.12 17:37:33 INFO - mozcrash == 0.16 17:37:33 INFO - mozdebug == 0.1 17:37:33 INFO - mozdevice == 0.47 17:37:33 INFO - mozfile == 1.2 17:37:33 INFO - mozhttpd == 0.7 17:37:33 INFO - mozinfo == 0.9 17:37:33 INFO - mozleak == 0.1 17:37:33 INFO - mozlog == 3.0 17:37:33 INFO - moznetwork == 0.27 17:37:33 INFO - mozprocess == 0.22 17:37:33 INFO - mozprofile == 0.27 17:37:33 INFO - mozrunner == 6.11 17:37:33 INFO - mozscreenshot == 0.1 17:37:33 INFO - mozsystemmonitor == 0.0 17:37:33 INFO - moztest == 0.7 17:37:33 INFO - mozversion == 1.4 17:37:33 INFO - psutil == 3.1.1 17:37:33 INFO - requests == 1.2.3 17:37:33 INFO - wsgiref == 0.1.2 17:37:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:37: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')]} 17:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:37:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37: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')]} 17:37:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:37:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37: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', '-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 0x105877490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd65b48b560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10579b9f0>, '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 0x1054fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10578e8c8>, '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.StM05ynPSB/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.dTwNFQQAuY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 17:37:33 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 17:37:33 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 17:37:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StM05ynPSB/Render', 17:37:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:37:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:37:33 INFO - 'HOME': '/Users/cltbld', 17:37:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:37:33 INFO - 'LOGNAME': 'cltbld', 17:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:37:33 INFO - 'MOZ_NO_REMOTE': '1', 17:37:33 INFO - 'NO_EM_RESTART': '1', 17:37:33 INFO - 'PAGER': '/bin/cat', 17:37:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:37:33 INFO - 'PWD': '/builds/slave/test', 17:37:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:37:33 INFO - 'SHELL': '/bin/bash', 17:37:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dTwNFQQAuY/Listeners', 17:37:33 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 17:37:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:37:33 INFO - 'USER': 'cltbld', 17:37:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:37:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:37:33 INFO - 'XPC_FLAGS': '0x0', 17:37:33 INFO - 'XPC_SERVICE_NAME': '0', 17:37:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:37:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:37:33 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)) 17:37:33 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)) 17:37:33 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)) 17:37:33 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)) 17:37:33 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)) 17:37:33 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)) 17:37:33 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)) 17:37:33 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)) 17:37:33 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)) 17:37:33 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)) 17:37:33 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)) 17:37:33 INFO - Cleaning up... 17:37:33 INFO - Return code: 0 17:37:33 INFO - Running post-action listener: _resource_record_post_action 17:37:33 INFO - Running post-action listener: _start_resource_monitoring 17:37:33 INFO - Starting resource monitoring. 17:37:33 INFO - ##### 17:37:33 INFO - ##### Running install step. 17:37:33 INFO - ##### 17:37:33 INFO - Running pre-action listener: _resource_record_pre_action 17:37:33 INFO - Running main action method: install 17:37:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:37:33 INFO - Reading from file tmpfile_stdout 17:37:33 INFO - Detecting whether we're running mozinstall >=1.0... 17:37:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:37:34 INFO - Reading from file tmpfile_stdout 17:37:34 INFO - Output received: 17:37:34 INFO - Usage: mozinstall [options] installer 17:37:34 INFO - Options: 17:37:34 INFO - -h, --help show this help message and exit 17:37:34 INFO - -d DEST, --destination=DEST 17:37:34 INFO - Directory to install application into. [default: 17:37:34 INFO - "/builds/slave/test"] 17:37:34 INFO - --app=APP Application being installed. [default: firefox] 17:37:34 INFO - mkdir: /builds/slave/test/build/application 17:37:34 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'] 17:37:34 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 17:38:03 INFO - Reading from file tmpfile_stdout 17:38:03 INFO - Output received: 17:38:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:38:03 INFO - Running post-action listener: _resource_record_post_action 17:38:03 INFO - ##### 17:38:03 INFO - ##### Running run-tests step. 17:38:03 INFO - ##### 17:38:03 INFO - Running pre-action listener: _resource_record_pre_action 17:38:03 INFO - Running main action method: run_tests 17:38:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:38:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:38:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:38:03 INFO - 2015-11-18 17:38:03.973 screenresolution[1944:12101] starting screenresolution argv=screenresolution get 17:38:03 INFO - 2015-11-18 17:38:03.975 screenresolution[1944:12101] Display 0: 1600x1200x32@60 17:38:03 INFO - 2015-11-18 17:38:03.980 screenresolution[1945:12103] starting screenresolution argv=screenresolution list 17:38:03 INFO - Available Modes on Display 0 17:38:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:38:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:38:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:38:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:38:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:38:04 INFO - Intel Iris: 17:38:04 INFO - Chipset Model: Intel Iris 17:38:04 INFO - Type: GPU 17:38:04 INFO - Bus: Built-In 17:38:04 INFO - VRAM (Dynamic, Max): 1536 MB 17:38:04 INFO - Vendor: Intel (0x8086) 17:38:04 INFO - Device ID: 0x0a2e 17:38:04 INFO - Revision ID: 0x0009 17:38:04 INFO - Displays: 17:38:04 INFO - Display: 17:38:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:38:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:38:04 INFO - Display Serial Number: 1600x1200 60 17:38:04 INFO - Main Display: Yes 17:38:04 INFO - Mirror: Off 17:38:04 INFO - Online: Yes 17:38:04 INFO - Rotation: Supported 17:38:04 INFO - Return code: 0 17:38:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:38:04 INFO - Python 2.7.3 17:38:04 INFO - Return code: 0 17:38:04 INFO - grabbing minidump binary from tooltool 17:38:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:38:04 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 0x10579b9f0>, '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 0x1054fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10578e8c8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:38:04 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 17:38:04 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 17:38:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:38:04 INFO - Return code: 0 17:38:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:38:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:38:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:38:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:38:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:38:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost'] in /builds/slave/test/build 17:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0019 --webServer localhost 17:38:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StM05ynPSB/Render', 17:38:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:04 INFO - 'HOME': '/Users/cltbld', 17:38:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:04 INFO - 'LOGNAME': 'cltbld', 17:38:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:38:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:04 INFO - 'MOZ_NO_REMOTE': '1', 17:38:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:38:04 INFO - 'NO_EM_RESTART': '1', 17:38:04 INFO - 'PAGER': '/bin/cat', 17:38:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:04 INFO - 'PWD': '/builds/slave/test', 17:38:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:38:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:04 INFO - 'SHELL': '/bin/bash', 17:38:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dTwNFQQAuY/Listeners', 17:38:04 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 17:38:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:04 INFO - 'USER': 'cltbld', 17:38:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:04 INFO - 'XPC_FLAGS': '0x0', 17:38:04 INFO - 'XPC_SERVICE_NAME': '0', 17:38:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost'] with output_timeout 3600 17:38:04 INFO - mozversion INFO | application_buildid: 20151118143539 17:38:04 INFO - mozversion INFO | application_changeset: 8e22c8aac02322264ab50695d88901ac807fd96e 17:38:04 INFO - mozversion INFO | application_display_name: Nightly 17:38:04 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:38:04 INFO - mozversion INFO | application_name: Firefox 17:38:04 INFO - mozversion INFO | application_remotingname: firefox 17:38:04 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 17:38:04 INFO - mozversion INFO | application_vendor: Mozilla 17:38:04 INFO - mozversion INFO | application_version: 45.0a1 17:38:04 INFO - mozversion INFO | platform_buildid: 20151118143539 17:38:04 INFO - mozversion INFO | platform_changeset: 8e22c8aac02322264ab50695d88901ac807fd96e 17:38:04 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 17:38:04 INFO - mozversion INFO | platform_version: 45.0a1 17:38:04 INFO - 2015-11-18 17:38:04,519 DEBUG : using testdate: 1447897084 17:38:04 INFO - 2015-11-18 17:38:04,519 DEBUG : actual date: 1447897084 17:38:04 INFO - 2015-11-18 17:38:04,528 INFO : starting webserver on 'localhost:51603' 17:38:04 INFO - 2015-11-18 17:38:04,529 INFO : Starting test suite t-yosemite-r7-0019 17:38:04 INFO - 2015-11-18 17:38:04,529 INFO : Starting test dromaeo_css 17:38:04 INFO - 2015-11-18 17:38:04,529 DEBUG : operating with platform_type : mac_ 17:38:04 INFO - 2015-11-18 17:38:04,530 INFO : Initialising browser for dromaeo_css test... 17:38:04 INFO - 2015-11-18 17:38:04,544 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpKkEtN6/profile http://localhost:51603/getInfo.html 17:38:06 INFO - 2015-11-18 17:38:06,432 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:38:06 INFO - 2015-11-18 17:38:06,433 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:38:06 INFO - 2015-11-18 17:38:06,433 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 17:38:06 INFO - 2015-11-18 17:38:06,433 DEBUG : BROWSER_OUTPUT: __metrics 17:38:06 INFO - 2015-11-18 17:38:06,451 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:38:07 INFO - 2015-11-18 17:38:07,264 INFO : Browser initialized. 17:38:07 INFO - 2015-11-18 17:38:07,264 INFO : Running cycle 1/1 for dromaeo_css test... 17:38:07 INFO - 2015-11-18 17:38:07,264 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpKkEtN6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:38:19 INFO - 2015-11-18 17:38:19,073 DEBUG : BROWSER_OUTPUT: RSS: Main: 224833536 17:38:19 INFO - 2015-11-18 17:38:19,073 DEBUG : BROWSER_OUTPUT: 17:39:10 INFO - 2015-11-18 17:39:10,645 DEBUG : BROWSER_OUTPUT: 1447897150643 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] 17:41:20 INFO - 2015-11-18 17:41:20,265 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51603/tests/dromaeo/cssquery-ext.html) 17:41:21 INFO - 2015-11-18 17:41:21,590 DEBUG : BROWSER_OUTPUT: RSS: Main: 351248384 17:41:21 INFO - 2015-11-18 17:41:21,590 DEBUG : BROWSER_OUTPUT: 17:44:24 INFO - 2015-11-18 17:44:24,242 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/dromaeo/cssquery-ext.html (next: http://localhost:51603/tests/dromaeo/cssquery-jquery.html) 17:44:24 INFO - 2015-11-18 17:44:24,845 DEBUG : BROWSER_OUTPUT: RSS: Main: 356536320 17:44:24 INFO - 2015-11-18 17:44:24,845 DEBUG : BROWSER_OUTPUT: 17:47:26 INFO - 2015-11-18 17:47:26,978 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51603/tests/dromaeo/cssquery-mootools.html) 17:47:27 INFO - 2015-11-18 17:47:27,577 DEBUG : BROWSER_OUTPUT: RSS: Main: 356847616 17:47:27 INFO - 2015-11-18 17:47:27,577 DEBUG : BROWSER_OUTPUT: 17:50:29 INFO - 2015-11-18 17:50:29,536 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51603/tests/dromaeo/cssquery-prototype.html) 17:50:30 INFO - 2015-11-18 17:50:30,116 DEBUG : BROWSER_OUTPUT: RSS: Main: 314580992 17:50:30 INFO - 2015-11-18 17:50:30,116 DEBUG : BROWSER_OUTPUT: 17:53:32 INFO - 2015-11-18 17:53:32,956 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51603/tests/dromaeo/cssquery-yui.html) 17:53:33 INFO - 2015-11-18 17:53:33,568 DEBUG : BROWSER_OUTPUT: RSS: Main: 324145152 17:53:33 INFO - 2015-11-18 17:53:33,568 DEBUG : BROWSER_OUTPUT: 17:56:36 INFO - 2015-11-18 17:56:36,107 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/dromaeo/cssquery-yui.html (next: http://localhost:51603/tests/dromaeo/cssquery-dojo.html) 17:56:36 INFO - 2015-11-18 17:56:36,366 DEBUG : BROWSER_OUTPUT: RSS: Main: 370806784 17:56:36 INFO - 2015-11-18 17:56:36,366 DEBUG : BROWSER_OUTPUT: 17:56:36 INFO - 2015-11-18 17:56:36,367 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:56:36 INFO - 2015-11-18 17:56:36,367 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:56:36 INFO - 2015-11-18 17:56:36,367 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:56:36 INFO - 2015-11-18 17:56:36,367 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:56:36 INFO - 2015-11-18 17:56:36,367 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3400.863965441382;3427.7600567960235;3465.7573969822092;3486.2504561519268;3513.5432393788806;5703.429657034295;5851.356350801409;5929.88140237195;5939.732712027958;6017.939820601792;5890.852728681785;5892.322382925965;5895.469407753301;6022.036474164134;6030.8793824123495;5311.07056265154;5316.308879845625;5345.118055520835;5377.978184145019;5431.592630552705;4988.426330971948;5447.509724124828;5463.235147079412;5483.75323110461;5496.31296087989;5074.670146440507;5322.015427145951;5324.600654950856;5437.102878025121;5470.070088085015;5346.465353464661;5353.625246232766;5355.491228333302;5359.919601205985;5367.892642147135;5193.532582067632;5321.148616221386;5323.441038690959;5323.680579165262;5323.733813309319;4397.73613583186;5812.505936995361;5815.563832712563;5815.970920145414;5819.098039803837;6296.464800491918;6296.842578935518;6301.68491575424;6306.810795676137;6314.242290925073;6156.5998210116195;6339.683015849189;6341.87316253677;6377.744890204386;6392.456641185505;5949.167116603679;5968.134620480006;5995.490383317379;6046.667433291167;6058.909116363258;5650.491455769001;6418.454431373292;6479.222493300834;6487.286398496161;6498.057781621733;6147.770445910746;6308.369163083655;6310.5267104967315;6318.115463835067;6346.492280617632;6233.3143354231;6321.747130114744;6329.810105696836;6333.873322533524;6362.236531616236;6307.022411526221;6309.905351419732;6317.747290108436;6323.051542268697;6325.209348831396;6176.474999625082;6322.241331040304;6328.272248691367;6347.270063942613;6358.904616430757;4976.62675299354;4987.10232158215;4996.600271978233;5040.672356296829;5048.848534543969;4485.352474031768;4547.63618910493;4596.678232523758;4606.170889239902;4632.050429661911;5713.143028545752;5809;5832.445917637818;5856.092305692625;6054.969725151346;6218.626882387071;6289.150964619809;6290.779822706183;6301.7164227608955;6329.968350158219;5890;5996.670183139882;6013.098035294741;6031.728572214261;6046.244219472566;5520.641158324696;5567.053600887859;5571.637843540157;5582.190582365534;5585;6319.55763096588;6405.295417504164;6407.327230640902;6419.646919419383;6607.702653380609;6344.302126766144;6373.171487706569;6377.8724425512155;6401.103845461546;6420.871582568416;6552;6562.737490500326;6606.867862642816;6634.535582509178;6635.601863888182;6145.432914606926;6185.443310101932;6254.8123556293385;6321.146645203023;6325.620462772248;6358.618482891041;6394.584352017104;6408.935910640834;6413.454856337264;6418.871622567616;6119.816405507842;6286;6297.779577714758;6317.6841157941;6324.557280990287;6065.7573697053385;6154.476869466145;6182.227221597301;6312.400321969406;6318.715657795502;6105.053716673922;6172.351903050115;6189.597676151036;6190.164327815687;6200.038993955944;6236.251649802053;6294.992801151794;6301.8109456716375;6309.810705678837;6314.084457753692;5232.895342093213;5965.821025369246;6062.090686397076;6223.813285601439;6228 17:56:36 INFO - 2015-11-18 17:56:36,368 DEBUG : BROWSER_OUTPUT: |1;ext.html;554.7420449490987;558.9245451863998;594.7941170845525;617.0528239152899;632.8006677896461;32635.67364326356;32916.177095572595;32991.67008329916;39589;40083.59916400835;43840.46558370458;44053.33919991203;44188.337174942404;44281.557184428144;44351;9762.023797620252;9901.455419951899;9904.108630223278;9919.404835709844;9925.454100024495;8812.471251724917;8916.063813299608;8921.776955576097;8927.77680557988;8953.149450802164;12000.339981301026;12097.939510302436;12120.515179392814;12122.393880305994;12137.575184868532;11792.410379481034;11933;11951.342676152808;11958.820617690742;11982.161248712593;8790.25282850952;8938;8956.193942545202;11688;11874.821877671842;42915.78542107301;43483.56516434827;43492.782536087434;43513;43513.34729979053;3198.7281081108135;3203.1191422358806;3235.288236587947;3364.882229121969;3623.1847834237087;29039.98360057409;29209.561856572167;29321;29517.70482295171;29710.40579188426;2462.581361168606;2609.412882101531;2650.059228973706;2730.631364765751;2757.393373457836;463359;621703;622956;623077.884610574;638556;48544.27183592249;67712;68566.65716671385;68662.16371608469;68964.31035689679;65453;65761.6711916447;65929;65962;66018;13547.932260338832;16395.42616008434;16424.917875410785;16430.75353869693;16686.165691715367;12798.232106073665;12829.807552886714;12851.16467429614;12875.871241287654;12885.226886386847;19855.305064322678;20003;20043.29848455297;20056.298029568894;20072.89963550202;8625.568721563897;8789.648414063366;8812.471251724917;8814.162654547808;8829.911700883038;948.2224575847739;980.6371642492323;1029.5006921642996;1032.7882784029257;1106.391484683421;29545.704542954296;29675;30162.84918575437;30384.530784458566;30510.08469745911;1011.2112552209288;1081.291753901196;1091.0834898685143;1097.6487523992246;1104.3429159649995;12762.553310634083;14988.875834312252;15303.923480382527;15484.612884677748;15685.098256822295;539595;543455;543613.6915892529;545202;549435;59652;59976.40023599796;60119;60140.39859601348;60320;1438.4030627289767;1461.9342129604192;1481.029925398844;1489.4414594527052;1494.0438119603461;27175.72824271732;29611.85194074102;30677.693223067483;30845.691543084282;31298.687013129576;50613.74693126511;50952.74523627506;51043.23435148402;51049.744751276005;51064.48935510597;469763.90708278806;480005;480349;480588;481081;28994.565081524215;29294.853525732236;31684.841575791972;33249.833750831094;34151.487727683605;43691.158132163546;44026.559734402246;44030.779846100566;44395.55604444043;45984.31023534583;33804.66195338015;33921.32157356889;35225.64774352326;37529.061773455884;38068.67700888944;30378;36867.44698829466;36961.81519092495;37916;37997.620023799405;29872.954446595133;31835.04494865158;35853.1036724084;36156.819215904805;37568.060798480255;30123.69876301209;32261.838690806395;32388;33693;33820.32359352848;24691.382715432257;24961.50076998486;25247.990080397314;25561.488770224863;25609.743902560735 17:56:36 INFO - 2015-11-18 17:56:36,372 DEBUG : BROWSER_OUTPUT: |2;jquery.html;359.90282623691604;362.95100161478194;367.04018990340234;368.2322357883813;371.6098096998151;10872.565097396095;11175.21773475855;12608;12941.482340706372;19583.412497625075;10656.608743807346;10967.83548246777;11583.884161158387;12686.492540298377;12714.936425317861;4446.666500012496;5173.120569503193;5315.574754019669;5318.095923692981;5320.14877619581;3716.089558058266;3806.9238615227678;3822.0444888777806;5228.869278268055;5252.71110088945;5250.553702935236;5260.8684782880355;5273.446288139748;5285.973570132144;5292.285541451913;5044.722540260284;5341.332333458317;5341.65279256849;5343.599230057742;5349.866253343658;4470.307102399133;4473.194824931511;4486.102779444124;4488.932666010012;4490;11463.942680286546;12092.274463532216;14331.498397556139;14591.635209119857;14621.342039608244;1970.5861769028522;1989.383291179739;1994.072756168374;2048.4776382022637;2192.4299682082615;8817.470951742916;9962.252831037702;10099.596016159345;10500.78998420035;10597.099246563974;1633.0446688687132;1657.3784830688492;1665.258959762906;1668.966620667592;1686.376040864888;90713.54643226808;93663.53168234116;95084;96367;96954.39592852884;5792.768289268466;6849.760258391032;6856.485763567753;6857.862842743117;6944.166699996033;7789.33790627803;7792.181820908836;7809.804754881174;7820.296173344313;7826.295633393022;5799.3620701722775;5802.448767367094;5806.88386232273;5825.941740582624;5843.0066888629035;6233;6249.437550620466;6291.842703932347;6295.276043254993;6299.149614802035;3085.182426656938;3092.659807421227;3104.5032794752733;3127.358891427252;3137.9686203138135;1078.5254488025244;1084.1110289562482;1087.798757229915;1130.779497997899;1162.924409913353;432.45726613100356;453.1119006746813;458.48649512545495;459.58178057967757;481.5714014527074;12323.507059717691;12550.68623284407;12863.48546058166;12959.352032398343;12967.805482917764;466.34711404034624;470.6987527982094;482.7055496147347;488.067790520112;494.6784590016446;6443.677816109176;6557.737690492422;6608.207015158116;6660.100886380374;6688.030235615905;789794;792784;796145;809180;811666;108542.20102296381;110277;112796;112918.43540782243;114773.4261328688;531.6863050800047;546.3034630845624;547.5209191957038;547.6276132230042;547.8575570351737;11785.587504437304;11808.114391420506;11902.404879755804;11922.52309907594;11963.042956563628;46598.06803863836;47915;48420;48438.5156148434;48674.78313042202;64741;64916;65038;65328.34671653223;65688;6318;6371.6814159290925;6500.382463665944;6500.707468164039;6516.446102081376;12219.75560488803;12443.875561244271;12500.562480313145;12573.811392828931;12603.747925041265;9383.061693830836;9620.663276785559;10358.585656573652;10728.89271107279;11038.558457661604;8844.646214151362;10617.662072485864;10697.839532406864;10748.02233020781;10984.176186786182;11333.829992550283;11428.94285528567;11445.771084578428;11450.541978321107;11452.828207576726;5824.359320474659;6253.687315634291;6443.581167224115;6497.415232629086;6529.085927969993;8886.955565222133;10486.632967846088;10901;10977;11131.888681113409 17:56:36 INFO - 2015-11-18 17:56:36,372 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1580.076857813046;1587.1270801059422;1667.0414511655786;1872.522506760776;1893.9488583835969;7295.744648937281;7474.990876231714;7483.812904677379;8170.978627671541;8198.91801081989;7933.325667318285;8042.597870106486;8048.034235891687;8053.677852885878;8061.959690201541;2323.2101085630884;2340.9648855267183;2347.507023525062;2348.295511346589;2351.235848349285;2361.4332560185517;2363.7163540375177;2412.577798885197;2414.9275521734376;2415.1909110448023;2320.5590937721863;2351.000824649526;2352.0591763294647;2352.3648614873973;2352.741198468167;2321.1179751694417;2337.602607556711;2343.8476499027593;2344.3318654183563;2374.620060790265;2365.73976862545;2389.4504264019374;2396.940076498084;2398.8965076065033;2400.2679182849224;7989.5605741684185;8037.15609860962;8049.114597394282;8051;8061.193880611951;4146.543880173179;4186.790660466964;4329.675274354436;4585.449746030465;4731.858044258678;3392.6268110507826;3398.7790793598338;3450.27544215715;3531.0642679689904;3564.946525802089;1045.8873076385735;1068.551208492435;1080.4111759091313;1644.128611835729;1655.3461382753865;10531.315464494859;10607.204459665556;10639.680809575648;10653.680389588324;10661.733456663567;4420.756858372756;4459.710118842265;4500.729956202638;4503.392042074278;4628.143793398165;9349.15857572808;9359.970403255636;9373.859392109123;9378.155965963097;9399.624015039459;5094.490550944876;5096.898062038813;5111.591072714174;5123.538881500683;5135.871603209875;4657.0918670858855;4666.95333046672;4670.182718024332;4674.392328997276;4678.578927896506;3602.3335682898746;3694.9815250923575;3698.537682789651;3699.112213068844;3722.5905150433973;1217.3548019549653;1227.8710735372836;1231.6489800406841;1235.5243231355992;1242.3912282981273;1322.312397553267;1344.4823742859069;1368.6646771541036;1405.3816320818808;1409.2530958591758;7424.480286379901;7592.164861865191;7595.5822429765785;7600.315971562586;7600.391968642495;491.5796993570517;496.2035932328633;501.8068043803162;502.9949700502948;505.91652377357326;5890.617109887844;6013.188219590387;6077.848053798691;6129.509639228941;6138.846528836815;9180.954095229481;9762.121409073217;9845.753856153653;9855.26085543587;9856.211503079736;9370.718878433658;9446.716598502057;9506.287028472892;9536.713898583053;9587.233021358275;7547.207543207884;7608.695652173851;7623.161452240249;7660.923390766133;7668.233176682399;6731.697073631601;7498.58757768328;7522.548647081193;7568.167501574932;7585.810354741176;8373.916260837557;8547.61535730906;8590.613422396133;8613.48319100856;8659.740207793777;8563.828723425371;8848;8866.024737278765;8982.146696063863;9058.954705226432;7050.189228238613;7062.611556364449;7152.85694286101;7155;7157.355837974607;7613.467057305936;7695.384369250559;7724.729634462767;7739.92260077392;7747.922520774946;6952.756653517103;7177.533460325062;7198.5320954137815;7283.2352602975925;7315.853682926205;7131.572105673677;7152.141742990874;7200.495965282589;7258.274172582699;7295.270472952662;7175.677094530654;7203.423726102004;7258.020167277561;7279.890801637874;7296.124465064227;6611.966940165268;6659.800205993828;6663.233728121328;6667.666616669244;6669.699863506251;6357.650329231752;6712.83217919556;6767.830804229934;6768.08630834831;6773.864522709617 17:56:36 INFO - 2015-11-18 17:56:36,375 DEBUG : BROWSER_OUTPUT: |4;prototype.html;319.35649665923165;327.9376918385508;329.7938788257338;334.9279904820463;336.80128724052804;6712.865742685143;6850.3492168244;6875.690593923273;6954.791356259314;6986.5458745181595;6781.966090169542;6891.724331026766;6913.930860691392;6932.15781529573;6942.930570694291;4120.979395103021;5001.199808030716;5095.847124586268;5128.435872054071;5148.5881129509735;4878.951210487885;5077.822276220333;5113.411957624865;5121.078205922932;5146.459621739719;4814.1575224335775;4954.7522623868845;4967.751612419383;4977.900441991176;4984.601231901457;4892.975535122319;4898.485659005806;4910.435299940481;4990.525900039491;5039.143345631252;4222.451081359434;4226.957730422687;4241.618254357092;4244.917546025775;4245.766482843443;6781.660916954182;6787.04981302618;6802;6812.48906332027;6822.3859852613;5874.706264686749;5986;5999.550033747487;6010.429009244115;6018.187544681456;7409.8518029639645;7451.552906825608;7453.440991925573;7454;7457.95588617594;4306.643407326682;4446.110777844412;4744.217204161309;4745.383100196987;4768.833090841838;7648.770536883847;7812.3359514441345;7909.683612655487;7929.722459713882;7932.841343173104;4814.638902082358;4824.469308376146;4825.662203645711;4831.4202295724035;4834;4289.635380992589;4912.926306105412;4923.433805112386;4927.457979622239;4940.061388336205;5835.562332825055;5954.940450595525;5964.522838172936;5970.731317090655;5986.790462333797;7443.2184620615135;7502.2122677119205;7503.362214211853;7503.962480187564;7505.662245198979;1690.7886514185727;1710.1363811275226;1715.3395942561954;1716.9570760730833;1719.6560687862477;1069.4813015687384;1072.4423299884018;1075.301054314686;1090.3457925244948;1097.1442275025493;6878.69045892936;6915.965420172867;6926.363659078923;6946.652667366611;6952.721891124298;7757.456978011598;7770.106437759617;7770.883436748453;7778.183290754389;7798.766037018899;5715.257016587817;6268.811935641938;6875.106236189264;6906.758263460855;6919.550229235084;4077.4903137107863;4088.5707000765087;4107.322291821875;4111.444954931046;4114.465119534441;6470.4823614110755;8086.353091752646;8122.431429799975;8124.309433698084;8131.5527645978855;7873.133955264916;8082.515049097073;8097.554634495042;8098.676052957933;8100.594970251464;8089.555074470843;8093.838123237502;8097.190280971856;8100.594970251464;8105.473144245605;7626.771196864103;7811.257930496594;7819.257170568787;7831.099423566363;7866.9213307866185;7686.50037747567;7690.423218258765;7706.422018348759;7719.150893401834;7748;8135.145809689898;8140.430169888051;8143.42995990275;8145;8145.592720364077;4687.179743544798;4826.3243145958895;4844.709317440965;4850.733209673502;4854.296127061486;7802.024746906637;7806.297433231037;7818.452708310591;7824.4522883399895;7832.255935686101;7268;7475.626218689153;7487.363574096045;7494.70021199146;7495.887561686688;7438.516496427715;7444.478886477895;7456.067991501062;7459.440541959266;7465.141508726566;5964.821055368346;7281.162666293444;7301.196868344372;7313.232110628307;7317.743878964424;7302.780916572511;7434.851302974019;7481.738139165103;7486.7005319786595;7491.8501629966;7346.449016323904;7353.558786472615;7357.632118393952;7357.889631655637;7358.22738612438 17:56:36 INFO - 2015-11-18 17:56:36,376 DEBUG : BROWSER_OUTPUT: |5;yui.html;799.412431862581;801.350905766329;813.6663967773214;817.3297895725506;827.7309874290856;15052.548423547283;15540.456084037032;15566.299516521756;15572.221388930551;15605.609859753511;14302.785458218135;14370.209638469905;14393.496227632035;14414.567562973101;14416.927915360435;749.8762704153808;760.0309605253291;760.6310939194486;925.9768505787358;942.4157022645935;939.8308304505186;946.6260826973341;960.7117864640614;962.6823148361024;963.1476143612917;984;1003.713941526665;1004.507791182319;1005.33647792457;1005.7535903703603;768.5273556762606;790.0519376747925;800.8398320335926;801.7634797734624;802.9357651387875;732.3994324653786;741.0810594862386;741.9443541734391;745.117036311972;745.4744405194325;14983.550493485212;15218.543443696595;15252.923735381362;15254.466093686668;15256;10022.398656080659;12305;12472.314022728746;12598.874011259863;12651.74696506074;10033.238153613112;10246.118516299706;10321.380717156919;10336;10350;3505.333986542574;3533.505309256706;3547.6984456321243;3548.095235714902;3549.5208146900354;75991.86012209821;77568.61215693942;77720;77833.61083194548;80578;7199.9280007200305;7271.272872712686;7293.854122917512;7416.925830741731;7563.281488258606;11202.38386888729;11942.880571194177;12715.745685086431;12716.809247861289;12717.173383730027;6486.124373209556;6567;6659.900101498384;6712.731490740413;6818.965905170541;3547.396942519778;4187.14163596462;4191.580841915784;4191.643710284599;4201;2693.62289279501;3116.1897906544464;3154.369126174774;3154.5899033125547;3155.7475401967786;487.77318546875875;499.0692358750903;500.4445065976763;504.41487874065905;507.2062222621585;484.48886424821444;538.9705662185207;545.8346430371187;548.183207021535;636.9649669268142;8382.832343353099;8620.827583448296;8656.653733850575;8784.78038049054;8909.574468085206;528.4345750047412;603.6944118680428;614.7633161232868;621.5369549685513;629.1475051305507;3780.5513510831215;3990.4812374391145;4041.7170798043853;4053.574374690674;4162.8334866605;85911;86306.56846715727;97752;98613;98659.01340986499;70436.64781676058;71661.2833871665;72101.2789872105;72261;78258;66392.66803665951;66515.66742166354;66908;67273.32726672769;69303.65348173227;8151.1441298662785;8917.152870477294;9016.504092274989;9017.864732028893;9088.318376121684;45015.32477012913;45657.858553536425;46549.53450465452;46858;52445.7377713109;66722.66638666776;68059;68791;69607.65196173987;70061.64969175121;7917.247861453153;7937.523748575104;7955.5624440654;7962.32320252785;7988.960055199686;11984.101192410779;12094.298897007737;12174.14780965324;12305.323207223331;12489.875101249234;11336.716582085513;11414.714632134263;11474.94262528682;11507.539698411969;11527.711807204887;11473.36896470702;11574.768504630028;11575.305481671126;11576.94211528937;11592.594259200887;11346.94326528362;11412.885871141183;11418.714532136431;11437.370944598128;11456;8111.337947517848;9728.319017668695;9786.706398807763;9859.901400986186;10100.393976361442;9517.857232141385;9739.123478886933;9802.65690700822;9821.066998635119;9859.704208873745 17:56:36 INFO - 2015-11-18 17:56:36,376 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:56:36 INFO - 2015-11-18 17:56:36,376 DEBUG : BROWSER_OUTPUT: __start_cc_report 17:56:36 INFO - 2015-11-18 17:56:36,377 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1132 17:56:36 INFO - 2015-11-18 17:56:36,377 DEBUG : BROWSER_OUTPUT: __end_cc_report 17:56:36 INFO - 2015-11-18 17:56:36,377 DEBUG : BROWSER_OUTPUT: __startTimestamp1447898196363__endTimestamp 17:56:36 INFO - 2015-11-18 17:56:36,377 DEBUG : BROWSER_OUTPUT: 17:56:36 INFO - 2015-11-18 17:56:36,377 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:56:36 INFO - 2015-11-18 17:56:36,377 DEBUG : BROWSER_OUTPUT: Number of tests: 6 17:56:36 INFO - 2015-11-18 17:56:36,377 DEBUG : BROWSER_OUTPUT: 17:56:36 INFO - 2015-11-18 17:56:36,377 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5876.44 Median:6145.43 stddev:657.78 (10.7%) stddev-sans-first:630.47 17:56:36 INFO - 2015-11-18 17:56:36,377 DEBUG : BROWSER_OUTPUT: Values: 3400.9 3427.8 3465.8 3486.3 3513.5 5703.4 5851.4 5929.9 5939.7 6017.9 5890.9 5892.3 5895.5 6022.0 6030.9 5311.1 5316.3 5345.1 5378.0 5431.6 4988.4 5447.5 5463.2 5483.8 5496.3 5074.7 5322.0 5324.6 5437.1 5470.1 5346.5 5353.6 5355.5 5359.9 5367.9 5193.5 5321.1 5323.4 5323.7 5323.7 4397.7 5812.5 5815.6 5816.0 5819.1 6296.5 6296.8 6301.7 6306.8 6314.2 6156.6 6339.7 6341.9 6377.7 6392.5 5949.2 5968.1 5995.5 6046.7 6058.9 5650.5 6418.5 6479.2 6487.3 6498.1 6147.8 6308.4 6310.5 6318.1 6346.5 6233.3 6321.7 6329.8 6333.9 6362.2 6307.0 6309.9 6317.7 6323.1 6325.2 6176.5 6322.2 6328.3 6347.3 6358.9 4976.6 4987.1 4996.6 5040.7 5048.8 4485.4 4547.6 4596.7 4606.2 4632.1 5713.1 5809.0 5832.4 5856.1 6055.0 6218.6 6289.2 6290.8 6301.7 6330.0 5890.0 5996.7 6013.1 6031.7 6046.2 5520.6 5567.1 5571.6 5582.2 5585.0 6319.6 6405.3 6407.3 6419.6 6607.7 6344.3 6373.2 6377.9 6401.1 6420.9 6552.0 6562.7 6606.9 6634.5 6635.6 6145.4 6185.4 6254.8 6321.1 6325.6 6358.6 6394.6 6408.9 6413.5 6418.9 6119.8 6286.0 6297.8 6317.7 6324.6 6065.8 6154.5 6182.2 6312.4 6318.7 6105.1 6172.4 6189.6 6190.2 6200.0 6236.3 6295.0 6301.8 6309.8 6314.1 5232.9 5965.8 6062.1 6223.8 6228.0 17:56:36 INFO - 2015-11-18 17:56:36,377 DEBUG : BROWSER_OUTPUT: 17:56:36 INFO - 2015-11-18 17:56:36,377 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:67924.34 Median:34036.40 stddev:144614.87 (424.9%) stddev-sans-first:144932.09 17:56:36 INFO - 2015-11-18 17:56:36,379 DEBUG : BROWSER_OUTPUT: Values: 554.7 558.9 594.8 617.1 632.8 32635.7 32916.2 32991.7 39589.0 40083.6 43840.5 44053.3 44188.3 44281.6 44351.0 9762.0 9901.5 9904.1 9919.4 9925.5 8812.5 8916.1 8921.8 8927.8 8953.1 12000.3 12097.9 12120.5 12122.4 12137.6 11792.4 11933.0 11951.3 11958.8 11982.2 8790.3 8938.0 8956.2 11688.0 11874.8 42915.8 43483.6 43492.8 43513.0 43513.3 3198.7 3203.1 3235.3 3364.9 3623.2 29040.0 29209.6 29321.0 29517.7 29710.4 2462.6 2609.4 2650.1 2730.6 2757.4 463359.0 621703.0 622956.0 623077.9 638556.0 48544.3 67712.0 68566.7 68662.2 68964.3 65453.0 65761.7 65929.0 65962.0 66018.0 13547.9 16395.4 16424.9 16430.8 16686.2 12798.2 12829.8 12851.2 12875.9 12885.2 19855.3 20003.0 20043.3 20056.3 20072.9 8625.6 8789.6 8812.5 8814.2 8829.9 948.2 980.6 1029.5 1032.8 1106.4 29545.7 29675.0 30162.8 30384.5 30510.1 1011.2 1081.3 1091.1 1097.6 1104.3 12762.6 14988.9 15303.9 15484.6 15685.1 539595.0 543455.0 543613.7 545202.0 549435.0 59652.0 59976.4 60119.0 60140.4 60320.0 1438.4 1461.9 1481.0 1489.4 1494.0 27175.7 29611.9 30677.7 30845.7 31298.7 50613.7 50952.7 51043.2 51049.7 51064.5 469763.9 480005.0 480349.0 480588.0 481081.0 28994.6 29294.9 31684.8 33249.8 34151.5 43691.2 44026.6 44030.8 44395.6 45984.3 33804.7 33921.3 35225.6 37529.1 38068.7 30378.0 36867.4 36961.8 37916.0 37997.6 29873.0 31835.0 35853.1 36156.8 37568.1 30123.7 32261.8 32388.0 33693.0 33820.3 24691.4 24961.5 25248.0 25561.5 25609.7 17:56:36 INFO - 2015-11-18 17:56:36,379 DEBUG : BROWSER_OUTPUT: 17:56:36 INFO - 2015-11-18 17:56:36,379 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:36883.99 Median:459.03 stddev:131699.84 (28690.6%) stddev-sans-first:132040.73 17:56:36 INFO - 2015-11-18 17:56:36,380 DEBUG : BROWSER_OUTPUT: Values: 359.9 363.0 367.0 368.2 371.6 10872.6 11175.2 12608.0 12941.5 19583.4 10656.6 10967.8 11583.9 12686.5 12714.9 4446.7 5173.1 5315.6 5318.1 5320.1 3716.1 3806.9 3822.0 5228.9 5252.7 5250.6 5260.9 5273.4 5286.0 5292.3 5044.7 5341.3 5341.7 5343.6 5349.9 4470.3 4473.2 4486.1 4488.9 4490.0 11463.9 12092.3 14331.5 14591.6 14621.3 1970.6 1989.4 1994.1 2048.5 2192.4 8817.5 9962.3 10099.6 10500.8 10597.1 1633.0 1657.4 1665.3 1669.0 1686.4 90713.5 93663.5 95084.0 96367.0 96954.4 5792.8 6849.8 6856.5 6857.9 6944.2 7789.3 7792.2 7809.8 7820.3 7826.3 5799.4 5802.4 5806.9 5825.9 5843.0 6233.0 6249.4 6291.8 6295.3 6299.1 3085.2 3092.7 3104.5 3127.4 3138.0 1078.5 1084.1 1087.8 1130.8 1162.9 432.5 453.1 458.5 459.6 481.6 12323.5 12550.7 12863.5 12959.4 12967.8 466.3 470.7 482.7 488.1 494.7 6443.7 6557.7 6608.2 6660.1 6688.0 789794.0 792784.0 796145.0 809180.0 811666.0 108542.2 110277.0 112796.0 112918.4 114773.4 531.7 546.3 547.5 547.6 547.9 11785.6 11808.1 11902.4 11922.5 11963.0 46598.1 47915.0 48420.0 48438.5 48674.8 64741.0 64916.0 65038.0 65328.3 65688.0 6318.0 6371.7 6500.4 6500.7 6516.4 12219.8 12443.9 12500.6 12573.8 12603.7 9383.1 9620.7 10358.6 10728.9 11038.6 8844.6 10617.7 10697.8 10748.0 10984.2 11333.8 11428.9 11445.8 11450.5 11452.8 5824.4 6253.7 6443.6 6497.4 6529.1 8887.0 10486.6 10901.0 10977.0 11131.9 17:56:36 INFO - 2015-11-18 17:56:36,380 DEBUG : BROWSER_OUTPUT: 17:56:36 INFO - 2015-11-18 17:56:36,381 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5626.69 Median:6484.81 stddev:2878.84 (44.4%) stddev-sans-first:2870.85 17:56:36 INFO - 2015-11-18 17:56:36,382 DEBUG : BROWSER_OUTPUT: Values: 1580.1 1587.1 1667.0 1872.5 1893.9 7295.7 7475.0 7483.8 8171.0 8198.9 7933.3 8042.6 8048.0 8053.7 8062.0 2323.2 2341.0 2347.5 2348.3 2351.2 2361.4 2363.7 2412.6 2414.9 2415.2 2320.6 2351.0 2352.1 2352.4 2352.7 2321.1 2337.6 2343.8 2344.3 2374.6 2365.7 2389.5 2396.9 2398.9 2400.3 7989.6 8037.2 8049.1 8051.0 8061.2 4146.5 4186.8 4329.7 4585.4 4731.9 3392.6 3398.8 3450.3 3531.1 3564.9 1045.9 1068.6 1080.4 1644.1 1655.3 10531.3 10607.2 10639.7 10653.7 10661.7 4420.8 4459.7 4500.7 4503.4 4628.1 9349.2 9360.0 9373.9 9378.2 9399.6 5094.5 5096.9 5111.6 5123.5 5135.9 4657.1 4667.0 4670.2 4674.4 4678.6 3602.3 3695.0 3698.5 3699.1 3722.6 1217.4 1227.9 1231.6 1235.5 1242.4 1322.3 1344.5 1368.7 1405.4 1409.3 7424.5 7592.2 7595.6 7600.3 7600.4 491.6 496.2 501.8 503.0 505.9 5890.6 6013.2 6077.8 6129.5 6138.8 9181.0 9762.1 9845.8 9855.3 9856.2 9370.7 9446.7 9506.3 9536.7 9587.2 7547.2 7608.7 7623.2 7660.9 7668.2 6731.7 7498.6 7522.5 7568.2 7585.8 8373.9 8547.6 8590.6 8613.5 8659.7 8563.8 8848.0 8866.0 8982.1 9059.0 7050.2 7062.6 7152.9 7155.0 7157.4 7613.5 7695.4 7724.7 7739.9 7747.9 6952.8 7177.5 7198.5 7283.2 7315.9 7131.6 7152.1 7200.5 7258.3 7295.3 7175.7 7203.4 7258.0 7279.9 7296.1 6612.0 6659.8 6663.2 6667.7 6669.7 6357.7 6712.8 6767.8 6768.1 6773.9 17:56:36 INFO - 2015-11-18 17:56:36,382 DEBUG : BROWSER_OUTPUT: 17:56:36 INFO - 2015-11-18 17:56:36,382 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:6082.82 Median:6899.24 stddev:1993.22 (28.9%) stddev-sans-first:1951.30 17:56:36 INFO - 2015-11-18 17:56:36,383 DEBUG : BROWSER_OUTPUT: Values: 319.4 327.9 329.8 334.9 336.8 6712.9 6850.3 6875.7 6954.8 6986.5 6782.0 6891.7 6913.9 6932.2 6942.9 4121.0 5001.2 5095.8 5128.4 5148.6 4879.0 5077.8 5113.4 5121.1 5146.5 4814.2 4954.8 4967.8 4977.9 4984.6 4893.0 4898.5 4910.4 4990.5 5039.1 4222.5 4227.0 4241.6 4244.9 4245.8 6781.7 6787.0 6802.0 6812.5 6822.4 5874.7 5986.0 5999.6 6010.4 6018.2 7409.9 7451.6 7453.4 7454.0 7458.0 4306.6 4446.1 4744.2 4745.4 4768.8 7648.8 7812.3 7909.7 7929.7 7932.8 4814.6 4824.5 4825.7 4831.4 4834.0 4289.6 4912.9 4923.4 4927.5 4940.1 5835.6 5954.9 5964.5 5970.7 5986.8 7443.2 7502.2 7503.4 7504.0 7505.7 1690.8 1710.1 1715.3 1717.0 1719.7 1069.5 1072.4 1075.3 1090.3 1097.1 6878.7 6916.0 6926.4 6946.7 6952.7 7757.5 7770.1 7770.9 7778.2 7798.8 5715.3 6268.8 6875.1 6906.8 6919.6 4077.5 4088.6 4107.3 4111.4 4114.5 6470.5 8086.4 8122.4 8124.3 8131.6 7873.1 8082.5 8097.6 8098.7 8100.6 8089.6 8093.8 8097.2 8100.6 8105.5 7626.8 7811.3 7819.3 7831.1 7866.9 7686.5 7690.4 7706.4 7719.2 7748.0 8135.1 8140.4 8143.4 8145.0 8145.6 4687.2 4826.3 4844.7 4850.7 4854.3 7802.0 7806.3 7818.5 7824.5 7832.3 7268.0 7475.6 7487.4 7494.7 7495.9 7438.5 7444.5 7456.1 7459.4 7465.1 5964.8 7281.2 7301.2 7313.2 7317.7 7302.8 7434.9 7481.7 7486.7 7491.9 7346.4 7353.6 7357.6 7357.9 7358.2 17:56:36 INFO - 2015-11-18 17:56:36,383 DEBUG : BROWSER_OUTPUT: 17:56:36 INFO - 2015-11-18 17:56:36,384 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:17609.15 Median:609.23 stddev:25169.80 (4131.4%) stddev-sans-first:25208.76 17:56:36 INFO - 2015-11-18 17:56:36,384 DEBUG : BROWSER_OUTPUT: Values: 799.4 801.4 813.7 817.3 827.7 15052.5 15540.5 15566.3 15572.2 15605.6 14302.8 14370.2 14393.5 14414.6 14416.9 749.9 760.0 760.6 926.0 942.4 939.8 946.6 960.7 962.7 963.1 984.0 1003.7 1004.5 1005.3 1005.8 768.5 790.1 800.8 801.8 802.9 732.4 741.1 741.9 745.1 745.5 14983.6 15218.5 15252.9 15254.5 15256.0 10022.4 12305.0 12472.3 12598.9 12651.7 10033.2 10246.1 10321.4 10336.0 10350.0 3505.3 3533.5 3547.7 3548.1 3549.5 75991.9 77568.6 77720.0 77833.6 80578.0 7199.9 7271.3 7293.9 7416.9 7563.3 11202.4 11942.9 12715.7 12716.8 12717.2 6486.1 6567.0 6659.9 6712.7 6819.0 3547.4 4187.1 4191.6 4191.6 4201.0 2693.6 3116.2 3154.4 3154.6 3155.7 487.8 499.1 500.4 504.4 507.2 484.5 539.0 545.8 548.2 637.0 8382.8 8620.8 8656.7 8784.8 8909.6 528.4 603.7 614.8 621.5 629.1 3780.6 3990.5 4041.7 4053.6 4162.8 85911.0 86306.6 97752.0 98613.0 98659.0 70436.6 71661.3 72101.3 72261.0 78258.0 66392.7 66515.7 66908.0 67273.3 69303.7 8151.1 8917.2 9016.5 9017.9 9088.3 45015.3 45657.9 46549.5 46858.0 52445.7 66722.7 68059.0 68791.0 69607.7 70061.6 7917.2 7937.5 7955.6 7962.3 7989.0 11984.1 12094.3 12174.1 12305.3 12489.9 11336.7 11414.7 11474.9 11507.5 11527.7 11473.4 11574.8 11575.3 11576.9 11592.6 11346.9 11412.9 11418.7 11437.4 11456.0 8111.3 9728.3 9786.7 9859.9 10100.4 9517.9 9739.1 9802.7 9821.1 9859.7 17:56:36 INFO - 2015-11-18 17:56:36,384 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:56:36 INFO - 2015-11-18 17:56:36,384 DEBUG : BROWSER_OUTPUT: 17:56:36 INFO - 2015-11-18 17:56:36,810 INFO : Browser exited with error code: 0 17:56:36 INFO - 2015-11-18 17:56:36,834 INFO : Completed test dromaeo_css (00:18:32) 17:56:36 INFO - 2015-11-18 17:56:36,834 INFO : Starting test kraken 17:56:36 INFO - 2015-11-18 17:56:36,834 DEBUG : operating with platform_type : mac_ 17:56:36 INFO - 2015-11-18 17:56:36,835 INFO : Initialising browser for kraken test... 17:56:36 INFO - 2015-11-18 17:56:36,847 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmp6QeMnD/profile http://localhost:51603/getInfo.html 17:56:38 INFO - 2015-11-18 17:56:38,915 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:56:38 INFO - 2015-11-18 17:56:38,915 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:56:38 INFO - 2015-11-18 17:56:38,916 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 17:56:38 INFO - 2015-11-18 17:56:38,916 DEBUG : BROWSER_OUTPUT: __metrics 17:56:38 INFO - 2015-11-18 17:56:38,933 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:56:39 INFO - 2015-11-18 17:56:39,195 DEBUG : BROWSER_OUTPUT: console.error: 17:56:39 INFO - 2015-11-18 17:56:39,195 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction. 17:56:39 INFO - 2015-11-18 17:56:39,195 DEBUG : BROWSER_OUTPUT: Stack: 17:56:39 INFO - 2015-11-18 17:56:39,196 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 17:56:39 INFO - 2015-11-18 17:56:39,196 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 17:56:39 INFO - 2015-11-18 17:56:39,196 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 17:56:39 INFO - 2015-11-18 17:56:39,196 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 17:56:39 INFO - 2015-11-18 17:56:39,196 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 17:56:39 INFO - 2015-11-18 17:56:39,196 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 17:56:39 INFO - 2015-11-18 17:56:39,196 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 17:56:39 INFO - 2015-11-18 17:56:39,196 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7 17:56:39 INFO - 2015-11-18 17:56:39,196 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 17:56:39 INFO - 2015-11-18 17:56:39,197 DEBUG : BROWSER_OUTPUT: 17:56:39 INFO - 2015-11-18 17:56:39,602 INFO : Browser initialized. 17:56:39 INFO - 2015-11-18 17:56:39,603 INFO : Running cycle 1/1 for kraken test... 17:56:39 INFO - 2015-11-18 17:56:39,603 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmp6QeMnD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:56:51 INFO - 2015-11-18 17:56:51,520 DEBUG : BROWSER_OUTPUT: RSS: Main: 226521088 17:56:51 INFO - 2015-11-18 17:56:51,520 DEBUG : BROWSER_OUTPUT: 17:56:54 INFO - 2015-11-18 17:56:54,044 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51603/tests/kraken/driver.html?testName=audio-beat-detection) 17:56:54 INFO - 2015-11-18 17:56:54,635 DEBUG : BROWSER_OUTPUT: RSS: Main: 275365888 17:56:54 INFO - 2015-11-18 17:56:54,635 DEBUG : BROWSER_OUTPUT: 17:56:57 INFO - 2015-11-18 17:56:57,271 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51603/tests/kraken/driver.html?testName=audio-dft) 17:56:57 INFO - 2015-11-18 17:56:57,861 DEBUG : BROWSER_OUTPUT: RSS: Main: 291368960 17:56:57 INFO - 2015-11-18 17:56:57,861 DEBUG : BROWSER_OUTPUT: 17:57:01 INFO - 2015-11-18 17:57:01,094 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51603/tests/kraken/driver.html?testName=audio-fft) 17:57:01 INFO - 2015-11-18 17:57:01,673 DEBUG : BROWSER_OUTPUT: RSS: Main: 275791872 17:57:01 INFO - 2015-11-18 17:57:01,674 DEBUG : BROWSER_OUTPUT: 17:57:03 INFO - 2015-11-18 17:57:03,580 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51603/tests/kraken/driver.html?testName=audio-oscillator) 17:57:04 INFO - 2015-11-18 17:57:04,166 DEBUG : BROWSER_OUTPUT: RSS: Main: 279019520 17:57:04 INFO - 2015-11-18 17:57:04,167 DEBUG : BROWSER_OUTPUT: 17:57:06 INFO - 2015-11-18 17:57:06,414 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51603/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:57:06 INFO - 2015-11-18 17:57:06,998 DEBUG : BROWSER_OUTPUT: RSS: Main: 280244224 17:57:06 INFO - 2015-11-18 17:57:06,998 DEBUG : BROWSER_OUTPUT: 17:57:09 INFO - 2015-11-18 17:57:09,677 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51603/tests/kraken/driver.html?testName=imaging-darkroom) 17:57:10 INFO - 2015-11-18 17:57:10,269 DEBUG : BROWSER_OUTPUT: RSS: Main: 279097344 17:57:10 INFO - 2015-11-18 17:57:10,269 DEBUG : BROWSER_OUTPUT: 17:57:13 INFO - 2015-11-18 17:57:13,117 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51603/tests/kraken/driver.html?testName=imaging-desaturate) 17:57:13 INFO - 2015-11-18 17:57:13,724 DEBUG : BROWSER_OUTPUT: RSS: Main: 281206784 17:57:13 INFO - 2015-11-18 17:57:13,724 DEBUG : BROWSER_OUTPUT: 17:57:16 INFO - 2015-11-18 17:57:16,585 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51603/tests/kraken/driver.html?testName=json-parse-financial) 17:57:17 INFO - 2015-11-18 17:57:17,179 DEBUG : BROWSER_OUTPUT: RSS: Main: 279797760 17:57:17 INFO - 2015-11-18 17:57:17,179 DEBUG : BROWSER_OUTPUT: 17:57:18 INFO - 2015-11-18 17:57:18,708 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51603/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:57:19 INFO - 2015-11-18 17:57:19,282 DEBUG : BROWSER_OUTPUT: RSS: Main: 313221120 17:57:19 INFO - 2015-11-18 17:57:19,282 DEBUG : BROWSER_OUTPUT: 17:57:21 INFO - 2015-11-18 17:57:21,043 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51603/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:57:21 INFO - 2015-11-18 17:57:21,664 DEBUG : BROWSER_OUTPUT: RSS: Main: 317161472 17:57:21 INFO - 2015-11-18 17:57:21,664 DEBUG : BROWSER_OUTPUT: 17:57:23 INFO - 2015-11-18 17:57:23,797 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51603/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:57:24 INFO - 2015-11-18 17:57:24,383 DEBUG : BROWSER_OUTPUT: RSS: Main: 323133440 17:57:24 INFO - 2015-11-18 17:57:24,383 DEBUG : BROWSER_OUTPUT: 17:57:26 INFO - 2015-11-18 17:57:26,942 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51603/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:57:27 INFO - 2015-11-18 17:57:27,544 DEBUG : BROWSER_OUTPUT: RSS: Main: 325337088 17:57:27 INFO - 2015-11-18 17:57:27,544 DEBUG : BROWSER_OUTPUT: 17:57:29 INFO - 2015-11-18 17:57:29,952 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51603/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:57:30 INFO - 2015-11-18 17:57:30,538 DEBUG : BROWSER_OUTPUT: RSS: Main: 325054464 17:57:30 INFO - 2015-11-18 17:57:30,538 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,209 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51603/tests/kraken/driver.html?testName=ai-astar) 17:57:32 INFO - 2015-11-18 17:57:32,466 DEBUG : BROWSER_OUTPUT: RSS: Main: 384331776 17:57:32 INFO - 2015-11-18 17:57:32,466 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,466 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:57:32 INFO - 2015-11-18 17:57:32,466 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:57:32 INFO - 2015-11-18 17:57:32,466 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:57:32 INFO - 2015-11-18 17:57:32,466 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:57:32 INFO - 2015-11-18 17:57:32,466 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;104;111;109;110;102;111;103;109 17:57:32 INFO - 2015-11-18 17:57:32,466 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;121;141;158;127;167;167;116;156;117 17:57:32 INFO - 2015-11-18 17:57:32,467 DEBUG : BROWSER_OUTPUT: |2;audio-dft;186;188;178;183;183;188;187;187;181;184 17:57:32 INFO - 2015-11-18 17:57:32,467 DEBUG : BROWSER_OUTPUT: |3;audio-fft;63;70;76;72;56;56;59;63;89;79 17:57:32 INFO - 2015-11-18 17:57:32,467 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;97;134;65;107;110;142;111;87;112 17:57:32 INFO - 2015-11-18 17:57:32,467 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;86;86;87;87;86;87;85;89;86;87 17:57:32 INFO - 2015-11-18 17:57:32,467 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;100;145;94;93;94;93;93;97;94;89 17:57:32 INFO - 2015-11-18 17:57:32,467 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;87;86;83;92;71;127;138;138;84;83 17:57:32 INFO - 2015-11-18 17:57:32,467 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;57;56;56;56;57;55;65;53;54 17:57:32 INFO - 2015-11-18 17:57:32,467 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;62;64;63;63;64;67;65;66;68;67 17:57:32 INFO - 2015-11-18 17:57:32,467 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;82;97;105;81;82;84;83;83;84 17:57:32 INFO - 2015-11-18 17:57:32,467 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;130;115;124;122;122;126;102;123;145;125 17:57:32 INFO - 2015-11-18 17:57:32,467 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;124;135;136;135;127;137;135;136;124 17:57:32 INFO - 2015-11-18 17:57:32,467 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;65;65;64;65;65;63;67;64;66 17:57:32 INFO - 2015-11-18 17:57:32,467 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:57:32 INFO - 2015-11-18 17:57:32,468 DEBUG : BROWSER_OUTPUT: __start_cc_report 17:57:32 INFO - 2015-11-18 17:57:32,468 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1048 17:57:32 INFO - 2015-11-18 17:57:32,468 DEBUG : BROWSER_OUTPUT: __end_cc_report 17:57:32 INFO - 2015-11-18 17:57:32,468 DEBUG : BROWSER_OUTPUT: __startTimestamp1447898252459__endTimestamp 17:57:32 INFO - 2015-11-18 17:57:32,468 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,468 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:57:32 INFO - 2015-11-18 17:57:32,468 DEBUG : BROWSER_OUTPUT: Number of tests: 14 17:57:32 INFO - 2015-11-18 17:57:32,468 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,468 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.70 Median:109.50 stddev:3.40 (3.1%) stddev-sans-first:3.61 17:57:32 INFO - 2015-11-18 17:57:32,468 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 104.0 111.0 109.0 110.0 102.0 111.0 103.0 109.0 17:57:32 INFO - 2015-11-18 17:57:32,468 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,468 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.10 Median:153.50 stddev:20.41 (13.3%) stddev-sans-first:21.40 17:57:32 INFO - 2015-11-18 17:57:32,468 DEBUG : BROWSER_OUTPUT: Values: 151.0 121.0 141.0 158.0 127.0 167.0 167.0 116.0 156.0 117.0 17:57:32 INFO - 2015-11-18 17:57:32,469 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,469 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:184.50 Median:186.50 stddev:3.31 (1.8%) stddev-sans-first:3.46 17:57:32 INFO - 2015-11-18 17:57:32,469 DEBUG : BROWSER_OUTPUT: Values: 186.0 188.0 178.0 183.0 183.0 188.0 187.0 187.0 181.0 184.0 17:57:32 INFO - 2015-11-18 17:57:32,469 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,469 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:68.30 Median:71.00 stddev:10.87 (15.3%) stddev-sans-first:11.36 17:57:32 INFO - 2015-11-18 17:57:32,469 DEBUG : BROWSER_OUTPUT: Values: 63.0 70.0 76.0 72.0 56.0 56.0 59.0 63.0 89.0 79.0 17:57:32 INFO - 2015-11-18 17:57:32,469 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,469 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.70 Median:103.50 stddev:22.30 (21.5%) stddev-sans-first:23.10 17:57:32 INFO - 2015-11-18 17:57:32,469 DEBUG : BROWSER_OUTPUT: Values: 92.0 97.0 134.0 65.0 107.0 110.0 142.0 111.0 87.0 112.0 17:57:32 INFO - 2015-11-18 17:57:32,469 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,469 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.00 stddev:1.07 (1.2%) stddev-sans-first:1.12 17:57:32 INFO - 2015-11-18 17:57:32,469 DEBUG : BROWSER_OUTPUT: Values: 86.0 86.0 87.0 87.0 86.0 87.0 85.0 89.0 86.0 87.0 17:57:32 INFO - 2015-11-18 17:57:32,469 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,470 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:99.20 Median:93.50 stddev:16.34 (17.5%) stddev-sans-first:17.33 17:57:32 INFO - 2015-11-18 17:57:32,470 DEBUG : BROWSER_OUTPUT: Values: 100.0 145.0 94.0 93.0 94.0 93.0 93.0 97.0 94.0 89.0 17:57:32 INFO - 2015-11-18 17:57:32,470 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,470 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:98.90 Median:83.50 stddev:25.19 (30.2%) stddev-sans-first:26.34 17:57:32 INFO - 2015-11-18 17:57:32,470 DEBUG : BROWSER_OUTPUT: Values: 87.0 86.0 83.0 92.0 71.0 127.0 138.0 138.0 84.0 83.0 17:57:32 INFO - 2015-11-18 17:57:32,470 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,470 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.70 Median:56.50 stddev:3.27 (5.8%) stddev-sans-first:3.43 17:57:32 INFO - 2015-11-18 17:57:32,470 DEBUG : BROWSER_OUTPUT: Values: 58.0 57.0 56.0 56.0 56.0 57.0 55.0 65.0 53.0 54.0 17:57:32 INFO - 2015-11-18 17:57:32,470 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,470 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:65.50 stddev:2.02 (3.1%) stddev-sans-first:1.86 17:57:32 INFO - 2015-11-18 17:57:32,470 DEBUG : BROWSER_OUTPUT: Values: 62.0 64.0 63.0 63.0 64.0 67.0 65.0 66.0 68.0 67.0 17:57:32 INFO - 2015-11-18 17:57:32,470 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,470 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:83.00 stddev:8.03 (9.7%) stddev-sans-first:8.36 17:57:32 INFO - 2015-11-18 17:57:32,471 DEBUG : BROWSER_OUTPUT: Values: 82.0 82.0 97.0 105.0 81.0 82.0 84.0 83.0 83.0 84.0 17:57:32 INFO - 2015-11-18 17:57:32,471 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,471 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.40 Median:124.50 stddev:10.81 (8.7%) stddev-sans-first:11.20 17:57:32 INFO - 2015-11-18 17:57:32,471 DEBUG : BROWSER_OUTPUT: Values: 130.0 115.0 124.0 122.0 122.0 126.0 102.0 123.0 145.0 125.0 17:57:32 INFO - 2015-11-18 17:57:32,471 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,471 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:132.40 Median:135.00 stddev:5.21 (3.9%) stddev-sans-first:5.44 17:57:32 INFO - 2015-11-18 17:57:32,471 DEBUG : BROWSER_OUTPUT: Values: 135.0 124.0 135.0 136.0 135.0 127.0 137.0 135.0 136.0 124.0 17:57:32 INFO - 2015-11-18 17:57:32,471 DEBUG : BROWSER_OUTPUT: 17:57:32 INFO - 2015-11-18 17:57:32,471 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:1.10 (1.7%) stddev-sans-first:1.17 17:57:32 INFO - 2015-11-18 17:57:32,471 DEBUG : BROWSER_OUTPUT: Values: 65.0 65.0 65.0 64.0 65.0 65.0 63.0 67.0 64.0 66.0 17:57:32 INFO - 2015-11-18 17:57:32,471 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:57:32 INFO - 2015-11-18 17:57:32,471 DEBUG : BROWSER_OUTPUT: 17:57:33 INFO - 2015-11-18 17:57:33,191 INFO : Browser exited with error code: 0 17:57:33 INFO - 2015-11-18 17:57:33,239 INFO : Completed test kraken (00:00:56) 17:57:33 INFO - 2015-11-18 17:57:33,239 INFO : Starting test v8_7 17:57:33 INFO - 2015-11-18 17:57:33,239 DEBUG : operating with platform_type : mac_ 17:57:33 INFO - 2015-11-18 17:57:33,239 INFO : Initialising browser for v8_7 test... 17:57:33 INFO - 2015-11-18 17:57:33,252 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmph5MKgT/profile http://localhost:51603/getInfo.html 17:57:35 INFO - 2015-11-18 17:57:35,281 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:57:35 INFO - 2015-11-18 17:57:35,281 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:57:35 INFO - 2015-11-18 17:57:35,281 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 17:57:35 INFO - 2015-11-18 17:57:35,281 DEBUG : BROWSER_OUTPUT: __metrics 17:57:35 INFO - 2015-11-18 17:57:35,298 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:57:35 INFO - 2015-11-18 17:57:35,563 DEBUG : BROWSER_OUTPUT: console.error: 17:57:35 INFO - 2015-11-18 17:57:35,564 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction. 17:57:35 INFO - 2015-11-18 17:57:35,565 DEBUG : BROWSER_OUTPUT: Stack: 17:57:35 INFO - 2015-11-18 17:57:35,565 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 17:57:35 INFO - 2015-11-18 17:57:35,565 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 17:57:35 INFO - 2015-11-18 17:57:35,565 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 17:57:35 INFO - 2015-11-18 17:57:35,565 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 17:57:35 INFO - 2015-11-18 17:57:35,565 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 17:57:35 INFO - 2015-11-18 17:57:35,565 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 17:57:35 INFO - 2015-11-18 17:57:35,565 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 17:57:35 INFO - 2015-11-18 17:57:35,565 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7 17:57:35 INFO - 2015-11-18 17:57:35,565 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 17:57:35 INFO - 2015-11-18 17:57:35,565 DEBUG : BROWSER_OUTPUT: 17:57:35 INFO - 2015-11-18 17:57:35,956 INFO : Browser initialized. 17:57:35 INFO - 2015-11-18 17:57:35,957 INFO : Running cycle 1/1 for v8_7 test... 17:57:35 INFO - 2015-11-18 17:57:35,957 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmph5MKgT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:57:47 INFO - 2015-11-18 17:57:47,887 DEBUG : BROWSER_OUTPUT: RSS: Main: 225722368 17:57:47 INFO - 2015-11-18 17:57:47,887 DEBUG : BROWSER_OUTPUT: 17:58:09 INFO - 2015-11-18 17:58:09,258 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51603/tests/v8_7/run.html (next: http://localhost:51603/tests/v8_7/run.html) 17:58:09 INFO - 2015-11-18 17:58:09,516 DEBUG : BROWSER_OUTPUT: RSS: Main: 617172992 17:58:09 INFO - 2015-11-18 17:58:09,516 DEBUG : BROWSER_OUTPUT: 17:58:09 INFO - 2015-11-18 17:58:09,516 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:58:09 INFO - 2015-11-18 17:58:09,516 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:58:09 INFO - 2015-11-18 17:58:09,517 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:58:09 INFO - 2015-11-18 17:58:09,517 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:58:09 INFO - 2015-11-18 17:58:09,517 DEBUG : BROWSER_OUTPUT: |0;Richards;148.76524843796489 17:58:09 INFO - 2015-11-18 17:58:09,517 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;191.9754271453254 17:58:09 INFO - 2015-11-18 17:58:09,517 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.04557033682426 17:58:09 INFO - 2015-11-18 17:58:09,517 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4911.764705882353 17:58:09 INFO - 2015-11-18 17:58:09,517 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1754.3859649122808 17:58:09 INFO - 2015-11-18 17:58:09,517 DEBUG : BROWSER_OUTPUT: |5;Earley;867.3026886383348 17:58:09 INFO - 2015-11-18 17:58:09,517 DEBUG : BROWSER_OUTPUT: |6;Boyer;12537.5 17:58:09 INFO - 2015-11-18 17:58:09,517 DEBUG : BROWSER_OUTPUT: |7;RegExp;29142.85714285714 17:58:09 INFO - 2015-11-18 17:58:09,517 DEBUG : BROWSER_OUTPUT: |8;Splay;647.5145255003227 17:58:09 INFO - 2015-11-18 17:58:09,517 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4509.009009009009 17:58:09 INFO - 2015-11-18 17:58:09,517 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:58:09 INFO - 2015-11-18 17:58:09,518 DEBUG : BROWSER_OUTPUT: __startTimestamp1447898289509__endTimestamp 17:58:09 INFO - 2015-11-18 17:58:09,518 DEBUG : BROWSER_OUTPUT: 17:58:09 INFO - 2015-11-18 17:58:09,518 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:58:09 INFO - 2015-11-18 17:58:09,518 DEBUG : BROWSER_OUTPUT: Number of tests: 10 17:58:09 INFO - 2015-11-18 17:58:09,518 DEBUG : BROWSER_OUTPUT: 17:58:09 INFO - 2015-11-18 17:58:09,518 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:148.77 Median:148.77 stddev:0.00 (0.0%) 17:58:09 INFO - 2015-11-18 17:58:09,518 DEBUG : BROWSER_OUTPUT: Values: 148.8 17:58:09 INFO - 2015-11-18 17:58:09,518 DEBUG : BROWSER_OUTPUT: 17:58:09 INFO - 2015-11-18 17:58:09,518 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:191.98 Median:191.98 stddev:0.00 (0.0%) 17:58:09 INFO - 2015-11-18 17:58:09,518 DEBUG : BROWSER_OUTPUT: Values: 192.0 17:58:09 INFO - 2015-11-18 17:58:09,518 DEBUG : BROWSER_OUTPUT: 17:58:09 INFO - 2015-11-18 17:58:09,518 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.05 Median:283.05 stddev:0.00 (0.0%) 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: Values: 283.0 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4911.76 Median:4911.76 stddev:0.00 (0.0%) 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: Values: 4911.8 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1754.39 Median:1754.39 stddev:0.00 (0.0%) 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: Values: 1754.4 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:867.30 Median:867.30 stddev:0.00 (0.0%) 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: Values: 867.3 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12537.50 Median:12537.50 stddev:0.00 (0.0%) 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: Values: 12537.5 17:58:09 INFO - 2015-11-18 17:58:09,519 DEBUG : BROWSER_OUTPUT: 17:58:09 INFO - 2015-11-18 17:58:09,520 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:29142.86 Median:29142.86 stddev:0.00 (0.0%) 17:58:09 INFO - 2015-11-18 17:58:09,520 DEBUG : BROWSER_OUTPUT: Values: 29142.9 17:58:09 INFO - 2015-11-18 17:58:09,520 DEBUG : BROWSER_OUTPUT: 17:58:09 INFO - 2015-11-18 17:58:09,520 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:647.51 Median:647.51 stddev:0.00 (0.0%) 17:58:09 INFO - 2015-11-18 17:58:09,520 DEBUG : BROWSER_OUTPUT: Values: 647.5 17:58:09 INFO - 2015-11-18 17:58:09,520 DEBUG : BROWSER_OUTPUT: 17:58:09 INFO - 2015-11-18 17:58:09,520 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4509.01 Median:4509.01 stddev:0.00 (0.0%) 17:58:09 INFO - 2015-11-18 17:58:09,520 DEBUG : BROWSER_OUTPUT: Values: 4509.0 17:58:09 INFO - 2015-11-18 17:58:09,520 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:58:09 INFO - 2015-11-18 17:58:09,520 DEBUG : BROWSER_OUTPUT: 17:58:10 INFO - 2015-11-18 17:58:10,115 INFO : Browser exited with error code: 0 17:58:10 INFO - 2015-11-18 17:58:10,132 INFO : Completed test v8_7 (00:00:36) 17:58:10 INFO - 2015-11-18 17:58:10,136 INFO : Completed test suite (00:20:05) 17:58:10 INFO - 2015-11-18 17:58:10,136 DEBUG : Working with test: dromaeo_css 17:58:10 INFO - 2015-11-18 17:58:10,136 DEBUG : Generating results file: dromaeo_css 17:58:10 INFO - 2015-11-18 17:58:10,137 DEBUG : Working with test: kraken 17:58:10 INFO - 2015-11-18 17:58:10,137 DEBUG : Generating results file: kraken 17:58:10 INFO - 2015-11-18 17:58:10,137 INFO : javascript benchmark 17:58:10 INFO - 2015-11-18 17:58:10,137 DEBUG : Working with test: v8_7 17:58:10 INFO - 2015-11-18 17:58:10,137 DEBUG : Generating results file: v8_7 17:58:10 INFO - 2015-11-18 17:58:10,138 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:58:10 INFO - 2015-11-18 17:58:10,190 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 17:58:10 INFO - 2015-11-18 17:58:10,190 DEBUG : process_Request line: dromaeo_css 7211.31 graph.html#tests=[[72,64,59]] 17:58:10 INFO - 2015-11-18 17:58:10,190 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:58:10 INFO - 2015-11-18 17:58:10,222 DEBUG : process_Request line: kraken 1421.60 graph.html#tests=[[232,64,59]] 17:58:10 INFO - 2015-11-18 17:58:10,222 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:58:10 INFO - 2015-11-18 17:58:10,248 DEBUG : process_Request line: v8_7 19888.03 graph.html#tests=[[230,64,59]] 17:58:10 INFO - 2015-11-18 17:58:10,249 INFO : javascript benchmark 17:58:10 INFO - 2015-11-18 17:58:10,251 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3400.863965441382, 3427.7600567960235, 3465.7573969822092, 3486.2504561519268, 3513.5432393788806, 5703.429657034295, 5851.356350801409, 5929.88140237195, 5939.732712027958, 6017.939820601792, 5890.852728681785, 5892.322382925965, 5895.469407753301, 6022.036474164134, 6030.8793824123495, 5311.07056265154, 5316.308879845625, 5345.118055520835, 5377.978184145019, 5431.592630552705, 4988.426330971948, 5447.509724124828, 5463.235147079412, 5483.75323110461, 5496.31296087989, 5074.670146440507, 5322.015427145951, 5324.600654950856, 5437.102878025121, 5470.070088085015, 5346.465353464661, 5353.625246232766, 5355.491228333302, 5359.919601205985, 5367.892642147135, 5193.532582067632, 5321.148616221386, 5323.441038690959, 5323.680579165262, 5323.733813309319, 4397.73613583186, 5812.505936995361, 5815.563832712563, 5815.970920145414, 5819.098039803837, 6296.464800491918, 6296.842578935518, 6301.68491575424, 6306.810795676137, 6314.242290925073, 6156.5998210116195, 6339.683015849189, 6341.87316253677, 6377.744890204386, 6392.456641185505, 5949.167116603679, 5968.134620480006, 5995.490383317379, 6046.667433291167, 6058.909116363258, 5650.491455769001, 6418.454431373292, 6479.222493300834, 6487.286398496161, 6498.057781621733, 6147.770445910746, 6308.369163083655, 6310.5267104967315, 6318.115463835067, 6346.492280617632, 6233.3143354231, 6321.747130114744, 6329.810105696836, 6333.873322533524, 6362.236531616236, 6307.022411526221, 6309.905351419732, 6317.747290108436, 6323.051542268697, 6325.209348831396, 6176.474999625082, 6322.241331040304, 6328.272248691367, 6347.270063942613, 6358.904616430757, 4976.62675299354, 4987.10232158215, 4996.600271978233, 5040.672356296829, 5048.848534543969, 4485.352474031768, 4547.63618910493, 4596.678232523758, 4606.170889239902, 4632.050429661911, 5713.143028545752, 5809.0, 5832.445917637818, 5856.092305692625, 6054.969725151346, 6218.626882387071, 6289.150964619809, 6290.779822706183, 6301.7164227608955, 6329.968350158219, 5890.0, 5996.670183139882, 6013.098035294741, 6031.728572214261, 6046.244219472566, 5520.641158324696, 5567.053600887859, 5571.637843540157, 5582.190582365534, 5585.0, 6319.55763096588, 6405.295417504164, 6407.327230640902, 6419.646919419383, 6607.702653380609, 6344.302126766144, 6373.171487706569, 6377.8724425512155, 6401.103845461546, 6420.871582568416, 6552.0, 6562.737490500326, 6606.867862642816, 6634.535582509178, 6635.601863888182, 6145.432914606926, 6185.443310101932, 6254.8123556293385, 6321.146645203023, 6325.620462772248, 6358.618482891041, 6394.584352017104, 6408.935910640834, 6413.454856337264, 6418.871622567616, 6119.816405507842, 6286.0, 6297.779577714758, 6317.6841157941, 6324.557280990287, 6065.7573697053385, 6154.476869466145, 6182.227221597301, 6312.400321969406, 6318.715657795502, 6105.053716673922, 6172.351903050115, 6189.597676151036, 6190.164327815687, 6200.038993955944, 6236.251649802053, 6294.992801151794, 6301.8109456716375, 6309.810705678837, 6314.084457753692, 5232.895342093213, 5965.821025369246, 6062.090686397076, 6223.813285601439, 6228.0], "unit": "ms", "name": "dojo.html", "value": 5835.017929053213}, {"lowerIsBetter": false, "replicates": [554.7420449490987, 558.9245451863998, 594.7941170845525, 617.0528239152899, 632.8006677896461, 32635.67364326356, 32916.177095572595, 32991.67008329916, 39589.0, 40083.59916400835, 43840.46558370458, 44053.33919991203, 44188.337174942404, 44281.557184428144, 44351.0, 9762.023797620252, 9901.455419951899, 9904.108630223278, 9919.404835709844, 9925.454100024495, 8812.471251724917, 8916.063813299608, 8921.776955576097, 8927.77680557988, 8953.149450802164, 12000.339981301026, 12097.939510302436, 12120.515179392814, 12122.393880305994, 12137.575184868532, 11792.410379481034, 11933.0, 11951.342676152808, 11958.820617690742, 11982.161248712593, 8790.25282850952, 8938.0, 8956.193942545202, 11688.0, 11874.821877671842, 42915.78542107301, 43483.56516434827, 43492.782536087434, 43513.0, 43513.34729979053, 3198.7281081108135, 3203.1191422358806, 3235.288236587947, 3364.882229121969, 3623.1847834237087, 29039.98360057409, 29209.561856572167, 29321.0, 29517.70482295171, 29710.40579188426, 2462.581361168606, 2609.412882101531, 2650.059228973706, 2730.631364765751, 2757.393373457836, 463359.0, 621703.0, 622956.0, 623077.884610574, 638556.0, 48544.27183592249, 67712.0, 68566.65716671385, 68662.16371608469, 68964.31035689679, 65453.0, 65761.6711916447, 65929.0, 65962.0, 66018.0, 13547.932260338832, 16395.42616008434, 16424.917875410785, 16430.75353869693, 16686.165691715367, 12798.232106073665, 12829.807552886714, 12851.16467429614, 12875.871241287654, 12885.226886386847, 19855.305064322678, 20003.0, 20043.29848455297, 20056.298029568894, 20072.89963550202, 8625.568721563897, 8789.648414063366, 8812.471251724917, 8814.162654547808, 8829.911700883038, 948.2224575847739, 980.6371642492323, 1029.5006921642996, 1032.7882784029257, 1106.391484683421, 29545.704542954296, 29675.0, 30162.84918575437, 30384.530784458566, 30510.08469745911, 1011.2112552209288, 1081.291753901196, 1091.0834898685143, 1097.6487523992246, 1104.3429159649995, 12762.553310634083, 14988.875834312252, 15303.923480382527, 15484.612884677748, 15685.098256822295, 539595.0, 543455.0, 543613.6915892529, 545202.0, 549435.0, 59652.0, 59976.40023599796, 60119.0, 60140.39859601348, 60320.0, 1438.4030627289767, 1461.9342129604192, 1481.029925398844, 1489.4414594527052, 1494.0438119603461, 27175.72824271732, 29611.85194074102, 30677.693223067483, 30845.691543084282, 31298.687013129576, 50613.74693126511, 50952.74523627506, 51043.23435148402, 51049.744751276005, 51064.48935510597, 469763.90708278806, 480005.0, 480349.0, 480588.0, 481081.0, 28994.565081524215, 29294.853525732236, 31684.841575791972, 33249.833750831094, 34151.487727683605, 43691.158132163546, 44026.559734402246, 44030.779846100566, 44395.55604444043, 45984.31023534583, 33804.66195338015, 33921.32157356889, 35225.64774352326, 37529.061773455884, 38068.67700888944, 30378.0, 36867.44698829466, 36961.81519092495, 37916.0, 37997.620023799405, 29872.954446595133, 31835.04494865158, 35853.1036724084, 36156.819215904805, 37568.060798480255, 30123.69876301209, 32261.838690806395, 32388.0, 33693.0, 33820.32359352848, 24691.382715432257, 24961.50076998486, 25247.990080397314, 25561.488770224863, 25609.743902560735], "unit": "ms", "name": "ext.html", "value": 20234.99741295559}, {"lowerIsBetter": false, "replicates": [359.90282623691604, 362.95100161478194, 367.04018990340234, 368.2322357883813, 371.6098096998151, 10872.565097396095, 11175.21773475855, 12608.0, 12941.482340706372, 19583.412497625075, 10656.608743807346, 10967.83548246777, 11583.884161158387, 12686.492540298377, 12714.936425317861, 4446.666500012496, 5173.120569503193, 5315.574754019669, 5318.095923692981, 5320.14877619581, 3716.089558058266, 3806.9238615227678, 3822.0444888777806, 5228.869278268055, 5252.71110088945, 5250.553702935236, 5260.8684782880355, 5273.446288139748, 5285.973570132144, 5292.285541451913, 5044.722540260284, 5341.332333458317, 5341.65279256849, 5343.599230057742, 5349.866253343658, 4470.307102399133, 4473.194824931511, 4486.102779444124, 4488.932666010012, 4490.0, 11463.942680286546, 12092.274463532216, 14331.498397556139, 14591.635209119857, 14621.342039608244, 1970.5861769028522, 1989.383291179739, 1994.072756168374, 2048.4776382022637, 2192.4299682082615, 8817.470951742916, 9962.252831037702, 10099.596016159345, 10500.78998420035, 10597.099246563974, 1633.0446688687132, 1657.3784830688492, 1665.258959762906, 1668.966620667592, 1686.376040864888, 90713.54643226808, 93663.53168234116, 95084.0, 96367.0, 96954.39592852884, 5792.768289268466, 6849.760258391032, 6856.485763567753, 6857.862842743117, 6944.166699996033, 7789.33790627803, 7792.181820908836, 7809.804754881174, 7820.296173344313, 7826.295633393022, 5799.3620701722775, 5802.448767367094, 5806.88386232273, 5825.941740582624, 5843.0066888629035, 6233.0, 6249.437550620466, 6291.842703932347, 6295.276043254993, 6299.149614802035, 3085.182426656938, 3092.659807421227, 3104.5032794752733, 3127.358891427252, 3137.9686203138135, 1078.5254488025244, 1084.1110289562482, 1087.798757229915, 1130.779497997899, 1162.924409913353, 432.45726613100356, 453.1119006746813, 458.48649512545495, 459.58178057967757, 481.5714014527074, 12323.507059717691, 12550.68623284407, 12863.48546058166, 12959.352032398343, 12967.805482917764, 466.34711404034624, 470.6987527982094, 482.7055496147347, 488.067790520112, 494.6784590016446, 6443.677816109176, 6557.737690492422, 6608.207015158116, 6660.100886380374, 6688.030235615905, 789794.0, 792784.0, 796145.0, 809180.0, 811666.0, 108542.20102296381, 110277.0, 112796.0, 112918.43540782243, 114773.4261328688, 531.6863050800047, 546.3034630845624, 547.5209191957038, 547.6276132230042, 547.8575570351737, 11785.587504437304, 11808.114391420506, 11902.404879755804, 11922.52309907594, 11963.042956563628, 46598.06803863836, 47915.0, 48420.0, 48438.5156148434, 48674.78313042202, 64741.0, 64916.0, 65038.0, 65328.34671653223, 65688.0, 6318.0, 6371.6814159290925, 6500.382463665944, 6500.707468164039, 6516.446102081376, 12219.75560488803, 12443.875561244271, 12500.562480313145, 12573.811392828931, 12603.747925041265, 9383.061693830836, 9620.663276785559, 10358.585656573652, 10728.89271107279, 11038.558457661604, 8844.646214151362, 10617.662072485864, 10697.839532406864, 10748.02233020781, 10984.176186786182, 11333.829992550283, 11428.94285528567, 11445.771084578428, 11450.541978321107, 11452.828207576726, 5824.359320474659, 6253.687315634291, 6443.581167224115, 6497.415232629086, 6529.085927969993, 8886.955565222133, 10486.632967846088, 10901.0, 10977.0, 11131.888681113409], "unit": "ms", "name": "jquery.html", "value": 7258.544483929493}, {"lowerIsBetter": false, "replicates": [1580.076857813046, 1587.1270801059422, 1667.0414511655786, 1872.522506760776, 1893.9488583835969, 7295.744648937281, 7474.990876231714, 7483.812904677379, 8170.978627671541, 8198.91801081989, 7933.325667318285, 8042.597870106486, 8048.034235891687, 8053.677852885878, 8061.959690201541, 2323.2101085630884, 2340.9648855267183, 2347.507023525062, 2348.295511346589, 2351.235848349285, 2361.4332560185517, 2363.7163540375177, 2412.577798885197, 2414.9275521734376, 2415.1909110448023, 2320.5590937721863, 2351.000824649526, 2352.0591763294647, 2352.3648614873973, 2352.741198468167, 2321.1179751694417, 2337.602607556711, 2343.8476499027593, 2344.3318654183563, 2374.620060790265, 2365.73976862545, 2389.4504264019374, 2396.940076498084, 2398.8965076065033, 2400.2679182849224, 7989.5605741684185, 8037.15609860962, 8049.114597394282, 8051.0, 8061.193880611951, 4146.543880173179, 4186.790660466964, 4329.675274354436, 4585.449746030465, 4731.858044258678, 3392.6268110507826, 3398.7790793598338, 3450.27544215715, 3531.0642679689904, 3564.946525802089, 1045.8873076385735, 1068.551208492435, 1080.4111759091313, 1644.128611835729, 1655.3461382753865, 10531.315464494859, 10607.204459665556, 10639.680809575648, 10653.680389588324, 10661.733456663567, 4420.756858372756, 4459.710118842265, 4500.729956202638, 4503.392042074278, 4628.143793398165, 9349.15857572808, 9359.970403255636, 9373.859392109123, 9378.155965963097, 9399.624015039459, 5094.490550944876, 5096.898062038813, 5111.591072714174, 5123.538881500683, 5135.871603209875, 4657.0918670858855, 4666.95333046672, 4670.182718024332, 4674.392328997276, 4678.578927896506, 3602.3335682898746, 3694.9815250923575, 3698.537682789651, 3699.112213068844, 3722.5905150433973, 1217.3548019549653, 1227.8710735372836, 1231.6489800406841, 1235.5243231355992, 1242.3912282981273, 1322.312397553267, 1344.4823742859069, 1368.6646771541036, 1405.3816320818808, 1409.2530958591758, 7424.480286379901, 7592.164861865191, 7595.5822429765785, 7600.315971562586, 7600.391968642495, 491.5796993570517, 496.2035932328633, 501.8068043803162, 502.9949700502948, 505.91652377357326, 5890.617109887844, 6013.188219590387, 6077.848053798691, 6129.509639228941, 6138.846528836815, 9180.954095229481, 9762.121409073217, 9845.753856153653, 9855.26085543587, 9856.211503079736, 9370.718878433658, 9446.716598502057, 9506.287028472892, 9536.713898583053, 9587.233021358275, 7547.207543207884, 7608.695652173851, 7623.161452240249, 7660.923390766133, 7668.233176682399, 6731.697073631601, 7498.58757768328, 7522.548647081193, 7568.167501574932, 7585.810354741176, 8373.916260837557, 8547.61535730906, 8590.613422396133, 8613.48319100856, 8659.740207793777, 8563.828723425371, 8848.0, 8866.024737278765, 8982.146696063863, 9058.954705226432, 7050.189228238613, 7062.611556364449, 7152.85694286101, 7155.0, 7157.355837974607, 7613.467057305936, 7695.384369250559, 7724.729634462767, 7739.92260077392, 7747.922520774946, 6952.756653517103, 7177.533460325062, 7198.5320954137815, 7283.2352602975925, 7315.853682926205, 7131.572105673677, 7152.141742990874, 7200.495965282589, 7258.274172582699, 7295.270472952662, 7175.677094530654, 7203.423726102004, 7258.020167277561, 7279.890801637874, 7296.124465064227, 6611.966940165268, 6659.800205993828, 6663.233728121328, 6667.666616669244, 6669.699863506251, 6357.650329231752, 6712.83217919556, 6767.830804229934, 6768.08630834831, 6773.864522709617], "unit": "ms", "name": "mootools.html", "value": 4592.899621481471}, {"lowerIsBetter": false, "replicates": [319.35649665923165, 327.9376918385508, 329.7938788257338, 334.9279904820463, 336.80128724052804, 6712.865742685143, 6850.3492168244, 6875.690593923273, 6954.791356259314, 6986.5458745181595, 6781.966090169542, 6891.724331026766, 6913.930860691392, 6932.15781529573, 6942.930570694291, 4120.979395103021, 5001.199808030716, 5095.847124586268, 5128.435872054071, 5148.5881129509735, 4878.951210487885, 5077.822276220333, 5113.411957624865, 5121.078205922932, 5146.459621739719, 4814.1575224335775, 4954.7522623868845, 4967.751612419383, 4977.900441991176, 4984.601231901457, 4892.975535122319, 4898.485659005806, 4910.435299940481, 4990.525900039491, 5039.143345631252, 4222.451081359434, 4226.957730422687, 4241.618254357092, 4244.917546025775, 4245.766482843443, 6781.660916954182, 6787.04981302618, 6802.0, 6812.48906332027, 6822.3859852613, 5874.706264686749, 5986.0, 5999.550033747487, 6010.429009244115, 6018.187544681456, 7409.8518029639645, 7451.552906825608, 7453.440991925573, 7454.0, 7457.95588617594, 4306.643407326682, 4446.110777844412, 4744.217204161309, 4745.383100196987, 4768.833090841838, 7648.770536883847, 7812.3359514441345, 7909.683612655487, 7929.722459713882, 7932.841343173104, 4814.638902082358, 4824.469308376146, 4825.662203645711, 4831.4202295724035, 4834.0, 4289.635380992589, 4912.926306105412, 4923.433805112386, 4927.457979622239, 4940.061388336205, 5835.562332825055, 5954.940450595525, 5964.522838172936, 5970.731317090655, 5986.790462333797, 7443.2184620615135, 7502.2122677119205, 7503.362214211853, 7503.962480187564, 7505.662245198979, 1690.7886514185727, 1710.1363811275226, 1715.3395942561954, 1716.9570760730833, 1719.6560687862477, 1069.4813015687384, 1072.4423299884018, 1075.301054314686, 1090.3457925244948, 1097.1442275025493, 6878.69045892936, 6915.965420172867, 6926.363659078923, 6946.652667366611, 6952.721891124298, 7757.456978011598, 7770.106437759617, 7770.883436748453, 7778.183290754389, 7798.766037018899, 5715.257016587817, 6268.811935641938, 6875.106236189264, 6906.758263460855, 6919.550229235084, 4077.4903137107863, 4088.5707000765087, 4107.322291821875, 4111.444954931046, 4114.465119534441, 6470.4823614110755, 8086.353091752646, 8122.431429799975, 8124.309433698084, 8131.5527645978855, 7873.133955264916, 8082.515049097073, 8097.554634495042, 8098.676052957933, 8100.594970251464, 8089.555074470843, 8093.838123237502, 8097.190280971856, 8100.594970251464, 8105.473144245605, 7626.771196864103, 7811.257930496594, 7819.257170568787, 7831.099423566363, 7866.9213307866185, 7686.50037747567, 7690.423218258765, 7706.422018348759, 7719.150893401834, 7748.0, 8135.145809689898, 8140.430169888051, 8143.42995990275, 8145.0, 8145.592720364077, 4687.179743544798, 4826.3243145958895, 4844.709317440965, 4850.733209673502, 4854.296127061486, 7802.024746906637, 7806.297433231037, 7818.452708310591, 7824.4522883399895, 7832.255935686101, 7268.0, 7475.626218689153, 7487.363574096045, 7494.70021199146, 7495.887561686688, 7438.516496427715, 7444.478886477895, 7456.067991501062, 7459.440541959266, 7465.141508726566, 5964.821055368346, 7281.162666293444, 7301.196868344372, 7313.232110628307, 7317.743878964424, 7302.780916572511, 7434.851302974019, 7481.738139165103, 7486.7005319786595, 7491.8501629966, 7346.449016323904, 7353.558786472615, 7357.632118393952, 7357.889631655637, 7358.22738612438], "unit": "ms", "name": "prototype.html", "value": 5405.870304035019}, {"lowerIsBetter": false, "replicates": [799.412431862581, 801.350905766329, 813.6663967773214, 817.3297895725506, 827.7309874290856, 15052.548423547283, 15540.456084037032, 15566.299516521756, 15572.221388930551, 15605.609859753511, 14302.785458218135, 14370.209638469905, 14393.496227632035, 14414.567562973101, 14416.927915360435, 749.8762704153808, 760.0309605253291, 760.6310939194486, 925.9768505787358, 942.4157022645935, 939.8308304505186, 946.6260826973341, 960.7117864640614, 962.6823148361024, 963.1476143612917, 984.0, 1003.713941526665, 1004.507791182319, 1005.33647792457, 1005.7535903703603, 768.5273556762606, 790.0519376747925, 800.8398320335926, 801.7634797734624, 802.9357651387875, 732.3994324653786, 741.0810594862386, 741.9443541734391, 745.117036311972, 745.4744405194325, 14983.550493485212, 15218.543443696595, 15252.923735381362, 15254.466093686668, 15256.0, 10022.398656080659, 12305.0, 12472.314022728746, 12598.874011259863, 12651.74696506074, 10033.238153613112, 10246.118516299706, 10321.380717156919, 10336.0, 10350.0, 3505.333986542574, 3533.505309256706, 3547.6984456321243, 3548.095235714902, 3549.5208146900354, 75991.86012209821, 77568.61215693942, 77720.0, 77833.61083194548, 80578.0, 7199.9280007200305, 7271.272872712686, 7293.854122917512, 7416.925830741731, 7563.281488258606, 11202.38386888729, 11942.880571194177, 12715.745685086431, 12716.809247861289, 12717.173383730027, 6486.124373209556, 6567.0, 6659.900101498384, 6712.731490740413, 6818.965905170541, 3547.396942519778, 4187.14163596462, 4191.580841915784, 4191.643710284599, 4201.0, 2693.62289279501, 3116.1897906544464, 3154.369126174774, 3154.5899033125547, 3155.7475401967786, 487.77318546875875, 499.0692358750903, 500.4445065976763, 504.41487874065905, 507.2062222621585, 484.48886424821444, 538.9705662185207, 545.8346430371187, 548.183207021535, 636.9649669268142, 8382.832343353099, 8620.827583448296, 8656.653733850575, 8784.78038049054, 8909.574468085206, 528.4345750047412, 603.6944118680428, 614.7633161232868, 621.5369549685513, 629.1475051305507, 3780.5513510831215, 3990.4812374391145, 4041.7170798043853, 4053.574374690674, 4162.8334866605, 85911.0, 86306.56846715727, 97752.0, 98613.0, 98659.01340986499, 70436.64781676058, 71661.2833871665, 72101.2789872105, 72261.0, 78258.0, 66392.66803665951, 66515.66742166354, 66908.0, 67273.32726672769, 69303.65348173227, 8151.1441298662785, 8917.152870477294, 9016.504092274989, 9017.864732028893, 9088.318376121684, 45015.32477012913, 45657.858553536425, 46549.53450465452, 46858.0, 52445.7377713109, 66722.66638666776, 68059.0, 68791.0, 69607.65196173987, 70061.64969175121, 7917.247861453153, 7937.523748575104, 7955.5624440654, 7962.32320252785, 7988.960055199686, 11984.101192410779, 12094.298897007737, 12174.14780965324, 12305.323207223331, 12489.875101249234, 11336.716582085513, 11414.714632134263, 11474.94262528682, 11507.539698411969, 11527.711807204887, 11473.36896470702, 11574.768504630028, 11575.305481671126, 11576.94211528937, 11592.594259200887, 11346.94326528362, 11412.885871141183, 11418.714532136431, 11437.370944598128, 11456.0, 8111.337947517848, 9728.319017668695, 9786.706398807763, 9859.901400986186, 10100.393976361442, 9517.857232141385, 9739.123478886933, 9802.65690700822, 9821.066998635119, 9859.704208873745], "unit": "ms", "name": "yui.html", "value": 6608.5053059756565}], "name": "dromaeo_css", "value": 7211.3119393453535}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 104.0, 111.0, 109.0, 110.0, 102.0, 111.0, 103.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 107.7}, {"lowerIsBetter": true, "replicates": [151.0, 121.0, 141.0, 158.0, 127.0, 167.0, 167.0, 116.0, 156.0, 117.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.1}, {"lowerIsBetter": true, "replicates": [186.0, 188.0, 178.0, 183.0, 183.0, 188.0, 187.0, 187.0, 181.0, 184.0], "unit": "ms", "name": "audio-dft", "value": 184.5}, {"lowerIsBetter": true, "replicates": [63.0, 70.0, 76.0, 72.0, 56.0, 56.0, 59.0, 63.0, 89.0, 79.0], "unit": "ms", "name": "audio-fft", "value": 68.3}, {"lowerIsBetter": true, "replicates": [92.0, 97.0, 134.0, 65.0, 107.0, 110.0, 142.0, 111.0, 87.0, 112.0], "unit": "ms", "name": "audio-oscillator", "value": 105.7}, {"lowerIsBetter": true, "replicates": [86.0, 86.0, 87.0, 87.0, 86.0, 87.0, 85.0, 89.0, 86.0, 87.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 86.6}, {"lowerIsBetter": true, "replicates": [100.0, 145.0, 94.0, 93.0, 94.0, 93.0, 93.0, 97.0, 94.0, 89.0], "unit": "ms", "name": "imaging-darkroom", "value": 99.2}, {"lowerIsBetter": true, "replicates": [87.0, 86.0, 83.0, 92.0, 71.0, 127.0, 138.0, 138.0, 84.0, 83.0], "unit": "ms", "name": "imaging-desaturate", "value": 98.9}, {"lowerIsBetter": true, "replicates": [58.0, 57.0, 56.0, 56.0, 56.0, 57.0, 55.0, 65.0, 53.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.7}, {"lowerIsBetter": true, "replicates": [62.0, 64.0, 63.0, 63.0, 64.0, 67.0, 65.0, 66.0, 68.0, 67.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.9}, {"lowerIsBetter": true, "replicates": [82.0, 82.0, 97.0, 105.0, 81.0, 82.0, 84.0, 83.0, 83.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.3}, {"lowerIsBetter": true, "replicates": [130.0, 115.0, 124.0, 122.0, 122.0, 126.0, 102.0, 123.0, 145.0, 125.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 123.4}, {"lowerIsBetter": true, "replicates": [135.0, 124.0, 135.0, 136.0, 135.0, 127.0, 137.0, 135.0, 136.0, 124.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 132.4}, {"lowerIsBetter": true, "replicates": [65.0, 65.0, 65.0, 64.0, 65.0, 65.0, 63.0, 67.0, 64.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.9}], "name": "kraken", "value": 1421.6000000000004}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [148.76524843796489], "unit": "score", "name": "Richards", "value": 237.300044}, {"lowerIsBetter": false, "replicates": [191.9754271453254], "unit": "score", "name": "DeltaBlue", "value": 344.4086619999998}, {"lowerIsBetter": false, "replicates": [283.04557033682426], "unit": "score", "name": "Encrypt", "value": 940.4174729999997}, {"lowerIsBetter": false, "replicates": [4911.764705882353], "unit": "score", "name": "Decrypt", "value": 54.192538922155705}, {"lowerIsBetter": false, "replicates": [1754.3859649122808], "unit": "score", "name": "RayTrace", "value": 421.7937299999998}, {"lowerIsBetter": false, "replicates": [867.3026886383348], "unit": "score", "name": "Earley", "value": 768.4318390000002}, {"lowerIsBetter": false, "replicates": [12537.5], "unit": "score", "name": "Boyer", "value": 53.15756729810566}, {"lowerIsBetter": false, "replicates": [29142.85714285714], "unit": "score", "name": "RegExp", "value": 31.25928921568629}, {"lowerIsBetter": false, "replicates": [647.5145255003227], "unit": "score", "name": "Splay", "value": 125.85200299102691}, {"lowerIsBetter": false, "replicates": [4509.009009009009], "unit": "score", "name": "NavierStokes", "value": 329.11888111888123}], "name": "v8_7", "value": 19888.0317281776}]} 17:58:10 INFO - RETURN: dromaeo_css: 7211.31 17:58:10 INFO - RETURN: kraken: 1421.60 17:58:10 INFO - RETURN: v8_7: 19888.03 17:58:10 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,59]]", "result": "19888.03"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1421.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7211.31"}}} 17:58:10 INFO - Return code: 0 17:58:10 INFO - # TBPL SUCCESS # 17:58:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:58:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:58:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:58:10 INFO - 2015-11-18 17:58:10.347 screenresolution[1971:14376] starting screenresolution argv=screenresolution get 17:58:10 INFO - 2015-11-18 17:58:10.349 screenresolution[1971:14376] Display 0: 1600x1200x32@60 17:58:10 INFO - 2015-11-18 17:58:10.356 screenresolution[1972:14379] starting screenresolution argv=screenresolution list 17:58:10 INFO - Available Modes on Display 0 17:58:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:58:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:58:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:58:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:58:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:58:10 INFO - Intel Iris: 17:58:10 INFO - Chipset Model: Intel Iris 17:58:10 INFO - Type: GPU 17:58:10 INFO - Bus: Built-In 17:58:10 INFO - VRAM (Dynamic, Max): 1536 MB 17:58:10 INFO - Vendor: Intel (0x8086) 17:58:10 INFO - Device ID: 0x0a2e 17:58:10 INFO - Revision ID: 0x0009 17:58:10 INFO - Displays: 17:58:10 INFO - Display: 17:58:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:58:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:58:10 INFO - Display Serial Number: 1600x1200 60 17:58:10 INFO - Main Display: Yes 17:58:10 INFO - Mirror: Off 17:58:10 INFO - Online: Yes 17:58:10 INFO - Rotation: Supported 17:58:10 INFO - Return code: 0 17:58:10 INFO - Running post-action listener: _resource_record_post_action 17:58:10 INFO - Running post-run listener: _resource_record_post_run 17:58:11 INFO - Total resource usage - Wall time: 1236s; CPU: 53.0%; Read bytes: 696320; Write bytes: 684827136; Read time: 11; Write time: 2052 17:58:11 INFO - install - Wall time: 30s; CPU: 54.0%; Read bytes: 341338624; Write bytes: 335484416; Read time: 26267; Write time: 612 17:58:11 INFO - run-tests - Wall time: 1207s; CPU: 53.0%; Read bytes: 634880; Write bytes: 338725888; Read time: 9; Write time: 1402 17:58:11 INFO - Running post-run listener: _upload_blobber_files 17:58:11 INFO - Blob upload gear active. 17:58:11 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 17:58:11 INFO - Copying logs to upload dir... 17:58:11 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1277.366219 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2015-11-18 17:58:11.714370) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-18 17:58:11.718312) ========= 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.StM05ynPSB/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.dTwNFQQAuY/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009573 build_url: 'https://queue.taskcluster.net/v1/task/YVIpQFebT4uW0P4Up-x0jQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg' ========= master_lag: 0.01 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-18 17:58:11.742615) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 17:58:11.743003) ========= 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.StM05ynPSB/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.dTwNFQQAuY/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.005106 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 17:58:11.793559) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 17:58:11.794091) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 17:58:11.794950) ========= ========= Total master_lag: 0.22 =========