builder: ash_yosemite_test-dromaeojs slave: t-yosemite-r5-0017 starttime: 1447855101.59 results: success (0) buildid: 20151118040203 builduid: e8c307be5c9f44989b2290e7c5964c9d revision: 2185368ac9231548d3b0f9379812dd0a0ae50484 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:21.589937) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:21.590395) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:21.590723) ========= 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.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners TMPDIR=/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/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:0x0:0x0 using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006157 basedir: '/builds/slave/test' ========= master_lag: 0.34 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:21.938142) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:21.938442) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:21.967238) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:21.967518) ========= 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.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners TMPDIR=/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/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:0x0:0x0 using PTY: False program finished with exit code 0 elapsedTime=0.013597 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:22.016066) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:22.018423) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:22.018783) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:22.019089) ========= 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.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners TMPDIR=/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/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:0x0:0x0 using PTY: False --2015-11-18 05:58:22-- 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.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.03G=0s 2015-11-18 05:58:22 (1.03 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.242435 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:22.318192) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:22.318499) ========= 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.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners TMPDIR=/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/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:0x0:0x0 using PTY: False program finished with exit code 0 elapsedTime=0.127293 ========= master_lag: 0.10 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:22.544592) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:22.544941) ========= bash -c 'python archiver_client.py mozharness --repo projects/ash --rev 2185368ac9231548d3b0f9379812dd0a0ae50484 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/ash --rev 2185368ac9231548d3b0f9379812dd0a0ae50484 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners TMPDIR=/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/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:0x0:0x0 using PTY: False 2015-11-18 05:58:22,648 truncating revision to first 12 chars 2015-11-18 05:58:22,648 Setting DEBUG logging. 2015-11-18 05:58:22,648 attempt 1/10 2015-11-18 05:58:22,648 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/ash/2185368ac923?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-18 05:58:23,045 unpacking tar archive at: ash-2185368ac923/testing/mozharness/ program finished with exit code 0 elapsedTime=0.655974 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:23.269851) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:23.270127) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:23.287916) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:23.288180) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 05:58:23.288567) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 30 secs) (at 2015-11-18 05:58:23.288861) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Ash --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Ash 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', 'Ash', '--system-bits', '64', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Ash'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners TMPDIR=/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/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:0x0:0x0 using PTY: False 05:58:23 INFO - MultiFileLogger online at 20151118 05:58:23 in /builds/slave/test 05:58:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Ash --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Ash 05:58:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:58:23 INFO - {'append_to_log': False, 05:58:23 INFO - 'base_work_dir': '/builds/slave/test', 05:58:23 INFO - 'blob_upload_branch': 'Ash', 05:58:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:58:23 INFO - 'branch': 'Ash', 05:58:23 INFO - 'buildbot_json_path': 'buildprops.json', 05:58:23 INFO - 'config_files': ('talos/mac_config.py',), 05:58:23 INFO - 'default_actions': ('clobber', 05:58:23 INFO - 'read-buildbot-config', 05:58:23 INFO - 'download-and-extract', 05:58:23 INFO - 'populate-webroot', 05:58:23 INFO - 'create-virtualenv', 05:58:23 INFO - 'install', 05:58:23 INFO - 'run-tests'), 05:58:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:58:23 INFO - 'download_minidump_stackwalk': True, 05:58:23 INFO - 'download_symbols': 'ondemand', 05:58:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:58:23 INFO - 'tooltool.py': '/tools/tooltool.py', 05:58:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:58:23 INFO - '/tools/misc-python/virtualenv.py')}, 05:58:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:58:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:58:23 INFO - 'installer_path': 'installer.exe', 05:58:23 INFO - 'log_level': 'info', 05:58:23 INFO - 'log_name': 'talos', 05:58:23 INFO - 'log_to_console': True, 05:58:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:58:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:58:23 INFO - 'opt_config_files': (), 05:58:23 INFO - 'pip_index': False, 05:58:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:58:23 INFO - 'cmd': ('bash', 05:58:23 INFO - '-c', 05:58:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:58:23 INFO - 'enabled': True, 05:58:23 INFO - 'halt_on_failure': False, 05:58:23 INFO - 'name': 'check_screen_resolution'},), 05:58:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:58:23 INFO - 'cmd': ('bash', 05:58:23 INFO - '-c', 05:58:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:58:23 INFO - 'enabled': True, 05:58:23 INFO - 'halt_on_failure': False, 05:58:23 INFO - 'name': 'check_screen_resolution'},), 05:58:23 INFO - 'run_cmd_checks_enabled': True, 05:58:23 INFO - 'sps_profile': False, 05:58:23 INFO - 'sps_profile_interval': 0, 05:58:23 INFO - 'suite': 'dromaeojs', 05:58:23 INFO - 'system_bits': '64', 05:58:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:58:23 INFO - 'title': 't-yosemite-r5-0017', 05:58:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:58:23 INFO - 'use_talos_json': True, 05:58:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:58:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:58:23 INFO - 'work_dir': 'build'} 05:58:23 INFO - ##### 05:58:23 INFO - ##### Running clobber step. 05:58:23 INFO - ##### 05:58:23 INFO - Running pre-action listener: _resource_record_pre_action 05:58:23 INFO - Running main action method: clobber 05:58:23 INFO - rmtree: /builds/slave/test/build 05:58:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:58:27 INFO - Running post-action listener: _resource_record_post_action 05:58:27 INFO - ##### 05:58:27 INFO - ##### Running read-buildbot-config step. 05:58:27 INFO - ##### 05:58:27 INFO - Running pre-action listener: _resource_record_pre_action 05:58:27 INFO - Running main action method: read_buildbot_config 05:58:27 INFO - Using buildbot properties: 05:58:27 INFO - { 05:58:27 INFO - "properties": { 05:58:27 INFO - "buildnumber": 5, 05:58:27 INFO - "product": "firefox", 05:58:27 INFO - "script_repo_revision": "production", 05:58:27 INFO - "builddir": "ash_yosemite_test-dromaeojs", 05:58:27 INFO - "repository": "", 05:58:27 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10 ash talos dromaeojs", 05:58:27 INFO - "buildid": "20151118040203", 05:58:27 INFO - "slavename": "t-yosemite-r5-0017", 05:58:27 INFO - "stage_platform": "macosx64", 05:58:27 INFO - "basedir": "/builds/slave/test", 05:58:27 INFO - "nightly_build": "True", 05:58:27 INFO - "project": "", 05:58:27 INFO - "platform": "yosemite", 05:58:27 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:58:27 INFO - "slavebuilddir": "test", 05:58:27 INFO - "scheduler": "tests-ash-macosx64-talos", 05:58:27 INFO - "branch": "ash", 05:58:27 INFO - "repo_path": "projects/ash", 05:58:27 INFO - "pgo_build": "False", 05:58:27 INFO - "builduid": "e8c307be5c9f44989b2290e7c5964c9d", 05:58:27 INFO - "revision": "2185368ac9231548d3b0f9379812dd0a0ae50484" 05:58:27 INFO - }, 05:58:27 INFO - "sourcestamp": { 05:58:27 INFO - "repository": "", 05:58:27 INFO - "hasPatch": false, 05:58:27 INFO - "project": "", 05:58:27 INFO - "branch": "ash-macosx64-talos", 05:58:27 INFO - "changes": [ 05:58:27 INFO - { 05:58:27 INFO - "category": null, 05:58:27 INFO - "files": [ 05:58:27 INFO - { 05:58:27 INFO - "url": null, 05:58:27 INFO - "name": "https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 05:58:27 INFO - } 05:58:27 INFO - ], 05:58:27 INFO - "repository": "", 05:58:27 INFO - "rev": "2185368ac9231548d3b0f9379812dd0a0ae50484", 05:58:27 INFO - "who": "sendchange", 05:58:27 INFO - "when": 1447855054, 05:58:27 INFO - "number": 6676967, 05:58:27 INFO - "comments": "", 05:58:27 INFO - "project": "", 05:58:27 INFO - "at": "Wed 18 Nov 2015 05:57:34", 05:58:27 INFO - "branch": "ash-macosx64-talos", 05:58:27 INFO - "revlink": "", 05:58:27 INFO - "properties": [ 05:58:27 INFO - [ 05:58:27 INFO - "buildid", 05:58:27 INFO - "20151118040203", 05:58:27 INFO - "Change" 05:58:27 INFO - ], 05:58:27 INFO - [ 05:58:27 INFO - "builduid", 05:58:27 INFO - "e8c307be5c9f44989b2290e7c5964c9d", 05:58:27 INFO - "Change" 05:58:27 INFO - ], 05:58:27 INFO - [ 05:58:27 INFO - "nightly_build", 05:58:27 INFO - "True", 05:58:27 INFO - "Change" 05:58:27 INFO - ], 05:58:27 INFO - [ 05:58:27 INFO - "pgo_build", 05:58:27 INFO - "False", 05:58:27 INFO - "Change" 05:58:27 INFO - ] 05:58:27 INFO - ], 05:58:27 INFO - "revision": "2185368ac9231548d3b0f9379812dd0a0ae50484" 05:58:27 INFO - } 05:58:27 INFO - ], 05:58:27 INFO - "revision": "2185368ac9231548d3b0f9379812dd0a0ae50484" 05:58:27 INFO - } 05:58:27 INFO - } 05:58:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 05:58:27 INFO - Running post-action listener: _resource_record_post_action 05:58:27 INFO - ##### 05:58:27 INFO - ##### Running download-and-extract step. 05:58:27 INFO - ##### 05:58:27 INFO - Running pre-action listener: _resource_record_pre_action 05:58:27 INFO - Running main action method: download_and_extract 05:58:27 INFO - mkdir: /builds/slave/test/build/tests 05:58:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:27 INFO - https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:58:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/test_packages.json 05:58:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/test_packages.json 05:58:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 05:58:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 05:58:51 INFO - Downloaded 1149 bytes. 05:58:51 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:58:51 INFO - Using the following test package requirements: 05:58:51 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 05:58:51 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:58:51 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 05:58:51 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:58:51 INFO - u'jsshell-mac.zip'], 05:58:51 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:58:51 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 05:58:51 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 05:58:51 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:58:51 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 05:58:51 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:58:51 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 05:58:51 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:58:51 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 05:58:51 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 05:58:51 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 05:58:51 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 05:58:51 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:58:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:51 INFO - https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:58:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:58:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:58:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:58:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:58:54 INFO - Downloaded 17461130 bytes. 05:58:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] in /builds/slave/test/build/tests 05:58:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:58:54 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 05:58:59 INFO - Return code: 0 05:58:59 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip', u'firefox-45.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:58:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:59 INFO - https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:58:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:58:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:58:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:58:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:59:08 INFO - Downloaded 17461130 bytes. 05:59:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] in /builds/slave/test/build/tests 05:59:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 05:59:08 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 05:59:14 INFO - Return code: 0 05:59: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')]} 05:59:14 INFO - https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:59:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 05:59:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 05:59:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 05:59:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:59:15 INFO - Downloaded 11131693 bytes. 05:59:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] in /builds/slave/test/build/tests 05:59:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 05:59:15 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 05:59:15 INFO - Return code: 0 05:59:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:15 INFO - https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:59:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 05:59:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 05:59:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg 05:59:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg'}, attempt #1 05:59:21 INFO - Downloaded 105163213 bytes. 05:59:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 05:59:21 INFO - mkdir: /builds/slave/test/properties 05:59:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:59:21 INFO - Writing to file /builds/slave/test/properties/build_url 05:59:21 INFO - Contents: 05:59:21 INFO - build_url:https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 05:59:21 INFO - Running post-action listener: _resource_record_post_action 05:59:21 INFO - Running post-action listener: set_extra_try_arguments 05:59:21 INFO - ##### 05:59:21 INFO - ##### Running populate-webroot step. 05:59:21 INFO - ##### 05:59:21 INFO - Running pre-action listener: _resource_record_pre_action 05:59:21 INFO - Running main action method: populate_webroot 05:59:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:59:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:59:21 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:59:21 INFO - '../fennec_ids.txt'], 05:59:21 INFO - 'tests': ['tcheck2']}, 05:59:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:59:21 INFO - '--tppagecycles', 05:59:21 INFO - '7'], 05:59:21 INFO - 'tests': ['tsvgm']}}, 05:59:21 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:59:21 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:59:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:59:21 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:59:21 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:59:21 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:59:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:21 INFO - 'talos_options': ['--e10s'], 05:59:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:21 INFO - 'tests': ['damp', 'tps']}, 05:59:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:21 INFO - 'talos_options': ['--e10s'], 05:59:21 INFO - 'tests': ['damp', 'tps']}, 05:59:21 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:59:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:59:21 INFO - 'other': {'tests': ['a11yr', 05:59:21 INFO - 'ts_paint', 05:59:21 INFO - 'tpaint', 05:59:21 INFO - 'sessionrestore', 05:59:21 INFO - 'sessionrestore_no_auto_restore']}, 05:59:21 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:59:21 INFO - 'tests': ['a11yr', 05:59:21 INFO - 'ts_paint', 05:59:21 INFO - 'tpaint', 05:59:21 INFO - 'sessionrestore', 05:59:21 INFO - 'sessionrestore_no_auto_restore']}, 05:59:21 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:59:21 INFO - 'tests': ['a11yr', 05:59:21 INFO - 'ts_paint', 05:59:21 INFO - 'tpaint', 05:59:21 INFO - 'sessionrestore', 05:59:21 INFO - 'sessionrestore_no_auto_restore']}, 05:59:21 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:59:21 INFO - 'tests': ['a11yr', 05:59:21 INFO - 'ts_paint', 05:59:21 INFO - 'tpaint', 05:59:21 INFO - 'sessionrestore', 05:59:21 INFO - 'sessionrestore_no_auto_restore']}, 05:59:21 INFO - 'other_l64': {'tests': ['a11yr', 05:59:21 INFO - 'ts_paint', 05:59:21 INFO - 'tpaint', 05:59:21 INFO - 'sessionrestore', 05:59:21 INFO - 'sessionrestore_no_auto_restore']}, 05:59:21 INFO - 'other_nol64': {'tests': ['a11yr', 05:59:21 INFO - 'ts_paint', 05:59:21 INFO - 'tpaint', 05:59:21 INFO - 'sessionrestore', 05:59:21 INFO - 'sessionrestore_no_auto_restore']}, 05:59:21 INFO - 'svgr': {'tests': ['tsvgx', 05:59:21 INFO - 'tsvgr_opacity', 05:59:21 INFO - 'tart', 05:59:21 INFO - 'tscrollx', 05:59:21 INFO - 'cart']}, 05:59:21 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:59:21 INFO - 'tests': ['tsvgx', 05:59:21 INFO - 'tsvgr_opacity', 05:59:21 INFO - 'tart', 05:59:21 INFO - 'tscrollx', 05:59:21 INFO - 'cart']}, 05:59:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:21 INFO - 'tests': ['tp5o']}, 05:59:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:21 INFO - 'talos_options': ['--e10s'], 05:59:21 INFO - 'tests': ['tp5o']}, 05:59:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:21 INFO - 'talos_options': ['--xperf_path', 05:59:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:21 INFO - 'tests': ['tp5n']}, 05:59:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:21 INFO - 'talos_options': ['--e10s', 05:59:21 INFO - '--xperf_path', 05:59:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:21 INFO - 'tests': ['tp5n']}}, 05:59:21 INFO - 'talos.zip': {'path': '', 05:59:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:59:21 INFO - Running post-action listener: _resource_record_post_action 05:59:21 INFO - ##### 05:59:21 INFO - ##### Running create-virtualenv step. 05:59:21 INFO - ##### 05:59:21 INFO - Running pre-action listener: _resource_record_pre_action 05:59:21 INFO - Running main action method: create_virtualenv 05:59:21 INFO - Creating virtualenv /builds/slave/test/build/venv 05:59:21 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:59:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:59:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:59:22 INFO - Using real prefix '/tools/python27' 05:59:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:59:22 INFO - Installing distribute.............................................................................................................................................................................................done. 05:59:26 INFO - Installing pip.................done. 05:59:26 INFO - Return code: 0 05:59:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10367b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103658228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbc800b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10359e9f0>, '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 0x1032fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035928c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:59:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:59:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/Render', 05:59:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:26 INFO - 'HOME': '/Users/cltbld', 05:59:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:26 INFO - 'LOGNAME': 'cltbld', 05:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:26 INFO - 'MOZ_NO_REMOTE': '1', 05:59:26 INFO - 'NO_EM_RESTART': '1', 05:59:26 INFO - 'PAGER': '/bin/cat', 05:59:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:26 INFO - 'PWD': '/builds/slave/test', 05:59:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:26 INFO - 'SHELL': '/bin/bash', 05:59:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QF3ZDCHnGJ/Listeners', 05:59:26 INFO - 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', 05:59:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:26 INFO - 'USER': 'cltbld', 05:59:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:26 INFO - 'XPC_FLAGS': '0x0', 05:59:26 INFO - 'XPC_SERVICE_NAME': '0', 05:59:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 05:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:59:27 INFO - Cleaning up... 05:59:27 INFO - Return code: 0 05:59:27 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:27 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 0x10367b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103658228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbc800b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10359e9f0>, '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 0x1032fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035928c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:59:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:59:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/Render', 05:59:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:27 INFO - 'HOME': '/Users/cltbld', 05:59:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:27 INFO - 'LOGNAME': 'cltbld', 05:59:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:27 INFO - 'MOZ_NO_REMOTE': '1', 05:59:27 INFO - 'NO_EM_RESTART': '1', 05:59:27 INFO - 'PAGER': '/bin/cat', 05:59:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:27 INFO - 'PWD': '/builds/slave/test', 05:59:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:27 INFO - 'SHELL': '/bin/bash', 05:59:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QF3ZDCHnGJ/Listeners', 05:59:27 INFO - 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', 05:59:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:27 INFO - 'USER': 'cltbld', 05:59:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:27 INFO - 'XPC_FLAGS': '0x0', 05:59:27 INFO - 'XPC_SERVICE_NAME': '0', 05:59:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 05:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:27 INFO - Downloading/unpacking psutil>=0.7.1 05:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:29 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 05:59:29 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:59:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:59:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:59:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:59:29 INFO - Installing collected packages: psutil 05:59:29 INFO - Running setup.py install for psutil 05:59:30 INFO - building 'psutil._psutil_osx' extension 05:59:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:59:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:59:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:59:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:59:32 INFO - building 'psutil._psutil_posix' extension 05:59:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:59:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:59:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:59:32 INFO - ^ 05:59:32 INFO - 1 warning generated. 05:59:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:59:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:59:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:59:32 INFO - Successfully installed psutil 05:59:32 INFO - Cleaning up... 05:59:32 INFO - Return code: 0 05:59:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:59:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:32 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 0x10367b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103658228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbc800b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10359e9f0>, '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 0x1032fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035928c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:59:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:59:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/Render', 05:59:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:32 INFO - 'HOME': '/Users/cltbld', 05:59:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:32 INFO - 'LOGNAME': 'cltbld', 05:59:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:32 INFO - 'MOZ_NO_REMOTE': '1', 05:59:32 INFO - 'NO_EM_RESTART': '1', 05:59:32 INFO - 'PAGER': '/bin/cat', 05:59:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:32 INFO - 'PWD': '/builds/slave/test', 05:59:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:32 INFO - 'SHELL': '/bin/bash', 05:59:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QF3ZDCHnGJ/Listeners', 05:59:32 INFO - 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', 05:59:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:32 INFO - 'USER': 'cltbld', 05:59:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:32 INFO - 'XPC_FLAGS': '0x0', 05:59:32 INFO - 'XPC_SERVICE_NAME': '0', 05:59:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 05:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:33 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:35 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:59:35 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:59:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:59:35 INFO - Installing collected packages: mozsystemmonitor 05:59:35 INFO - Running setup.py install for mozsystemmonitor 05:59:35 INFO - Successfully installed mozsystemmonitor 05:59:35 INFO - Cleaning up... 05:59:35 INFO - Return code: 0 05:59:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:59: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')]} 05:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59: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')]} 05:59:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59: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', '--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 0x10367b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103658228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbc800b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10359e9f0>, '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 0x1032fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035928c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:59:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:59:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/Render', 05:59:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:35 INFO - 'HOME': '/Users/cltbld', 05:59:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:35 INFO - 'LOGNAME': 'cltbld', 05:59:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:35 INFO - 'MOZ_NO_REMOTE': '1', 05:59:35 INFO - 'NO_EM_RESTART': '1', 05:59:35 INFO - 'PAGER': '/bin/cat', 05:59:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:35 INFO - 'PWD': '/builds/slave/test', 05:59:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:35 INFO - 'SHELL': '/bin/bash', 05:59:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QF3ZDCHnGJ/Listeners', 05:59:35 INFO - 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', 05:59:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:35 INFO - 'USER': 'cltbld', 05:59:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:35 INFO - 'XPC_FLAGS': '0x0', 05:59:35 INFO - 'XPC_SERVICE_NAME': '0', 05:59:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 05:59:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:35 INFO - Downloading/unpacking blobuploader==1.2.4 05:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:38 INFO - Downloading blobuploader-1.2.4.tar.gz 05:59:38 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:59:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:59:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:38 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:59:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:59:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:39 INFO - Downloading docopt-0.6.1.tar.gz 05:59:39 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:59:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:59:39 INFO - Installing collected packages: blobuploader, requests, docopt 05:59:39 INFO - Running setup.py install for blobuploader 05:59:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:59:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:59:39 INFO - Running setup.py install for requests 05:59:40 INFO - Running setup.py install for docopt 05:59:40 INFO - Successfully installed blobuploader requests docopt 05:59:40 INFO - Cleaning up... 05:59:40 INFO - Return code: 0 05:59:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:40 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 0x10367b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103658228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbc800b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10359e9f0>, '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 0x1032fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035928c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:59:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:59:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/Render', 05:59:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:40 INFO - 'HOME': '/Users/cltbld', 05:59:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:40 INFO - 'LOGNAME': 'cltbld', 05:59:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:40 INFO - 'MOZ_NO_REMOTE': '1', 05:59:40 INFO - 'NO_EM_RESTART': '1', 05:59:40 INFO - 'PAGER': '/bin/cat', 05:59:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:40 INFO - 'PWD': '/builds/slave/test', 05:59:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:40 INFO - 'SHELL': '/bin/bash', 05:59:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QF3ZDCHnGJ/Listeners', 05:59:40 INFO - 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', 05:59:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:40 INFO - 'USER': 'cltbld', 05:59:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:40 INFO - 'XPC_FLAGS': '0x0', 05:59:40 INFO - 'XPC_SERVICE_NAME': '0', 05:59:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 05:59:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:41 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-A5CFa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:41 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-XGQp_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:41 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-R1Q7WP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:41 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-iGUXMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:41 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-xh5lvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:41 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-YhQk1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:41 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-u_xqBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:41 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-V8dAUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:41 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-BO3LLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:42 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-CZUiSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:42 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-sk_6g2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:42 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-XrT0XQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:42 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-JGEDmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:59:42 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-8VXasP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:42 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-woI8xr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:59:42 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-GWxS3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:59:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:59:42 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-9lEKQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:59:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:59:43 INFO - Running setup.py install for manifestparser 05:59:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:59:43 INFO - Running setup.py install for mozcrash 05:59:43 INFO - Running setup.py install for mozdebug 05:59:43 INFO - Running setup.py install for mozdevice 05:59:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:59:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:59:43 INFO - Running setup.py install for mozfile 05:59:43 INFO - Running setup.py install for mozhttpd 05:59:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Running setup.py install for mozinfo 05:59:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Running setup.py install for mozInstall 05:59:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Running setup.py install for mozleak 05:59:44 INFO - Running setup.py install for mozlog 05:59:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:59:44 INFO - Running setup.py install for moznetwork 05:59:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:59:45 INFO - Running setup.py install for mozprocess 05:59:45 INFO - Running setup.py install for mozprofile 05:59:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:59:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:59:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:59:45 INFO - Running setup.py install for mozrunner 05:59:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:59:45 INFO - Running setup.py install for mozscreenshot 05:59:45 INFO - Running setup.py install for moztest 05:59:45 INFO - Running setup.py install for mozversion 05:59:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:59:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:59:46 INFO - Cleaning up... 05:59:46 INFO - Return code: 0 05:59:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:46 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 0x10367b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103658228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbc800b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10359e9f0>, '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 0x1032fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035928c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:59:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/Render', 05:59:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:46 INFO - 'HOME': '/Users/cltbld', 05:59:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:46 INFO - 'LOGNAME': 'cltbld', 05:59:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:46 INFO - 'MOZ_NO_REMOTE': '1', 05:59:46 INFO - 'NO_EM_RESTART': '1', 05:59:46 INFO - 'PAGER': '/bin/cat', 05:59:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:46 INFO - 'PWD': '/builds/slave/test', 05:59:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:46 INFO - 'SHELL': '/bin/bash', 05:59:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QF3ZDCHnGJ/Listeners', 05:59:46 INFO - 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', 05:59:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:46 INFO - 'USER': 'cltbld', 05:59:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:46 INFO - 'XPC_FLAGS': '0x0', 05:59:46 INFO - 'XPC_SERVICE_NAME': '0', 05:59:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 05:59:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:46 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-sQNunO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:46 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-fGXU59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:46 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-mRj5bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:46 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-4i6rnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:46 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-f60KPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:47 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-qhVF0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:47 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-TQkmYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:47 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-sRGpJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:47 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-icaqVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:47 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-5kTn2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:47 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-JyRWrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:47 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-sB94Ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:48 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-4BtSSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:59:48 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-UyaeLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:48 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-WaKYPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:59:48 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-REAH4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:59:48 INFO - Running setup.py (path:/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/pip-SyUMDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:59:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:51 INFO - Downloading blessings-1.5.1.tar.gz 05:59:51 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 05:59:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:59:51 INFO - Installing collected packages: blessings 05:59:51 INFO - Running setup.py install for blessings 05:59:51 INFO - Successfully installed blessings 05:59:51 INFO - Cleaning up... 05:59:51 INFO - Return code: 0 05:59:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:59:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:59:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:59:51 INFO - Reading from file tmpfile_stdout 05:59:51 INFO - Current package versions: 05:59:51 INFO - blessings == 1.5.1 05:59:51 INFO - blobuploader == 1.2.4 05:59:51 INFO - docopt == 0.6.1 05:59:51 INFO - manifestparser == 1.1 05:59:51 INFO - mozInstall == 1.12 05:59:51 INFO - mozcrash == 0.16 05:59:51 INFO - mozdebug == 0.1 05:59:51 INFO - mozdevice == 0.47 05:59:51 INFO - mozfile == 1.2 05:59:51 INFO - mozhttpd == 0.7 05:59:51 INFO - mozinfo == 0.9 05:59:51 INFO - mozleak == 0.1 05:59:51 INFO - mozlog == 3.0 05:59:51 INFO - moznetwork == 0.27 05:59:51 INFO - mozprocess == 0.22 05:59:51 INFO - mozprofile == 0.27 05:59:51 INFO - mozrunner == 6.11 05:59:51 INFO - mozscreenshot == 0.1 05:59:51 INFO - mozsystemmonitor == 0.0 05:59:51 INFO - moztest == 0.7 05:59:51 INFO - mozversion == 1.4 05:59:51 INFO - psutil == 3.1.1 05:59:51 INFO - requests == 1.2.3 05:59:51 INFO - wsgiref == 0.1.2 05:59:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:51 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 0x10367b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103658228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdbc800b30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10359e9f0>, '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 0x1032fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035928c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:59:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:59:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:59:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/Render', 05:59:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:51 INFO - 'HOME': '/Users/cltbld', 05:59:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:51 INFO - 'LOGNAME': 'cltbld', 05:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:51 INFO - 'MOZ_NO_REMOTE': '1', 05:59:51 INFO - 'NO_EM_RESTART': '1', 05:59:51 INFO - 'PAGER': '/bin/cat', 05:59:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:51 INFO - 'PWD': '/builds/slave/test', 05:59:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:51 INFO - 'SHELL': '/bin/bash', 05:59:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QF3ZDCHnGJ/Listeners', 05:59:51 INFO - 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', 05:59:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:51 INFO - 'USER': 'cltbld', 05:59:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:51 INFO - 'XPC_FLAGS': '0x0', 05:59:51 INFO - 'XPC_SERVICE_NAME': '0', 05:59:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 05:59:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:59:52 INFO - Cleaning up... 05:59:52 INFO - Return code: 0 05:59:52 INFO - Running post-action listener: _resource_record_post_action 05:59:52 INFO - Running post-action listener: _start_resource_monitoring 05:59:52 INFO - Starting resource monitoring. 05:59:52 INFO - ##### 05:59:52 INFO - ##### Running install step. 05:59:52 INFO - ##### 05:59:52 INFO - Running pre-action listener: _resource_record_pre_action 05:59:52 INFO - Running main action method: install 05:59:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:59:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:59:52 INFO - Reading from file tmpfile_stdout 05:59:52 INFO - Detecting whether we're running mozinstall >=1.0... 05:59:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:59:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:59:52 INFO - Reading from file tmpfile_stdout 05:59:52 INFO - Output received: 05:59:52 INFO - Usage: mozinstall [options] installer 05:59:52 INFO - Options: 05:59:52 INFO - -h, --help show this help message and exit 05:59:52 INFO - -d DEST, --destination=DEST 05:59:52 INFO - Directory to install application into. [default: 05:59:52 INFO - "/builds/slave/test"] 05:59:52 INFO - --app=APP Application being installed. [default: firefox] 05:59:52 INFO - mkdir: /builds/slave/test/build/application 05:59:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:59:52 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 06:00:33 INFO - Reading from file tmpfile_stdout 06:00:33 INFO - Output received: 06:00:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:00:33 INFO - Running post-action listener: _resource_record_post_action 06:00:33 INFO - ##### 06:00:33 INFO - ##### Running run-tests step. 06:00:33 INFO - ##### 06:00:33 INFO - Running pre-action listener: _resource_record_pre_action 06:00:33 INFO - Running main action method: run_tests 06:00:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:00:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:00:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:00:33 INFO - 2015-11-18 06:00:33.757 screenresolution[1635:8274] starting screenresolution argv=screenresolution get 06:00:33 INFO - 2015-11-18 06:00:33.761 screenresolution[1635:8274] Display 0: 1600x1200x32@60 06:00:33 INFO - 2015-11-18 06:00:33.767 screenresolution[1636:8276] starting screenresolution argv=screenresolution list 06:00:33 INFO - Available Modes on Display 0 06:00:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:00:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:00:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:00:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:00:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:00:34 INFO - Intel HD Graphics 3000: 06:00:34 INFO - Chipset Model: Intel HD Graphics 3000 06:00:34 INFO - Type: GPU 06:00:34 INFO - Bus: Built-In 06:00:34 INFO - VRAM (Total): 512 MB 06:00:34 INFO - VRAM (Dynamic, Max): 10 06:00:34 INFO - Vendor: Intel (0x8086) 06:00:34 INFO - Device ID: 0x0116 06:00:34 INFO - Revision ID: 0x0009 06:00:34 INFO - Displays: 06:00:34 INFO - Display: 06:00:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:00:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:00:34 INFO - Display Serial Number: 1600x1200 60 06:00:34 INFO - Main Display: Yes 06:00:34 INFO - Mirror: Off 06:00:34 INFO - Online: Yes 06:00:34 INFO - Rotation: Supported 06:00:34 INFO - Return code: 0 06:00:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:00:34 INFO - Python 2.7.3 06:00:34 INFO - Return code: 0 06:00:34 INFO - grabbing minidump binary from tooltool 06:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:00:34 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 0x10359e9f0>, '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 0x1032fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1035928c8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:00:34 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 06:00:34 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 06:00:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:00:34 INFO - Return code: 0 06:00:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:00:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:00:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:00:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:00:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:00:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Ash', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r5-0017', '--webServer', 'localhost'] in /builds/slave/test/build 06:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Ash --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r5-0017 --webServer localhost 06:00:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R6rwYQGox8/Render', 06:00:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:00:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:00:34 INFO - 'HOME': '/Users/cltbld', 06:00:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:00:34 INFO - 'LOGNAME': 'cltbld', 06:00:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:00:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:34 INFO - 'MOZ_NO_REMOTE': '1', 06:00:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:00:34 INFO - 'NO_EM_RESTART': '1', 06:00:34 INFO - 'PAGER': '/bin/cat', 06:00:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:00:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:34 INFO - 'PWD': '/builds/slave/test', 06:00:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:00:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:00:34 INFO - 'SHELL': '/bin/bash', 06:00:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QF3ZDCHnGJ/Listeners', 06:00:34 INFO - 'TMPDIR': '/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/', 06:00:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:00:34 INFO - 'USER': 'cltbld', 06:00:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:00:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:34 INFO - 'XPC_FLAGS': '0x0', 06:00:34 INFO - 'XPC_SERVICE_NAME': '0', 06:00:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 06:00:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Ash', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r5-0017', '--webServer', 'localhost'] with output_timeout 3600 06:00:34 INFO - mozversion INFO | application_buildid: 20151118040203 06:00:34 INFO - mozversion INFO | application_changeset: 2185368ac9231548d3b0f9379812dd0a0ae50484 06:00:34 INFO - mozversion INFO | application_display_name: Nightly 06:00:34 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:00:34 INFO - mozversion INFO | application_name: Firefox 06:00:34 INFO - mozversion INFO | application_remotingname: firefox 06:00:34 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/projects/ash 06:00:34 INFO - mozversion INFO | application_vendor: Mozilla 06:00:34 INFO - mozversion INFO | application_version: 45.0a1 06:00:34 INFO - mozversion INFO | platform_buildid: 20151118040203 06:00:34 INFO - mozversion INFO | platform_changeset: 2185368ac9231548d3b0f9379812dd0a0ae50484 06:00:34 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/projects/ash 06:00:34 INFO - mozversion INFO | platform_version: 45.0a1 06:00:34 INFO - 2015-11-18 06:00:34,510 DEBUG : using testdate: 1447855234 06:00:34 INFO - 2015-11-18 06:00:34,510 DEBUG : actual date: 1447855234 06:00:34 INFO - 2015-11-18 06:00:34,528 INFO : starting webserver on 'localhost:49280' 06:00:34 INFO - 2015-11-18 06:00:34,529 INFO : Starting test suite t-yosemite-r5-0017 06:00:34 INFO - 2015-11-18 06:00:34,529 INFO : Starting test dromaeo_css 06:00:34 INFO - 2015-11-18 06:00:34,529 DEBUG : operating with platform_type : mac_ 06:00:34 INFO - 2015-11-18 06:00:34,530 INFO : Initialising browser for dromaeo_css test... 06:00:34 INFO - 2015-11-18 06:00:34,545 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/tmp_h3sTV/profile http://localhost:49280/getInfo.html 06:00:38 INFO - 2015-11-18 06:00:38,167 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:00:38 INFO - 2015-11-18 06:00:38,167 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:00:38 INFO - 2015-11-18 06:00:38,168 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 06:00:38 INFO - 2015-11-18 06:00:38,168 DEBUG : BROWSER_OUTPUT: __metrics 06:00:38 INFO - 2015-11-18 06:00:38,185 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:00:38 INFO - 2015-11-18 06:00:38,899 INFO : Browser initialized. 06:00:38 INFO - 2015-11-18 06:00:38,899 INFO : Running cycle 1/1 for dromaeo_css test... 06:00:38 INFO - 2015-11-18 06:00:38,899 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/tmp_h3sTV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:00:50 INFO - 2015-11-18 06:00:50,927 DEBUG : BROWSER_OUTPUT: RSS: Main: 236220416 06:00:50 INFO - 2015-11-18 06:00:50,927 DEBUG : BROWSER_OUTPUT: 06:01:43 INFO - 2015-11-18 06:01:43,003 DEBUG : BROWSER_OUTPUT: 1447855303002 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] 06:03:52 INFO - 2015-11-18 06:03:52,751 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 06:03:55 INFO - 2015-11-18 06:03:55,944 DEBUG : BROWSER_OUTPUT: RSS: Main: 535814144 06:03:55 INFO - 2015-11-18 06:03:55,944 DEBUG : BROWSER_OUTPUT: 06:06:59 INFO - 2015-11-18 06:06:59,143 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 06:06:59 INFO - 2015-11-18 06:06:59,730 DEBUG : BROWSER_OUTPUT: RSS: Main: 388304896 06:06:59 INFO - 2015-11-18 06:06:59,730 DEBUG : BROWSER_OUTPUT: 06:10:01 INFO - 2015-11-18 06:10:01,928 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 06:10:02 INFO - 2015-11-18 06:10:02,515 DEBUG : BROWSER_OUTPUT: RSS: Main: 357732352 06:10:02 INFO - 2015-11-18 06:10:02,515 DEBUG : BROWSER_OUTPUT: 06:13:04 INFO - 2015-11-18 06:13:04,603 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 06:13:05 INFO - 2015-11-18 06:13:05,209 DEBUG : BROWSER_OUTPUT: RSS: Main: 338976768 06:13:05 INFO - 2015-11-18 06:13:05,209 DEBUG : BROWSER_OUTPUT: 06:16:08 INFO - 2015-11-18 06:16:08,255 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 06:16:08 INFO - 2015-11-18 06:16:08,879 DEBUG : BROWSER_OUTPUT: RSS: Main: 349663232 06:16:08 INFO - 2015-11-18 06:16:08,879 DEBUG : BROWSER_OUTPUT: 06:19:11 INFO - 2015-11-18 06:19:11,678 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 06:19:11 INFO - 2015-11-18 06:19:11,938 DEBUG : BROWSER_OUTPUT: RSS: Main: 383770624 06:19:11 INFO - 2015-11-18 06:19:11,938 DEBUG : BROWSER_OUTPUT: 06:19:11 INFO - 2015-11-18 06:19:11,938 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:19:11 INFO - 2015-11-18 06:19:11,938 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:19:11 INFO - 2015-11-18 06:19:11,938 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:19:11 INFO - 2015-11-18 06:19:11,939 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:19:11 INFO - 2015-11-18 06:19:11,940 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2966.4660361134993;3000.0699783067266;3007.3233522457435;3055.1292881528757;3190.888318908838;4714.669973101884;4830.106430310395;4832.009438065197;4853.902921941559;4864.537868902448;4792.736399498018;4835.564799168074;4942.975285123578;4956.553910148086;5010.473900240477;4352.7823608819435;4395.802188901491;4402.3836662867225;4414.713043652152;4429.114177164563;4516.0967806438675;4609.861704148881;4696.765161741916;4700.341952126688;4724.952750472486;4526.818927242906;4600.97699511502;4648.070385922795;4651.930221046687;4661.650376221797;4549.61328287096;4597.034622729231;4616.607588354994;4622.168009758242;4679.64902632304;4598.172328980782;4610.723356598614;4623.8150473981;4641.489436162019;4645.094206629675;4856.344393506866;4878.780454879539;4885.60915126789;4887.364642596475;4927.556519913189;4969.900601987976;5065.670731402447;5143.048536020848;5159.226116082617;5179.533841954242;5198.298229738974;5300.257963885059;5305.840824775263;5332.946670533283;5358.196270559409;4939.333190019373;4989.25161225819;4989.925151122736;5016.1723315653235;5062.11413002723;5176.663516871391;5205.063088644005;5268.130758424817;5319.069162896478;5368.812091576776;5256.421793602701;5261.3686357637325;5267.525922666979;5274.182501712241;5334.493223143795;5334.59990500714;5347.51872331492;5360.624756267024;5394.811181608625;5418.593605479605;5132.0762262792305;5211.921821172686;5255;5265.420803711589;5286.180642000496;5140.768665410065;5199.298094757235;5204;5209.348831396075;5220.895582088413;4011.0373510357304;4053.3717273822604;4099.672026237894;4102.466679331667;4104.712670113064;3684.281565094781;3726.0312318796764;3735.3836616958442;3737.0470530014672;3777.433384992218;4941.654084214142;4963.10664080469;4965.577925876269;4968.8757781055765;4977.228529577848;5217.113090774577;5326.307580014573;5342.305500284938;5343.893122137613;5379.515843574019;4963.305137280783;4990.4011518618;4999.5500404963;5005.27423523587;5010.69935803853;4688.742119183477;4697.694649847749;4701.271302948048;4705.129551033001;4709.1994360958715;5380.300560927054;5438.619296649275;5478.205660179331;5491.313585801775;5510.118381059011;5166.793328266827;5234.842954711365;5248.711320877388;5282.524572788468;5289.127293996447;5271.4464981176425;5284.180951952453;5292.311999440049;5300.072487314782;5309.2301616266195;5082.720450375265;5089.160288552347;5089.949100508948;5101.438841727481;5157.226416037624;5058.974705126451;5085.160948443466;5220.582353411642;5246.396664383645;5255.763490643007;5201.245819356248;5209.244659524424;5212.791488340424;5218.6868787872845;5258.526732594085;5226.817061402832;5228.032813929283;5234.764435600331;5242.606804489755;5249.7375131242525;5103.617228707788;5114.207297868837;5137.794488220429;5143.048536020848;5144.30551875492;4931.876703082452;5208.244804503402;5227.398849132246;5246.422893481637;5253.317068781034;5123.974380128076;5140.30605868203;5155.587552995677;5162.561182299547;5203.713795741119 06:19:11 INFO - 2015-11-18 06:19:11,941 DEBUG : BROWSER_OUTPUT: |1;ext.html;512.6385897941951;513.1763519551121;514.4880843560658;523.4373048972354;537.5887446103732;29513.557296640567;35414.82292588537;36365.090872728164;36516.63483365165;36660.266794664094;39256.018599535026;39326.10108483729;39328.60671393285;39356.80321598388;39358.40962385567;7511.436642251826;7615.657295421719;8154.836903261931;8376.958115209445;8388.748337549883;10211.897881021168;10385.117265032444;10401.84397234042;10401.947990260038;10443.686689399292;10093.495325233745;10106.393616383004;10114.595416183343;10139.695809125738;10170.491475426235;7695.192004839467;7893.210678932061;10108.342957707762;10158.746031349201;10212.18302535799;9905.702828915144;9981.30130890838;10013.449260290681;10016.54925528353;10099.29304948654;36534.269314613826;36920.44619330712;36974.26051478955;37008.814955925045;37065.814670926746;2706.688730795964;2710.444358906439;2729.426820367725;2809.2415047936943;2817.4874431598764;24759.133430329854;24811;24879.253622391356;24970.250892473254;25711.61432578513;2039.785822488647;2050.149188086942;2053.9486512837148;2053.9589208215752;2154.633712268918;234238;235987;238012;238354;240972.79513602494;47460.52539474561;48343.51656483461;54407.72796136073;54770.452295476534;54892.315075889404;61874;62014.68992655098;64058;64090.359096408436;64223.35776642267;12160.695982600506;12320.137590368588;12335.198212116184;12338.198017128858;12370.072244581512;9087.727368178965;9137.314701397421;9148.588313525906;9149.359544831797;9156.313276504288;17614.559636009202;17675.29298828058;17866.677331946765;17908.731369029476;17980.55048623795;7128.893066604005;7369.594672292968;7390.556566606021;7404.1855395906405;7442.553446793101;842.7766641839918;856.2037305306001;927.0590350793949;975.5219942228257;996.3324572536418;26012.609810852853;26299.342516437242;26455;27015;27213.319667008087;900.2752784008935;924.246738907788;974.1378879691524;1021.3667526133845;1025.8409946458312;12191.87808121925;12278.754424911453;12789.552365667158;13016.934915325362;13078;295613;295839;301300;303787;304040;48894.266586001235;48981.26528102082;49297.26054109191;49437.50562494401;49790;1190.113365542659;1218.5125949620224;1224.5407972010496;1225.5097960815744;1233.6360773571819;22056.44858878541;23533.411664708517;24403.38991525226;27185.59221611638;27483.31291717723;46180;47238;47381.52618473771;47572;47781;191580;192926;192983;193213;252894;23679.881600591885;28502.287442814097;28953.565696514146;29118.379345858975;29401.852990735766;35686.107847302985;35732.4640130393;35737.1065723359;36044.81977590095;36254;30166;30605.693943060283;30909.690903090683;32659;33501.99494015183;31416.057518274487;31497.527537086502;31588.36823263476;31642.367152656356;31656.208594785316;30963.535546966363;31462.213444664067;31644.208894777814;31732.841335793175;31761.523577146825;26180;28263.717362826108;28324.291892702848;28441.573376399785;28823.279418014718;21419.892900535397;21657.458563536038;21671;21678.34964951054;21693.78306216981 06:19:11 INFO - 2015-11-18 06:19:11,945 DEBUG : BROWSER_OUTPUT: |2;jquery.html;303.3916996422173;306.23594132639056;309.24081380211584;311.9610048743907;312.5374445820186;13285.202887826737;13692.041557091008;14121;14329.140251584899;14765.630859228524;10746.516406761695;11066.502007409666;11077.390743509104;13603.591892243223;15882.841171588281;3948.4669569608095;4087.2847251730977;4088.264112459771;4093.7953102344914;4108.26051310764;3094.3037816491224;3166.4142133705222;4194.643455306303;4241.957580424187;4384.320430333303;4257.212415703089;4279.186954478655;4317.309230523117;4322.935155972663;4347.804348804311;4201.936970945439;4296.334068219431;4311.568843115695;4314.870553883357;4322.481302243735;3649.6350364963555;3666.156783939682;3669.669729724311;3681.5029970954115;3682.834272457746;11604.187706860523;12096.758064838741;12099.213551119237;12328.81506777399;12369.257844529357;1400.2998500749625;1634.558669159332;1721.535185499908;1738.278614375033;1854.5905112114756;8472.915270847274;8576.957115214447;8805.886587219085;8806.339524535686;8925.910740892637;1316.4405127820733;1354.9796753048713;1357.6605848537865;1358.3072632957164;1384.536180379574;49221.50778492205;51901.22148167781;51933.48284346468;54548.45451545474;55562.72218638921;5954.285485741652;5966.194563733927;5966.612170208922;6024.90962635552;6040.667763272974;6849.280825513349;6858.5199036068;6866.965665171642;6871.587704737732;6875.7937261882225;4680.204365257914;4687.156311863829;4708.058388322281;4718.693284936468;4727.4327080749845;4838.2016967200725;4854.8786280342565;4855.514448555187;4864.73243971585;4879.414470263591;2683.745044220796;2696.2720065582184;2699.122785094852;2702.905398311089;2721.8502982336163;925.7500474871747;927.3879239701764;935.8034812689411;937.8124375125002;997.9800403992025;341.7013964328739;355.78119456534046;376.2437500623766;386.01373490731453;400.1377032494978;9996.250281228937;10732.195085368628;10760.677179684622;10863.293885897392;10913.727156820984;354.8864363403738;403.10912882529936;411.32953286143396;412.0132283181763;424.22578793701;5698.8290351289525;5730.541556675456;5974.731137098841;6002.789902353396;6006.489448396847;479161;479331;479599;480204;482996;63635.04547431792;64449.67775161188;64831.67584162049;65408.345916540224;67472.3252767466;460.41757177170905;464.7234895237261;468.21807581339243;469.45543169922723;469.8426027280899;10081.546330415002;10284.794304114026;10300.124489418184;10324.896751032393;10377.636782712569;32330;33351.49972750459;33714.83142584271;33878.830605846815;34112;42449.78775106105;42609;42973.57026429821;43138.13723725571;43430.78284608557;5600.599850037491;5672.149177623224;5682.829515114553;5699.772009119754;5715.228444159985;10383.428911409945;10392.272540922062;10729;10770.623028193975;10828.675139745821;9228.723138305862;9250.352475326663;9285.442873427615;9326.626934922528;9339;8890.377673562789;9035.367524273237;9089.863652045357;9124.635014599608;9260.861087083835;7555.962220188864;9182.67860624875;9408.059194080537;9463.716088517356;9472.810543789223;5589.636673616202;5632.436756324335;5673.234113394804;5675.347335056356;5708.286464191976;9166.404183728037;9350.625974960925;9487.288453365887;9516.19112375456;9573.569189386355 06:19:11 INFO - 2015-11-18 06:19:11,945 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1169.2575214738638;1258.0753146437373;1343.0665687347298;1518.3243456661783;1596.2018990504744;6280.937190628092;6454.19322584677;6543.60738355699;6573.474122070223;6594.53838231324;6737.730490780363;6863.965680171592;6870.07254020707;6887.5178737488395;6939.895901561468;1816.9454916352465;1847.030309087733;1990.1044529961503;2023.079498828037;2034.969475457862;2023.8077382648698;2071.2439959414783;2075.439631299548;2076.3044380132674;2078.83369330454;1961.4213806926991;1986.3345779163926;1991.8306943909786;2018.606371757508;2019.4850313170195;1991.6813309870352;2043.6117137743856;2054.4042227753935;2068.7931206879266;2079.241077006897;1972.7139564763172;2069.2343832781967;2069.4412508622736;2070.523779530716;2071.6788897720726;6838.965805170942;6893.448524118108;6906.136732908387;6938.965305173442;7026.332498412643;4326.221280169569;4395.340698895159;4398.23030969579;4417.050334178167;4432.335149727534;2514.786243169333;2565.5510285700116;2625.133705877075;2627.395698989224;2640.6171105189833;685.7051467868814;758.9962050189769;829.3613917283658;835.7952301686125;840.6217202259008;8923.643054277822;8984.966728826203;8988.460692358414;9008.549572521348;9023.09769023099;3871.7676939383728;3961.8217180226384;4024.2756303865585;4040.373742069984;4054;7953.880691789628;7958.0848202457455;7963.203679632106;7976.680932762624;7986.3610911128135;4378.868633940987;4381.539938306432;4403.647708183337;4406.801693923781;4415.51429342772;3956.9802150989635;3966.2662407454714;3983.521977362735;3988.780617066031;4017.6384125428854;3322.5182348559806;3391.6608339165887;3401.5748031496064;3408.199073217792;3409.505621684842;1089.2919602258596;1095.5562996986234;1096.9286996345213;1097.846301517788;1101.5758932810925;967.3712087143413;975.1662328708993;975.512243878061;998.9300748947504;999.1756800639502;6526.28210896801;6564.901526477107;6594.274629790719;6603.834904127435;6606.6036037837885;442.00548765278126;445.0653627382471;445.69469913109623;446.4642429085111;447.0611715397639;5116.309298244763;5170.17277235648;5191.714455704972;5192.92210616841;5215.400228973641;7575.393968482508;7824.726134585262;7837.9216207838335;7943.046834379796;7943.761687149395;7941.36469082472;7962.56205908669;8008.039035315799;8062.838743225103;8070.112287648354;6478.125453063776;6482.416582507597;6525.673716314261;6543.672816359258;6591.802245932629;6529.706163222571;6532.934670653232;6541.476681865344;6560;6567.868642627216;7088;7175.425965922818;7187.82030449222;7211.639418029183;7236.891446628199;7340.302671246223;7447.069116360455;7456.813579660552;7501.474896757175;7523.435742319458;6138.14066030765;6180.47465965398;6198.81403557894;6206.317305096345;6206.689665516797;6371.076193951758;6628.7017084230365;6710.027046078194;6737.484066085091;6737.696803643946;6106.1146133811235;6153.630782153085;6196.907046394398;6212.534059945612;6267.2165979252595;6074.149619053424;6097.085437184458;6175.505959523138;6177.444030037318;6217.813465595858;6196.225471816023;6253.343398943045;6276.278228003655;6283.277423096402;6294.024426213944;5667.4615911488345;5703.543716502587;5712.885742285214;5715.628484148517;5730.541556675373;5779.913301300399;5781.913271301018;5795.043817770021;5821.38875418075;5828.2423284974375 06:19:11 INFO - 2015-11-18 06:19:11,949 DEBUG : BROWSER_OUTPUT: |4;prototype.html;268.66819477944733;276.67628874217155;279.02341803687096;288.8353638426098;291.13097404248305;5673.574481913863;5683.971580142093;5810.7094645267725;5831.416858314166;5833.5333173346135;5732.426757324265;5798.594098413113;5806.738696758645;5813.912791308123;5822.4177582241755;4480.305552639346;4481.282994720846;4497.572730590588;4505.256632655608;4513.909721805579;4260.936085958713;4373.387725718402;4379.299312110063;4488.192125417424;4492.460904691442;4383.3424986252;4431.889202769924;4432.445944256968;4442.577955094261;4449.977750111261;4404.163208990313;4424.77876106195;4426.645868330525;4434.60088592025;4453.977730111361;3780.489633899416;3789.8673546425744;3790;3797.2974999625158;3801;5649.378568357477;5650;5655.604107712462;5663.49028587425;5672.404397538282;4914.754262286907;4938.432080310774;4939.777710003059;4940.90118197634;4942.258661200813;6032.758689652409;6042.788502402439;6043.395660433922;6044.304904935944;6049.062395328731;4040.6363427291685;4046.838126474967;4058.4115303281155;4074.7962601870086;4079.4288799568085;5942;6374.649394283312;6380.712867920908;6395.999999999954;6420.871582568369;4139.5653456387245;4149.398337241144;4150.771707556053;4162.042730172077;4172.77049762266;4210.978945105255;4215.241256573785;4220.599043090901;4229.921370050679;4246.702730808814;4919.729414882144;4927.827526036644;4928.310036594879;4930.8767280818265;4950.801967921243;5895.204147440127;6097.786577469856;6099.176611157525;6101.328853826076;6131;1478.2830327291256;1497.9550613481613;1500.8199016118135;1503.6842263124647;1506.1941861104256;955.9522023898777;957.5068839547639;963.2438535749403;978.4373984958677;981.2787601113176;5825.825225243249;5839.328477225174;5840.503557197686;5850.531957443394;5876.265466816648;6307.716152773044;6342.587731797511;6348.460380867586;6351.364863513704;6386.042093685985;5865.794697185578;5880.7353669085;5882.705864706748;5892.469677728939;5934.584579079509;3264.7061764441314;3418.1283772638317;3422.435298175823;3424.9828750856086;3428.708559772447;6493.675316234169;6500.772472963424;6512.706928188243;6519.250286217051;6522.217333919865;6303.149074874925;6453.064305675744;6457.321981191907;6465.321141280192;6473.449756770633;6145.20112385396;6465.741370345228;6470.579412338183;6472.320406357265;6583.419979205052;6335.841603959938;6378.808635740936;6394.520410969103;6404.391582799627;6437.517186210959;6056.727447264796;6227.532935029982;6229.750809967551;6244.906326405016;6263.843403914939;6546.214454265519;6558.278589355263;6565.540412171294;6572.342765723581;6623.768168114093;4041.5958404160524;4085.285075111784;4086.7139300248696;4088.12105397341;4095.8361665533043;6216.968915155395;6378.394052564999;6385.297617262005;6396.680165991775;6401.967990160019;6179.104029915727;6191.597546159485;6196.442320191204;6197.442230199304;6208.627482350893;6155.84610384744;6170.167027451236;6174.506039516738;6190.659513726789;6191.721372538156;5893.44012318829;6054.394560544087;6062.333143354315;6079.422454866781;6110.205673262448;6183.659898705614;6192.907106393318;6214.40963108504;6216.72024758896;6217.564770466205;6048.183495228264;6053.606515576474;6070.696465176635;6072.423119803611;6080.78717244912 06:19:11 INFO - 2015-11-18 06:19:11,950 DEBUG : BROWSER_OUTPUT: |5;yui.html;629.4586655476293;667.2226855713094;688.284184448174;702.076769048701;707.9858402831942;12308.815367769479;12507.562235321766;12594.181378210422;12595.181313214634;12614.81077783834;11548.133889958244;11561.017313528338;11610.419479026057;11631.709207269823;11648.708782280426;612.3753771153421;622.5859803230869;623.7286780250582;626.1765778001935;784.1061190243113;797.2625321577547;807.1202389395557;807.9192080791903;814.9592520373988;817.3706246190429;828.7265202483196;836.7196989008689;843.2073850580465;844.1980118887051;846.7290467050547;833.8248967716756;833.8665813469846;834.9749507514723;837.1962915601031;838.9664413423455;477.49147158276327;488.182294656449;489.6009752052102;490.03463177540186;605.2253116011512;10362.740931476697;11424.600138995178;11876.52493900243;11883.227590206609;11928.940355298255;9751.756206094833;10322.071013608811;10520.210984176141;10699.78600427995;11025.889741102566;7866.370690344816;7946.443748937577;8045.637946292372;8251.051129120167;8535.061143274235;3029.8485075746125;3075.3695492424;3121.4069326828057;3124.187711195083;3127.734142597882;42768.3584746229;42811.57188428107;43088.35367469459;43134.78432607848;43217.13565728668;7816.804579885548;8143.959280203561;8251.381146413923;8364.330853531703;8443.493390396474;17109.743353849703;17116;17117.400890968755;17139.571510712332;17171.914140429468;6202.906956395657;6349.238091428966;6445.871082578416;6449.290578036413;6594.8351291218105;3404.472306792451;3509.842057107436;3546.6985306249258;3585.4263317869018;3625.0393645683916;2662.547366947585;2677.3039009857575;2683.5706286993986;2687.2341382705836;2695.4070104576963;379.9259144466858;383.3502213747671;383.56273847813515;393.29600015971664;395.5470985721333;441.4702357171343;464.54706661005923;497.2739799892126;506.2357683473093;563.2227526014157;7148.180165141522;7419.332260096618;7457.589832559262;7501.549907005598;7597.696092156362;414.40325930659753;420.0842164082269;486.42601729958284;505.8254902058784;590.2474345209891;3180.538821870816;3322.3853587086464;3378.2567835076243;3480.1125712943544;3597.640235976434;47519.52480475151;47682.5231747685;47911.76044119827;48045.5195448048;48262.51737482651;44931.77534112309;45142.54857451449;45382.319265211045;45520.54479455229;45912.770436147606;40232.396514052314;41223.587764122574;42286;42532.574674252864;42783.572164279205;7103.467239957127;7185.748498802517;7284.526505777108;7637.847243054996;7709.190534993745;30340.24149396283;30988.0703578893;31088.22279443032;31475.685243147276;31748.841255793574;38506.6149338503;40545;41625;41991.58008419999;42020.94947626334;6288.779892703734;6532.510061745483;6768.492363072691;6821.556598821061;6920.480963927826;9986.500674966368;10027.849582256418;10059.144972677404;10096.899031009889;10127.24045696561;9366.718998430058;9440.386374885611;9457.527123643928;9474.85787713171;9520.714378568655;9432.339736218673;9438.811223775348;9456.2907781918;9476.241900647794;9525;8391.790205244917;9354.625814967325;9360.859587106062;9378.296627752808;9411.29415293842;7635.88546171819;7794.532328060334;7965.522068675898;8100.554469503998;8330.625121869409;7904;8173.468724532886;8363.247307742333;8487.915120848713;8589.871151932852 06:19:11 INFO - 2015-11-18 06:19:11,950 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:19:11 INFO - 2015-11-18 06:19:11,950 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:19:11 INFO - 2015-11-18 06:19:11,951 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,2995 06:19:11 INFO - 2015-11-18 06:19:11,951 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:19:11 INFO - 2015-11-18 06:19:11,951 DEBUG : BROWSER_OUTPUT: __startTimestamp1447856351937__endTimestamp 06:19:11 INFO - 2015-11-18 06:19:11,951 DEBUG : BROWSER_OUTPUT: 06:19:11 INFO - 2015-11-18 06:19:11,951 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:19:11 INFO - 2015-11-18 06:19:11,951 DEBUG : BROWSER_OUTPUT: Number of tests: 6 06:19:11 INFO - 2015-11-18 06:19:11,952 DEBUG : BROWSER_OUTPUT: 06:19:11 INFO - 2015-11-18 06:19:11,952 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4917.58 Median:5089.95 stddev:505.15 (9.9%) stddev-sans-first:482.96 06:19:11 INFO - 2015-11-18 06:19:11,953 DEBUG : BROWSER_OUTPUT: Values: 2966.5 3000.1 3007.3 3055.1 3190.9 4714.7 4830.1 4832.0 4853.9 4864.5 4792.7 4835.6 4943.0 4956.6 5010.5 4352.8 4395.8 4402.4 4414.7 4429.1 4516.1 4609.9 4696.8 4700.3 4725.0 4526.8 4601.0 4648.1 4651.9 4661.7 4549.6 4597.0 4616.6 4622.2 4679.6 4598.2 4610.7 4623.8 4641.5 4645.1 4856.3 4878.8 4885.6 4887.4 4927.6 4969.9 5065.7 5143.0 5159.2 5179.5 5198.3 5300.3 5305.8 5332.9 5358.2 4939.3 4989.3 4989.9 5016.2 5062.1 5176.7 5205.1 5268.1 5319.1 5368.8 5256.4 5261.4 5267.5 5274.2 5334.5 5334.6 5347.5 5360.6 5394.8 5418.6 5132.1 5211.9 5255.0 5265.4 5286.2 5140.8 5199.3 5204.0 5209.3 5220.9 4011.0 4053.4 4099.7 4102.5 4104.7 3684.3 3726.0 3735.4 3737.0 3777.4 4941.7 4963.1 4965.6 4968.9 4977.2 5217.1 5326.3 5342.3 5343.9 5379.5 4963.3 4990.4 4999.6 5005.3 5010.7 4688.7 4697.7 4701.3 4705.1 4709.2 5380.3 5438.6 5478.2 5491.3 5510.1 5166.8 5234.8 5248.7 5282.5 5289.1 5271.4 5284.2 5292.3 5300.1 5309.2 5082.7 5089.2 5089.9 5101.4 5157.2 5059.0 5085.2 5220.6 5246.4 5255.8 5201.2 5209.2 5212.8 5218.7 5258.5 5226.8 5228.0 5234.8 5242.6 5249.7 5103.6 5114.2 5137.8 5143.0 5144.3 4931.9 5208.2 5227.4 5246.4 5253.3 5124.0 5140.3 5155.6 5162.6 5203.7 06:19:11 INFO - 2015-11-18 06:19:11,953 DEBUG : BROWSER_OUTPUT: 06:19:11 INFO - 2015-11-18 06:19:11,953 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:40702.72 Median:298569.50 stddev:65666.59 (22.0%) stddev-sans-first:65781.47 06:19:11 INFO - 2015-11-18 06:19:11,954 DEBUG : BROWSER_OUTPUT: Values: 512.6 513.2 514.5 523.4 537.6 29513.6 35414.8 36365.1 36516.6 36660.3 39256.0 39326.1 39328.6 39356.8 39358.4 7511.4 7615.7 8154.8 8377.0 8388.7 10211.9 10385.1 10401.8 10401.9 10443.7 10093.5 10106.4 10114.6 10139.7 10170.5 7695.2 7893.2 10108.3 10158.7 10212.2 9905.7 9981.3 10013.4 10016.5 10099.3 36534.3 36920.4 36974.3 37008.8 37065.8 2706.7 2710.4 2729.4 2809.2 2817.5 24759.1 24811.0 24879.3 24970.3 25711.6 2039.8 2050.1 2053.9 2054.0 2154.6 234238.0 235987.0 238012.0 238354.0 240972.8 47460.5 48343.5 54407.7 54770.5 54892.3 61874.0 62014.7 64058.0 64090.4 64223.4 12160.7 12320.1 12335.2 12338.2 12370.1 9087.7 9137.3 9148.6 9149.4 9156.3 17614.6 17675.3 17866.7 17908.7 17980.6 7128.9 7369.6 7390.6 7404.2 7442.6 842.8 856.2 927.1 975.5 996.3 26012.6 26299.3 26455.0 27015.0 27213.3 900.3 924.2 974.1 1021.4 1025.8 12191.9 12278.8 12789.6 13016.9 13078.0 295613.0 295839.0 301300.0 303787.0 304040.0 48894.3 48981.3 49297.3 49437.5 49790.0 1190.1 1218.5 1224.5 1225.5 1233.6 22056.4 23533.4 24403.4 27185.6 27483.3 46180.0 47238.0 47381.5 47572.0 47781.0 191580.0 192926.0 192983.0 193213.0 252894.0 23679.9 28502.3 28953.6 29118.4 29401.9 35686.1 35732.5 35737.1 36044.8 36254.0 30166.0 30605.7 30909.7 32659.0 33502.0 31416.1 31497.5 31588.4 31642.4 31656.2 30963.5 31462.2 31644.2 31732.8 31761.5 26180.0 28263.7 28324.3 28441.6 28823.3 21419.9 21657.5 21671.0 21678.3 21693.8 06:19:11 INFO - 2015-11-18 06:19:11,955 DEBUG : BROWSER_OUTPUT: 06:19:11 INFO - 2015-11-18 06:19:11,955 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:23836.78 Median:23876.86 stddev:78703.65 (329.6%) stddev-sans-first:78904.59 06:19:11 INFO - 2015-11-18 06:19:11,956 DEBUG : BROWSER_OUTPUT: Values: 303.4 306.2 309.2 312.0 312.5 13285.2 13692.0 14121.0 14329.1 14765.6 10746.5 11066.5 11077.4 13603.6 15882.8 3948.5 4087.3 4088.3 4093.8 4108.3 3094.3 3166.4 4194.6 4242.0 4384.3 4257.2 4279.2 4317.3 4322.9 4347.8 4201.9 4296.3 4311.6 4314.9 4322.5 3649.6 3666.2 3669.7 3681.5 3682.8 11604.2 12096.8 12099.2 12328.8 12369.3 1400.3 1634.6 1721.5 1738.3 1854.6 8472.9 8577.0 8805.9 8806.3 8925.9 1316.4 1355.0 1357.7 1358.3 1384.5 49221.5 51901.2 51933.5 54548.5 55562.7 5954.3 5966.2 5966.6 6024.9 6040.7 6849.3 6858.5 6867.0 6871.6 6875.8 4680.2 4687.2 4708.1 4718.7 4727.4 4838.2 4854.9 4855.5 4864.7 4879.4 2683.7 2696.3 2699.1 2702.9 2721.9 925.8 927.4 935.8 937.8 998.0 341.7 355.8 376.2 386.0 400.1 9996.3 10732.2 10760.7 10863.3 10913.7 354.9 403.1 411.3 412.0 424.2 5698.8 5730.5 5974.7 6002.8 6006.5 479161.0 479331.0 479599.0 480204.0 482996.0 63635.0 64449.7 64831.7 65408.3 67472.3 460.4 464.7 468.2 469.5 469.8 10081.5 10284.8 10300.1 10324.9 10377.6 32330.0 33351.5 33714.8 33878.8 34112.0 42449.8 42609.0 42973.6 43138.1 43430.8 5600.6 5672.1 5682.8 5699.8 5715.2 10383.4 10392.3 10729.0 10770.6 10828.7 9228.7 9250.4 9285.4 9326.6 9339.0 8890.4 9035.4 9089.9 9124.6 9260.9 7556.0 9182.7 9408.1 9463.7 9472.8 5589.6 5632.4 5673.2 5675.3 5708.3 9166.4 9350.6 9487.3 9516.2 9573.6 06:19:11 INFO - 2015-11-18 06:19:11,956 DEBUG : BROWSER_OUTPUT: 06:19:11 INFO - 2015-11-18 06:19:11,956 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4805.10 Median:6176.47 stddev:2456.12 (39.8%) stddev-sans-first:2447.80 06:19:11 INFO - 2015-11-18 06:19:11,956 DEBUG : BROWSER_OUTPUT: Values: 1169.3 1258.1 1343.1 1518.3 1596.2 6280.9 6454.2 6543.6 6573.5 6594.5 6737.7 6864.0 6870.1 6887.5 6939.9 1816.9 1847.0 1990.1 2023.1 2035.0 2023.8 2071.2 2075.4 2076.3 2078.8 1961.4 1986.3 1991.8 2018.6 2019.5 1991.7 2043.6 2054.4 2068.8 2079.2 1972.7 2069.2 2069.4 2070.5 2071.7 6839.0 6893.4 6906.1 6939.0 7026.3 4326.2 4395.3 4398.2 4417.1 4432.3 2514.8 2565.6 2625.1 2627.4 2640.6 685.7 759.0 829.4 835.8 840.6 8923.6 8985.0 8988.5 9008.5 9023.1 3871.8 3961.8 4024.3 4040.4 4054.0 7953.9 7958.1 7963.2 7976.7 7986.4 4378.9 4381.5 4403.6 4406.8 4415.5 3957.0 3966.3 3983.5 3988.8 4017.6 3322.5 3391.7 3401.6 3408.2 3409.5 1089.3 1095.6 1096.9 1097.8 1101.6 967.4 975.2 975.5 998.9 999.2 6526.3 6564.9 6594.3 6603.8 6606.6 442.0 445.1 445.7 446.5 447.1 5116.3 5170.2 5191.7 5192.9 5215.4 7575.4 7824.7 7837.9 7943.0 7943.8 7941.4 7962.6 8008.0 8062.8 8070.1 6478.1 6482.4 6525.7 6543.7 6591.8 6529.7 6532.9 6541.5 6560.0 6567.9 7088.0 7175.4 7187.8 7211.6 7236.9 7340.3 7447.1 7456.8 7501.5 7523.4 6138.1 6180.5 6198.8 6206.3 6206.7 6371.1 6628.7 6710.0 6737.5 6737.7 6106.1 6153.6 6196.9 6212.5 6267.2 6074.1 6097.1 6175.5 6177.4 6217.8 6196.2 6253.3 6276.3 6283.3 6294.0 5667.5 5703.5 5712.9 5715.6 5730.5 5779.9 5781.9 5795.0 5821.4 5828.2 06:19:11 INFO - 2015-11-18 06:19:11,957 DEBUG : BROWSER_OUTPUT: 06:19:11 INFO - 2015-11-18 06:19:11,958 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5093.17 Median:5878.50 stddev:1571.41 (26.7%) stddev-sans-first:1533.53 06:19:11 INFO - 2015-11-18 06:19:11,959 DEBUG : BROWSER_OUTPUT: Values: 268.7 276.7 279.0 288.8 291.1 5673.6 5684.0 5810.7 5831.4 5833.5 5732.4 5798.6 5806.7 5813.9 5822.4 4480.3 4481.3 4497.6 4505.3 4513.9 4260.9 4373.4 4379.3 4488.2 4492.5 4383.3 4431.9 4432.4 4442.6 4450.0 4404.2 4424.8 4426.6 4434.6 4454.0 3780.5 3789.9 3790.0 3797.3 3801.0 5649.4 5650.0 5655.6 5663.5 5672.4 4914.8 4938.4 4939.8 4940.9 4942.3 6032.8 6042.8 6043.4 6044.3 6049.1 4040.6 4046.8 4058.4 4074.8 4079.4 5942.0 6374.6 6380.7 6396.0 6420.9 4139.6 4149.4 4150.8 4162.0 4172.8 4211.0 4215.2 4220.6 4229.9 4246.7 4919.7 4927.8 4928.3 4930.9 4950.8 5895.2 6097.8 6099.2 6101.3 6131.0 1478.3 1498.0 1500.8 1503.7 1506.2 956.0 957.5 963.2 978.4 981.3 5825.8 5839.3 5840.5 5850.5 5876.3 6307.7 6342.6 6348.5 6351.4 6386.0 5865.8 5880.7 5882.7 5892.5 5934.6 3264.7 3418.1 3422.4 3425.0 3428.7 6493.7 6500.8 6512.7 6519.3 6522.2 6303.1 6453.1 6457.3 6465.3 6473.4 6145.2 6465.7 6470.6 6472.3 6583.4 6335.8 6378.8 6394.5 6404.4 6437.5 6056.7 6227.5 6229.8 6244.9 6263.8 6546.2 6558.3 6565.5 6572.3 6623.8 4041.6 4085.3 4086.7 4088.1 4095.8 6217.0 6378.4 6385.3 6396.7 6402.0 6179.1 6191.6 6196.4 6197.4 6208.6 6155.8 6170.2 6174.5 6190.7 6191.7 5893.4 6054.4 6062.3 6079.4 6110.2 6183.7 6192.9 6214.4 6216.7 6217.6 6048.2 6053.6 6070.7 6072.4 6080.8 06:19:11 INFO - 2015-11-18 06:19:11,959 DEBUG : BROWSER_OUTPUT: 06:19:11 INFO - 2015-11-18 06:19:11,960 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:11955.46 Median:489.82 stddev:14131.88 (2885.1%) stddev-sans-first:14145.93 06:19:11 INFO - 2015-11-18 06:19:11,960 DEBUG : BROWSER_OUTPUT: Values: 629.5 667.2 688.3 702.1 708.0 12308.8 12507.6 12594.2 12595.2 12614.8 11548.1 11561.0 11610.4 11631.7 11648.7 612.4 622.6 623.7 626.2 784.1 797.3 807.1 807.9 815.0 817.4 828.7 836.7 843.2 844.2 846.7 833.8 833.9 835.0 837.2 839.0 477.5 488.2 489.6 490.0 605.2 10362.7 11424.6 11876.5 11883.2 11928.9 9751.8 10322.1 10520.2 10699.8 11025.9 7866.4 7946.4 8045.6 8251.1 8535.1 3029.8 3075.4 3121.4 3124.2 3127.7 42768.4 42811.6 43088.4 43134.8 43217.1 7816.8 8144.0 8251.4 8364.3 8443.5 17109.7 17116.0 17117.4 17139.6 17171.9 6202.9 6349.2 6445.9 6449.3 6594.8 3404.5 3509.8 3546.7 3585.4 3625.0 2662.5 2677.3 2683.6 2687.2 2695.4 379.9 383.4 383.6 393.3 395.5 441.5 464.5 497.3 506.2 563.2 7148.2 7419.3 7457.6 7501.5 7597.7 414.4 420.1 486.4 505.8 590.2 3180.5 3322.4 3378.3 3480.1 3597.6 47519.5 47682.5 47911.8 48045.5 48262.5 44931.8 45142.5 45382.3 45520.5 45912.8 40232.4 41223.6 42286.0 42532.6 42783.6 7103.5 7185.7 7284.5 7637.8 7709.2 30340.2 30988.1 31088.2 31475.7 31748.8 38506.6 40545.0 41625.0 41991.6 42020.9 6288.8 6532.5 6768.5 6821.6 6920.5 9986.5 10027.8 10059.1 10096.9 10127.2 9366.7 9440.4 9457.5 9474.9 9520.7 9432.3 9438.8 9456.3 9476.2 9525.0 8391.8 9354.6 9360.9 9378.3 9411.3 7635.9 7794.5 7965.5 8100.6 8330.6 7904.0 8173.5 8363.2 8487.9 8589.9 06:19:11 INFO - 2015-11-18 06:19:11,960 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:19:11 INFO - 2015-11-18 06:19:11,960 DEBUG : BROWSER_OUTPUT: 06:19:12 INFO - 2015-11-18 06:19:12,641 INFO : Browser exited with error code: 0 06:19:12 INFO - 2015-11-18 06:19:12,671 INFO : Completed test dromaeo_css (00:18:38) 06:19:12 INFO - 2015-11-18 06:19:12,671 INFO : Starting test kraken 06:19:12 INFO - 2015-11-18 06:19:12,671 DEBUG : operating with platform_type : mac_ 06:19:12 INFO - 2015-11-18 06:19:12,671 INFO : Initialising browser for kraken test... 06:19:12 INFO - 2015-11-18 06:19:12,685 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/tmpsAUDfC/profile http://localhost:49280/getInfo.html 06:19:15 INFO - 2015-11-18 06:19:15,058 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:19:15 INFO - 2015-11-18 06:19:15,058 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:19:15 INFO - 2015-11-18 06:19:15,058 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 06:19:15 INFO - 2015-11-18 06:19:15,058 DEBUG : BROWSER_OUTPUT: __metrics 06:19:15 INFO - 2015-11-18 06:19:15,075 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:19:15 INFO - 2015-11-18 06:19:15,820 INFO : Browser initialized. 06:19:15 INFO - 2015-11-18 06:19:15,821 INFO : Running cycle 1/1 for kraken test... 06:19:15 INFO - 2015-11-18 06:19:15,821 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/tmpsAUDfC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:19:27 INFO - 2015-11-18 06:19:27,834 DEBUG : BROWSER_OUTPUT: RSS: Main: 235413504 06:19:27 INFO - 2015-11-18 06:19:27,834 DEBUG : BROWSER_OUTPUT: 06:19:30 INFO - 2015-11-18 06:19:30,447 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 06:19:31 INFO - 2015-11-18 06:19:31,035 DEBUG : BROWSER_OUTPUT: RSS: Main: 293351424 06:19:31 INFO - 2015-11-18 06:19:31,035 DEBUG : BROWSER_OUTPUT: 06:19:33 INFO - 2015-11-18 06:19:33,835 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 06:19:34 INFO - 2015-11-18 06:19:34,424 DEBUG : BROWSER_OUTPUT: RSS: Main: 305127424 06:19:34 INFO - 2015-11-18 06:19:34,425 DEBUG : BROWSER_OUTPUT: 06:19:37 INFO - 2015-11-18 06:19:37,577 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 06:19:38 INFO - 2015-11-18 06:19:38,145 DEBUG : BROWSER_OUTPUT: RSS: Main: 287293440 06:19:38 INFO - 2015-11-18 06:19:38,145 DEBUG : BROWSER_OUTPUT: 06:19:40 INFO - 2015-11-18 06:19:40,270 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 06:19:40 INFO - 2015-11-18 06:19:40,852 DEBUG : BROWSER_OUTPUT: RSS: Main: 290930688 06:19:40 INFO - 2015-11-18 06:19:40,852 DEBUG : BROWSER_OUTPUT: 06:19:43 INFO - 2015-11-18 06:19:43,177 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:19:43 INFO - 2015-11-18 06:19:43,748 DEBUG : BROWSER_OUTPUT: RSS: Main: 290537472 06:19:43 INFO - 2015-11-18 06:19:43,748 DEBUG : BROWSER_OUTPUT: 06:19:47 INFO - 2015-11-18 06:19:47,050 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 06:19:47 INFO - 2015-11-18 06:19:47,636 DEBUG : BROWSER_OUTPUT: RSS: Main: 289435648 06:19:47 INFO - 2015-11-18 06:19:47,636 DEBUG : BROWSER_OUTPUT: 06:19:51 INFO - 2015-11-18 06:19:51,040 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 06:19:51 INFO - 2015-11-18 06:19:51,614 DEBUG : BROWSER_OUTPUT: RSS: Main: 291753984 06:19:51 INFO - 2015-11-18 06:19:51,614 DEBUG : BROWSER_OUTPUT: 06:19:54 INFO - 2015-11-18 06:19:54,683 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 06:19:55 INFO - 2015-11-18 06:19:55,271 DEBUG : BROWSER_OUTPUT: RSS: Main: 290611200 06:19:55 INFO - 2015-11-18 06:19:55,271 DEBUG : BROWSER_OUTPUT: 06:19:56 INFO - 2015-11-18 06:19:56,938 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:19:57 INFO - 2015-11-18 06:19:57,517 DEBUG : BROWSER_OUTPUT: RSS: Main: 327839744 06:19:57 INFO - 2015-11-18 06:19:57,517 DEBUG : BROWSER_OUTPUT: 06:19:59 INFO - 2015-11-18 06:19:59,435 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:20:00 INFO - 2015-11-18 06:20:00,057 DEBUG : BROWSER_OUTPUT: RSS: Main: 327028736 06:20:00 INFO - 2015-11-18 06:20:00,057 DEBUG : BROWSER_OUTPUT: 06:20:02 INFO - 2015-11-18 06:20:02,300 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:20:02 INFO - 2015-11-18 06:20:02,884 DEBUG : BROWSER_OUTPUT: RSS: Main: 334905344 06:20:02 INFO - 2015-11-18 06:20:02,884 DEBUG : BROWSER_OUTPUT: 06:20:05 INFO - 2015-11-18 06:20:05,682 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:20:06 INFO - 2015-11-18 06:20:06,277 DEBUG : BROWSER_OUTPUT: RSS: Main: 336584704 06:20:06 INFO - 2015-11-18 06:20:06,277 DEBUG : BROWSER_OUTPUT: 06:20:09 INFO - 2015-11-18 06:20:09,084 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:20:09 INFO - 2015-11-18 06:20:09,665 DEBUG : BROWSER_OUTPUT: RSS: Main: 336379904 06:20:09 INFO - 2015-11-18 06:20:09,665 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,518 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 06:20:11 INFO - 2015-11-18 06:20:11,767 DEBUG : BROWSER_OUTPUT: RSS: Main: 395108352 06:20:11 INFO - 2015-11-18 06:20:11,767 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,768 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:20:11 INFO - 2015-11-18 06:20:11,769 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:20:11 INFO - 2015-11-18 06:20:11,769 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:20:11 INFO - 2015-11-18 06:20:11,769 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:20:11 INFO - 2015-11-18 06:20:11,769 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;111;105;113;113;109;102;112;119;116 06:20:11 INFO - 2015-11-18 06:20:11,769 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;163;135;162;175;142;166;166;140;182;139 06:20:11 INFO - 2015-11-18 06:20:11,770 DEBUG : BROWSER_OUTPUT: |2;audio-dft;169;173;178;181;173;175;175;179;176;172 06:20:11 INFO - 2015-11-18 06:20:11,770 DEBUG : BROWSER_OUTPUT: |3;audio-fft;80;90;99;94;78;77;80;89;88;74 06:20:11 INFO - 2015-11-18 06:20:11,770 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;106;101;155;100;101;99;145;98;95;144 06:20:11 INFO - 2015-11-18 06:20:11,770 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;131;135;129;133;129;135;135;128;123;135 06:20:11 INFO - 2015-11-18 06:20:11,770 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;136;142;148;144;139;136;142;141;142;139 06:20:11 INFO - 2015-11-18 06:20:11,770 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;101;105;113;104;113;105;114;114;113;113 06:20:11 INFO - 2015-11-18 06:20:11,771 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;61;62;64;66;67;67;67;76;65;67 06:20:11 INFO - 2015-11-18 06:20:11,771 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;70;71;77;78;78;76;77;76;74 06:20:11 INFO - 2015-11-18 06:20:11,771 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;89;97;108;98;98;96;96;98;99;97 06:20:11 INFO - 2015-11-18 06:20:11,772 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;147;140;141;156;148;138;150;136;141;140 06:20:11 INFO - 2015-11-18 06:20:11,772 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;163;160;173;171;176;154;170;173;171;158 06:20:11 INFO - 2015-11-18 06:20:11,772 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;75;76;76;80;80;79;80;82;81;80 06:20:11 INFO - 2015-11-18 06:20:11,772 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:20:11 INFO - 2015-11-18 06:20:11,772 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:20:11 INFO - 2015-11-18 06:20:11,773 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,928 06:20:11 INFO - 2015-11-18 06:20:11,773 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:20:11 INFO - 2015-11-18 06:20:11,773 DEBUG : BROWSER_OUTPUT: __startTimestamp1447856411768__endTimestamp 06:20:11 INFO - 2015-11-18 06:20:11,773 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,773 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:20:11 INFO - 2015-11-18 06:20:11,774 DEBUG : BROWSER_OUTPUT: Number of tests: 14 06:20:11 INFO - 2015-11-18 06:20:11,774 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,774 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.80 Median:112.50 stddev:5.03 (4.5%) stddev-sans-first:5.23 06:20:11 INFO - 2015-11-18 06:20:11,774 DEBUG : BROWSER_OUTPUT: Values: 108.0 111.0 105.0 113.0 113.0 109.0 102.0 112.0 119.0 116.0 06:20:11 INFO - 2015-11-18 06:20:11,774 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,775 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:157.00 Median:164.50 stddev:16.65 (10.1%) stddev-sans-first:17.51 06:20:11 INFO - 2015-11-18 06:20:11,775 DEBUG : BROWSER_OUTPUT: Values: 163.0 135.0 162.0 175.0 142.0 166.0 166.0 140.0 182.0 139.0 06:20:11 INFO - 2015-11-18 06:20:11,775 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,775 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:175.10 Median:175.50 stddev:3.57 (2.0%) stddev-sans-first:3.03 06:20:11 INFO - 2015-11-18 06:20:11,775 DEBUG : BROWSER_OUTPUT: Values: 169.0 173.0 178.0 181.0 173.0 175.0 175.0 179.0 176.0 172.0 06:20:11 INFO - 2015-11-18 06:20:11,775 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,777 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:84.90 Median:88.50 stddev:8.24 (9.3%) stddev-sans-first:8.55 06:20:11 INFO - 2015-11-18 06:20:11,777 DEBUG : BROWSER_OUTPUT: Values: 80.0 90.0 99.0 94.0 78.0 77.0 80.0 89.0 88.0 74.0 06:20:11 INFO - 2015-11-18 06:20:11,777 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,777 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:114.40 Median:150.00 stddev:23.52 (15.7%) stddev-sans-first:24.75 06:20:11 INFO - 2015-11-18 06:20:11,777 DEBUG : BROWSER_OUTPUT: Values: 106.0 101.0 155.0 100.0 101.0 99.0 145.0 98.0 95.0 144.0 06:20:11 INFO - 2015-11-18 06:20:11,778 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,778 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:131.30 Median:134.00 stddev:4.06 (3.0%) stddev-sans-first:4.30 06:20:11 INFO - 2015-11-18 06:20:11,778 DEBUG : BROWSER_OUTPUT: Values: 131.0 135.0 129.0 133.0 129.0 135.0 135.0 128.0 123.0 135.0 06:20:11 INFO - 2015-11-18 06:20:11,778 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,778 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:140.90 Median:142.00 stddev:3.63 (2.6%) stddev-sans-first:3.40 06:20:11 INFO - 2015-11-18 06:20:11,779 DEBUG : BROWSER_OUTPUT: Values: 136.0 142.0 148.0 144.0 139.0 136.0 142.0 141.0 142.0 139.0 06:20:11 INFO - 2015-11-18 06:20:11,779 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,779 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:109.50 Median:113.00 stddev:5.08 (4.5%) stddev-sans-first:4.36 06:20:11 INFO - 2015-11-18 06:20:11,779 DEBUG : BROWSER_OUTPUT: Values: 101.0 105.0 113.0 104.0 113.0 105.0 114.0 114.0 113.0 113.0 06:20:11 INFO - 2015-11-18 06:20:11,779 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,779 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:66.20 Median:67.00 stddev:4.08 (6.1%) stddev-sans-first:3.87 06:20:11 INFO - 2015-11-18 06:20:11,780 DEBUG : BROWSER_OUTPUT: Values: 61.0 62.0 64.0 66.0 67.0 67.0 67.0 76.0 65.0 67.0 06:20:11 INFO - 2015-11-18 06:20:11,780 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,780 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:74.60 Median:76.50 stddev:3.41 (4.5%) stddev-sans-first:2.95 06:20:11 INFO - 2015-11-18 06:20:11,780 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 71.0 77.0 78.0 78.0 76.0 77.0 76.0 74.0 06:20:11 INFO - 2015-11-18 06:20:11,780 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,781 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:97.60 Median:97.50 stddev:4.60 (4.7%) stddev-sans-first:3.68 06:20:11 INFO - 2015-11-18 06:20:11,781 DEBUG : BROWSER_OUTPUT: Values: 89.0 97.0 108.0 98.0 98.0 96.0 96.0 98.0 99.0 97.0 06:20:11 INFO - 2015-11-18 06:20:11,781 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,781 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:144.00 stddev:6.27 (4.4%) stddev-sans-first:6.54 06:20:11 INFO - 2015-11-18 06:20:11,781 DEBUG : BROWSER_OUTPUT: Values: 147.0 140.0 141.0 156.0 148.0 138.0 150.0 136.0 141.0 140.0 06:20:11 INFO - 2015-11-18 06:20:11,781 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,783 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:171.00 stddev:7.52 (4.4%) stddev-sans-first:7.84 06:20:11 INFO - 2015-11-18 06:20:11,783 DEBUG : BROWSER_OUTPUT: Values: 163.0 160.0 173.0 171.0 176.0 154.0 170.0 173.0 171.0 158.0 06:20:11 INFO - 2015-11-18 06:20:11,783 DEBUG : BROWSER_OUTPUT: 06:20:11 INFO - 2015-11-18 06:20:11,783 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.90 Median:80.00 stddev:2.38 (3.0%) stddev-sans-first:2.06 06:20:11 INFO - 2015-11-18 06:20:11,783 DEBUG : BROWSER_OUTPUT: Values: 75.0 76.0 76.0 80.0 80.0 79.0 80.0 82.0 81.0 80.0 06:20:11 INFO - 2015-11-18 06:20:11,783 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:20:11 INFO - 2015-11-18 06:20:11,783 DEBUG : BROWSER_OUTPUT: 06:20:12 INFO - 2015-11-18 06:20:12,536 INFO : Browser exited with error code: 0 06:20:12 INFO - 2015-11-18 06:20:12,588 INFO : Completed test kraken (00:00:59) 06:20:12 INFO - 2015-11-18 06:20:12,588 INFO : Starting test v8_7 06:20:12 INFO - 2015-11-18 06:20:12,588 DEBUG : operating with platform_type : mac_ 06:20:12 INFO - 2015-11-18 06:20:12,588 INFO : Initialising browser for v8_7 test... 06:20:12 INFO - 2015-11-18 06:20:12,602 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/tmp4Bsn3k/profile http://localhost:49280/getInfo.html 06:20:14 INFO - 2015-11-18 06:20:14,890 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:20:14 INFO - 2015-11-18 06:20:14,890 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:20:14 INFO - 2015-11-18 06:20:14,891 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 06:20:14 INFO - 2015-11-18 06:20:14,891 DEBUG : BROWSER_OUTPUT: __metrics 06:20:14 INFO - 2015-11-18 06:20:14,907 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:20:15 INFO - 2015-11-18 06:20:15,604 INFO : Browser initialized. 06:20:15 INFO - 2015-11-18 06:20:15,604 INFO : Running cycle 1/1 for v8_7 test... 06:20:15 INFO - 2015-11-18 06:20:15,605 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/T/tmp4Bsn3k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:20:27 INFO - 2015-11-18 06:20:27,622 DEBUG : BROWSER_OUTPUT: RSS: Main: 235737088 06:20:27 INFO - 2015-11-18 06:20:27,622 DEBUG : BROWSER_OUTPUT: 06:20:50 INFO - 2015-11-18 06:20:50,389 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49280/tests/v8_7/run.html (next: http://localhost:49280/tests/v8_7/run.html) 06:20:50 INFO - 2015-11-18 06:20:50,646 DEBUG : BROWSER_OUTPUT: RSS: Main: 711675904 06:20:50 INFO - 2015-11-18 06:20:50,646 DEBUG : BROWSER_OUTPUT: 06:20:50 INFO - 2015-11-18 06:20:50,646 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:20:50 INFO - 2015-11-18 06:20:50,646 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:20:50 INFO - 2015-11-18 06:20:50,646 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:20:50 INFO - 2015-11-18 06:20:50,646 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:20:50 INFO - 2015-11-18 06:20:50,647 DEBUG : BROWSER_OUTPUT: |0;Richards;175.28483786152498 06:20:50 INFO - 2015-11-18 06:20:50,647 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;207.3828287017835 06:20:50 INFO - 2015-11-18 06:20:50,647 DEBUG : BROWSER_OUTPUT: |2;Encrypt;353.1073446327684 06:20:50 INFO - 2015-11-18 06:20:50,647 DEBUG : BROWSER_OUTPUT: |3;Decrypt;6410.25641025641 06:20:50 INFO - 2015-11-18 06:20:50,647 DEBUG : BROWSER_OUTPUT: |4;RayTrace;2092.050209205021 06:20:50 INFO - 2015-11-18 06:20:50,648 DEBUG : BROWSER_OUTPUT: |5;Earley;983.284169124877 06:20:50 INFO - 2015-11-18 06:20:50,648 DEBUG : BROWSER_OUTPUT: |6;Boyer;14140.845070422536 06:20:50 INFO - 2015-11-18 06:20:50,648 DEBUG : BROWSER_OUTPUT: |7;RegExp;34067.79661016949 06:20:50 INFO - 2015-11-18 06:20:50,648 DEBUG : BROWSER_OUTPUT: |8;Splay;926.7840593141798 06:20:50 INFO - 2015-11-18 06:20:50,648 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;6308.176100628931 06:20:50 INFO - 2015-11-18 06:20:50,648 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:20:50 INFO - 2015-11-18 06:20:50,649 DEBUG : BROWSER_OUTPUT: __startTimestamp1447856450645__endTimestamp 06:20:50 INFO - 2015-11-18 06:20:50,649 DEBUG : BROWSER_OUTPUT: 06:20:50 INFO - 2015-11-18 06:20:50,649 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:20:50 INFO - 2015-11-18 06:20:50,649 DEBUG : BROWSER_OUTPUT: Number of tests: 10 06:20:50 INFO - 2015-11-18 06:20:50,649 DEBUG : BROWSER_OUTPUT: 06:20:50 INFO - 2015-11-18 06:20:50,649 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:175.28 Median:175.28 stddev:0.00 (0.0%) 06:20:50 INFO - 2015-11-18 06:20:50,650 DEBUG : BROWSER_OUTPUT: Values: 175.3 06:20:50 INFO - 2015-11-18 06:20:50,650 DEBUG : BROWSER_OUTPUT: 06:20:50 INFO - 2015-11-18 06:20:50,650 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:207.38 Median:207.38 stddev:0.00 (0.0%) 06:20:50 INFO - 2015-11-18 06:20:50,650 DEBUG : BROWSER_OUTPUT: Values: 207.4 06:20:50 INFO - 2015-11-18 06:20:50,650 DEBUG : BROWSER_OUTPUT: 06:20:50 INFO - 2015-11-18 06:20:50,650 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:353.11 Median:353.11 stddev:0.00 (0.0%) 06:20:50 INFO - 2015-11-18 06:20:50,651 DEBUG : BROWSER_OUTPUT: Values: 353.1 06:20:50 INFO - 2015-11-18 06:20:50,651 DEBUG : BROWSER_OUTPUT: 06:20:50 INFO - 2015-11-18 06:20:50,652 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:6410.26 Median:6410.26 stddev:0.00 (0.0%) 06:20:50 INFO - 2015-11-18 06:20:50,652 DEBUG : BROWSER_OUTPUT: Values: 6410.3 06:20:50 INFO - 2015-11-18 06:20:50,652 DEBUG : BROWSER_OUTPUT: 06:20:50 INFO - 2015-11-18 06:20:50,653 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:2092.05 Median:2092.05 stddev:0.00 (0.0%) 06:20:50 INFO - 2015-11-18 06:20:50,653 DEBUG : BROWSER_OUTPUT: Values: 2092.1 06:20:50 INFO - 2015-11-18 06:20:50,653 DEBUG : BROWSER_OUTPUT: 06:20:50 INFO - 2015-11-18 06:20:50,653 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:983.28 Median:983.28 stddev:0.00 (0.0%) 06:20:50 INFO - 2015-11-18 06:20:50,653 DEBUG : BROWSER_OUTPUT: Values: 983.3 06:20:50 INFO - 2015-11-18 06:20:50,653 DEBUG : BROWSER_OUTPUT: 06:20:50 INFO - 2015-11-18 06:20:50,653 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:14140.85 Median:14140.85 stddev:0.00 (0.0%) 06:20:50 INFO - 2015-11-18 06:20:50,653 DEBUG : BROWSER_OUTPUT: Values: 14140.8 06:20:50 INFO - 2015-11-18 06:20:50,654 DEBUG : BROWSER_OUTPUT: 06:20:50 INFO - 2015-11-18 06:20:50,654 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:34067.80 Median:34067.80 stddev:0.00 (0.0%) 06:20:50 INFO - 2015-11-18 06:20:50,654 DEBUG : BROWSER_OUTPUT: Values: 34067.8 06:20:50 INFO - 2015-11-18 06:20:50,654 DEBUG : BROWSER_OUTPUT: 06:20:50 INFO - 2015-11-18 06:20:50,654 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:926.78 Median:926.78 stddev:0.00 (0.0%) 06:20:50 INFO - 2015-11-18 06:20:50,654 DEBUG : BROWSER_OUTPUT: Values: 926.8 06:20:50 INFO - 2015-11-18 06:20:50,654 DEBUG : BROWSER_OUTPUT: 06:20:50 INFO - 2015-11-18 06:20:50,654 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:6308.18 Median:6308.18 stddev:0.00 (0.0%) 06:20:50 INFO - 2015-11-18 06:20:50,654 DEBUG : BROWSER_OUTPUT: Values: 6308.2 06:20:50 INFO - 2015-11-18 06:20:50,655 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:20:50 INFO - 2015-11-18 06:20:50,655 DEBUG : BROWSER_OUTPUT: 06:20:51 INFO - 2015-11-18 06:20:51,380 INFO : Browser exited with error code: 0 06:20:51 INFO - 2015-11-18 06:20:51,401 INFO : Completed test v8_7 (00:00:38) 06:20:51 INFO - 2015-11-18 06:20:51,808 INFO : Completed test suite (00:20:17) 06:20:51 INFO - 2015-11-18 06:20:51,808 DEBUG : Working with test: dromaeo_css 06:20:51 INFO - 2015-11-18 06:20:51,808 DEBUG : Generating results file: dromaeo_css 06:20:51 INFO - 2015-11-18 06:20:51,809 DEBUG : Working with test: kraken 06:20:51 INFO - 2015-11-18 06:20:51,809 DEBUG : Generating results file: kraken 06:20:51 INFO - 2015-11-18 06:20:51,809 INFO : javascript benchmark 06:20:51 INFO - 2015-11-18 06:20:51,809 DEBUG : Working with test: v8_7 06:20:51 INFO - 2015-11-18 06:20:51,810 DEBUG : Generating results file: v8_7 06:20:51 INFO - 2015-11-18 06:20:51,810 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:20:51 INFO - 2015-11-18 06:20:51,988 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,140,55]] 06:20:51 INFO - 2015-11-18 06:20:51,988 DEBUG : process_Request line: dromaeo_css 5981.43 graph.html#tests=[[72,140,55]] 06:20:51 INFO - 2015-11-18 06:20:51,988 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:20:52 INFO - 2015-11-18 06:20:52,126 DEBUG : process_Request line: kraken 1651.80 graph.html#tests=[[232,140,55]] 06:20:52 INFO - 2015-11-18 06:20:52,127 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:20:52 INFO - 2015-11-18 06:20:52,249 DEBUG : process_Request line: v8_7 16295.16 graph.html#tests=[[230,140,55]] 06:20:52 INFO - 2015-11-18 06:20:52,250 INFO : javascript benchmark 06:20:52 INFO - 2015-11-18 06:20:52,252 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"jquery.html": [303.3916996422173, 306.23594132639056, 309.24081380211584, 311.9610048743907, 312.5374445820186, 13285.202887826737, 13692.041557091008, 14121.0, 14329.140251584899, 14765.630859228524, 10746.516406761695, 11066.502007409666, 11077.390743509104, 13603.591892243223, 15882.841171588281, 3948.4669569608095, 4087.2847251730977, 4088.264112459771, 4093.7953102344914, 4108.26051310764, 3094.3037816491224, 3166.4142133705222, 4194.643455306303, 4241.957580424187, 4384.320430333303, 4257.212415703089, 4279.186954478655, 4317.309230523117, 4322.935155972663, 4347.804348804311, 4201.936970945439, 4296.334068219431, 4311.568843115695, 4314.870553883357, 4322.481302243735, 3649.6350364963555, 3666.156783939682, 3669.669729724311, 3681.5029970954115, 3682.834272457746, 11604.187706860523, 12096.758064838741, 12099.213551119237, 12328.81506777399, 12369.257844529357, 1400.2998500749625, 1634.558669159332, 1721.535185499908, 1738.278614375033, 1854.5905112114756, 8472.915270847274, 8576.957115214447, 8805.886587219085, 8806.339524535686, 8925.910740892637, 1316.4405127820733, 1354.9796753048713, 1357.6605848537865, 1358.3072632957164, 1384.536180379574, 49221.50778492205, 51901.22148167781, 51933.48284346468, 54548.45451545474, 55562.72218638921, 5954.285485741652, 5966.194563733927, 5966.612170208922, 6024.90962635552, 6040.667763272974, 6849.280825513349, 6858.5199036068, 6866.965665171642, 6871.587704737732, 6875.7937261882225, 4680.204365257914, 4687.156311863829, 4708.058388322281, 4718.693284936468, 4727.4327080749845, 4838.2016967200725, 4854.8786280342565, 4855.514448555187, 4864.73243971585, 4879.414470263591, 2683.745044220796, 2696.2720065582184, 2699.122785094852, 2702.905398311089, 2721.8502982336163, 925.7500474871747, 927.3879239701764, 935.8034812689411, 937.8124375125002, 997.9800403992025, 341.7013964328739, 355.78119456534046, 376.2437500623766, 386.01373490731453, 400.1377032494978, 9996.250281228937, 10732.195085368628, 10760.677179684622, 10863.293885897392, 10913.727156820984, 354.8864363403738, 403.10912882529936, 411.32953286143396, 412.0132283181763, 424.22578793701, 5698.8290351289525, 5730.541556675456, 5974.731137098841, 6002.789902353396, 6006.489448396847, 479161.0, 479331.0, 479599.0, 480204.0, 482996.0, 63635.04547431792, 64449.67775161188, 64831.67584162049, 65408.345916540224, 67472.3252767466, 460.41757177170905, 464.7234895237261, 468.21807581339243, 469.45543169922723, 469.8426027280899, 10081.546330415002, 10284.794304114026, 10300.124489418184, 10324.896751032393, 10377.636782712569, 32330.0, 33351.49972750459, 33714.83142584271, 33878.830605846815, 34112.0, 42449.78775106105, 42609.0, 42973.57026429821, 43138.13723725571, 43430.78284608557, 5600.599850037491, 5672.149177623224, 5682.829515114553, 5699.772009119754, 5715.228444159985, 10383.428911409945, 10392.272540922062, 10729.0, 10770.623028193975, 10828.675139745821, 9228.723138305862, 9250.352475326663, 9285.442873427615, 9326.626934922528, 9339.0, 8890.377673562789, 9035.367524273237, 9089.863652045357, 9124.635014599608, 9260.861087083835, 7555.962220188864, 9182.67860624875, 9408.059194080537, 9463.716088517356, 9472.810543789223, 5589.636673616202, 5632.436756324335, 5673.234113394804, 5675.347335056356, 5708.286464191976, 9166.404183728037, 9350.625974960925, 9487.288453365887, 9516.19112375456, 9573.569189386355], "dojo.html": [2966.4660361134993, 3000.0699783067266, 3007.3233522457435, 3055.1292881528757, 3190.888318908838, 4714.669973101884, 4830.106430310395, 4832.009438065197, 4853.902921941559, 4864.537868902448, 4792.736399498018, 4835.564799168074, 4942.975285123578, 4956.553910148086, 5010.473900240477, 4352.7823608819435, 4395.802188901491, 4402.3836662867225, 4414.713043652152, 4429.114177164563, 4516.0967806438675, 4609.861704148881, 4696.765161741916, 4700.341952126688, 4724.952750472486, 4526.818927242906, 4600.97699511502, 4648.070385922795, 4651.930221046687, 4661.650376221797, 4549.61328287096, 4597.034622729231, 4616.607588354994, 4622.168009758242, 4679.64902632304, 4598.172328980782, 4610.723356598614, 4623.8150473981, 4641.489436162019, 4645.094206629675, 4856.344393506866, 4878.780454879539, 4885.60915126789, 4887.364642596475, 4927.556519913189, 4969.900601987976, 5065.670731402447, 5143.048536020848, 5159.226116082617, 5179.533841954242, 5198.298229738974, 5300.257963885059, 5305.840824775263, 5332.946670533283, 5358.196270559409, 4939.333190019373, 4989.25161225819, 4989.925151122736, 5016.1723315653235, 5062.11413002723, 5176.663516871391, 5205.063088644005, 5268.130758424817, 5319.069162896478, 5368.812091576776, 5256.421793602701, 5261.3686357637325, 5267.525922666979, 5274.182501712241, 5334.493223143795, 5334.59990500714, 5347.51872331492, 5360.624756267024, 5394.811181608625, 5418.593605479605, 5132.0762262792305, 5211.921821172686, 5255.0, 5265.420803711589, 5286.180642000496, 5140.768665410065, 5199.298094757235, 5204.0, 5209.348831396075, 5220.895582088413, 4011.0373510357304, 4053.3717273822604, 4099.672026237894, 4102.466679331667, 4104.712670113064, 3684.281565094781, 3726.0312318796764, 3735.3836616958442, 3737.0470530014672, 3777.433384992218, 4941.654084214142, 4963.10664080469, 4965.577925876269, 4968.8757781055765, 4977.228529577848, 5217.113090774577, 5326.307580014573, 5342.305500284938, 5343.893122137613, 5379.515843574019, 4963.305137280783, 4990.4011518618, 4999.5500404963, 5005.27423523587, 5010.69935803853, 4688.742119183477, 4697.694649847749, 4701.271302948048, 4705.129551033001, 4709.1994360958715, 5380.300560927054, 5438.619296649275, 5478.205660179331, 5491.313585801775, 5510.118381059011, 5166.793328266827, 5234.842954711365, 5248.711320877388, 5282.524572788468, 5289.127293996447, 5271.4464981176425, 5284.180951952453, 5292.311999440049, 5300.072487314782, 5309.2301616266195, 5082.720450375265, 5089.160288552347, 5089.949100508948, 5101.438841727481, 5157.226416037624, 5058.974705126451, 5085.160948443466, 5220.582353411642, 5246.396664383645, 5255.763490643007, 5201.245819356248, 5209.244659524424, 5212.791488340424, 5218.6868787872845, 5258.526732594085, 5226.817061402832, 5228.032813929283, 5234.764435600331, 5242.606804489755, 5249.7375131242525, 5103.617228707788, 5114.207297868837, 5137.794488220429, 5143.048536020848, 5144.30551875492, 4931.876703082452, 5208.244804503402, 5227.398849132246, 5246.422893481637, 5253.317068781034, 5123.974380128076, 5140.30605868203, 5155.587552995677, 5162.561182299547, 5203.713795741119], "mootools.html": [1169.2575214738638, 1258.0753146437373, 1343.0665687347298, 1518.3243456661783, 1596.2018990504744, 6280.937190628092, 6454.19322584677, 6543.60738355699, 6573.474122070223, 6594.53838231324, 6737.730490780363, 6863.965680171592, 6870.07254020707, 6887.5178737488395, 6939.895901561468, 1816.9454916352465, 1847.030309087733, 1990.1044529961503, 2023.079498828037, 2034.969475457862, 2023.8077382648698, 2071.2439959414783, 2075.439631299548, 2076.3044380132674, 2078.83369330454, 1961.4213806926991, 1986.3345779163926, 1991.8306943909786, 2018.606371757508, 2019.4850313170195, 1991.6813309870352, 2043.6117137743856, 2054.4042227753935, 2068.7931206879266, 2079.241077006897, 1972.7139564763172, 2069.2343832781967, 2069.4412508622736, 2070.523779530716, 2071.6788897720726, 6838.965805170942, 6893.448524118108, 6906.136732908387, 6938.965305173442, 7026.332498412643, 4326.221280169569, 4395.340698895159, 4398.23030969579, 4417.050334178167, 4432.335149727534, 2514.786243169333, 2565.5510285700116, 2625.133705877075, 2627.395698989224, 2640.6171105189833, 685.7051467868814, 758.9962050189769, 829.3613917283658, 835.7952301686125, 840.6217202259008, 8923.643054277822, 8984.966728826203, 8988.460692358414, 9008.549572521348, 9023.09769023099, 3871.7676939383728, 3961.8217180226384, 4024.2756303865585, 4040.373742069984, 4054.0, 7953.880691789628, 7958.0848202457455, 7963.203679632106, 7976.680932762624, 7986.3610911128135, 4378.868633940987, 4381.539938306432, 4403.647708183337, 4406.801693923781, 4415.51429342772, 3956.9802150989635, 3966.2662407454714, 3983.521977362735, 3988.780617066031, 4017.6384125428854, 3322.5182348559806, 3391.6608339165887, 3401.5748031496064, 3408.199073217792, 3409.505621684842, 1089.2919602258596, 1095.5562996986234, 1096.9286996345213, 1097.846301517788, 1101.5758932810925, 967.3712087143413, 975.1662328708993, 975.512243878061, 998.9300748947504, 999.1756800639502, 6526.28210896801, 6564.901526477107, 6594.274629790719, 6603.834904127435, 6606.6036037837885, 442.00548765278126, 445.0653627382471, 445.69469913109623, 446.4642429085111, 447.0611715397639, 5116.309298244763, 5170.17277235648, 5191.714455704972, 5192.92210616841, 5215.400228973641, 7575.393968482508, 7824.726134585262, 7837.9216207838335, 7943.046834379796, 7943.761687149395, 7941.36469082472, 7962.56205908669, 8008.039035315799, 8062.838743225103, 8070.112287648354, 6478.125453063776, 6482.416582507597, 6525.673716314261, 6543.672816359258, 6591.802245932629, 6529.706163222571, 6532.934670653232, 6541.476681865344, 6560.0, 6567.868642627216, 7088.0, 7175.425965922818, 7187.82030449222, 7211.639418029183, 7236.891446628199, 7340.302671246223, 7447.069116360455, 7456.813579660552, 7501.474896757175, 7523.435742319458, 6138.14066030765, 6180.47465965398, 6198.81403557894, 6206.317305096345, 6206.689665516797, 6371.076193951758, 6628.7017084230365, 6710.027046078194, 6737.484066085091, 6737.696803643946, 6106.1146133811235, 6153.630782153085, 6196.907046394398, 6212.534059945612, 6267.2165979252595, 6074.149619053424, 6097.085437184458, 6175.505959523138, 6177.444030037318, 6217.813465595858, 6196.225471816023, 6253.343398943045, 6276.278228003655, 6283.277423096402, 6294.024426213944, 5667.4615911488345, 5703.543716502587, 5712.885742285214, 5715.628484148517, 5730.541556675373, 5779.913301300399, 5781.913271301018, 5795.043817770021, 5821.38875418075, 5828.2423284974375], "ext.html": [512.6385897941951, 513.1763519551121, 514.4880843560658, 523.4373048972354, 537.5887446103732, 29513.557296640567, 35414.82292588537, 36365.090872728164, 36516.63483365165, 36660.266794664094, 39256.018599535026, 39326.10108483729, 39328.60671393285, 39356.80321598388, 39358.40962385567, 7511.436642251826, 7615.657295421719, 8154.836903261931, 8376.958115209445, 8388.748337549883, 10211.897881021168, 10385.117265032444, 10401.84397234042, 10401.947990260038, 10443.686689399292, 10093.495325233745, 10106.393616383004, 10114.595416183343, 10139.695809125738, 10170.491475426235, 7695.192004839467, 7893.210678932061, 10108.342957707762, 10158.746031349201, 10212.18302535799, 9905.702828915144, 9981.30130890838, 10013.449260290681, 10016.54925528353, 10099.29304948654, 36534.269314613826, 36920.44619330712, 36974.26051478955, 37008.814955925045, 37065.814670926746, 2706.688730795964, 2710.444358906439, 2729.426820367725, 2809.2415047936943, 2817.4874431598764, 24759.133430329854, 24811.0, 24879.253622391356, 24970.250892473254, 25711.61432578513, 2039.785822488647, 2050.149188086942, 2053.9486512837148, 2053.9589208215752, 2154.633712268918, 234238.0, 235987.0, 238012.0, 238354.0, 240972.79513602494, 47460.52539474561, 48343.51656483461, 54407.72796136073, 54770.452295476534, 54892.315075889404, 61874.0, 62014.68992655098, 64058.0, 64090.359096408436, 64223.35776642267, 12160.695982600506, 12320.137590368588, 12335.198212116184, 12338.198017128858, 12370.072244581512, 9087.727368178965, 9137.314701397421, 9148.588313525906, 9149.359544831797, 9156.313276504288, 17614.559636009202, 17675.29298828058, 17866.677331946765, 17908.731369029476, 17980.55048623795, 7128.893066604005, 7369.594672292968, 7390.556566606021, 7404.1855395906405, 7442.553446793101, 842.7766641839918, 856.2037305306001, 927.0590350793949, 975.5219942228257, 996.3324572536418, 26012.609810852853, 26299.342516437242, 26455.0, 27015.0, 27213.319667008087, 900.2752784008935, 924.246738907788, 974.1378879691524, 1021.3667526133845, 1025.8409946458312, 12191.87808121925, 12278.754424911453, 12789.552365667158, 13016.934915325362, 13078.0, 295613.0, 295839.0, 301300.0, 303787.0, 304040.0, 48894.266586001235, 48981.26528102082, 49297.26054109191, 49437.50562494401, 49790.0, 1190.113365542659, 1218.5125949620224, 1224.5407972010496, 1225.5097960815744, 1233.6360773571819, 22056.44858878541, 23533.411664708517, 24403.38991525226, 27185.59221611638, 27483.31291717723, 46180.0, 47238.0, 47381.52618473771, 47572.0, 47781.0, 191580.0, 192926.0, 192983.0, 193213.0, 252894.0, 23679.881600591885, 28502.287442814097, 28953.565696514146, 29118.379345858975, 29401.852990735766, 35686.107847302985, 35732.4640130393, 35737.1065723359, 36044.81977590095, 36254.0, 30166.0, 30605.693943060283, 30909.690903090683, 32659.0, 33501.99494015183, 31416.057518274487, 31497.527537086502, 31588.36823263476, 31642.367152656356, 31656.208594785316, 30963.535546966363, 31462.213444664067, 31644.208894777814, 31732.841335793175, 31761.523577146825, 26180.0, 28263.717362826108, 28324.291892702848, 28441.573376399785, 28823.279418014718, 21419.892900535397, 21657.458563536038, 21671.0, 21678.34964951054, 21693.78306216981], "prototype.html": [268.66819477944733, 276.67628874217155, 279.02341803687096, 288.8353638426098, 291.13097404248305, 5673.574481913863, 5683.971580142093, 5810.7094645267725, 5831.416858314166, 5833.5333173346135, 5732.426757324265, 5798.594098413113, 5806.738696758645, 5813.912791308123, 5822.4177582241755, 4480.305552639346, 4481.282994720846, 4497.572730590588, 4505.256632655608, 4513.909721805579, 4260.936085958713, 4373.387725718402, 4379.299312110063, 4488.192125417424, 4492.460904691442, 4383.3424986252, 4431.889202769924, 4432.445944256968, 4442.577955094261, 4449.977750111261, 4404.163208990313, 4424.77876106195, 4426.645868330525, 4434.60088592025, 4453.977730111361, 3780.489633899416, 3789.8673546425744, 3790.0, 3797.2974999625158, 3801.0, 5649.378568357477, 5650.0, 5655.604107712462, 5663.49028587425, 5672.404397538282, 4914.754262286907, 4938.432080310774, 4939.777710003059, 4940.90118197634, 4942.258661200813, 6032.758689652409, 6042.788502402439, 6043.395660433922, 6044.304904935944, 6049.062395328731, 4040.6363427291685, 4046.838126474967, 4058.4115303281155, 4074.7962601870086, 4079.4288799568085, 5942.0, 6374.649394283312, 6380.712867920908, 6395.999999999954, 6420.871582568369, 4139.5653456387245, 4149.398337241144, 4150.771707556053, 4162.042730172077, 4172.77049762266, 4210.978945105255, 4215.241256573785, 4220.599043090901, 4229.921370050679, 4246.702730808814, 4919.729414882144, 4927.827526036644, 4928.310036594879, 4930.8767280818265, 4950.801967921243, 5895.204147440127, 6097.786577469856, 6099.176611157525, 6101.328853826076, 6131.0, 1478.2830327291256, 1497.9550613481613, 1500.8199016118135, 1503.6842263124647, 1506.1941861104256, 955.9522023898777, 957.5068839547639, 963.2438535749403, 978.4373984958677, 981.2787601113176, 5825.825225243249, 5839.328477225174, 5840.503557197686, 5850.531957443394, 5876.265466816648, 6307.716152773044, 6342.587731797511, 6348.460380867586, 6351.364863513704, 6386.042093685985, 5865.794697185578, 5880.7353669085, 5882.705864706748, 5892.469677728939, 5934.584579079509, 3264.7061764441314, 3418.1283772638317, 3422.435298175823, 3424.9828750856086, 3428.708559772447, 6493.675316234169, 6500.772472963424, 6512.706928188243, 6519.250286217051, 6522.217333919865, 6303.149074874925, 6453.064305675744, 6457.321981191907, 6465.321141280192, 6473.449756770633, 6145.20112385396, 6465.741370345228, 6470.579412338183, 6472.320406357265, 6583.419979205052, 6335.841603959938, 6378.808635740936, 6394.520410969103, 6404.391582799627, 6437.517186210959, 6056.727447264796, 6227.532935029982, 6229.750809967551, 6244.906326405016, 6263.843403914939, 6546.214454265519, 6558.278589355263, 6565.540412171294, 6572.342765723581, 6623.768168114093, 4041.5958404160524, 4085.285075111784, 4086.7139300248696, 4088.12105397341, 4095.8361665533043, 6216.968915155395, 6378.394052564999, 6385.297617262005, 6396.680165991775, 6401.967990160019, 6179.104029915727, 6191.597546159485, 6196.442320191204, 6197.442230199304, 6208.627482350893, 6155.84610384744, 6170.167027451236, 6174.506039516738, 6190.659513726789, 6191.721372538156, 5893.44012318829, 6054.394560544087, 6062.333143354315, 6079.422454866781, 6110.205673262448, 6183.659898705614, 6192.907106393318, 6214.40963108504, 6216.72024758896, 6217.564770466205, 6048.183495228264, 6053.606515576474, 6070.696465176635, 6072.423119803611, 6080.78717244912], "yui.html": [629.4586655476293, 667.2226855713094, 688.284184448174, 702.076769048701, 707.9858402831942, 12308.815367769479, 12507.562235321766, 12594.181378210422, 12595.181313214634, 12614.81077783834, 11548.133889958244, 11561.017313528338, 11610.419479026057, 11631.709207269823, 11648.708782280426, 612.3753771153421, 622.5859803230869, 623.7286780250582, 626.1765778001935, 784.1061190243113, 797.2625321577547, 807.1202389395557, 807.9192080791903, 814.9592520373988, 817.3706246190429, 828.7265202483196, 836.7196989008689, 843.2073850580465, 844.1980118887051, 846.7290467050547, 833.8248967716756, 833.8665813469846, 834.9749507514723, 837.1962915601031, 838.9664413423455, 477.49147158276327, 488.182294656449, 489.6009752052102, 490.03463177540186, 605.2253116011512, 10362.740931476697, 11424.600138995178, 11876.52493900243, 11883.227590206609, 11928.940355298255, 9751.756206094833, 10322.071013608811, 10520.210984176141, 10699.78600427995, 11025.889741102566, 7866.370690344816, 7946.443748937577, 8045.637946292372, 8251.051129120167, 8535.061143274235, 3029.8485075746125, 3075.3695492424, 3121.4069326828057, 3124.187711195083, 3127.734142597882, 42768.3584746229, 42811.57188428107, 43088.35367469459, 43134.78432607848, 43217.13565728668, 7816.804579885548, 8143.959280203561, 8251.381146413923, 8364.330853531703, 8443.493390396474, 17109.743353849703, 17116.0, 17117.400890968755, 17139.571510712332, 17171.914140429468, 6202.906956395657, 6349.238091428966, 6445.871082578416, 6449.290578036413, 6594.8351291218105, 3404.472306792451, 3509.842057107436, 3546.6985306249258, 3585.4263317869018, 3625.0393645683916, 2662.547366947585, 2677.3039009857575, 2683.5706286993986, 2687.2341382705836, 2695.4070104576963, 379.9259144466858, 383.3502213747671, 383.56273847813515, 393.29600015971664, 395.5470985721333, 441.4702357171343, 464.54706661005923, 497.2739799892126, 506.2357683473093, 563.2227526014157, 7148.180165141522, 7419.332260096618, 7457.589832559262, 7501.549907005598, 7597.696092156362, 414.40325930659753, 420.0842164082269, 486.42601729958284, 505.8254902058784, 590.2474345209891, 3180.538821870816, 3322.3853587086464, 3378.2567835076243, 3480.1125712943544, 3597.640235976434, 47519.52480475151, 47682.5231747685, 47911.76044119827, 48045.5195448048, 48262.51737482651, 44931.77534112309, 45142.54857451449, 45382.319265211045, 45520.54479455229, 45912.770436147606, 40232.396514052314, 41223.587764122574, 42286.0, 42532.574674252864, 42783.572164279205, 7103.467239957127, 7185.748498802517, 7284.526505777108, 7637.847243054996, 7709.190534993745, 30340.24149396283, 30988.0703578893, 31088.22279443032, 31475.685243147276, 31748.841255793574, 38506.6149338503, 40545.0, 41625.0, 41991.58008419999, 42020.94947626334, 6288.779892703734, 6532.510061745483, 6768.492363072691, 6821.556598821061, 6920.480963927826, 9986.500674966368, 10027.849582256418, 10059.144972677404, 10096.899031009889, 10127.24045696561, 9366.718998430058, 9440.386374885611, 9457.527123643928, 9474.85787713171, 9520.714378568655, 9432.339736218673, 9438.811223775348, 9456.2907781918, 9476.241900647794, 9525.0, 8391.790205244917, 9354.625814967325, 9360.859587106062, 9378.296627752808, 9411.29415293842, 7635.88546171819, 7794.532328060334, 7965.522068675898, 8100.554469503998, 8330.625121869409, 7904.0, 8173.468724532886, 8363.247307742333, 8487.915120848713, 8589.871151932852]}, "summary": {"lowerIsBetter": false, "suite": 5981.422385754631, "subtests": {"jquery.html": {"lowerIsBetter": false, "filtered": 5968.075018530294, "unit": "ms", "value": 5968.075018530294}, "dojo.html": {"lowerIsBetter": false, "filtered": 4887.2980946505, "unit": "ms", "value": 4887.2980946505}, "mootools.html": {"lowerIsBetter": false, "filtered": 3885.2669103898115, "unit": "ms", "value": 3885.2669103898115}, "ext.html": {"lowerIsBetter": false, "filtered": 16598.62672262129, "unit": "ms", "value": 16598.62672262129}, "prototype.html": {"lowerIsBetter": false, "filtered": 4561.229129582973, "unit": "ms", "value": 4561.229129582973}, "yui.html": {"lowerIsBetter": false, "filtered": 5337.136544422904, "unit": "ms", "value": 5337.136544422904}}}, "test_machine": {"platform": "x86_64", "osversion": "OS X 10.10.2", "os": "mac", "name": "t-yosemite-r5-0017"}, "testrun": {"date": 1447855234, "suite": "dromaeo_css", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151118040203", "branch": "Ash", "revision": "2185368ac9231548d3b0f9379812dd0a0ae50484"}}, {"talos_counters": {}, "results": {"audio-dft": [169.0, 173.0, 178.0, 181.0, 173.0, 175.0, 175.0, 179.0, 176.0, 172.0], "ai-astar": [108.0, 111.0, 105.0, 113.0, 113.0, 109.0, 102.0, 112.0, 119.0, 116.0], "imaging-gaussian-blur": [131.0, 135.0, 129.0, 133.0, 129.0, 135.0, 135.0, 128.0, 123.0, 135.0], "audio-beat-detection": [163.0, 135.0, 162.0, 175.0, 142.0, 166.0, 166.0, 140.0, 182.0, 139.0], "json-stringify-tinderbox": [69.0, 70.0, 71.0, 77.0, 78.0, 78.0, 76.0, 77.0, 76.0, 74.0], "stanford-crypto-pbkdf2": [163.0, 160.0, 173.0, 171.0, 176.0, 154.0, 170.0, 173.0, 171.0, 158.0], "json-parse-financial": [61.0, 62.0, 64.0, 66.0, 67.0, 67.0, 67.0, 76.0, 65.0, 67.0], "stanford-crypto-sha256-iterative": [75.0, 76.0, 76.0, 80.0, 80.0, 79.0, 80.0, 82.0, 81.0, 80.0], "audio-fft": [80.0, 90.0, 99.0, 94.0, 78.0, 77.0, 80.0, 89.0, 88.0, 74.0], "imaging-darkroom": [136.0, 142.0, 148.0, 144.0, 139.0, 136.0, 142.0, 141.0, 142.0, 139.0], "stanford-crypto-ccm": [147.0, 140.0, 141.0, 156.0, 148.0, 138.0, 150.0, 136.0, 141.0, 140.0], "audio-oscillator": [106.0, 101.0, 155.0, 100.0, 101.0, 99.0, 145.0, 98.0, 95.0, 144.0], "stanford-crypto-aes": [89.0, 97.0, 108.0, 98.0, 98.0, 96.0, 96.0, 98.0, 99.0, 97.0], "imaging-desaturate": [101.0, 105.0, 113.0, 104.0, 113.0, 105.0, 114.0, 114.0, 113.0, 113.0]}, "summary": {"lowerIsBetter": true, "suite": 1651.8, "subtests": {"audio-fft": {"lowerIsBetter": true, "filtered": 84.9, "unit": "ms", "value": 84.9}, "stanford-crypto-pbkdf2": {"lowerIsBetter": true, "filtered": 166.9, "unit": "ms", "value": 166.9}, "audio-beat-detection": {"lowerIsBetter": true, "filtered": 157.0, "unit": "ms", "value": 157.0}, "stanford-crypto-ccm": {"lowerIsBetter": true, "filtered": 143.7, "unit": "ms", "value": 143.7}, "imaging-darkroom": {"lowerIsBetter": true, "filtered": 140.9, "unit": "ms", "value": 140.9}, "json-parse-financial": {"lowerIsBetter": true, "filtered": 66.2, "unit": "ms", "value": 66.2}, "audio-oscillator": {"lowerIsBetter": true, "filtered": 114.4, "unit": "ms", "value": 114.4}, "ai-astar": {"lowerIsBetter": true, "filtered": 110.8, "unit": "ms", "value": 110.8}, "audio-dft": {"lowerIsBetter": true, "filtered": 175.1, "unit": "ms", "value": 175.1}, "stanford-crypto-sha256-iterative": {"lowerIsBetter": true, "filtered": 78.9, "unit": "ms", "value": 78.9}, "json-stringify-tinderbox": {"lowerIsBetter": true, "filtered": 74.6, "unit": "ms", "value": 74.6}, "imaging-gaussian-blur": {"lowerIsBetter": true, "filtered": 131.3, "unit": "ms", "value": 131.3}, "stanford-crypto-aes": {"lowerIsBetter": true, "filtered": 97.6, "unit": "ms", "value": 97.6}, "imaging-desaturate": {"lowerIsBetter": true, "filtered": 109.5, "unit": "ms", "value": 109.5}}}, "test_machine": {"platform": "x86_64", "osversion": "OS X 10.10.2", "os": "mac", "name": "t-yosemite-r5-0017"}, "testrun": {"date": 1447855234, "suite": "kraken", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151118040203", "branch": "Ash", "revision": "2185368ac9231548d3b0f9379812dd0a0ae50484"}}, {"talos_counters": {}, "results": {"DeltaBlue": [207.3828287017835], "Encrypt": [353.1073446327684], "NavierStokes": [6308.176100628931], "Decrypt": [6410.25641025641], "Splay": [926.7840593141798], "Richards": [175.28483786152498], "RegExp": [34067.79661016949], "Earley": [983.284169124877], "Boyer": [14140.845070422536], "RayTrace": [2092.050209205021]}, "summary": {"lowerIsBetter": false, "suite": 16295.16448161502, "subtests": {"DeltaBlue": {"lowerIsBetter": false, "filtered": 318.8209960000001, "unit": "score", "value": 318.8209960000001}, "Encrypt": {"lowerIsBetter": false, "filtered": 753.824592, "unit": "score", "value": 753.824592}, "NavierStokes": {"lowerIsBetter": false, "filtered": 235.25024925224326, "unit": "score", "value": 235.25024925224326}, "Decrypt": {"lowerIsBetter": false, "filtered": 41.52423599999998, "unit": "score", "value": 41.52423599999998}, "Splay": {"lowerIsBetter": false, "filtered": 87.92878899999997, "unit": "score", "value": 87.92878899999997}, "RegExp": {"lowerIsBetter": false, "filtered": 26.74035572139306, "unit": "score", "value": 26.74035572139306}, "Richards": {"lowerIsBetter": false, "filtered": 201.39790999999997, "unit": "score", "value": 201.39790999999997}, "Earley": {"lowerIsBetter": false, "filtered": 677.7928709999999, "unit": "score", "value": 677.7928709999999}, "Boyer": {"lowerIsBetter": false, "filtered": 47.130351593625484, "unit": "score", "value": 47.130351593625484}, "RayTrace": {"lowerIsBetter": false, "filtered": 353.71474199999983, "unit": "score", "value": 353.71474199999983}}}, "test_machine": {"platform": "x86_64", "osversion": "OS X 10.10.2", "os": "mac", "name": "t-yosemite-r5-0017"}, "testrun": {"date": 1447855234, "suite": "v8_7", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151118040203", "branch": "Ash", "revision": "2185368ac9231548d3b0f9379812dd0a0ae50484"}}] 06:20:52 INFO - RETURN: dromaeo_css: 5981.43 06:20:52 INFO - RETURN: kraken: 1651.80 06:20:52 INFO - RETURN: v8_7: 16295.16 06:20:52 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,140,55]]", "result": "16295.16"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,140,55]]", "result": "1651.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,140,55]]", "result": "5981.43"}}} 06:20:52 INFO - Return code: 0 06:20:52 INFO - # TBPL SUCCESS # 06:20:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:20:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:20:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:20:52 INFO - 2015-11-18 06:20:52.385 screenresolution[1667:10499] starting screenresolution argv=screenresolution get 06:20:52 INFO - 2015-11-18 06:20:52.389 screenresolution[1667:10499] Display 0: 1600x1200x32@60 06:20:52 INFO - 2015-11-18 06:20:52.395 screenresolution[1668:10502] starting screenresolution argv=screenresolution list 06:20:52 INFO - Available Modes on Display 0 06:20:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:20:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:20:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:20:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:20:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:20:52 INFO - Intel HD Graphics 3000: 06:20:52 INFO - Chipset Model: Intel HD Graphics 3000 06:20:52 INFO - Type: GPU 06:20:52 INFO - Bus: Built-In 06:20:52 INFO - VRAM (Total): 512 MB 06:20:52 INFO - VRAM (Dynamic, Max): 10 06:20:52 INFO - Vendor: Intel (0x8086) 06:20:52 INFO - Device ID: 0x0116 06:20:52 INFO - Revision ID: 0x0009 06:20:52 INFO - Displays: 06:20:52 INFO - Display: 06:20:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:20:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:20:52 INFO - Display Serial Number: 1600x1200 60 06:20:52 INFO - Main Display: Yes 06:20:52 INFO - Mirror: Off 06:20:52 INFO - Online: Yes 06:20:52 INFO - Rotation: Supported 06:20:52 INFO - Return code: 0 06:20:52 INFO - Running post-action listener: _resource_record_post_action 06:20:52 INFO - Running post-run listener: _resource_record_post_run 06:20:53 INFO - Total resource usage - Wall time: 1260s; CPU: 30.0%; Read bytes: 40895488; Write bytes: 729350144; Read time: 4952; Write time: 49189 06:20:53 INFO - install - Wall time: 42s; CPU: 30.0%; Read bytes: 376420864; Write bytes: 370939904; Read time: 36614; Write time: 7491 06:20:53 INFO - run-tests - Wall time: 1219s; CPU: 30.0%; Read bytes: 39166976; Write bytes: 342317056; Read time: 4625; Write time: 40732 06:20:53 INFO - Running post-run listener: _upload_blobber_files 06:20:53 INFO - Blob upload gear active. 06:20:53 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 06:20:53 INFO - Copying logs to upload dir... 06:20:53 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1350.487469 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 30 secs) (at 2015-11-18 06:20:53.813409) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-18 06:20:53.816703) ========= 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.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners TMPDIR=/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/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:0x0:0x0 using PTY: False build_url:https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010025 build_url: 'https://queue.taskcluster.net/v1/task/KcFefR3sTv-U1Y2u0hPCMQ/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-18 06:20:53.844902) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 06:20:53.845256) ========= 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.R6rwYQGox8/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.QF3ZDCHnGJ/Listeners TMPDIR=/var/folders/39/xmx7vk895wzdssnchq4b_8l000000w/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:0x0:0x0 using PTY: False program finished with exit code 0 elapsedTime=0.005207 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 06:20:53.895871) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 06:20:53.896423) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-18 06:20:53.897030) ========= ========= Total master_lag: 0.70 =========