builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0054 starttime: 1448365731.37 results: success (0) buildid: 20151124025931 builduid: 87e7b14e097e41148c7a7b6a2320d83a revision: 45273bbed8efaface6f5ec56d984cb9faf4fbb6a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.367508) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.367948) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.368231) ========= 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.ybRguSRYwk/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.1bvyU3dc4i/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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.005372 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.396161) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.396683) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.414063) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.414443) ========= 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.ybRguSRYwk/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.1bvyU3dc4i/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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.006349 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.465961) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.466372) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.466766) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.467035) ========= 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.ybRguSRYwk/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.1bvyU3dc4i/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2015-11-24 03:48:51-- 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.62G=0s 2015-11-24 03:48:51 (1.62 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.232299 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.716285) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.719684) ========= 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.ybRguSRYwk/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.1bvyU3dc4i/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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.025938 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:51.768523) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2015-11-24 03:48:51.768893) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 45273bbed8efaface6f5ec56d984cb9faf4fbb6a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 45273bbed8efaface6f5ec56d984cb9faf4fbb6a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ybRguSRYwk/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.1bvyU3dc4i/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2015-11-24 03:48:51,853 truncating revision to first 12 chars 2015-11-24 03:48:51,854 Setting DEBUG logging. 2015-11-24 03:48:51,854 attempt 1/10 2015-11-24 03:48:51,854 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/45273bbed8ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 03:48:52,133 unpacking tar archive at: mozilla-central-45273bbed8ef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.517093 ========= master_lag: 2.54 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2015-11-24 03:48:54.830802) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:54.831184) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:54.930275) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:54.930618) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 03:48:54.931000) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2015-11-24 03:48:54.931301) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ybRguSRYwk/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.1bvyU3dc4i/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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 03:48:55 INFO - MultiFileLogger online at 20151124 03:48:55 in /builds/slave/test 03:48:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:48:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:48:55 INFO - {'append_to_log': False, 03:48:55 INFO - 'base_work_dir': '/builds/slave/test', 03:48:55 INFO - 'blob_upload_branch': 'Firefox', 03:48:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:48:55 INFO - 'branch': 'Firefox', 03:48:55 INFO - 'buildbot_json_path': 'buildprops.json', 03:48:55 INFO - 'config_files': ('talos/mac_config.py',), 03:48:55 INFO - 'default_actions': ('clobber', 03:48:55 INFO - 'read-buildbot-config', 03:48:55 INFO - 'download-and-extract', 03:48:55 INFO - 'populate-webroot', 03:48:55 INFO - 'create-virtualenv', 03:48:55 INFO - 'install', 03:48:55 INFO - 'run-tests'), 03:48:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:48:55 INFO - 'download_minidump_stackwalk': True, 03:48:55 INFO - 'download_symbols': 'ondemand', 03:48:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:48:55 INFO - 'tooltool.py': '/tools/tooltool.py', 03:48:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:48:55 INFO - '/tools/misc-python/virtualenv.py')}, 03:48:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:48:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:48:55 INFO - 'installer_path': 'installer.exe', 03:48:55 INFO - 'log_level': 'info', 03:48:55 INFO - 'log_name': 'talos', 03:48:55 INFO - 'log_to_console': True, 03:48:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:48:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:48:55 INFO - 'opt_config_files': (), 03:48:55 INFO - 'pip_index': False, 03:48:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:48:55 INFO - 'cmd': ('bash', 03:48:55 INFO - '-c', 03:48:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:48:55 INFO - 'enabled': True, 03:48:55 INFO - 'halt_on_failure': False, 03:48:55 INFO - 'name': 'check_screen_resolution'},), 03:48:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:48:55 INFO - 'cmd': ('bash', 03:48:55 INFO - '-c', 03:48:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:48:55 INFO - 'enabled': True, 03:48:55 INFO - 'halt_on_failure': False, 03:48:55 INFO - 'name': 'check_screen_resolution'},), 03:48:55 INFO - 'run_cmd_checks_enabled': True, 03:48:55 INFO - 'sps_profile': False, 03:48:55 INFO - 'sps_profile_interval': 0, 03:48:55 INFO - 'suite': 'dromaeojs', 03:48:55 INFO - 'system_bits': '64', 03:48:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:48:55 INFO - 'title': 't-yosemite-r7-0054', 03:48:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:48:55 INFO - 'use_talos_json': True, 03:48:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:48:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:48:55 INFO - 'work_dir': 'build'} 03:48:55 INFO - ##### 03:48:55 INFO - ##### Running clobber step. 03:48:55 INFO - ##### 03:48:55 INFO - Running pre-action listener: _resource_record_pre_action 03:48:55 INFO - Running main action method: clobber 03:48:55 INFO - rmtree: /builds/slave/test/build 03:48:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:48:56 INFO - Running post-action listener: _resource_record_post_action 03:48:56 INFO - ##### 03:48:56 INFO - ##### Running read-buildbot-config step. 03:48:56 INFO - ##### 03:48:56 INFO - Running pre-action listener: _resource_record_pre_action 03:48:56 INFO - Running main action method: read_buildbot_config 03:48:56 INFO - Using buildbot properties: 03:48:56 INFO - { 03:48:56 INFO - "properties": { 03:48:56 INFO - "buildnumber": 5, 03:48:56 INFO - "product": "firefox", 03:48:56 INFO - "script_repo_revision": "production", 03:48:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 03:48:56 INFO - "repository": "", 03:48:56 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 03:48:56 INFO - "buildid": "20151124025931", 03:48:56 INFO - "slavename": "t-yosemite-r7-0054", 03:48:56 INFO - "pgo_build": "False", 03:48:56 INFO - "basedir": "/builds/slave/test", 03:48:56 INFO - "project": "", 03:48:56 INFO - "platform": "yosemite_r7", 03:48:56 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:48:56 INFO - "slavebuilddir": "test", 03:48:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:48:56 INFO - "branch": "mozilla-central", 03:48:56 INFO - "repo_path": "mozilla-central", 03:48:56 INFO - "stage_platform": "macosx64", 03:48:56 INFO - "builduid": "87e7b14e097e41148c7a7b6a2320d83a", 03:48:56 INFO - "revision": "45273bbed8efaface6f5ec56d984cb9faf4fbb6a" 03:48:56 INFO - }, 03:48:56 INFO - "sourcestamp": { 03:48:56 INFO - "repository": "", 03:48:56 INFO - "hasPatch": false, 03:48:56 INFO - "project": "", 03:48:56 INFO - "branch": "mozilla-central-macosx64-talos", 03:48:56 INFO - "changes": [ 03:48:56 INFO - { 03:48:56 INFO - "category": null, 03:48:56 INFO - "files": [ 03:48:56 INFO - { 03:48:56 INFO - "url": null, 03:48:56 INFO - "name": "https://queue.taskcluster.net/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 03:48:56 INFO - } 03:48:56 INFO - ], 03:48:56 INFO - "repository": "", 03:48:56 INFO - "rev": "45273bbed8efaface6f5ec56d984cb9faf4fbb6a", 03:48:56 INFO - "who": "cbook@mozilla.com", 03:48:56 INFO - "when": 1448365476, 03:48:56 INFO - "number": 6699760, 03:48:56 INFO - "comments": "merge fx-team to mozilla-central a=merge", 03:48:56 INFO - "project": "", 03:48:56 INFO - "at": "Tue 24 Nov 2015 03:44:36", 03:48:56 INFO - "branch": "mozilla-central-macosx64-talos", 03:48:56 INFO - "revlink": "", 03:48:56 INFO - "properties": [ 03:48:56 INFO - [ 03:48:56 INFO - "buildid", 03:48:56 INFO - "20151124025931", 03:48:56 INFO - "Change" 03:48:56 INFO - ], 03:48:56 INFO - [ 03:48:56 INFO - "builduid", 03:48:56 INFO - "87e7b14e097e41148c7a7b6a2320d83a", 03:48:56 INFO - "Change" 03:48:56 INFO - ], 03:48:56 INFO - [ 03:48:56 INFO - "pgo_build", 03:48:56 INFO - "False", 03:48:56 INFO - "Change" 03:48:56 INFO - ] 03:48:56 INFO - ], 03:48:56 INFO - "revision": "45273bbed8efaface6f5ec56d984cb9faf4fbb6a" 03:48:56 INFO - } 03:48:56 INFO - ], 03:48:56 INFO - "revision": "45273bbed8efaface6f5ec56d984cb9faf4fbb6a" 03:48:56 INFO - } 03:48:56 INFO - } 03:48:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 03:48:56 INFO - Running post-action listener: _resource_record_post_action 03:48:56 INFO - ##### 03:48:56 INFO - ##### Running download-and-extract step. 03:48:56 INFO - ##### 03:48:56 INFO - Running pre-action listener: _resource_record_pre_action 03:48:56 INFO - Running main action method: download_and_extract 03:48:56 INFO - mkdir: /builds/slave/test/build/tests 03:48: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')]} 03:48:56 INFO - https://queue.taskcluster.net/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 03:48:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/test_packages.json 03:48:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/test_packages.json 03:48:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 03:48:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 03:48:57 INFO - Downloaded 1149 bytes. 03:48:57 INFO - Reading from file /builds/slave/test/build/test_packages.json 03:48:57 INFO - Using the following test package requirements: 03:48:57 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 03:48:57 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 03:48:57 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 03:48:57 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 03:48:57 INFO - u'jsshell-mac.zip'], 03:48:57 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 03:48:57 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 03:48:57 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 03:48:57 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 03:48:57 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 03:48:57 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 03:48:57 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 03:48:57 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 03:48:57 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 03:48:57 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 03:48:57 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 03:48:57 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 03:48:57 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:48:57 INFO - https://queue.taskcluster.net/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:48:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 03:48:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 03:48:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/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 03:48:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/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 03:48:58 INFO - Downloaded 17526888 bytes. 03:48:58 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 03:48:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 03:48:58 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 03:49:01 INFO - Return code: 0 03:49:01 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 03:49:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:49:01 INFO - https://queue.taskcluster.net/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:49:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 03:49:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 03:49:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/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 03:49:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/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 03:49:02 INFO - Downloaded 17526888 bytes. 03:49:02 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 03:49:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 03:49:02 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 03:49:06 INFO - Return code: 0 03:49: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')]} 03:49:06 INFO - https://queue.taskcluster.net/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:49:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 03:49:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 03:49:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/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 03:49:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/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 03:49:07 INFO - Downloaded 11130997 bytes. 03:49:07 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 03:49:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 03:49:07 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 03:49:07 INFO - Return code: 0 03:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:49:07 INFO - https://queue.taskcluster.net/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 03:49:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 03:49:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 03:49:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg 03:49:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/pViEQOlrTvWfHXCWcO66Fw/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 03:49:10 INFO - Downloaded 109915748 bytes. 03:49:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 03:49:10 INFO - mkdir: /builds/slave/test/properties 03:49:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:49:10 INFO - Writing to file /builds/slave/test/properties/build_url 03:49:10 INFO - Contents: 03:49:10 INFO - build_url:https://queue.taskcluster.net/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 03:49:10 INFO - Running post-action listener: _resource_record_post_action 03:49:10 INFO - Running post-action listener: set_extra_try_arguments 03:49:10 INFO - ##### 03:49:10 INFO - ##### Running populate-webroot step. 03:49:10 INFO - ##### 03:49:10 INFO - Running pre-action listener: _resource_record_pre_action 03:49:10 INFO - Running main action method: populate_webroot 03:49:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:49:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:49:10 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 03:49:10 INFO - '../fennec_ids.txt'], 03:49:10 INFO - 'tests': ['tcheck2']}, 03:49:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:49:10 INFO - '--tppagecycles', 03:49:10 INFO - '7'], 03:49:10 INFO - 'tests': ['tsvgm']}}, 03:49:10 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:49:10 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:49:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:49:10 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:49:10 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:49:10 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:49:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:49:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:10 INFO - 'talos_options': ['--e10s'], 03:49:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:49:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:10 INFO - 'tests': ['damp', 'tps']}, 03:49:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:10 INFO - 'talos_options': ['--e10s'], 03:49:10 INFO - 'tests': ['damp', 'tps']}, 03:49:10 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:49:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:49:10 INFO - 'other': {'tests': ['a11yr', 03:49:10 INFO - 'ts_paint', 03:49:10 INFO - 'tpaint', 03:49:10 INFO - 'sessionrestore', 03:49:10 INFO - 'sessionrestore_no_auto_restore']}, 03:49:10 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:49:10 INFO - 'tests': ['a11yr', 03:49:10 INFO - 'ts_paint', 03:49:10 INFO - 'tpaint', 03:49:10 INFO - 'sessionrestore', 03:49:10 INFO - 'sessionrestore_no_auto_restore']}, 03:49:10 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:49:10 INFO - 'tests': ['a11yr', 03:49:10 INFO - 'ts_paint', 03:49:10 INFO - 'tpaint', 03:49:10 INFO - 'sessionrestore', 03:49:10 INFO - 'sessionrestore_no_auto_restore']}, 03:49:10 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:49:10 INFO - 'tests': ['a11yr', 03:49:10 INFO - 'ts_paint', 03:49:10 INFO - 'tpaint', 03:49:10 INFO - 'sessionrestore', 03:49:10 INFO - 'sessionrestore_no_auto_restore']}, 03:49:10 INFO - 'other_l64': {'tests': ['a11yr', 03:49:10 INFO - 'ts_paint', 03:49:10 INFO - 'tpaint', 03:49:10 INFO - 'sessionrestore', 03:49:10 INFO - 'sessionrestore_no_auto_restore']}, 03:49:10 INFO - 'other_nol64': {'tests': ['a11yr', 03:49:10 INFO - 'ts_paint', 03:49:10 INFO - 'tpaint', 03:49:10 INFO - 'sessionrestore', 03:49:10 INFO - 'sessionrestore_no_auto_restore']}, 03:49:10 INFO - 'svgr': {'tests': ['tsvgx', 03:49:10 INFO - 'tsvgr_opacity', 03:49:10 INFO - 'tart', 03:49:10 INFO - 'tscrollx', 03:49:10 INFO - 'cart']}, 03:49:10 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:49:10 INFO - 'tests': ['tsvgx', 03:49:10 INFO - 'tsvgr_opacity', 03:49:10 INFO - 'tart', 03:49:10 INFO - 'tscrollx', 03:49:10 INFO - 'cart']}, 03:49:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:10 INFO - 'tests': ['tp5o']}, 03:49:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:10 INFO - 'talos_options': ['--e10s'], 03:49:10 INFO - 'tests': ['tp5o']}, 03:49:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:10 INFO - 'talos_options': ['--xperf_path', 03:49:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:49:10 INFO - 'tests': ['tp5n']}, 03:49:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:10 INFO - 'talos_options': ['--e10s', 03:49:10 INFO - '--xperf_path', 03:49:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:49:10 INFO - 'tests': ['tp5n']}}, 03:49:10 INFO - 'talos.zip': {'path': '', 03:49:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:49:10 INFO - Running post-action listener: _resource_record_post_action 03:49:10 INFO - ##### 03:49:10 INFO - ##### Running create-virtualenv step. 03:49:10 INFO - ##### 03:49:10 INFO - Running pre-action listener: _resource_record_pre_action 03:49:10 INFO - Running main action method: create_virtualenv 03:49:10 INFO - Creating virtualenv /builds/slave/test/build/venv 03:49:10 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 03:49:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:49:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:49:10 INFO - Using real prefix '/tools/python27' 03:49:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:49:11 INFO - Installing distribute.............................................................................................................................................................................................done. 03:49:14 INFO - Installing pip.................done. 03:49:14 INFO - Return code: 0 03:49:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:49:14 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')]} 03:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:14 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')]} 03:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:14 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 0x104d22490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cff228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd759daa800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c469f0>, '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 0x1049a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c398c8>, '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.ybRguSRYwk/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.1bvyU3dc4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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 03:49:14 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 03:49:14 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 03:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ybRguSRYwk/Render', 03:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:14 INFO - 'HOME': '/Users/cltbld', 03:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:14 INFO - 'LOGNAME': 'cltbld', 03:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:14 INFO - 'MOZ_NO_REMOTE': '1', 03:49:14 INFO - 'NO_EM_RESTART': '1', 03:49:14 INFO - 'PAGER': '/bin/cat', 03:49:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:14 INFO - 'PWD': '/builds/slave/test', 03:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:14 INFO - 'SHELL': '/bin/bash', 03:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1bvyU3dc4i/Listeners', 03:49:14 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 03:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:14 INFO - 'USER': 'cltbld', 03:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:14 INFO - 'XPC_FLAGS': '0x0', 03:49:14 INFO - 'XPC_SERVICE_NAME': '0', 03:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:14 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 03:49:14 INFO - Cleaning up... 03:49:14 INFO - Return code: 0 03:49:14 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:49:14 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')]} 03:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:14 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')]} 03:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:14 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 0x104d22490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cff228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd759daa800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c469f0>, '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 0x1049a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c398c8>, '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.ybRguSRYwk/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.1bvyU3dc4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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 03:49:14 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 03:49:14 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 03:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ybRguSRYwk/Render', 03:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:14 INFO - 'HOME': '/Users/cltbld', 03:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:14 INFO - 'LOGNAME': 'cltbld', 03:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:14 INFO - 'MOZ_NO_REMOTE': '1', 03:49:14 INFO - 'NO_EM_RESTART': '1', 03:49:14 INFO - 'PAGER': '/bin/cat', 03:49:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:14 INFO - 'PWD': '/builds/slave/test', 03:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:14 INFO - 'SHELL': '/bin/bash', 03:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1bvyU3dc4i/Listeners', 03:49:14 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 03:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:14 INFO - 'USER': 'cltbld', 03:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:14 INFO - 'XPC_FLAGS': '0x0', 03:49:14 INFO - 'XPC_SERVICE_NAME': '0', 03:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:14 INFO - Downloading/unpacking psutil>=0.7.1 03:49:14 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 03:49:14 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 03:49:14 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 03:49:14 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 03:49:14 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 03:49:14 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 03:49:17 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 03:49:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 03:49:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:49:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:49:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:49:17 INFO - Installing collected packages: psutil 03:49:17 INFO - Running setup.py install for psutil 03:49:17 INFO - building 'psutil._psutil_osx' extension 03:49:17 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 03:49:17 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 03:49:18 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 03:49:18 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 03:49:18 INFO - building 'psutil._psutil_posix' extension 03:49:18 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 03:49:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:49:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:49:18 INFO - ^ 03:49:18 INFO - 1 warning generated. 03:49:18 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 03:49:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:49:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:49:18 INFO - Successfully installed psutil 03:49:18 INFO - Cleaning up... 03:49:18 INFO - Return code: 0 03:49:18 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:18 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 0x104d22490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cff228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd759daa800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c469f0>, '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 0x1049a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c398c8>, '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.ybRguSRYwk/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.1bvyU3dc4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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 03:49:18 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 03:49:18 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 03:49:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ybRguSRYwk/Render', 03:49:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:18 INFO - 'HOME': '/Users/cltbld', 03:49:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:18 INFO - 'LOGNAME': 'cltbld', 03:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:18 INFO - 'MOZ_NO_REMOTE': '1', 03:49:18 INFO - 'NO_EM_RESTART': '1', 03:49:18 INFO - 'PAGER': '/bin/cat', 03:49:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:18 INFO - 'PWD': '/builds/slave/test', 03:49:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:18 INFO - 'SHELL': '/bin/bash', 03:49:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1bvyU3dc4i/Listeners', 03:49:18 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 03:49:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:18 INFO - 'USER': 'cltbld', 03:49:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:18 INFO - 'XPC_FLAGS': '0x0', 03:49:18 INFO - 'XPC_SERVICE_NAME': '0', 03:49:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:18 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:49:18 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 03:49:18 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 03:49:18 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 03:49:18 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 03:49:18 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 03:49:18 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 03:49:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:49:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 03:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:49:21 INFO - Installing collected packages: mozsystemmonitor 03:49:21 INFO - Running setup.py install for mozsystemmonitor 03:49:21 INFO - Successfully installed mozsystemmonitor 03:49:21 INFO - Cleaning up... 03:49:21 INFO - Return code: 0 03:49:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:49:21 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')]} 03:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:21 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')]} 03:49:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:21 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 0x104d22490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cff228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd759daa800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c469f0>, '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 0x1049a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c398c8>, '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.ybRguSRYwk/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.1bvyU3dc4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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 03:49:21 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 03:49:21 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 03:49:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ybRguSRYwk/Render', 03:49:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:21 INFO - 'HOME': '/Users/cltbld', 03:49:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:21 INFO - 'LOGNAME': 'cltbld', 03:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:21 INFO - 'MOZ_NO_REMOTE': '1', 03:49:21 INFO - 'NO_EM_RESTART': '1', 03:49:21 INFO - 'PAGER': '/bin/cat', 03:49:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:21 INFO - 'PWD': '/builds/slave/test', 03:49:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:21 INFO - 'SHELL': '/bin/bash', 03:49:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1bvyU3dc4i/Listeners', 03:49:21 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 03:49:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:21 INFO - 'USER': 'cltbld', 03:49:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:21 INFO - 'XPC_FLAGS': '0x0', 03:49:21 INFO - 'XPC_SERVICE_NAME': '0', 03:49:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:21 INFO - Downloading/unpacking blobuploader==1.2.4 03:49:21 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 03:49:21 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 03:49:21 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 03:49:21 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 03:49:21 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 03:49:21 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 03:49:23 INFO - Downloading blobuploader-1.2.4.tar.gz 03:49:23 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 03:49:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:49:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:49:23 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 03:49:23 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 03:49:23 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 03:49:23 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 03:49:23 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 03:49:23 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 03:49:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 03:49:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:49:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:49:24 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 03:49:24 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 03:49:24 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 03:49:24 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 03:49:24 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 03:49:24 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 03:49:24 INFO - Downloading docopt-0.6.1.tar.gz 03:49:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 03:49:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:49:24 INFO - Installing collected packages: blobuploader, requests, docopt 03:49:24 INFO - Running setup.py install for blobuploader 03:49:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:49:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:49:25 INFO - Running setup.py install for requests 03:49:25 INFO - Running setup.py install for docopt 03:49:25 INFO - Successfully installed blobuploader requests docopt 03:49:25 INFO - Cleaning up... 03:49:25 INFO - Return code: 0 03:49:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:49:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:25 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 0x104d22490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cff228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd759daa800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c469f0>, '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 0x1049a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c398c8>, '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.ybRguSRYwk/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.1bvyU3dc4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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 03:49:25 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 03:49:25 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 03:49:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ybRguSRYwk/Render', 03:49:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:25 INFO - 'HOME': '/Users/cltbld', 03:49:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:25 INFO - 'LOGNAME': 'cltbld', 03:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:25 INFO - 'MOZ_NO_REMOTE': '1', 03:49:25 INFO - 'NO_EM_RESTART': '1', 03:49:25 INFO - 'PAGER': '/bin/cat', 03:49:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:25 INFO - 'PWD': '/builds/slave/test', 03:49:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:25 INFO - 'SHELL': '/bin/bash', 03:49:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1bvyU3dc4i/Listeners', 03:49:25 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 03:49:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:25 INFO - 'USER': 'cltbld', 03:49:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:25 INFO - 'XPC_FLAGS': '0x0', 03:49:25 INFO - 'XPC_SERVICE_NAME': '0', 03:49:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:49:26 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-9RXWNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:49:26 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-haV_Kz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:49:26 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-OrpHHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:49:26 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-aBCqLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:49:26 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-c0UNKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:49:26 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-rRXvfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:49:26 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-S1Mpor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:49:26 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-8cNLFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:49:26 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-INTFNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:49:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:49:26 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-aAnpDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:49:27 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-2gFf1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:49:27 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-rMJbwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:49:27 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-TTSyN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:49:27 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-LOuYqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:49:27 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-Advt4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:49:27 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-PqJf69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:49:27 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-s0xFPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:49:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:49:27 INFO - Running setup.py install for manifestparser 03:49:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:49:27 INFO - Running setup.py install for mozcrash 03:49:28 INFO - Running setup.py install for mozdebug 03:49:28 INFO - Running setup.py install for mozdevice 03:49:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:49:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:49:28 INFO - Running setup.py install for mozfile 03:49:28 INFO - Running setup.py install for mozhttpd 03:49:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:49:28 INFO - Running setup.py install for mozinfo 03:49:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:49:28 INFO - Running setup.py install for mozInstall 03:49:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:49:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:49:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:49:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:49:28 INFO - Running setup.py install for mozleak 03:49:29 INFO - Running setup.py install for mozlog 03:49:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:49:29 INFO - Running setup.py install for moznetwork 03:49:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:49:29 INFO - Running setup.py install for mozprocess 03:49:29 INFO - Running setup.py install for mozprofile 03:49:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:49:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:49:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:49:29 INFO - Running setup.py install for mozrunner 03:49:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:49:29 INFO - Running setup.py install for mozscreenshot 03:49:29 INFO - Running setup.py install for moztest 03:49:30 INFO - Running setup.py install for mozversion 03:49:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:49:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:49:30 INFO - Cleaning up... 03:49:30 INFO - Return code: 0 03:49:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-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 0x104d22490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cff228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd759daa800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c469f0>, '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 0x1049a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c398c8>, '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.ybRguSRYwk/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.1bvyU3dc4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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 03:49:30 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 03:49:30 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 03:49:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ybRguSRYwk/Render', 03:49:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:30 INFO - 'HOME': '/Users/cltbld', 03:49:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:30 INFO - 'LOGNAME': 'cltbld', 03:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:30 INFO - 'MOZ_NO_REMOTE': '1', 03:49:30 INFO - 'NO_EM_RESTART': '1', 03:49:30 INFO - 'PAGER': '/bin/cat', 03:49:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:30 INFO - 'PWD': '/builds/slave/test', 03:49:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:30 INFO - 'SHELL': '/bin/bash', 03:49:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1bvyU3dc4i/Listeners', 03:49:30 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 03:49:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:30 INFO - 'USER': 'cltbld', 03:49:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:30 INFO - 'XPC_FLAGS': '0x0', 03:49:30 INFO - 'XPC_SERVICE_NAME': '0', 03:49:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:49:30 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-yvSkqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:49:30 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)) 03:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:49:30 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-eGKBpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:49:30 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)) 03:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:49:30 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-jrqGv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:49:30 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)) 03:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:49:30 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-Mx9LJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:49:31 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)) 03:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:49:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-qmzgOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:49:31 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)) 03:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:49:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-hxnx4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:49:31 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)) 03:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:49:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-lihEzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:49:31 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)) 03:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:49:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-yG_lbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:49:31 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)) 03:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:49:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-SVy0q9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:49:31 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)) 03:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:49:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-aCo4Xr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:49:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:49:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-KjnUex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:49:31 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)) 03:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:49:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-xZsdtn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:49:31 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)) 03:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:49:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-qkelPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:49:31 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)) 03:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:49:31 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-BvEyfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:49:32 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)) 03:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:49:32 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-321PzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:49:32 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)) 03:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:49:32 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-Nv7n1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:49:32 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)) 03:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:49:32 INFO - Running setup.py (path:/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/pip-8WK1o1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:49:32 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)) 03:49:32 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)) 03:49:32 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)) 03:49:32 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)) 03:49:32 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)) 03:49:32 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)) 03:49:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:34 INFO - Downloading blessings-1.5.1.tar.gz 03:49:34 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 03:49:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:49:34 INFO - Installing collected packages: blessings 03:49:34 INFO - Running setup.py install for blessings 03:49:34 INFO - Successfully installed blessings 03:49:34 INFO - Cleaning up... 03:49:34 INFO - Return code: 0 03:49:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:49:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:49:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:49:35 INFO - Reading from file tmpfile_stdout 03:49:35 INFO - Current package versions: 03:49:35 INFO - blessings == 1.5.1 03:49:35 INFO - blobuploader == 1.2.4 03:49:35 INFO - docopt == 0.6.1 03:49:35 INFO - manifestparser == 1.1 03:49:35 INFO - mozInstall == 1.12 03:49:35 INFO - mozcrash == 0.16 03:49:35 INFO - mozdebug == 0.1 03:49:35 INFO - mozdevice == 0.47 03:49:35 INFO - mozfile == 1.2 03:49:35 INFO - mozhttpd == 0.7 03:49:35 INFO - mozinfo == 0.9 03:49:35 INFO - mozleak == 0.1 03:49:35 INFO - mozlog == 3.1 03:49:35 INFO - moznetwork == 0.27 03:49:35 INFO - mozprocess == 0.22 03:49:35 INFO - mozprofile == 0.27 03:49:35 INFO - mozrunner == 6.11 03:49:35 INFO - mozscreenshot == 0.1 03:49:35 INFO - mozsystemmonitor == 0.0 03:49:35 INFO - moztest == 0.7 03:49:35 INFO - mozversion == 1.4 03:49:35 INFO - psutil == 3.1.1 03:49:35 INFO - requests == 1.2.3 03:49:35 INFO - wsgiref == 0.1.2 03:49:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:49:35 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')]} 03:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:35 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')]} 03:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:35 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 0x104d22490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cff228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd759daa800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c469f0>, '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 0x1049a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c398c8>, '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.ybRguSRYwk/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.1bvyU3dc4i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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 03:49:35 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 03:49:35 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 03:49:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ybRguSRYwk/Render', 03:49:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:35 INFO - 'HOME': '/Users/cltbld', 03:49:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:35 INFO - 'LOGNAME': 'cltbld', 03:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:35 INFO - 'MOZ_NO_REMOTE': '1', 03:49:35 INFO - 'NO_EM_RESTART': '1', 03:49:35 INFO - 'PAGER': '/bin/cat', 03:49:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:35 INFO - 'PWD': '/builds/slave/test', 03:49:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:35 INFO - 'SHELL': '/bin/bash', 03:49:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1bvyU3dc4i/Listeners', 03:49:35 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 03:49:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:35 INFO - 'USER': 'cltbld', 03:49:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:35 INFO - 'XPC_FLAGS': '0x0', 03:49:35 INFO - 'XPC_SERVICE_NAME': '0', 03:49:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:35 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)) 03:49:35 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)) 03:49:35 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)) 03:49:35 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)) 03:49:35 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)) 03:49:35 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)) 03:49:35 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)) 03:49:35 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)) 03:49:35 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)) 03:49:35 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)) 03:49:35 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)) 03:49:35 INFO - Cleaning up... 03:49:35 INFO - Return code: 0 03:49:35 INFO - Running post-action listener: _resource_record_post_action 03:49:35 INFO - Running post-action listener: _start_resource_monitoring 03:49:35 INFO - Starting resource monitoring. 03:49:35 INFO - ##### 03:49:35 INFO - ##### Running install step. 03:49:35 INFO - ##### 03:49:35 INFO - Running pre-action listener: _resource_record_pre_action 03:49:35 INFO - Running main action method: install 03:49:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:49:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:49:35 INFO - Reading from file tmpfile_stdout 03:49:35 INFO - Detecting whether we're running mozinstall >=1.0... 03:49:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:49:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:49:35 INFO - Reading from file tmpfile_stdout 03:49:35 INFO - Output received: 03:49:35 INFO - Usage: mozinstall [options] installer 03:49:35 INFO - Options: 03:49:35 INFO - -h, --help show this help message and exit 03:49:35 INFO - -d DEST, --destination=DEST 03:49:35 INFO - Directory to install application into. [default: 03:49:35 INFO - "/builds/slave/test"] 03:49:35 INFO - --app=APP Application being installed. [default: firefox] 03:49:35 INFO - mkdir: /builds/slave/test/build/application 03:49:35 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'] 03:49:35 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 03:50:06 INFO - Reading from file tmpfile_stdout 03:50:06 INFO - Output received: 03:50:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:50:06 INFO - Running post-action listener: _resource_record_post_action 03:50:06 INFO - ##### 03:50:06 INFO - ##### Running run-tests step. 03:50:06 INFO - ##### 03:50:06 INFO - Running pre-action listener: _resource_record_pre_action 03:50:06 INFO - Running main action method: run_tests 03:50:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:50:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:50:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:50:06 INFO - 2015-11-24 03:50:06.144 screenresolution[1643:7360] starting screenresolution argv=screenresolution get 03:50:06 INFO - 2015-11-24 03:50:06.147 screenresolution[1643:7360] Display 0: 1600x1200x32@60 03:50:06 INFO - 2015-11-24 03:50:06.152 screenresolution[1644:7362] starting screenresolution argv=screenresolution list 03:50:06 INFO - Available Modes on Display 0 03:50:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:50:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:50:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:50:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:50:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:50:06 INFO - Intel Iris: 03:50:06 INFO - Chipset Model: Intel Iris 03:50:06 INFO - Type: GPU 03:50:06 INFO - Bus: Built-In 03:50:06 INFO - VRAM (Dynamic, Max): 1536 MB 03:50:06 INFO - Vendor: Intel (0x8086) 03:50:06 INFO - Device ID: 0x0a2e 03:50:06 INFO - Revision ID: 0x0009 03:50:06 INFO - Displays: 03:50:06 INFO - Display: 03:50:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:50:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:50:06 INFO - Display Serial Number: 1600x1200 60 03:50:06 INFO - Main Display: Yes 03:50:06 INFO - Mirror: Off 03:50:06 INFO - Online: Yes 03:50:06 INFO - Rotation: Supported 03:50:06 INFO - Return code: 0 03:50:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:50:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:50:06 INFO - Python 2.7.3 03:50:06 INFO - Return code: 0 03:50:06 INFO - grabbing minidump binary from tooltool 03:50: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')]} 03:50:06 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 0x104c469f0>, '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 0x1049a5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c398c8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:50:06 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 03:50:06 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 03:50:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:50:06 INFO - Return code: 0 03:50:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:50:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:50:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:50:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:50:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:50:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0054', '--webServer', 'localhost'] in /builds/slave/test/build 03:50:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0054 --webServer localhost 03:50:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ybRguSRYwk/Render', 03:50:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:06 INFO - 'HOME': '/Users/cltbld', 03:50:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:06 INFO - 'LOGNAME': 'cltbld', 03:50:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:50:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:50:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:06 INFO - 'MOZ_NO_REMOTE': '1', 03:50:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:50:06 INFO - 'NO_EM_RESTART': '1', 03:50:06 INFO - 'PAGER': '/bin/cat', 03:50:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:06 INFO - 'PWD': '/builds/slave/test', 03:50:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:50:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:06 INFO - 'SHELL': '/bin/bash', 03:50:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1bvyU3dc4i/Listeners', 03:50:06 INFO - 'TMPDIR': '/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/', 03:50:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:06 INFO - 'USER': 'cltbld', 03:50:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:06 INFO - 'XPC_FLAGS': '0x0', 03:50:06 INFO - 'XPC_SERVICE_NAME': '0', 03:50:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0054', '--webServer', 'localhost'] with output_timeout 3600 03:50:06 INFO - mozversion INFO | application_buildid: 20151124025931 03:50:06 INFO - mozversion INFO | application_changeset: 45273bbed8efaface6f5ec56d984cb9faf4fbb6a 03:50:06 INFO - mozversion INFO | application_display_name: Nightly 03:50:06 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:50:06 INFO - mozversion INFO | application_name: Firefox 03:50:06 INFO - mozversion INFO | application_remotingname: firefox 03:50:06 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 03:50:06 INFO - mozversion INFO | application_vendor: Mozilla 03:50:06 INFO - mozversion INFO | application_version: 45.0a1 03:50:06 INFO - mozversion INFO | platform_buildid: 20151124025931 03:50:06 INFO - mozversion INFO | platform_changeset: 45273bbed8efaface6f5ec56d984cb9faf4fbb6a 03:50:06 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 03:50:06 INFO - mozversion INFO | platform_version: 45.0a1 03:50:06 INFO - 2015-11-24 03:50:06,684 DEBUG : using testdate: 1448365806 03:50:06 INFO - 2015-11-24 03:50:06,684 DEBUG : actual date: 1448365806 03:50:07 INFO - 2015-11-24 03:50:07,035 INFO : starting webserver on 'localhost:49264' 03:50:07 INFO - 2015-11-24 03:50:07,036 INFO : Starting test suite t-yosemite-r7-0054 03:50:07 INFO - 2015-11-24 03:50:07,036 INFO : Starting test dromaeo_css 03:50:07 INFO - 2015-11-24 03:50:07,036 DEBUG : operating with platform_type : mac_ 03:50:07 INFO - 2015-11-24 03:50:07,037 INFO : Initialising browser for dromaeo_css test... 03:50:07 INFO - 2015-11-24 03:50:07,049 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmpQ7hmPy/profile http://localhost:49264/getInfo.html 03:50:09 INFO - 2015-11-24 03:50:09,180 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:50:09 INFO - 2015-11-24 03:50:09,180 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:50:09 INFO - 2015-11-24 03:50:09,181 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 03:50:09 INFO - 2015-11-24 03:50:09,181 DEBUG : BROWSER_OUTPUT: __metrics 03:50:09 INFO - 2015-11-24 03:50:09,197 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:50:09 INFO - 2015-11-24 03:50:09,456 DEBUG : BROWSER_OUTPUT: console.error: 03:50:09 INFO - 2015-11-24 03:50:09,456 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction. 03:50:09 INFO - 2015-11-24 03:50:09,457 DEBUG : BROWSER_OUTPUT: Stack: 03:50:09 INFO - 2015-11-24 03:50:09,457 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 03:50:09 INFO - 2015-11-24 03:50:09,457 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 03:50:09 INFO - 2015-11-24 03:50:09,457 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 03:50:09 INFO - 2015-11-24 03:50:09,457 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 03:50:09 INFO - 2015-11-24 03:50:09,457 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 03:50:09 INFO - 2015-11-24 03:50:09,457 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 03:50:09 INFO - 2015-11-24 03:50:09,457 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 03:50:09 INFO - 2015-11-24 03:50:09,457 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7 03:50:09 INFO - 2015-11-24 03:50:09,457 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 03:50:09 INFO - 2015-11-24 03:50:09,457 DEBUG : BROWSER_OUTPUT: 03:50:09 INFO - 2015-11-24 03:50:09,926 INFO : Browser initialized. 03:50:09 INFO - 2015-11-24 03:50:09,927 INFO : Running cycle 1/1 for dromaeo_css test... 03:50:09 INFO - 2015-11-24 03:50:09,927 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmpQ7hmPy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:50:21 INFO - 2015-11-24 03:50:21,796 DEBUG : BROWSER_OUTPUT: RSS: Main: 226504704 03:50:21 INFO - 2015-11-24 03:50:21,796 DEBUG : BROWSER_OUTPUT: 03:51:13 INFO - 2015-11-24 03:51:13,670 DEBUG : BROWSER_OUTPUT: 1448365873665 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] 03:53:22 INFO - 2015-11-24 03:53:22,612 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49264/tests/dromaeo/cssquery-ext.html) 03:53:25 INFO - 2015-11-24 03:53:25,015 DEBUG : BROWSER_OUTPUT: RSS: Main: 366227456 03:53:25 INFO - 2015-11-24 03:53:25,016 DEBUG : BROWSER_OUTPUT: 03:56:27 INFO - 2015-11-24 03:56:27,672 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-ext.html (next: http://localhost:49264/tests/dromaeo/cssquery-jquery.html) 03:56:28 INFO - 2015-11-24 03:56:28,264 DEBUG : BROWSER_OUTPUT: RSS: Main: 365961216 03:56:28 INFO - 2015-11-24 03:56:28,264 DEBUG : BROWSER_OUTPUT: 03:59:30 INFO - 2015-11-24 03:59:30,375 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49264/tests/dromaeo/cssquery-mootools.html) 03:59:30 INFO - 2015-11-24 03:59:30,955 DEBUG : BROWSER_OUTPUT: RSS: Main: 347504640 03:59:30 INFO - 2015-11-24 03:59:30,956 DEBUG : BROWSER_OUTPUT: 04:02:32 INFO - 2015-11-24 04:02:32,918 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49264/tests/dromaeo/cssquery-prototype.html) 04:02:33 INFO - 2015-11-24 04:02:33,516 DEBUG : BROWSER_OUTPUT: RSS: Main: 319598592 04:02:33 INFO - 2015-11-24 04:02:33,516 DEBUG : BROWSER_OUTPUT: 04:05:36 INFO - 2015-11-24 04:05:36,519 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49264/tests/dromaeo/cssquery-yui.html) 04:05:37 INFO - 2015-11-24 04:05:37,113 DEBUG : BROWSER_OUTPUT: RSS: Main: 322908160 04:05:37 INFO - 2015-11-24 04:05:37,114 DEBUG : BROWSER_OUTPUT: 04:08:39 INFO - 2015-11-24 04:08:39,899 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-yui.html (next: http://localhost:49264/tests/dromaeo/cssquery-dojo.html) 04:08:40 INFO - 2015-11-24 04:08:40,158 DEBUG : BROWSER_OUTPUT: RSS: Main: 370520064 04:08:40 INFO - 2015-11-24 04:08:40,158 DEBUG : BROWSER_OUTPUT: 04:08:40 INFO - 2015-11-24 04:08:40,159 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:08:40 INFO - 2015-11-24 04:08:40,159 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:08:40 INFO - 2015-11-24 04:08:40,159 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:08:40 INFO - 2015-11-24 04:08:40,159 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:08:40 INFO - 2015-11-24 04:08:40,162 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3134;3145.6539780624134;3269.002954098999;3519.6480351964806;3547.858085676573;5552.805651802188;5736.913946290809;5798.130280457934;5834.941650583492;5878.412158784125;5746.396628354015;5792.7972520961775;5894.0864166054225;6030.125631783389;6057.697115144236;5156.381234251895;5175.223716442535;5197.350331208599;5229.058769421503;5292.973535132319;5308.283381743454;5319.654222475527;5333.120035194208;5338.946610533903;5380.946190538103;5036.169032109698;5166.0184564932915;5172.353455818023;5269.235960785683;5326.067938110815;5221.981713565856;5230.163173892159;5230.895382092375;5233.659812112239;5244.580433565344;5002.049610573979;5146.202338637517;5178.482151784829;5178.766955487012;5195.220716892459;5728.97135514324;5785.27684039495;5794.246747922785;5805.477507024388;5839.591228613998;6045.00523258984;6140.9385906140815;6197.504199664061;6247.468965137969;6250.374962503759;6146.555559443527;6222.782202622887;6262.530310226751;6281.026440901667;6294.150289710876;5976.432238937294;6013.729382177812;6038.245219347582;6052.727627256784;6065.60573562717;5379.488948549882;6207.34822843604;6324.1778568786685;6338.904916426257;6416.903746443807;6113.113598528277;6196.10156527301;6212.006079027334;6214.005759078616;6216.036514340284;6267.874642507216;6280.842978925563;6282.2147231596055;6290.308066112724;6294.527910406738;6102.328743838263;6135.845176233341;6221.3156552779155;6232;6233.0338797486465;5307.336582927134;6101.3079194897555;6229.937700623026;6239.1577137085915;6250.781222657185;4993;5030.4215015272985;5036.269740887551;5036.899262014739;5037.319961805109;4420.9336859947125;4532.977335113369;4542.341360502709;4558.49856515783;4568.109218702321;5755.510781583613;5836.241288632451;5840.328362238312;5856.180134781134;5877.735501902424;6285.057241413733;6295.937040629627;6300.1494798202575;6305.054241863757;6308.243010838729;5614.29821272341;5752.712364381764;5763.9135412968835;5779.942200578024;5817.767289308465;4623.491415944243;5382.650127741685;5425.186222066643;5452.991196628636;5490.956718223604;6295.118683384329;6324.494040476751;6339.746410143543;6347.1114044033875;6352.68236588178;4830.961343311205;5580.190872323572;6236.34518375564;6273.121762953099;6295.779647712308;5703.515201207943;6239.875202496016;6268.341824108403;6299.338569450142;6299.496040316855;5940.376260492759;6142.969285153546;6166.969165154325;6198.132261483306;6208.348123447154;6221.502279817513;6268.404501572343;6297.118403423616;6303.590266632654;6316.589421687576;6190.721417536131;6200.131981522501;6207.937920620917;6214.098955651496;6230.127782110418;5015.774290156879;5221.425643179323;6091.55698489275;6122.969385153046;6196.442320191204;5971.223740913653;5974.2233509643465;5978.581499295007;5997.490213331916;5998.430149135826;6033.117710232488;6090.543209259235;6092.299385570539;6194.721237544411;6203.038529028008;6057.363976782551;6087.178230938944;6090.69546522681;6108.267007959073;6108.938910610837 04:08:40 INFO - 2015-11-24 04:08:40,163 DEBUG : BROWSER_OUTPUT: |1;ext.html;575.8445219790653;584.5586582130491;599.8590331272153;631.1763149090438;655.947524198064;32949.682012719495;42232;42379;42451.78774106133;42482;44889.32666010012;45267.86830329235;45422.86442838922;45510.31734523985;45540.77229613847;8538.70114545991;8703.390762646617;8871.979722331918;9840.261980351484;10043;9768.951155244215;12138.575149869755;12140.392980350991;12156.756864862697;12183.14717969738;11904.40477976102;11982.64052078439;12016.09879259059;12022.098342624295;12031.879681203207;8923.241524470428;9083.727488175366;11888.405579721022;11946.522139114424;12003;11738.237014594024;11902.226355286879;11911.404429778562;11915.88084119165;11937.940310298392;41336.37995430071;41899.7905010476;41929.79035104835;41987.7900610498;42001.36997945033;2808.1996630960284;2940.3825196708717;3266.8856590019236;3419.4472389202556;3443.4146195146886;28881.765674929935;29497.262568435744;29595.964141254954;30905.690943090507;31169.75320987158;2822.9576556351467;2870.0959197852817;2877.079334612925;2887.5524293734293;2901.8839246430116;493502;498750;499514;499770;504036;73076.269237308;73587.26412735911;73639.26360736325;73704.20922640011;73705.63147184337;77130;77496.61251693705;77596;77623;77662.83505747418;16539.83460165407;16612.591174279663;16676.916615416845;16696.41562545305;16711.331546737994;12501;12591.181573197713;12598.118131730691;12600;12600.684982875502;17349.617960291413;18353.81646183521;22650.093996240295;22731.659025114637;22867.542649146922;7648.770536883902;8319.459235149696;9370.29722770795;9609.615615375447;9622.9895860935;847.4491580472619;961.3943215774018;1019.50553981319;1089.1123734156638;1126.2116518437126;29893.40213195724;30049.248768780955;30416.39167216643;30582.23544411363;30606.38787224243;996.7473509867751;1015.1219195395929;1114.7101753544136;1163.6741712320563;1190.9876604885778;14826;15640.765388519181;16942.57643558921;17108.973461592344;17116.74324885128;585000;590111;590214;596232;597166.0850174502;58848.41151588515;59557;59601;64363.03455448172;68517.65741171362;1491.850814918543;1499.3402902722767;1502.9929946935797;1515.0228102873584;1650.290375138678;28647.14058578246;30925;32500.83749581237;32883.587700398195;33010.66989330172;50864.49135508598;51179;55326;58570;59023;421190;426302;429043.70956290036;430345;432084;43292.35061474144;43521;43887.56112438835;44026.55973440353;44554;47461;47520.7623961878;47691.523084770095;47752.28371574498;49078;39645.00887477836;39651;39855.40216896807;40127.59872401239;40701.59298406978;34270;38938.610613894634;38999.2200156001;40008.399874001334;40074.79962600285;39476.80261598789;39545.80227098846;39637.60362396455;39663.603363965995;40722.77831665055;32299.677003229666;34151.829240853636;34671.95984120481;34844.303113938084;35884.28231435409;25881.352966175997;26012.609810852475;26107.955681772517;26164.73835261623;26429.20712378555 04:08:40 INFO - 2015-11-24 04:08:40,165 DEBUG : BROWSER_OUTPUT: |2;jquery.html;329.73785840257;350.1805774487699;357.46023504508184;361.3459638055119;363.8199091449733;15959.202039897988;17050.914745426257;19930.402087937367;20490.795092049077;21232.57534849304;18435.446936591925;18502.352417665385;18722.81277187228;18941.863488190716;18957;5174.327337446136;5603.075492543725;5967.970160149215;5968.373320801318;5971.253593300838;4260.595243451867;4372.497162826268;4403.603675669189;5448.618596698233;5866.677332746697;5722.885542289152;5850.268716410449;6295.401936816018;6357.618542887419;6375.617462952215;6389.872202555946;6433.999999999976;6445;6448.742050317982;6470.611763294218;5408.188771684239;5496.1755736639425;5517.200005999148;5538.501534861842;5539.25220095286;25083.49833003348;25113.372165696004;25228.747712523007;25537.361565960815;25554.36114097144;2189.397915573214;2268.1834539565743;2374.062245413052;2436.3300092474533;2447.3881529617597;15799.131047792367;15926;16020.279087441093;16168.24156021774;16527.50417487477;1532.7470967290383;1854.9258029678797;1873.4660621722744;1898.506388339028;1909.9809001909941;103693;109958;115574;117444;118225.40887295509;16791.076490793123;18324.54188645268;19579.161021652064;19686.803131968496;19704.113314900864;28805.423891522052;28841;28846.278843029093;28910.855445723057;28934.42131157407;9113.997460279365;10126.797464050826;10162.4918754062;10237.18102551794;10262.743431414274;7957.960210199028;8591.957040214758;8595.226429621363;8659;8680.392372533863;7340.743073992385;8150.858879756839;8896.510691912006;8943.865842012376;8979.775505612412;3147.9527807082914;3417.1627951152127;3428.2286485540553;3430.902111324402;3572.9464058039152;393.1449098211379;393.2842227146623;422.3664503245132;938.2915898496727;1117.3631030312645;10601.893981060244;11242;11272.774544509064;11521;11813;440.6650945281545;442.5353378952087;454.9818007279738;457.83060267701165;465.4159030416805;5959;5981.132735753292;6060.727267272983;6063.242094738158;6069.089636554464;189746;190542;191097;754653;767940.160299195;98292.01707982829;115150.42424787823;116223;122259;125350;474.6392741516472;487.58555228055866;487.9853604391945;488.40902507965757;493.0877875929502;10564.418956957441;10968.725781855517;18416.17127229251;18693.906530467262;19107.331243406414;50723.49276507335;52040;52160.47839521556;52412.21381679354;52942.67643308948;71697;71760.64119679369;72291;73753;75021.12447188864;9928.007199280015;10034.147097496503;10153.543090560795;10410.323328983592;10410.68767936963;20132.79867201368;20261.49346266355;20443.38669839907;20643.58712825765;20990.790092099494;13344.799828002782;16163.353465861705;16322.755158672868;16350.836491635408;16357.100359480342;15728.685426291182;15922.840771592137;16082.195890205678;16245;16299.022058676517;16689.749653755447;17120.058396788296;17182;17194.398196063077;17235.65528689394;9855.014498550088;9876.308658393844;9893.307468477426;9950.203983681433;9958.452285124387;10263.538140783832;10323.63867264642;14927.850721492647;16226.918865405598;16610.418635347705 04:08:40 INFO - 2015-11-24 04:08:40,168 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1591.594143493409;1593.442295196682;1696.8472837444629;1889.288870470874;1905.3712274949269;7524.510906791062;7690.769276921695;8161.836763264739;8237.464564803284;8323.25090741833;8276.337892968562;8407.663693452254;8437.873431898512;8448.4508506947;8455.830883382328;2381.583222935988;2403.975960240393;2404.4469771952463;2406.5186962607463;2408.229366602688;2009.7387339645898;2411.6985376827897;2416.8066554675584;2426.8786560671897;2427.8057755379527;2141.668041453577;2302.9193978210765;2399.7960173385195;2404.1224952892244;2414.7102347718296;2364.728056273533;2383.2731017039796;2396.35298469414;2397.976020239793;2399.076355603088;2380.904763809463;2393.5571919195004;2419;2421.3825474503888;2430.9149179778624;7576.4317676174505;8386.287165590933;8418.536980466071;8425.747227583182;8431.662733490653;4762.333273341735;4816.325714399987;4826.61387089032;4973.781423551217;5102.719350435725;3517.7537572369943;3527.7177825773874;3583.301256255031;3594;3620.2759448110482;1031.56243484477;1046.3251202974116;1050.5325130317012;1056.799208150449;1060.2631171335925;11243.269187502787;11302.773944521146;11303.660890173307;11317.264377815416;11383.14626403023;4633.397658304399;4679.766011699401;4693.366395536627;4755.595774359149;4768.332433459318;8324.66701331952;9789.902100979041;9817.901820981699;9823.312368134304;9835.459049752333;5067.265246539305;5082.949170508247;5088.389393272831;5104.668196567285;5107.642465027487;4576.542345765463;4626.768661566908;4630.3748993886065;4634.629229661619;4637.65217608674;3613.2050948791225;3701.759385639925;3709.8330575124182;3723.1809002019513;3748.5876553579083;1226.9815952760716;1240.925544467335;1245.439552201513;1248.6066888929959;1249.0507214517033;1374.3334482775845;1379.7309524642599;1413.971720565583;1416.1361569442631;1416.3838730152418;7776.688932442639;7922.920770792333;7936.56348900816;7937.722179723682;7939.563324017119;482.6959015820081;494.69329016010306;495.4030393375973;500.412015881338;500.86476651303946;5804.94195058044;5816.505597024216;5861.443162899518;5864.736086876101;6021.819345419644;8979.865302020475;9767.902320976842;10171.7457063574;10174;10193.847092293621;9930.602775889029;10077.69766906994;10205.489725513695;10223.897761022443;10237.334573252714;7888.960555197187;7991.600419978978;7992.84014319722;8061.153578874251;8075.475094118746;7377.8155546111775;7699.8846700227;7773.92226077732;7807.219278072147;7819.569923654253;9060.592273347847;9115.787581274937;9130.863037054316;9154.130357616015;9290.024547422694;9245.491497967676;9379.062093790566;9381.96798352167;9398.530073496435;9456.101670341306;7117;7145.64271786419;7151.678174482056;7183.497155199086;7243.927560724325;7967.083785364525;8016.559089250147;8025.438219324591;8028;8028.1169071403265;7362.153352364547;7472.663730132048;7488.438367122597;7618.657160427683;7628.46600737943;7342;7348.412127029718;7382.5570465772225;7474.663640136316;7540.886886696594;7488.663010164447;7527.059117610299;7547.962260188664;7559.886601700869;7572.4699271050495;6802.14973128359;6825.55633883796;6864.176298844171;6893.345132212432;6895;6920.653967301514;6964.199117101395;6972.372486476242;6998.930010699828;7001.719931202695 04:08:40 INFO - 2015-11-24 04:08:40,169 DEBUG : BROWSER_OUTPUT: |4;prototype.html;304.90090720515826;321.81334825801036;327.054811594492;332.931290557311;333.853104633961;6825.112735344404;6894.517383783137;6899;6930.376266136047;6950.235474097845;5762.3373312069125;6581.144451221347;6837.076994605727;6858.211305699846;6863.7940861774105;5039.3196918415915;5040.773165207556;5048.29323894655;5056.974715126419;5057.974710126463;4848;4984.700917944917;5045.545900868921;5062.443131255559;5077.238414237857;4972.776225069862;4995.750212489379;4996.650234483587;5006.899862002758;5015;4291.9356209657035;4744.952550474485;4834.323194752737;4841.128596852565;4878.000009997977;4166.979165104155;4179.811908464127;4182.811773470201;4207.64235040022;4214.789260536992;5945.16767652529;6350.269718982284;6567.93432065678;6583.275839657634;6623.834404139935;5809.448102430262;5982.04287314032;6006.579539432241;6007.4893634041155;6049.788257411014;7006.929930700679;7135.357817796424;7290.526115802456;7328.120625524918;7401.444891633095;4767.880802979953;4797.544233297831;4806.206975848981;4812.494688057774;4866.610671146335;7331.853362932711;7751.612419379008;7855.175206603281;7859.882101768478;7864.449488535862;4785.138675038457;4903.975480122576;4920.40955085392;4973.8010479581135;5004.374453193351;5086.9745651271505;5088.287639730441;5088.389393272757;5089.160288552422;5101.336826212643;5997.340292567814;6001.729922153513;6005.159277701126;6005.879882402328;6007.879842403127;7176.210616832144;7464.85070298591;7472.364848987897;7474.514156579805;7484.925150748531;1750.544858336816;1776.7690200273883;1779.9288028478716;1780.0298837133794;1786.0801687131138;1134.8978591926766;1141.241074685325;1153.763478486908;1165.155262434728;1166.5333866453486;5020.623453241022;6749.021391898147;6914.481413893978;6917;6919.4464442844455;7300.890486642705;7689.884651730228;7691.423143264277;7718.498297610749;7735.381169506426;6673.165854268216;6848.931510684929;6854.554453960476;6883.793486195422;6906.689198986058;4037.2127435149864;4061.5329237137516;4063.1264278180333;4065.0447144920918;4071.4707088078953;6414.61512309263;7970.083440404312;8027.157148499324;8035.357171426272;8036.959815201004;7762.456628035983;7987.281144697005;8015.198480152055;8022.037355517258;8054.536549283865;7668.424868134912;7794.142644309008;7901.920980790233;7916.84166316682;8026.759197223976;6406.391392817675;7491.587962662106;7650.579218143055;7654;7781;5454.59090568201;6677.09859169006;7476.813079673052;7622.69509219625;7656.923430765621;6437;8003.399745019264;8043.316318113026;8050.677972881019;8081.111077781557;4775.403074615673;4810.062037902575;4814.783334750015;4825.131476334293;4830.686005409637;6268.811935641938;7446.962765186356;7712.113106992768;7739.7678069658;7744.419168562267;7471.327580517779;7473.51422157558;7476.5514069156025;7481.663325150273;7534.811629709082;7416.851662966603;7448.068991376078;7458.850822983619;7459;7466;7009.474289428212;7257.201707812031;7264.200937896932;7265.418766498562;7266;6689.698963546554;6734.562253453706;7414.369778568666;7417.851642967218;7434.7769566913075;6435.710393032418;7024.894626580707;7244.673989670583;7256.492045556761;7267.636618168965 04:08:40 INFO - 2015-11-24 04:08:40,170 DEBUG : BROWSER_OUTPUT: |5;yui.html;769.1308821032231;796.6216047377495;807.6365635464039;815.64927081355;816.9346452283814;14566.927165364172;15075.095494270337;15130.470433534829;15159.242037898117;15159.317830697617;14049.227292498908;14194.290285485737;14196.503122390719;14204.715905681905;14206.14763114214;760.3461023519768;774.9925097373392;775.3642013548892;960.1071003966308;983.6065573770513;965.884093908732;974.3082411487852;974.44456659704;976.5312649928039;977.1108291454777;814.242754238558;1018.7402212435818;1019.2050200843317;1022.2946167144684;1022.9539670714798;820.6676296100089;1012.0790081026267;1027.578692735982;1031.2008193649906;1031.9432431216355;770.630097553172;774.0595176860141;774.44240147094;774.7908064822466;777.4829738224131;14996.250187490581;15160;15173.924130379277;15186.392544298214;15216.087034777838;12765.297908615023;12928.741425171444;13013.349332533335;13291.933540332333;13311.733765324736;10224;10279.383237005804;10389.84415233772;10723.946380268128;11060.889391106066;3278.360719659667;3409.2840503494294;3556.5198698175673;3606.7294952878638;3633.9273214535824;73800.63099684521;74433.62783186049;74688.25311746921;83719;83839.5808020956;9814.607415703293;9894.356866803635;9898.356606820533;9900.851487227697;10396.584136634601;22524.324270271918;23341.94961226749;23368.298951031495;23418.76581234166;23500.412489687893;7370;7395.112586489656;7515.962420187864;7540.245975402416;7722.266384693445;3869.6710779583486;3949.0522274653877;3962.722609417313;3974.980125099356;4038.273110840077;3555.822208889545;3601.459781032866;3602.0814692253307;3602.081469225383;3606.8376923038527;657.5495785387026;658.808945405827;665.6904539389208;668.669008840627;673.5419914458215;493;502.27170602626336;514.4418306137808;517.570416554259;534.9384820745664;8284.544350060683;8530.402871799039;8558.871616925751;8561.48631082137;8832.160944710242;540.7080176704535;548.569373042168;566.6260268222953;625.3090335179652;663.2604645819877;3795.9240815184094;3859.845606175778;3904.8047597620007;4084.81618327176;4175.290200665833;102360;104233;105502;105533.94466055241;105569.47215263874;87624;89298.55350723205;89543.10456895348;89917;90595.54702226579;77999.61000194962;78781;79030.20969790229;79126.60436697895;80602;6931.250404746191;7366.300201480851;7982.880256796268;8372.162783721822;8425.115362887054;53215;54594.45405545894;55432;55634.330970070965;55644.72177639248;73042.63478682573;73452.63273683598;78789.2121078782;79764;80022.19977800148;10630.149588033093;10693.14454843595;10769.62306319275;11339.206255562032;11604.709882252673;11871.881281187078;12394.62816115518;12504.43730032169;12768.808467873176;13173.341332933507;11555.768884622428;11629.418529073682;11737.647870563897;11923;11969;11667.883321167019;11946.940265298617;12008.219465734699;12059.638210853689;12104.213226140273;9027.516019600587;11707.121965852766;11734.413279335828;11849.881501185222;12159.81760273579;9500.857487137837;9576.56905439243;9630.47032413224;9645.08371704687;10569.28268205065;7332.376747976268;8525.317974562144;8727.91272087271;9295.163435290857;9367.531623418938 04:08:40 INFO - 2015-11-24 04:08:40,170 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:08:40 INFO - 2015-11-24 04:08:40,171 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:08:40 INFO - 2015-11-24 04:08:40,171 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,2060 04:08:40 INFO - 2015-11-24 04:08:40,171 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:08:40 INFO - 2015-11-24 04:08:40,171 DEBUG : BROWSER_OUTPUT: __startTimestamp1448366920158__endTimestamp 04:08:40 INFO - 2015-11-24 04:08:40,171 DEBUG : BROWSER_OUTPUT: 04:08:40 INFO - 2015-11-24 04:08:40,171 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:08:40 INFO - 2015-11-24 04:08:40,171 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:08:40 INFO - 2015-11-24 04:08:40,171 DEBUG : BROWSER_OUTPUT: 04:08:40 INFO - 2015-11-24 04:08:40,171 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5749.86 Median:6013.73 stddev:650.57 (10.8%) stddev-sans-first:619.35 04:08:40 INFO - 2015-11-24 04:08:40,173 DEBUG : BROWSER_OUTPUT: Values: 3134.0 3145.7 3269.0 3519.6 3547.9 5552.8 5736.9 5798.1 5834.9 5878.4 5746.4 5792.8 5894.1 6030.1 6057.7 5156.4 5175.2 5197.4 5229.1 5293.0 5308.3 5319.7 5333.1 5338.9 5380.9 5036.2 5166.0 5172.4 5269.2 5326.1 5222.0 5230.2 5230.9 5233.7 5244.6 5002.0 5146.2 5178.5 5178.8 5195.2 5729.0 5785.3 5794.2 5805.5 5839.6 6045.0 6140.9 6197.5 6247.5 6250.4 6146.6 6222.8 6262.5 6281.0 6294.2 5976.4 6013.7 6038.2 6052.7 6065.6 5379.5 6207.3 6324.2 6338.9 6416.9 6113.1 6196.1 6212.0 6214.0 6216.0 6267.9 6280.8 6282.2 6290.3 6294.5 6102.3 6135.8 6221.3 6232.0 6233.0 5307.3 6101.3 6229.9 6239.2 6250.8 4993.0 5030.4 5036.3 5036.9 5037.3 4420.9 4533.0 4542.3 4558.5 4568.1 5755.5 5836.2 5840.3 5856.2 5877.7 6285.1 6295.9 6300.1 6305.1 6308.2 5614.3 5752.7 5763.9 5779.9 5817.8 4623.5 5382.7 5425.2 5453.0 5491.0 6295.1 6324.5 6339.7 6347.1 6352.7 4831.0 5580.2 6236.3 6273.1 6295.8 5703.5 6239.9 6268.3 6299.3 6299.5 5940.4 6143.0 6167.0 6198.1 6208.3 6221.5 6268.4 6297.1 6303.6 6316.6 6190.7 6200.1 6207.9 6214.1 6230.1 5015.8 5221.4 6091.6 6123.0 6196.4 5971.2 5974.2 5978.6 5997.5 5998.4 6033.1 6090.5 6092.3 6194.7 6203.0 6057.4 6087.2 6090.7 6108.3 6108.9 04:08:40 INFO - 2015-11-24 04:08:40,173 DEBUG : BROWSER_OUTPUT: 04:08:40 INFO - 2015-11-24 04:08:40,173 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:67167.10 Median:39591.70 stddev:135690.98 (342.7%) stddev-sans-first:135979.51 04:08:40 INFO - 2015-11-24 04:08:40,174 DEBUG : BROWSER_OUTPUT: Values: 575.8 584.6 599.9 631.2 655.9 32949.7 42232.0 42379.0 42451.8 42482.0 44889.3 45267.9 45422.9 45510.3 45540.8 8538.7 8703.4 8872.0 9840.3 10043.0 9769.0 12138.6 12140.4 12156.8 12183.1 11904.4 11982.6 12016.1 12022.1 12031.9 8923.2 9083.7 11888.4 11946.5 12003.0 11738.2 11902.2 11911.4 11915.9 11937.9 41336.4 41899.8 41929.8 41987.8 42001.4 2808.2 2940.4 3266.9 3419.4 3443.4 28881.8 29497.3 29596.0 30905.7 31169.8 2823.0 2870.1 2877.1 2887.6 2901.9 493502.0 498750.0 499514.0 499770.0 504036.0 73076.3 73587.3 73639.3 73704.2 73705.6 77130.0 77496.6 77596.0 77623.0 77662.8 16539.8 16612.6 16676.9 16696.4 16711.3 12501.0 12591.2 12598.1 12600.0 12600.7 17349.6 18353.8 22650.1 22731.7 22867.5 7648.8 8319.5 9370.3 9609.6 9623.0 847.4 961.4 1019.5 1089.1 1126.2 29893.4 30049.2 30416.4 30582.2 30606.4 996.7 1015.1 1114.7 1163.7 1191.0 14826.0 15640.8 16942.6 17109.0 17116.7 585000.0 590111.0 590214.0 596232.0 597166.1 58848.4 59557.0 59601.0 64363.0 68517.7 1491.9 1499.3 1503.0 1515.0 1650.3 28647.1 30925.0 32500.8 32883.6 33010.7 50864.5 51179.0 55326.0 58570.0 59023.0 421190.0 426302.0 429043.7 430345.0 432084.0 43292.4 43521.0 43887.6 44026.6 44554.0 47461.0 47520.8 47691.5 47752.3 49078.0 39645.0 39651.0 39855.4 40127.6 40701.6 34270.0 38938.6 38999.2 40008.4 40074.8 39476.8 39545.8 39637.6 39663.6 40722.8 32299.7 34151.8 34672.0 34844.3 35884.3 25881.4 26012.6 26108.0 26164.7 26429.2 04:08:40 INFO - 2015-11-24 04:08:40,174 DEBUG : BROWSER_OUTPUT: 04:08:40 INFO - 2015-11-24 04:08:40,174 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30555.28 Median:27179.89 stddev:85140.24 (313.2%) stddev-sans-first:85348.84 04:08:40 INFO - 2015-11-24 04:08:40,176 DEBUG : BROWSER_OUTPUT: Values: 329.7 350.2 357.5 361.3 363.8 15959.2 17050.9 19930.4 20490.8 21232.6 18435.4 18502.4 18722.8 18941.9 18957.0 5174.3 5603.1 5968.0 5968.4 5971.3 4260.6 4372.5 4403.6 5448.6 5866.7 5722.9 5850.3 6295.4 6357.6 6375.6 6389.9 6434.0 6445.0 6448.7 6470.6 5408.2 5496.2 5517.2 5538.5 5539.3 25083.5 25113.4 25228.7 25537.4 25554.4 2189.4 2268.2 2374.1 2436.3 2447.4 15799.1 15926.0 16020.3 16168.2 16527.5 1532.7 1854.9 1873.5 1898.5 1910.0 103693.0 109958.0 115574.0 117444.0 118225.4 16791.1 18324.5 19579.2 19686.8 19704.1 28805.4 28841.0 28846.3 28910.9 28934.4 9114.0 10126.8 10162.5 10237.2 10262.7 7958.0 8592.0 8595.2 8659.0 8680.4 7340.7 8150.9 8896.5 8943.9 8979.8 3148.0 3417.2 3428.2 3430.9 3572.9 393.1 393.3 422.4 938.3 1117.4 10601.9 11242.0 11272.8 11521.0 11813.0 440.7 442.5 455.0 457.8 465.4 5959.0 5981.1 6060.7 6063.2 6069.1 189746.0 190542.0 191097.0 754653.0 767940.2 98292.0 115150.4 116223.0 122259.0 125350.0 474.6 487.6 488.0 488.4 493.1 10564.4 10968.7 18416.2 18693.9 19107.3 50723.5 52040.0 52160.5 52412.2 52942.7 71697.0 71760.6 72291.0 73753.0 75021.1 9928.0 10034.1 10153.5 10410.3 10410.7 20132.8 20261.5 20443.4 20643.6 20990.8 13344.8 16163.4 16322.8 16350.8 16357.1 15728.7 15922.8 16082.2 16245.0 16299.0 16689.7 17120.1 17182.0 17194.4 17235.7 9855.0 9876.3 9893.3 9950.2 9958.5 10263.5 10323.6 14927.9 16226.9 16610.4 04:08:40 INFO - 2015-11-24 04:08:40,176 DEBUG : BROWSER_OUTPUT: 04:08:40 INFO - 2015-11-24 04:08:40,176 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5814.19 Median:5106.16 stddev:3045.36 (59.6%) stddev-sans-first:3037.37 04:08:40 INFO - 2015-11-24 04:08:40,176 DEBUG : BROWSER_OUTPUT: Values: 1591.6 1593.4 1696.8 1889.3 1905.4 7524.5 7690.8 8161.8 8237.5 8323.3 8276.3 8407.7 8437.9 8448.5 8455.8 2381.6 2404.0 2404.4 2406.5 2408.2 2009.7 2411.7 2416.8 2426.9 2427.8 2141.7 2302.9 2399.8 2404.1 2414.7 2364.7 2383.3 2396.4 2398.0 2399.1 2380.9 2393.6 2419.0 2421.4 2430.9 7576.4 8386.3 8418.5 8425.7 8431.7 4762.3 4816.3 4826.6 4973.8 5102.7 3517.8 3527.7 3583.3 3594.0 3620.3 1031.6 1046.3 1050.5 1056.8 1060.3 11243.3 11302.8 11303.7 11317.3 11383.1 4633.4 4679.8 4693.4 4755.6 4768.3 8324.7 9789.9 9817.9 9823.3 9835.5 5067.3 5082.9 5088.4 5104.7 5107.6 4576.5 4626.8 4630.4 4634.6 4637.7 3613.2 3701.8 3709.8 3723.2 3748.6 1227.0 1240.9 1245.4 1248.6 1249.1 1374.3 1379.7 1414.0 1416.1 1416.4 7776.7 7922.9 7936.6 7937.7 7939.6 482.7 494.7 495.4 500.4 500.9 5804.9 5816.5 5861.4 5864.7 6021.8 8979.9 9767.9 10171.7 10174.0 10193.8 9930.6 10077.7 10205.5 10223.9 10237.3 7889.0 7991.6 7992.8 8061.2 8075.5 7377.8 7699.9 7773.9 7807.2 7819.6 9060.6 9115.8 9130.9 9154.1 9290.0 9245.5 9379.1 9382.0 9398.5 9456.1 7117.0 7145.6 7151.7 7183.5 7243.9 7967.1 8016.6 8025.4 8028.0 8028.1 7362.2 7472.7 7488.4 7618.7 7628.5 7342.0 7348.4 7382.6 7474.7 7540.9 7488.7 7527.1 7548.0 7559.9 7572.5 6802.1 6825.6 6864.2 6893.3 6895.0 6920.7 6964.2 6972.4 6998.9 7001.7 04:08:40 INFO - 2015-11-24 04:08:40,176 DEBUG : BROWSER_OUTPUT: 04:08:40 INFO - 2015-11-24 04:08:40,176 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5984.80 Median:6683.40 stddev:1912.46 (28.6%) stddev-sans-first:1869.70 04:08:40 INFO - 2015-11-24 04:08:40,177 DEBUG : BROWSER_OUTPUT: Values: 304.9 321.8 327.1 332.9 333.9 6825.1 6894.5 6899.0 6930.4 6950.2 5762.3 6581.1 6837.1 6858.2 6863.8 5039.3 5040.8 5048.3 5057.0 5058.0 4848.0 4984.7 5045.5 5062.4 5077.2 4972.8 4995.8 4996.7 5006.9 5015.0 4291.9 4745.0 4834.3 4841.1 4878.0 4167.0 4179.8 4182.8 4207.6 4214.8 5945.2 6350.3 6567.9 6583.3 6623.8 5809.4 5982.0 6006.6 6007.5 6049.8 7006.9 7135.4 7290.5 7328.1 7401.4 4767.9 4797.5 4806.2 4812.5 4866.6 7331.9 7751.6 7855.2 7859.9 7864.4 4785.1 4904.0 4920.4 4973.8 5004.4 5087.0 5088.3 5088.4 5089.2 5101.3 5997.3 6001.7 6005.2 6005.9 6007.9 7176.2 7464.9 7472.4 7474.5 7484.9 1750.5 1776.8 1779.9 1780.0 1786.1 1134.9 1141.2 1153.8 1165.2 1166.5 5020.6 6749.0 6914.5 6917.0 6919.4 7300.9 7689.9 7691.4 7718.5 7735.4 6673.2 6848.9 6854.6 6883.8 6906.7 4037.2 4061.5 4063.1 4065.0 4071.5 6414.6 7970.1 8027.2 8035.4 8037.0 7762.5 7987.3 8015.2 8022.0 8054.5 7668.4 7794.1 7901.9 7916.8 8026.8 6406.4 7491.6 7650.6 7654.0 7781.0 5454.6 6677.1 7476.8 7622.7 7656.9 6437.0 8003.4 8043.3 8050.7 8081.1 4775.4 4810.1 4814.8 4825.1 4830.7 6268.8 7447.0 7712.1 7739.8 7744.4 7471.3 7473.5 7476.6 7481.7 7534.8 7416.9 7448.1 7458.9 7459.0 7466.0 7009.5 7257.2 7264.2 7265.4 7266.0 6689.7 6734.6 7414.4 7417.9 7434.8 6435.7 7024.9 7244.7 7256.5 7267.6 04:08:40 INFO - 2015-11-24 04:08:40,177 DEBUG : BROWSER_OUTPUT: 04:08:40 INFO - 2015-11-24 04:08:40,178 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:19647.89 Median:508.36 stddev:28497.09 (5605.7%) stddev-sans-first:28541.77 04:08:40 INFO - 2015-11-24 04:08:40,179 DEBUG : BROWSER_OUTPUT: Values: 769.1 796.6 807.6 815.6 816.9 14566.9 15075.1 15130.5 15159.2 15159.3 14049.2 14194.3 14196.5 14204.7 14206.1 760.3 775.0 775.4 960.1 983.6 965.9 974.3 974.4 976.5 977.1 814.2 1018.7 1019.2 1022.3 1023.0 820.7 1012.1 1027.6 1031.2 1031.9 770.6 774.1 774.4 774.8 777.5 14996.3 15160.0 15173.9 15186.4 15216.1 12765.3 12928.7 13013.3 13291.9 13311.7 10224.0 10279.4 10389.8 10723.9 11060.9 3278.4 3409.3 3556.5 3606.7 3633.9 73800.6 74433.6 74688.3 83719.0 83839.6 9814.6 9894.4 9898.4 9900.9 10396.6 22524.3 23341.9 23368.3 23418.8 23500.4 7370.0 7395.1 7516.0 7540.2 7722.3 3869.7 3949.1 3962.7 3975.0 4038.3 3555.8 3601.5 3602.1 3602.1 3606.8 657.5 658.8 665.7 668.7 673.5 493.0 502.3 514.4 517.6 534.9 8284.5 8530.4 8558.9 8561.5 8832.2 540.7 548.6 566.6 625.3 663.3 3795.9 3859.8 3904.8 4084.8 4175.3 102360.0 104233.0 105502.0 105533.9 105569.5 87624.0 89298.6 89543.1 89917.0 90595.5 77999.6 78781.0 79030.2 79126.6 80602.0 6931.3 7366.3 7982.9 8372.2 8425.1 53215.0 54594.5 55432.0 55634.3 55644.7 73042.6 73452.6 78789.2 79764.0 80022.2 10630.1 10693.1 10769.6 11339.2 11604.7 11871.9 12394.6 12504.4 12768.8 13173.3 11555.8 11629.4 11737.6 11923.0 11969.0 11667.9 11946.9 12008.2 12059.6 12104.2 9027.5 11707.1 11734.4 11849.9 12159.8 9500.9 9576.6 9630.5 9645.1 10569.3 7332.4 8525.3 8727.9 9295.2 9367.5 04:08:40 INFO - 2015-11-24 04:08:40,179 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:08:40 INFO - 2015-11-24 04:08:40,179 DEBUG : BROWSER_OUTPUT: 04:08:40 INFO - 2015-11-24 04:08:40,569 INFO : Browser exited with error code: 0 04:08:40 INFO - 2015-11-24 04:08:40,593 INFO : Completed test dromaeo_css (00:18:33) 04:08:40 INFO - 2015-11-24 04:08:40,593 INFO : Starting test kraken 04:08:40 INFO - 2015-11-24 04:08:40,593 DEBUG : operating with platform_type : mac_ 04:08:40 INFO - 2015-11-24 04:08:40,594 INFO : Initialising browser for kraken test... 04:08:40 INFO - 2015-11-24 04:08:40,605 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmpIuOB6j/profile http://localhost:49264/getInfo.html 04:08:42 INFO - 2015-11-24 04:08:42,725 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:08:42 INFO - 2015-11-24 04:08:42,726 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:08:42 INFO - 2015-11-24 04:08:42,726 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 04:08:42 INFO - 2015-11-24 04:08:42,726 DEBUG : BROWSER_OUTPUT: __metrics 04:08:42 INFO - 2015-11-24 04:08:42,747 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:08:43 INFO - 2015-11-24 04:08:43,543 INFO : Browser initialized. 04:08:43 INFO - 2015-11-24 04:08:43,543 INFO : Running cycle 1/1 for kraken test... 04:08:43 INFO - 2015-11-24 04:08:43,544 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmpIuOB6j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:08:55 INFO - 2015-11-24 04:08:55,428 DEBUG : BROWSER_OUTPUT: RSS: Main: 226705408 04:08:55 INFO - 2015-11-24 04:08:55,428 DEBUG : BROWSER_OUTPUT: 04:08:57 INFO - 2015-11-24 04:08:57,928 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-beat-detection) 04:08:58 INFO - 2015-11-24 04:08:58,528 DEBUG : BROWSER_OUTPUT: RSS: Main: 275566592 04:08:58 INFO - 2015-11-24 04:08:58,528 DEBUG : BROWSER_OUTPUT: 04:09:01 INFO - 2015-11-24 04:09:01,062 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-dft) 04:09:01 INFO - 2015-11-24 04:09:01,661 DEBUG : BROWSER_OUTPUT: RSS: Main: 291225600 04:09:01 INFO - 2015-11-24 04:09:01,661 DEBUG : BROWSER_OUTPUT: 04:09:04 INFO - 2015-11-24 04:09:04,847 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-fft) 04:09:05 INFO - 2015-11-24 04:09:05,433 DEBUG : BROWSER_OUTPUT: RSS: Main: 277241856 04:09:05 INFO - 2015-11-24 04:09:05,433 DEBUG : BROWSER_OUTPUT: 04:09:07 INFO - 2015-11-24 04:09:07,319 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-oscillator) 04:09:07 INFO - 2015-11-24 04:09:07,924 DEBUG : BROWSER_OUTPUT: RSS: Main: 278728704 04:09:07 INFO - 2015-11-24 04:09:07,924 DEBUG : BROWSER_OUTPUT: 04:09:09 INFO - 2015-11-24 04:09:09,910 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:09:10 INFO - 2015-11-24 04:09:10,490 DEBUG : BROWSER_OUTPUT: RSS: Main: 280625152 04:09:10 INFO - 2015-11-24 04:09:10,490 DEBUG : BROWSER_OUTPUT: 04:09:13 INFO - 2015-11-24 04:09:13,179 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-darkroom) 04:09:13 INFO - 2015-11-24 04:09:13,771 DEBUG : BROWSER_OUTPUT: RSS: Main: 281423872 04:09:13 INFO - 2015-11-24 04:09:13,771 DEBUG : BROWSER_OUTPUT: 04:09:16 INFO - 2015-11-24 04:09:16,557 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-desaturate) 04:09:17 INFO - 2015-11-24 04:09:17,163 DEBUG : BROWSER_OUTPUT: RSS: Main: 282726400 04:09:17 INFO - 2015-11-24 04:09:17,163 DEBUG : BROWSER_OUTPUT: 04:09:20 INFO - 2015-11-24 04:09:20,014 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49264/tests/kraken/driver.html?testName=json-parse-financial) 04:09:20 INFO - 2015-11-24 04:09:20,614 DEBUG : BROWSER_OUTPUT: RSS: Main: 281448448 04:09:20 INFO - 2015-11-24 04:09:20,614 DEBUG : BROWSER_OUTPUT: 04:09:22 INFO - 2015-11-24 04:09:22,108 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49264/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:09:22 INFO - 2015-11-24 04:09:22,688 DEBUG : BROWSER_OUTPUT: RSS: Main: 316592128 04:09:22 INFO - 2015-11-24 04:09:22,688 DEBUG : BROWSER_OUTPUT: 04:09:24 INFO - 2015-11-24 04:09:24,450 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:09:25 INFO - 2015-11-24 04:09:25,068 DEBUG : BROWSER_OUTPUT: RSS: Main: 317538304 04:09:25 INFO - 2015-11-24 04:09:25,068 DEBUG : BROWSER_OUTPUT: 04:09:27 INFO - 2015-11-24 04:09:27,100 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:09:27 INFO - 2015-11-24 04:09:27,687 DEBUG : BROWSER_OUTPUT: RSS: Main: 324186112 04:09:27 INFO - 2015-11-24 04:09:27,687 DEBUG : BROWSER_OUTPUT: 04:09:30 INFO - 2015-11-24 04:09:30,191 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:09:30 INFO - 2015-11-24 04:09:30,814 DEBUG : BROWSER_OUTPUT: RSS: Main: 324300800 04:09:30 INFO - 2015-11-24 04:09:30,814 DEBUG : BROWSER_OUTPUT: 04:09:33 INFO - 2015-11-24 04:09:33,347 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:09:33 INFO - 2015-11-24 04:09:33,956 DEBUG : BROWSER_OUTPUT: RSS: Main: 323747840 04:09:33 INFO - 2015-11-24 04:09:33,957 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,610 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49264/tests/kraken/driver.html?testName=ai-astar) 04:09:35 INFO - 2015-11-24 04:09:35,870 DEBUG : BROWSER_OUTPUT: RSS: Main: 383770624 04:09:35 INFO - 2015-11-24 04:09:35,870 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,870 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:09:35 INFO - 2015-11-24 04:09:35,870 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:09:35 INFO - 2015-11-24 04:09:35,870 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:09:35 INFO - 2015-11-24 04:09:35,870 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:09:35 INFO - 2015-11-24 04:09:35,870 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;105;110;111;110;99;110;110;101 04:09:35 INFO - 2015-11-24 04:09:35,871 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;120;143;159;121;140;143;121;155;119 04:09:35 INFO - 2015-11-24 04:09:35,871 DEBUG : BROWSER_OUTPUT: |2;audio-dft;183;182;184;180;184;184;183;187;182;179 04:09:35 INFO - 2015-11-24 04:09:35,871 DEBUG : BROWSER_OUTPUT: |3;audio-fft;61;69;74;72;55;56;57;72;76;63 04:09:35 INFO - 2015-11-24 04:09:35,871 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;83;85;114;75;74;75;107;77;74;103 04:09:35 INFO - 2015-11-24 04:09:35,871 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;87;87;88;86;86;87;87;86;88;87 04:09:35 INFO - 2015-11-24 04:09:35,871 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;93;93;93;93;93;94;94;94;93;103 04:09:35 INFO - 2015-11-24 04:09:35,871 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;85;86;84;86;104;145;141;86;86;84 04:09:35 INFO - 2015-11-24 04:09:35,871 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;54;54;53;55;53;53;61;52;52 04:09:35 INFO - 2015-11-24 04:09:35,871 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;63;64;65;66;67;65;65;67;65 04:09:35 INFO - 2015-11-24 04:09:35,871 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;81;82;82;83;80;83;82;82;83 04:09:35 INFO - 2015-11-24 04:09:35,871 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;125;124;133;120;127;112;116;134;121;103 04:09:35 INFO - 2015-11-24 04:09:35,871 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;136;135;134;135;135;123;154;176;170;125 04:09:35 INFO - 2015-11-24 04:09:35,871 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;64;64;64;65;65;66;64;64;64 04:09:35 INFO - 2015-11-24 04:09:35,872 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:09:35 INFO - 2015-11-24 04:09:35,872 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:09:35 INFO - 2015-11-24 04:09:35,872 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1104 04:09:35 INFO - 2015-11-24 04:09:35,872 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:09:35 INFO - 2015-11-24 04:09:35,872 DEBUG : BROWSER_OUTPUT: __startTimestamp1448366975864__endTimestamp 04:09:35 INFO - 2015-11-24 04:09:35,872 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,872 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:09:35 INFO - 2015-11-24 04:09:35,872 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:09:35 INFO - 2015-11-24 04:09:35,872 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,872 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.40 Median:110.00 stddev:4.27 (3.9%) stddev-sans-first:4.53 04:09:35 INFO - 2015-11-24 04:09:35,872 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 105.0 110.0 111.0 110.0 99.0 110.0 110.0 101.0 04:09:35 INFO - 2015-11-24 04:09:35,872 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,872 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:137.10 Median:143.00 stddev:15.57 (10.9%) stddev-sans-first:15.80 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: Values: 150.0 120.0 143.0 159.0 121.0 140.0 143.0 121.0 155.0 119.0 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:182.80 Median:183.50 stddev:2.25 (1.2%) stddev-sans-first:2.39 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: Values: 183.0 182.0 184.0 180.0 184.0 184.0 183.0 187.0 182.0 179.0 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:65.50 Median:70.50 stddev:8.02 (11.4%) stddev-sans-first:8.34 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: Values: 61.0 69.0 74.0 72.0 55.0 56.0 57.0 72.0 76.0 63.0 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:86.70 Median:75.00 stddev:15.38 (20.5%) stddev-sans-first:16.26 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: Values: 83.0 85.0 114.0 75.0 74.0 75.0 107.0 77.0 74.0 103.0 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:87.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: Values: 87.0 87.0 88.0 86.0 86.0 87.0 87.0 86.0 88.0 87.0 04:09:35 INFO - 2015-11-24 04:09:35,873 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,874 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:94.30 Median:93.00 stddev:3.09 (3.3%) stddev-sans-first:3.24 04:09:35 INFO - 2015-11-24 04:09:35,874 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 93.0 93.0 93.0 94.0 94.0 94.0 93.0 103.0 04:09:35 INFO - 2015-11-24 04:09:35,874 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,874 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:98.70 Median:85.50 stddev:24.10 (28.2%) stddev-sans-first:25.04 04:09:35 INFO - 2015-11-24 04:09:35,874 DEBUG : BROWSER_OUTPUT: Values: 85.0 86.0 84.0 86.0 104.0 145.0 141.0 86.0 86.0 84.0 04:09:35 INFO - 2015-11-24 04:09:35,874 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,874 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:2.62 (4.8%) stddev-sans-first:2.76 04:09:35 INFO - 2015-11-24 04:09:35,874 DEBUG : BROWSER_OUTPUT: Values: 55.0 54.0 54.0 53.0 55.0 53.0 53.0 61.0 52.0 52.0 04:09:35 INFO - 2015-11-24 04:09:35,874 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,874 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:1.29 (2.0%) stddev-sans-first:1.30 04:09:35 INFO - 2015-11-24 04:09:35,874 DEBUG : BROWSER_OUTPUT: Values: 64.0 63.0 64.0 65.0 66.0 67.0 65.0 65.0 67.0 65.0 04:09:35 INFO - 2015-11-24 04:09:35,874 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,874 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:82.00 stddev:0.94 (1.1%) stddev-sans-first:1.00 04:09:35 INFO - 2015-11-24 04:09:35,875 DEBUG : BROWSER_OUTPUT: Values: 82.0 81.0 82.0 82.0 83.0 80.0 83.0 82.0 82.0 83.0 04:09:35 INFO - 2015-11-24 04:09:35,875 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,875 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.50 Median:124.50 stddev:9.44 (7.6%) stddev-sans-first:9.93 04:09:35 INFO - 2015-11-24 04:09:35,875 DEBUG : BROWSER_OUTPUT: Values: 125.0 124.0 133.0 120.0 127.0 112.0 116.0 134.0 121.0 103.0 04:09:35 INFO - 2015-11-24 04:09:35,875 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,875 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.30 Median:135.50 stddev:18.20 (13.4%) stddev-sans-first:19.16 04:09:35 INFO - 2015-11-24 04:09:35,875 DEBUG : BROWSER_OUTPUT: Values: 136.0 135.0 134.0 135.0 135.0 123.0 154.0 176.0 170.0 125.0 04:09:35 INFO - 2015-11-24 04:09:35,875 DEBUG : BROWSER_OUTPUT: 04:09:35 INFO - 2015-11-24 04:09:35,875 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:64.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 04:09:35 INFO - 2015-11-24 04:09:35,875 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 64.0 65.0 65.0 66.0 64.0 64.0 64.0 04:09:35 INFO - 2015-11-24 04:09:35,875 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:09:35 INFO - 2015-11-24 04:09:35,875 DEBUG : BROWSER_OUTPUT: 04:09:36 INFO - 2015-11-24 04:09:36,508 INFO : Browser exited with error code: 0 04:09:36 INFO - 2015-11-24 04:09:36,555 INFO : Completed test kraken (00:00:55) 04:09:36 INFO - 2015-11-24 04:09:36,555 INFO : Starting test v8_7 04:09:36 INFO - 2015-11-24 04:09:36,555 DEBUG : operating with platform_type : mac_ 04:09:36 INFO - 2015-11-24 04:09:36,555 INFO : Initialising browser for v8_7 test... 04:09:36 INFO - 2015-11-24 04:09:36,567 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmp6tHzBt/profile http://localhost:49264/getInfo.html 04:09:38 INFO - 2015-11-24 04:09:38,626 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:09:38 INFO - 2015-11-24 04:09:38,626 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:09:38 INFO - 2015-11-24 04:09:38,627 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 04:09:38 INFO - 2015-11-24 04:09:38,627 DEBUG : BROWSER_OUTPUT: __metrics 04:09:38 INFO - 2015-11-24 04:09:38,644 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:09:38 INFO - 2015-11-24 04:09:38,907 DEBUG : BROWSER_OUTPUT: console.error: 04:09:38 INFO - 2015-11-24 04:09:38,907 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction. 04:09:38 INFO - 2015-11-24 04:09:38,907 DEBUG : BROWSER_OUTPUT: Stack: 04:09:38 INFO - 2015-11-24 04:09:38,907 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 04:09:38 INFO - 2015-11-24 04:09:38,908 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 04:09:38 INFO - 2015-11-24 04:09:38,908 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 04:09:38 INFO - 2015-11-24 04:09:38,909 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 04:09:38 INFO - 2015-11-24 04:09:38,909 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 04:09:38 INFO - 2015-11-24 04:09:38,909 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 04:09:38 INFO - 2015-11-24 04:09:38,909 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 04:09:38 INFO - 2015-11-24 04:09:38,909 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7 04:09:38 INFO - 2015-11-24 04:09:38,909 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 04:09:38 INFO - 2015-11-24 04:09:38,909 DEBUG : BROWSER_OUTPUT: 04:09:39 INFO - 2015-11-24 04:09:39,303 INFO : Browser initialized. 04:09:39 INFO - 2015-11-24 04:09:39,303 INFO : Running cycle 1/1 for v8_7 test... 04:09:39 INFO - 2015-11-24 04:09:39,303 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/T/tmp6tHzBt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:09:51 INFO - 2015-11-24 04:09:51,224 DEBUG : BROWSER_OUTPUT: RSS: Main: 226172928 04:09:51 INFO - 2015-11-24 04:09:51,225 DEBUG : BROWSER_OUTPUT: 04:10:12 INFO - 2015-11-24 04:10:12,576 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/v8_7/run.html (next: http://localhost:49264/tests/v8_7/run.html) 04:10:12 INFO - 2015-11-24 04:10:12,832 DEBUG : BROWSER_OUTPUT: RSS: Main: 713261056 04:10:12 INFO - 2015-11-24 04:10:12,832 DEBUG : BROWSER_OUTPUT: 04:10:12 INFO - 2015-11-24 04:10:12,832 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:10:12 INFO - 2015-11-24 04:10:12,832 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:10:12 INFO - 2015-11-24 04:10:12,832 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:10:12 INFO - 2015-11-24 04:10:12,832 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:10:12 INFO - 2015-11-24 04:10:12,832 DEBUG : BROWSER_OUTPUT: |0;Richards;176.95983011856308 04:10:12 INFO - 2015-11-24 04:10:12,832 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;190.3311762466692 04:10:12 INFO - 2015-11-24 04:10:12,832 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.5315315315315 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4716.981132075472 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1765.432098765432 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: |5;Earley;825.7638315441784 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: |6;Boyer;12432.098765432098 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: |7;RegExp;27108.108108108107 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: |8;Splay;647.2491909385113 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4864.077669902913 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: __startTimestamp1448367012827__endTimestamp 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: Number of tests: 10 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: 04:10:12 INFO - 2015-11-24 04:10:12,833 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:176.96 Median:176.96 stddev:0.00 (0.0%) 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: Values: 177.0 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:190.33 Median:190.33 stddev:0.00 (0.0%) 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: Values: 190.3 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.53 Median:281.53 stddev:0.00 (0.0%) 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: Values: 281.5 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4716.98 Median:4716.98 stddev:0.00 (0.0%) 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: Values: 4717.0 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1765.43 Median:1765.43 stddev:0.00 (0.0%) 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: Values: 1765.4 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: 04:10:12 INFO - 2015-11-24 04:10:12,834 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:825.76 Median:825.76 stddev:0.00 (0.0%) 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: Values: 825.8 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12432.10 Median:12432.10 stddev:0.00 (0.0%) 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: Values: 12432.1 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:27108.11 Median:27108.11 stddev:0.00 (0.0%) 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: Values: 27108.1 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:647.25 Median:647.25 stddev:0.00 (0.0%) 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: Values: 647.2 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4864.08 Median:4864.08 stddev:0.00 (0.0%) 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: Values: 4864.1 04:10:12 INFO - 2015-11-24 04:10:12,835 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:10:12 INFO - 2015-11-24 04:10:12,836 DEBUG : BROWSER_OUTPUT: 04:10:13 INFO - 2015-11-24 04:10:13,473 INFO : Browser exited with error code: 0 04:10:13 INFO - 2015-11-24 04:10:13,490 INFO : Completed test v8_7 (00:00:36) 04:10:13 INFO - 2015-11-24 04:10:13,532 INFO : Completed test suite (00:20:06) 04:10:13 INFO - 2015-11-24 04:10:13,532 DEBUG : Working with test: dromaeo_css 04:10:13 INFO - 2015-11-24 04:10:13,533 DEBUG : Generating results file: dromaeo_css 04:10:13 INFO - 2015-11-24 04:10:13,533 DEBUG : Working with test: kraken 04:10:13 INFO - 2015-11-24 04:10:13,533 DEBUG : Generating results file: kraken 04:10:13 INFO - 2015-11-24 04:10:13,534 INFO : javascript benchmark 04:10:13 INFO - 2015-11-24 04:10:13,534 DEBUG : Working with test: v8_7 04:10:13 INFO - 2015-11-24 04:10:13,534 DEBUG : Generating results file: v8_7 04:10:13 INFO - 2015-11-24 04:10:13,534 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:10:14 INFO - 2015-11-24 04:10:14,010 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,59]] 04:10:14 INFO - 2015-11-24 04:10:14,010 DEBUG : process_Request line: dromaeo_css 7733.92 graph.html#tests=[[72,1,59]] 04:10:14 INFO - 2015-11-24 04:10:14,010 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:10:14 INFO - 2015-11-24 04:10:14,127 DEBUG : process_Request line: kraken 1388.90 graph.html#tests=[[232,1,59]] 04:10:14 INFO - 2015-11-24 04:10:14,127 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:10:14 INFO - 2015-11-24 04:10:14,263 DEBUG : process_Request line: v8_7 19742.69 graph.html#tests=[[230,1,59]] 04:10:14 INFO - 2015-11-24 04:10:14,264 INFO : javascript benchmark 04:10:14 INFO - 2015-11-24 04:10:14,266 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3134.0, 3145.6539780624134, 3269.002954098999, 3519.6480351964806, 3547.858085676573, 5552.805651802188, 5736.913946290809, 5798.130280457934, 5834.941650583492, 5878.412158784125, 5746.396628354015, 5792.7972520961775, 5894.0864166054225, 6030.125631783389, 6057.697115144236, 5156.381234251895, 5175.223716442535, 5197.350331208599, 5229.058769421503, 5292.973535132319, 5308.283381743454, 5319.654222475527, 5333.120035194208, 5338.946610533903, 5380.946190538103, 5036.169032109698, 5166.0184564932915, 5172.353455818023, 5269.235960785683, 5326.067938110815, 5221.981713565856, 5230.163173892159, 5230.895382092375, 5233.659812112239, 5244.580433565344, 5002.049610573979, 5146.202338637517, 5178.482151784829, 5178.766955487012, 5195.220716892459, 5728.97135514324, 5785.27684039495, 5794.246747922785, 5805.477507024388, 5839.591228613998, 6045.00523258984, 6140.9385906140815, 6197.504199664061, 6247.468965137969, 6250.374962503759, 6146.555559443527, 6222.782202622887, 6262.530310226751, 6281.026440901667, 6294.150289710876, 5976.432238937294, 6013.729382177812, 6038.245219347582, 6052.727627256784, 6065.60573562717, 5379.488948549882, 6207.34822843604, 6324.1778568786685, 6338.904916426257, 6416.903746443807, 6113.113598528277, 6196.10156527301, 6212.006079027334, 6214.005759078616, 6216.036514340284, 6267.874642507216, 6280.842978925563, 6282.2147231596055, 6290.308066112724, 6294.527910406738, 6102.328743838263, 6135.845176233341, 6221.3156552779155, 6232.0, 6233.0338797486465, 5307.336582927134, 6101.3079194897555, 6229.937700623026, 6239.1577137085915, 6250.781222657185, 4993.0, 5030.4215015272985, 5036.269740887551, 5036.899262014739, 5037.319961805109, 4420.9336859947125, 4532.977335113369, 4542.341360502709, 4558.49856515783, 4568.109218702321, 5755.510781583613, 5836.241288632451, 5840.328362238312, 5856.180134781134, 5877.735501902424, 6285.057241413733, 6295.937040629627, 6300.1494798202575, 6305.054241863757, 6308.243010838729, 5614.29821272341, 5752.712364381764, 5763.9135412968835, 5779.942200578024, 5817.767289308465, 4623.491415944243, 5382.650127741685, 5425.186222066643, 5452.991196628636, 5490.956718223604, 6295.118683384329, 6324.494040476751, 6339.746410143543, 6347.1114044033875, 6352.68236588178, 4830.961343311205, 5580.190872323572, 6236.34518375564, 6273.121762953099, 6295.779647712308, 5703.515201207943, 6239.875202496016, 6268.341824108403, 6299.338569450142, 6299.496040316855, 5940.376260492759, 6142.969285153546, 6166.969165154325, 6198.132261483306, 6208.348123447154, 6221.502279817513, 6268.404501572343, 6297.118403423616, 6303.590266632654, 6316.589421687576, 6190.721417536131, 6200.131981522501, 6207.937920620917, 6214.098955651496, 6230.127782110418, 5015.774290156879, 5221.425643179323, 6091.55698489275, 6122.969385153046, 6196.442320191204, 5971.223740913653, 5974.2233509643465, 5978.581499295007, 5997.490213331916, 5998.430149135826, 6033.117710232488, 6090.543209259235, 6092.299385570539, 6194.721237544411, 6203.038529028008, 6057.363976782551, 6087.178230938944, 6090.69546522681, 6108.267007959073, 6108.938910610837], "unit": "ms", "name": "dojo.html", "value": 5709.295496876314}, {"lowerIsBetter": false, "replicates": [575.8445219790653, 584.5586582130491, 599.8590331272153, 631.1763149090438, 655.947524198064, 32949.682012719495, 42232.0, 42379.0, 42451.78774106133, 42482.0, 44889.32666010012, 45267.86830329235, 45422.86442838922, 45510.31734523985, 45540.77229613847, 8538.70114545991, 8703.390762646617, 8871.979722331918, 9840.261980351484, 10043.0, 9768.951155244215, 12138.575149869755, 12140.392980350991, 12156.756864862697, 12183.14717969738, 11904.40477976102, 11982.64052078439, 12016.09879259059, 12022.098342624295, 12031.879681203207, 8923.241524470428, 9083.727488175366, 11888.405579721022, 11946.522139114424, 12003.0, 11738.237014594024, 11902.226355286879, 11911.404429778562, 11915.88084119165, 11937.940310298392, 41336.37995430071, 41899.7905010476, 41929.79035104835, 41987.7900610498, 42001.36997945033, 2808.1996630960284, 2940.3825196708717, 3266.8856590019236, 3419.4472389202556, 3443.4146195146886, 28881.765674929935, 29497.262568435744, 29595.964141254954, 30905.690943090507, 31169.75320987158, 2822.9576556351467, 2870.0959197852817, 2877.079334612925, 2887.5524293734293, 2901.8839246430116, 493502.0, 498750.0, 499514.0, 499770.0, 504036.0, 73076.269237308, 73587.26412735911, 73639.26360736325, 73704.20922640011, 73705.63147184337, 77130.0, 77496.61251693705, 77596.0, 77623.0, 77662.83505747418, 16539.83460165407, 16612.591174279663, 16676.916615416845, 16696.41562545305, 16711.331546737994, 12501.0, 12591.181573197713, 12598.118131730691, 12600.0, 12600.684982875502, 17349.617960291413, 18353.81646183521, 22650.093996240295, 22731.659025114637, 22867.542649146922, 7648.770536883902, 8319.459235149696, 9370.29722770795, 9609.615615375447, 9622.9895860935, 847.4491580472619, 961.3943215774018, 1019.50553981319, 1089.1123734156638, 1126.2116518437126, 29893.40213195724, 30049.248768780955, 30416.39167216643, 30582.23544411363, 30606.38787224243, 996.7473509867751, 1015.1219195395929, 1114.7101753544136, 1163.6741712320563, 1190.9876604885778, 14826.0, 15640.765388519181, 16942.57643558921, 17108.973461592344, 17116.74324885128, 585000.0, 590111.0, 590214.0, 596232.0, 597166.0850174502, 58848.41151588515, 59557.0, 59601.0, 64363.03455448172, 68517.65741171362, 1491.850814918543, 1499.3402902722767, 1502.9929946935797, 1515.0228102873584, 1650.290375138678, 28647.14058578246, 30925.0, 32500.83749581237, 32883.587700398195, 33010.66989330172, 50864.49135508598, 51179.0, 55326.0, 58570.0, 59023.0, 421190.0, 426302.0, 429043.70956290036, 430345.0, 432084.0, 43292.35061474144, 43521.0, 43887.56112438835, 44026.55973440353, 44554.0, 47461.0, 47520.7623961878, 47691.523084770095, 47752.28371574498, 49078.0, 39645.00887477836, 39651.0, 39855.40216896807, 40127.59872401239, 40701.59298406978, 34270.0, 38938.610613894634, 38999.2200156001, 40008.399874001334, 40074.79962600285, 39476.80261598789, 39545.80227098846, 39637.60362396455, 39663.603363965995, 40722.77831665055, 32299.677003229666, 34151.829240853636, 34671.95984120481, 34844.303113938084, 35884.28231435409, 25881.352966175997, 26012.609810852475, 26107.955681772517, 26164.73835261623, 26429.20712378555], "unit": "ms", "name": "ext.html", "value": 21259.935665443078}, {"lowerIsBetter": false, "replicates": [329.73785840257, 350.1805774487699, 357.46023504508184, 361.3459638055119, 363.8199091449733, 15959.202039897988, 17050.914745426257, 19930.402087937367, 20490.795092049077, 21232.57534849304, 18435.446936591925, 18502.352417665385, 18722.81277187228, 18941.863488190716, 18957.0, 5174.327337446136, 5603.075492543725, 5967.970160149215, 5968.373320801318, 5971.253593300838, 4260.595243451867, 4372.497162826268, 4403.603675669189, 5448.618596698233, 5866.677332746697, 5722.885542289152, 5850.268716410449, 6295.401936816018, 6357.618542887419, 6375.617462952215, 6389.872202555946, 6433.999999999976, 6445.0, 6448.742050317982, 6470.611763294218, 5408.188771684239, 5496.1755736639425, 5517.200005999148, 5538.501534861842, 5539.25220095286, 25083.49833003348, 25113.372165696004, 25228.747712523007, 25537.361565960815, 25554.36114097144, 2189.397915573214, 2268.1834539565743, 2374.062245413052, 2436.3300092474533, 2447.3881529617597, 15799.131047792367, 15926.0, 16020.279087441093, 16168.24156021774, 16527.50417487477, 1532.7470967290383, 1854.9258029678797, 1873.4660621722744, 1898.506388339028, 1909.9809001909941, 103693.0, 109958.0, 115574.0, 117444.0, 118225.40887295509, 16791.076490793123, 18324.54188645268, 19579.161021652064, 19686.803131968496, 19704.113314900864, 28805.423891522052, 28841.0, 28846.278843029093, 28910.855445723057, 28934.42131157407, 9113.997460279365, 10126.797464050826, 10162.4918754062, 10237.18102551794, 10262.743431414274, 7957.960210199028, 8591.957040214758, 8595.226429621363, 8659.0, 8680.392372533863, 7340.743073992385, 8150.858879756839, 8896.510691912006, 8943.865842012376, 8979.775505612412, 3147.9527807082914, 3417.1627951152127, 3428.2286485540553, 3430.902111324402, 3572.9464058039152, 393.1449098211379, 393.2842227146623, 422.3664503245132, 938.2915898496727, 1117.3631030312645, 10601.893981060244, 11242.0, 11272.774544509064, 11521.0, 11813.0, 440.6650945281545, 442.5353378952087, 454.9818007279738, 457.83060267701165, 465.4159030416805, 5959.0, 5981.132735753292, 6060.727267272983, 6063.242094738158, 6069.089636554464, 189746.0, 190542.0, 191097.0, 754653.0, 767940.160299195, 98292.01707982829, 115150.42424787823, 116223.0, 122259.0, 125350.0, 474.6392741516472, 487.58555228055866, 487.9853604391945, 488.40902507965757, 493.0877875929502, 10564.418956957441, 10968.725781855517, 18416.17127229251, 18693.906530467262, 19107.331243406414, 50723.49276507335, 52040.0, 52160.47839521556, 52412.21381679354, 52942.67643308948, 71697.0, 71760.64119679369, 72291.0, 73753.0, 75021.12447188864, 9928.007199280015, 10034.147097496503, 10153.543090560795, 10410.323328983592, 10410.68767936963, 20132.79867201368, 20261.49346266355, 20443.38669839907, 20643.58712825765, 20990.790092099494, 13344.799828002782, 16163.353465861705, 16322.755158672868, 16350.836491635408, 16357.100359480342, 15728.685426291182, 15922.840771592137, 16082.195890205678, 16245.0, 16299.022058676517, 16689.749653755447, 17120.058396788296, 17182.0, 17194.398196063077, 17235.65528689394, 9855.014498550088, 9876.308658393844, 9893.307468477426, 9950.203983681433, 9958.452285124387, 10263.538140783832, 10323.63867264642, 14927.850721492647, 16226.918865405598, 16610.418635347705], "unit": "ms", "name": "jquery.html", "value": 9889.844566345964}, {"lowerIsBetter": false, "replicates": [1591.594143493409, 1593.442295196682, 1696.8472837444629, 1889.288870470874, 1905.3712274949269, 7524.510906791062, 7690.769276921695, 8161.836763264739, 8237.464564803284, 8323.25090741833, 8276.337892968562, 8407.663693452254, 8437.873431898512, 8448.4508506947, 8455.830883382328, 2381.583222935988, 2403.975960240393, 2404.4469771952463, 2406.5186962607463, 2408.229366602688, 2009.7387339645898, 2411.6985376827897, 2416.8066554675584, 2426.8786560671897, 2427.8057755379527, 2141.668041453577, 2302.9193978210765, 2399.7960173385195, 2404.1224952892244, 2414.7102347718296, 2364.728056273533, 2383.2731017039796, 2396.35298469414, 2397.976020239793, 2399.076355603088, 2380.904763809463, 2393.5571919195004, 2419.0, 2421.3825474503888, 2430.9149179778624, 7576.4317676174505, 8386.287165590933, 8418.536980466071, 8425.747227583182, 8431.662733490653, 4762.333273341735, 4816.325714399987, 4826.61387089032, 4973.781423551217, 5102.719350435725, 3517.7537572369943, 3527.7177825773874, 3583.301256255031, 3594.0, 3620.2759448110482, 1031.56243484477, 1046.3251202974116, 1050.5325130317012, 1056.799208150449, 1060.2631171335925, 11243.269187502787, 11302.773944521146, 11303.660890173307, 11317.264377815416, 11383.14626403023, 4633.397658304399, 4679.766011699401, 4693.366395536627, 4755.595774359149, 4768.332433459318, 8324.66701331952, 9789.902100979041, 9817.901820981699, 9823.312368134304, 9835.459049752333, 5067.265246539305, 5082.949170508247, 5088.389393272831, 5104.668196567285, 5107.642465027487, 4576.542345765463, 4626.768661566908, 4630.3748993886065, 4634.629229661619, 4637.65217608674, 3613.2050948791225, 3701.759385639925, 3709.8330575124182, 3723.1809002019513, 3748.5876553579083, 1226.9815952760716, 1240.925544467335, 1245.439552201513, 1248.6066888929959, 1249.0507214517033, 1374.3334482775845, 1379.7309524642599, 1413.971720565583, 1416.1361569442631, 1416.3838730152418, 7776.688932442639, 7922.920770792333, 7936.56348900816, 7937.722179723682, 7939.563324017119, 482.6959015820081, 494.69329016010306, 495.4030393375973, 500.412015881338, 500.86476651303946, 5804.94195058044, 5816.505597024216, 5861.443162899518, 5864.736086876101, 6021.819345419644, 8979.865302020475, 9767.902320976842, 10171.7457063574, 10174.0, 10193.847092293621, 9930.602775889029, 10077.69766906994, 10205.489725513695, 10223.897761022443, 10237.334573252714, 7888.960555197187, 7991.600419978978, 7992.84014319722, 8061.153578874251, 8075.475094118746, 7377.8155546111775, 7699.8846700227, 7773.92226077732, 7807.219278072147, 7819.569923654253, 9060.592273347847, 9115.787581274937, 9130.863037054316, 9154.130357616015, 9290.024547422694, 9245.491497967676, 9379.062093790566, 9381.96798352167, 9398.530073496435, 9456.101670341306, 7117.0, 7145.64271786419, 7151.678174482056, 7183.497155199086, 7243.927560724325, 7967.083785364525, 8016.559089250147, 8025.438219324591, 8028.0, 8028.1169071403265, 7362.153352364547, 7472.663730132048, 7488.438367122597, 7618.657160427683, 7628.46600737943, 7342.0, 7348.412127029718, 7382.5570465772225, 7474.663640136316, 7540.886886696594, 7488.663010164447, 7527.059117610299, 7547.962260188664, 7559.886601700869, 7572.4699271050495, 6802.14973128359, 6825.55633883796, 6864.176298844171, 6893.345132212432, 6895.0, 6920.653967301514, 6964.199117101395, 6972.372486476242, 6998.930010699828, 7001.719931202695], "unit": "ms", "name": "mootools.html", "value": 4687.727649917372}, {"lowerIsBetter": false, "replicates": [304.90090720515826, 321.81334825801036, 327.054811594492, 332.931290557311, 333.853104633961, 6825.112735344404, 6894.517383783137, 6899.0, 6930.376266136047, 6950.235474097845, 5762.3373312069125, 6581.144451221347, 6837.076994605727, 6858.211305699846, 6863.7940861774105, 5039.3196918415915, 5040.773165207556, 5048.29323894655, 5056.974715126419, 5057.974710126463, 4848.0, 4984.700917944917, 5045.545900868921, 5062.443131255559, 5077.238414237857, 4972.776225069862, 4995.750212489379, 4996.650234483587, 5006.899862002758, 5015.0, 4291.9356209657035, 4744.952550474485, 4834.323194752737, 4841.128596852565, 4878.000009997977, 4166.979165104155, 4179.811908464127, 4182.811773470201, 4207.64235040022, 4214.789260536992, 5945.16767652529, 6350.269718982284, 6567.93432065678, 6583.275839657634, 6623.834404139935, 5809.448102430262, 5982.04287314032, 6006.579539432241, 6007.4893634041155, 6049.788257411014, 7006.929930700679, 7135.357817796424, 7290.526115802456, 7328.120625524918, 7401.444891633095, 4767.880802979953, 4797.544233297831, 4806.206975848981, 4812.494688057774, 4866.610671146335, 7331.853362932711, 7751.612419379008, 7855.175206603281, 7859.882101768478, 7864.449488535862, 4785.138675038457, 4903.975480122576, 4920.40955085392, 4973.8010479581135, 5004.374453193351, 5086.9745651271505, 5088.287639730441, 5088.389393272757, 5089.160288552422, 5101.336826212643, 5997.340292567814, 6001.729922153513, 6005.159277701126, 6005.879882402328, 6007.879842403127, 7176.210616832144, 7464.85070298591, 7472.364848987897, 7474.514156579805, 7484.925150748531, 1750.544858336816, 1776.7690200273883, 1779.9288028478716, 1780.0298837133794, 1786.0801687131138, 1134.8978591926766, 1141.241074685325, 1153.763478486908, 1165.155262434728, 1166.5333866453486, 5020.623453241022, 6749.021391898147, 6914.481413893978, 6917.0, 6919.4464442844455, 7300.890486642705, 7689.884651730228, 7691.423143264277, 7718.498297610749, 7735.381169506426, 6673.165854268216, 6848.931510684929, 6854.554453960476, 6883.793486195422, 6906.689198986058, 4037.2127435149864, 4061.5329237137516, 4063.1264278180333, 4065.0447144920918, 4071.4707088078953, 6414.61512309263, 7970.083440404312, 8027.157148499324, 8035.357171426272, 8036.959815201004, 7762.456628035983, 7987.281144697005, 8015.198480152055, 8022.037355517258, 8054.536549283865, 7668.424868134912, 7794.142644309008, 7901.920980790233, 7916.84166316682, 8026.759197223976, 6406.391392817675, 7491.587962662106, 7650.579218143055, 7654.0, 7781.0, 5454.59090568201, 6677.09859169006, 7476.813079673052, 7622.69509219625, 7656.923430765621, 6437.0, 8003.399745019264, 8043.316318113026, 8050.677972881019, 8081.111077781557, 4775.403074615673, 4810.062037902575, 4814.783334750015, 4825.131476334293, 4830.686005409637, 6268.811935641938, 7446.962765186356, 7712.113106992768, 7739.7678069658, 7744.419168562267, 7471.327580517779, 7473.51422157558, 7476.5514069156025, 7481.663325150273, 7534.811629709082, 7416.851662966603, 7448.068991376078, 7458.850822983619, 7459.0, 7466.0, 7009.474289428212, 7257.201707812031, 7264.200937896932, 7265.418766498562, 7266.0, 6689.698963546554, 6734.562253453706, 7414.369778568666, 7417.851642967218, 7434.7769566913075, 6435.710393032418, 7024.894626580707, 7244.673989670583, 7256.492045556761, 7267.636618168965], "unit": "ms", "name": "prototype.html", "value": 5348.384925291586}, {"lowerIsBetter": false, "replicates": [769.1308821032231, 796.6216047377495, 807.6365635464039, 815.64927081355, 816.9346452283814, 14566.927165364172, 15075.095494270337, 15130.470433534829, 15159.242037898117, 15159.317830697617, 14049.227292498908, 14194.290285485737, 14196.503122390719, 14204.715905681905, 14206.14763114214, 760.3461023519768, 774.9925097373392, 775.3642013548892, 960.1071003966308, 983.6065573770513, 965.884093908732, 974.3082411487852, 974.44456659704, 976.5312649928039, 977.1108291454777, 814.242754238558, 1018.7402212435818, 1019.2050200843317, 1022.2946167144684, 1022.9539670714798, 820.6676296100089, 1012.0790081026267, 1027.578692735982, 1031.2008193649906, 1031.9432431216355, 770.630097553172, 774.0595176860141, 774.44240147094, 774.7908064822466, 777.4829738224131, 14996.250187490581, 15160.0, 15173.924130379277, 15186.392544298214, 15216.087034777838, 12765.297908615023, 12928.741425171444, 13013.349332533335, 13291.933540332333, 13311.733765324736, 10224.0, 10279.383237005804, 10389.84415233772, 10723.946380268128, 11060.889391106066, 3278.360719659667, 3409.2840503494294, 3556.5198698175673, 3606.7294952878638, 3633.9273214535824, 73800.63099684521, 74433.62783186049, 74688.25311746921, 83719.0, 83839.5808020956, 9814.607415703293, 9894.356866803635, 9898.356606820533, 9900.851487227697, 10396.584136634601, 22524.324270271918, 23341.94961226749, 23368.298951031495, 23418.76581234166, 23500.412489687893, 7370.0, 7395.112586489656, 7515.962420187864, 7540.245975402416, 7722.266384693445, 3869.6710779583486, 3949.0522274653877, 3962.722609417313, 3974.980125099356, 4038.273110840077, 3555.822208889545, 3601.459781032866, 3602.0814692253307, 3602.081469225383, 3606.8376923038527, 657.5495785387026, 658.808945405827, 665.6904539389208, 668.669008840627, 673.5419914458215, 493.0, 502.27170602626336, 514.4418306137808, 517.570416554259, 534.9384820745664, 8284.544350060683, 8530.402871799039, 8558.871616925751, 8561.48631082137, 8832.160944710242, 540.7080176704535, 548.569373042168, 566.6260268222953, 625.3090335179652, 663.2604645819877, 3795.9240815184094, 3859.845606175778, 3904.8047597620007, 4084.81618327176, 4175.290200665833, 102360.0, 104233.0, 105502.0, 105533.94466055241, 105569.47215263874, 87624.0, 89298.55350723205, 89543.10456895348, 89917.0, 90595.54702226579, 77999.61000194962, 78781.0, 79030.20969790229, 79126.60436697895, 80602.0, 6931.250404746191, 7366.300201480851, 7982.880256796268, 8372.162783721822, 8425.115362887054, 53215.0, 54594.45405545894, 55432.0, 55634.330970070965, 55644.72177639248, 73042.63478682573, 73452.63273683598, 78789.2121078782, 79764.0, 80022.19977800148, 10630.149588033093, 10693.14454843595, 10769.62306319275, 11339.206255562032, 11604.709882252673, 11871.881281187078, 12394.62816115518, 12504.43730032169, 12768.808467873176, 13173.341332933507, 11555.768884622428, 11629.418529073682, 11737.647870563897, 11923.0, 11969.0, 11667.883321167019, 11946.940265298617, 12008.219465734699, 12059.638210853689, 12104.213226140273, 9027.516019600587, 11707.121965852766, 11734.413279335828, 11849.881501185222, 12159.81760273579, 9500.857487137837, 9576.56905439243, 9630.47032413224, 9645.08371704687, 10569.28268205065, 7332.376747976268, 8525.317974562144, 8727.91272087271, 9295.163435290857, 9367.531623418938], "unit": "ms", "name": "yui.html", "value": 7109.553245253738}], "name": "dromaeo_css", "value": 7733.924822937521}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 105.0, 110.0, 111.0, 110.0, 99.0, 110.0, 110.0, 101.0], "unit": "ms", "name": "ai-astar", "value": 107.4}, {"lowerIsBetter": true, "replicates": [150.0, 120.0, 143.0, 159.0, 121.0, 140.0, 143.0, 121.0, 155.0, 119.0], "unit": "ms", "name": "audio-beat-detection", "value": 137.1}, {"lowerIsBetter": true, "replicates": [183.0, 182.0, 184.0, 180.0, 184.0, 184.0, 183.0, 187.0, 182.0, 179.0], "unit": "ms", "name": "audio-dft", "value": 182.8}, {"lowerIsBetter": true, "replicates": [61.0, 69.0, 74.0, 72.0, 55.0, 56.0, 57.0, 72.0, 76.0, 63.0], "unit": "ms", "name": "audio-fft", "value": 65.5}, {"lowerIsBetter": true, "replicates": [83.0, 85.0, 114.0, 75.0, 74.0, 75.0, 107.0, 77.0, 74.0, 103.0], "unit": "ms", "name": "audio-oscillator", "value": 86.7}, {"lowerIsBetter": true, "replicates": [87.0, 87.0, 88.0, 86.0, 86.0, 87.0, 87.0, 86.0, 88.0, 87.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 86.9}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0, 103.0], "unit": "ms", "name": "imaging-darkroom", "value": 94.3}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 84.0, 86.0, 104.0, 145.0, 141.0, 86.0, 86.0, 84.0], "unit": "ms", "name": "imaging-desaturate", "value": 98.7}, {"lowerIsBetter": true, "replicates": [55.0, 54.0, 54.0, 53.0, 55.0, 53.0, 53.0, 61.0, 52.0, 52.0], "unit": "ms", "name": "json-parse-financial", "value": 54.2}, {"lowerIsBetter": true, "replicates": [64.0, 63.0, 64.0, 65.0, 66.0, 67.0, 65.0, 65.0, 67.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 65.1}, {"lowerIsBetter": true, "replicates": [82.0, 81.0, 82.0, 82.0, 83.0, 80.0, 83.0, 82.0, 82.0, 83.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 82.0}, {"lowerIsBetter": true, "replicates": [125.0, 124.0, 133.0, 120.0, 127.0, 112.0, 116.0, 134.0, 121.0, 103.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 121.5}, {"lowerIsBetter": true, "replicates": [136.0, 135.0, 134.0, 135.0, 135.0, 123.0, 154.0, 176.0, 170.0, 125.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 142.3}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 64.0, 65.0, 65.0, 66.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.4}], "name": "kraken", "value": 1388.9}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [176.95983011856308], "unit": "score", "name": "Richards", "value": 199.4916020000001}, {"lowerIsBetter": false, "replicates": [190.3311762466692], "unit": "score", "name": "DeltaBlue", "value": 347.3839720000001}, {"lowerIsBetter": false, "replicates": [281.5315315315315], "unit": "score", "name": "Encrypt", "value": 945.4749119999998}, {"lowerIsBetter": false, "replicates": [4716.981132075472], "unit": "score", "name": "Decrypt", "value": 56.430372000000034}, {"lowerIsBetter": false, "replicates": [1765.432098765432], "unit": "score", "name": "RayTrace", "value": 419.1546083916085}, {"lowerIsBetter": false, "replicates": [825.7638315441784], "unit": "score", "name": "Earley", "value": 807.0866929999997}, {"lowerIsBetter": false, "replicates": [12432.098765432098], "unit": "score", "name": "Boyer", "value": 53.608245283018846}, {"lowerIsBetter": false, "replicates": [27108.108108108107], "unit": "score", "name": "RegExp", "value": 33.60562811565303}, {"lowerIsBetter": false, "replicates": [647.2491909385113], "unit": "score", "name": "Splay", "value": 125.90359500000004}, {"lowerIsBetter": false, "replicates": [4864.077669902913], "unit": "score", "name": "NavierStokes", "value": 305.0938123752493}], "name": "v8_7", "value": 19742.690611098613}]} 04:10:14 INFO - RETURN: dromaeo_css: 7733.92 04:10:14 INFO - RETURN: kraken: 1388.90 04:10:14 INFO - RETURN: v8_7: 19742.69 04:10:14 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,59]]", "result": "19742.69"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,59]]", "result": "1388.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,59]]", "result": "7733.92"}}} 04:10:14 INFO - Return code: 0 04:10:14 INFO - # TBPL SUCCESS # 04:10:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:10:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:10:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:10:14 INFO - 2015-11-24 04:10:14.342 screenresolution[1678:9889] starting screenresolution argv=screenresolution get 04:10:14 INFO - 2015-11-24 04:10:14.345 screenresolution[1678:9889] Display 0: 1600x1200x32@60 04:10:14 INFO - 2015-11-24 04:10:14.351 screenresolution[1679:9892] starting screenresolution argv=screenresolution list 04:10:14 INFO - Available Modes on Display 0 04:10:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:10:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:10:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:10:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:10:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:10:14 INFO - Intel Iris: 04:10:14 INFO - Chipset Model: Intel Iris 04:10:14 INFO - Type: GPU 04:10:14 INFO - Bus: Built-In 04:10:14 INFO - VRAM (Dynamic, Max): 1536 MB 04:10:14 INFO - Vendor: Intel (0x8086) 04:10:14 INFO - Device ID: 0x0a2e 04:10:14 INFO - Revision ID: 0x0009 04:10:14 INFO - Displays: 04:10:14 INFO - Display: 04:10:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:10:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:10:14 INFO - Display Serial Number: 1600x1200 60 04:10:14 INFO - Main Display: Yes 04:10:14 INFO - Mirror: Off 04:10:14 INFO - Online: Yes 04:10:14 INFO - Rotation: Supported 04:10:14 INFO - Return code: 0 04:10:14 INFO - Running post-action listener: _resource_record_post_action 04:10:14 INFO - Running post-run listener: _resource_record_post_run 04:10:15 INFO - Total resource usage - Wall time: 1238s; CPU: 53.0%; Read bytes: 38905344; Write bytes: 688198656; Read time: 428; Write time: 2000 04:10:15 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 337867264; Write bytes: 336676864; Read time: 26128; Write time: 574 04:10:15 INFO - run-tests - Wall time: 1208s; CPU: 53.0%; Read bytes: 37950976; Write bytes: 341093376; Read time: 409; Write time: 1389 04:10:15 INFO - Running post-run listener: _upload_blobber_files 04:10:15 INFO - Blob upload gear active. 04:10:15 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:10:15 INFO - Copying logs to upload dir... 04:10:15 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1280.743509 ========= master_lag: 1.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2015-11-24 04:10:16.805074) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 04:10:16.809021) ========= 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.ybRguSRYwk/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.1bvyU3dc4i/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009420 build_url: 'https://queue.taskcluster.net/v1/task/pViEQOlrTvWfHXCWcO66Fw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 04:10:16.857698) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 04:10:16.858055) ========= 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.ybRguSRYwk/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.1bvyU3dc4i/Listeners TMPDIR=/var/folders/83/drrqg_ss7sldg8ggnl_73bbm00000w/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.005173 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 04:10:16.909296) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 04:10:16.909612) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 04:10:16.911042) ========= ========= Total master_lag: 3.87 =========