builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0018 starttime: 1448309446.52 results: success (0) buildid: 20151121014332 builduid: 366d0f62097c4c84bad0a3f63b5705d4 revision: 489332006384610e3322739f66729448da0e98d9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.519702) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.520240) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.520561) ========= 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.IYKFbnqFOL/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.DYGH0cfsM6/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.005481 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.541975) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.542270) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.558636) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.559019) ========= 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.IYKFbnqFOL/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.DYGH0cfsM6/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005420 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.607486) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.607813) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.608189) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.608504) ========= 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.IYKFbnqFOL/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.DYGH0cfsM6/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2015-11-23 12:10:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 1.41G=0s 2015-11-23 12:10:46 (1.41 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.180426 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.802298) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.803289) ========= 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.IYKFbnqFOL/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.DYGH0cfsM6/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.026088 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:46.851317) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 12:10:46.851673) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 489332006384610e3322739f66729448da0e98d9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 489332006384610e3322739f66729448da0e98d9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IYKFbnqFOL/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.DYGH0cfsM6/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2015-11-23 12:10:46,949 truncating revision to first 12 chars 2015-11-23 12:10:46,950 Setting DEBUG logging. 2015-11-23 12:10:46,950 attempt 1/10 2015-11-23 12:10:46,950 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/489332006384?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-23 12:10:48,582 unpacking tar archive at: fx-team-489332006384/testing/mozharness/ program finished with exit code 0 elapsedTime=1.874278 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 12:10:48.740951) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:48.741288) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:48.757957) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:48.758336) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 12:10:48.759532) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2015-11-23 12:10:48.760081) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IYKFbnqFOL/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.DYGH0cfsM6/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:10:48 INFO - MultiFileLogger online at 20151123 12:10:48 in /builds/slave/test 12:10:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:10:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:10:48 INFO - {'append_to_log': False, 12:10:48 INFO - 'base_work_dir': '/builds/slave/test', 12:10:48 INFO - 'blob_upload_branch': 'Fx-Team', 12:10:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:10:48 INFO - 'branch': 'Fx-Team', 12:10:48 INFO - 'buildbot_json_path': 'buildprops.json', 12:10:48 INFO - 'config_files': ('talos/mac_config.py',), 12:10:48 INFO - 'default_actions': ('clobber', 12:10:48 INFO - 'read-buildbot-config', 12:10:48 INFO - 'download-and-extract', 12:10:48 INFO - 'populate-webroot', 12:10:48 INFO - 'create-virtualenv', 12:10:48 INFO - 'install', 12:10:48 INFO - 'run-tests'), 12:10:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:10:48 INFO - 'download_minidump_stackwalk': True, 12:10:48 INFO - 'download_symbols': 'ondemand', 12:10:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:10:48 INFO - 'tooltool.py': '/tools/tooltool.py', 12:10:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:10:48 INFO - '/tools/misc-python/virtualenv.py')}, 12:10:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:10:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:10:48 INFO - 'installer_path': 'installer.exe', 12:10:48 INFO - 'log_level': 'info', 12:10:48 INFO - 'log_name': 'talos', 12:10:48 INFO - 'log_to_console': True, 12:10:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:10:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:10:48 INFO - 'opt_config_files': (), 12:10:48 INFO - 'pip_index': False, 12:10:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:10:48 INFO - 'cmd': ('bash', 12:10:48 INFO - '-c', 12:10:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:10:48 INFO - 'enabled': True, 12:10:48 INFO - 'halt_on_failure': False, 12:10:48 INFO - 'name': 'check_screen_resolution'},), 12:10:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:10:48 INFO - 'cmd': ('bash', 12:10:48 INFO - '-c', 12:10:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:10:48 INFO - 'enabled': True, 12:10:48 INFO - 'halt_on_failure': False, 12:10:48 INFO - 'name': 'check_screen_resolution'},), 12:10:48 INFO - 'run_cmd_checks_enabled': True, 12:10:48 INFO - 'sps_profile': False, 12:10:48 INFO - 'sps_profile_interval': 0, 12:10:48 INFO - 'suite': 'dromaeojs', 12:10:48 INFO - 'system_bits': '64', 12:10:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:10:48 INFO - 'title': 't-yosemite-r7-0018', 12:10:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:10:48 INFO - 'use_talos_json': True, 12:10:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:10:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:10:48 INFO - 'work_dir': 'build'} 12:10:48 INFO - ##### 12:10:48 INFO - ##### Running clobber step. 12:10:48 INFO - ##### 12:10:48 INFO - Running pre-action listener: _resource_record_pre_action 12:10:48 INFO - Running main action method: clobber 12:10:48 INFO - rmtree: /builds/slave/test/build 12:10:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:10:50 INFO - Running post-action listener: _resource_record_post_action 12:10:50 INFO - ##### 12:10:50 INFO - ##### Running read-buildbot-config step. 12:10:50 INFO - ##### 12:10:50 INFO - Running pre-action listener: _resource_record_pre_action 12:10:50 INFO - Running main action method: read_buildbot_config 12:10:50 INFO - Using buildbot properties: 12:10:50 INFO - { 12:10:50 INFO - "properties": { 12:10:50 INFO - "buildnumber": 10, 12:10:50 INFO - "product": "firefox", 12:10:50 INFO - "script_repo_revision": "production", 12:10:50 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:10:50 INFO - "repository": "", 12:10:50 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:10:50 INFO - "buildid": "20151121014332", 12:10:50 INFO - "slavename": "t-yosemite-r7-0018", 12:10:50 INFO - "pgo_build": "False", 12:10:50 INFO - "basedir": "/builds/slave/test", 12:10:50 INFO - "project": "", 12:10:50 INFO - "platform": "yosemite_r7", 12:10:50 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:10:50 INFO - "slavebuilddir": "test", 12:10:50 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:10:50 INFO - "branch": "fx-team", 12:10:50 INFO - "repo_path": "integration/fx-team", 12:10:50 INFO - "stage_platform": "macosx64", 12:10:50 INFO - "builduid": "366d0f62097c4c84bad0a3f63b5705d4", 12:10:50 INFO - "revision": "489332006384610e3322739f66729448da0e98d9" 12:10:50 INFO - }, 12:10:50 INFO - "sourcestamp": { 12:10:50 INFO - "repository": "", 12:10:50 INFO - "hasPatch": false, 12:10:50 INFO - "project": "", 12:10:50 INFO - "branch": "fx-team-macosx64-talos", 12:10:50 INFO - "changes": [ 12:10:50 INFO - { 12:10:50 INFO - "category": null, 12:10:50 INFO - "files": [ 12:10:50 INFO - { 12:10:50 INFO - "url": null, 12:10:50 INFO - "name": "https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 12:10:50 INFO - } 12:10:50 INFO - ], 12:10:50 INFO - "repository": "", 12:10:50 INFO - "rev": "489332006384610e3322739f66729448da0e98d9", 12:10:50 INFO - "who": "dgottwald@mozilla.com", 12:10:50 INFO - "when": 1448101888, 12:10:50 INFO - "number": 6692178, 12:10:50 INFO - "comments": "Bug 644529 - Remove gap between bookmarks toolbar menu buttons and their popups. r=gijs", 12:10:50 INFO - "project": "", 12:10:50 INFO - "at": "Sat 21 Nov 2015 02:31:28", 12:10:50 INFO - "branch": "fx-team-macosx64-talos", 12:10:50 INFO - "revlink": "", 12:10:50 INFO - "properties": [ 12:10:50 INFO - [ 12:10:50 INFO - "buildid", 12:10:50 INFO - "20151121014332", 12:10:50 INFO - "Change" 12:10:50 INFO - ], 12:10:50 INFO - [ 12:10:50 INFO - "builduid", 12:10:50 INFO - "366d0f62097c4c84bad0a3f63b5705d4", 12:10:50 INFO - "Change" 12:10:50 INFO - ], 12:10:50 INFO - [ 12:10:50 INFO - "pgo_build", 12:10:50 INFO - "False", 12:10:50 INFO - "Change" 12:10:50 INFO - ] 12:10:50 INFO - ], 12:10:50 INFO - "revision": "489332006384610e3322739f66729448da0e98d9" 12:10:50 INFO - } 12:10:50 INFO - ], 12:10:50 INFO - "revision": "489332006384610e3322739f66729448da0e98d9" 12:10:50 INFO - } 12:10:50 INFO - } 12:10:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 12:10:50 INFO - Running post-action listener: _resource_record_post_action 12:10:50 INFO - ##### 12:10:50 INFO - ##### Running download-and-extract step. 12:10:50 INFO - ##### 12:10:50 INFO - Running pre-action listener: _resource_record_pre_action 12:10:50 INFO - Running main action method: download_and_extract 12:10:50 INFO - mkdir: /builds/slave/test/build/tests 12:10:50 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')]} 12:10:50 INFO - https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 12:10:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/test_packages.json 12:10:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/test_packages.json 12:10:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 12:10:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 12:10:51 INFO - Downloaded 1149 bytes. 12:10:51 INFO - Reading from file /builds/slave/test/build/test_packages.json 12:10:51 INFO - Using the following test package requirements: 12:10:51 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 12:10:51 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 12:10:51 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 12:10:51 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 12:10:51 INFO - u'jsshell-mac.zip'], 12:10:51 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 12:10:51 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 12:10:51 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 12:10:51 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 12:10:51 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 12:10:51 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 12:10:51 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 12:10:51 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 12:10:51 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 12:10:51 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 12:10:51 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 12:10:51 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 12:10:51 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:10: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')]} 12:10:51 INFO - https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:10:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 12:10:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 12:10:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/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 12:10:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/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 12:10:52 INFO - Downloaded 17525290 bytes. 12:10:52 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 12:10:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 12:10:52 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 12:10:55 INFO - Return code: 0 12:10:55 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 12:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:55 INFO - https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:10:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 12:10:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 12:10:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/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 12:10:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/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 12:10:56 INFO - Downloaded 17525290 bytes. 12:10:56 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 12:10:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 12:10:56 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 12:10:59 INFO - Return code: 0 12:10: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')]} 12:10:59 INFO - https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:10:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 12:10:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 12:10:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/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 12:10:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/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 12:11:01 INFO - Downloaded 11130997 bytes. 12:11:01 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 12:11:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 12:11:01 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 12:11:01 INFO - Return code: 0 12:11:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:01 INFO - https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:11:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 12:11:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 12:11:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg 12:11:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nl1e7m18QGeoKefNA5LIqw/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 12:11:04 INFO - Downloaded 109635538 bytes. 12:11:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 12:11:04 INFO - mkdir: /builds/slave/test/properties 12:11:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:11:04 INFO - Writing to file /builds/slave/test/properties/build_url 12:11:04 INFO - Contents: 12:11:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 12:11:04 INFO - Running post-action listener: _resource_record_post_action 12:11:04 INFO - Running post-action listener: set_extra_try_arguments 12:11:04 INFO - ##### 12:11:04 INFO - ##### Running populate-webroot step. 12:11:04 INFO - ##### 12:11:04 INFO - Running pre-action listener: _resource_record_pre_action 12:11:04 INFO - Running main action method: populate_webroot 12:11:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:11:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:11:04 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 12:11:04 INFO - '../fennec_ids.txt'], 12:11:04 INFO - 'tests': ['tcheck2']}, 12:11:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:11:04 INFO - '--tppagecycles', 12:11:04 INFO - '7'], 12:11:04 INFO - 'tests': ['tsvgm']}}, 12:11:04 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 12:11:04 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 12:11:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:11:04 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:11:04 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 12:11:04 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 12:11:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'talos_options': ['--e10s'], 12:11:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'tests': ['damp', 'tps']}, 12:11:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'talos_options': ['--e10s'], 12:11:04 INFO - 'tests': ['damp', 'tps']}, 12:11:04 INFO - 'g3': {'tests': ['dromaeo_dom']}, 12:11:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:11:04 INFO - 'other': {'tests': ['a11yr', 12:11:04 INFO - 'ts_paint', 12:11:04 INFO - 'tpaint', 12:11:04 INFO - 'sessionrestore', 12:11:04 INFO - 'sessionrestore_no_auto_restore']}, 12:11:04 INFO - 'other-e10s': {'talos_options': ['--e10s'], 12:11:04 INFO - 'tests': ['a11yr', 12:11:04 INFO - 'ts_paint', 12:11:04 INFO - 'tpaint', 12:11:04 INFO - 'sessionrestore', 12:11:04 INFO - 'sessionrestore_no_auto_restore']}, 12:11:04 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 12:11:04 INFO - 'tests': ['a11yr', 12:11:04 INFO - 'ts_paint', 12:11:04 INFO - 'tpaint', 12:11:04 INFO - 'sessionrestore', 12:11:04 INFO - 'sessionrestore_no_auto_restore']}, 12:11:04 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 12:11:04 INFO - 'tests': ['a11yr', 12:11:04 INFO - 'ts_paint', 12:11:04 INFO - 'tpaint', 12:11:04 INFO - 'sessionrestore', 12:11:04 INFO - 'sessionrestore_no_auto_restore']}, 12:11:04 INFO - 'other_l64': {'tests': ['a11yr', 12:11:04 INFO - 'ts_paint', 12:11:04 INFO - 'tpaint', 12:11:04 INFO - 'sessionrestore', 12:11:04 INFO - 'sessionrestore_no_auto_restore']}, 12:11:04 INFO - 'other_nol64': {'tests': ['a11yr', 12:11:04 INFO - 'ts_paint', 12:11:04 INFO - 'tpaint', 12:11:04 INFO - 'sessionrestore', 12:11:04 INFO - 'sessionrestore_no_auto_restore']}, 12:11:04 INFO - 'svgr': {'tests': ['tsvgx', 12:11:04 INFO - 'tsvgr_opacity', 12:11:04 INFO - 'tart', 12:11:04 INFO - 'tscrollx', 12:11:04 INFO - 'cart']}, 12:11:04 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 12:11:04 INFO - 'tests': ['tsvgx', 12:11:04 INFO - 'tsvgr_opacity', 12:11:04 INFO - 'tart', 12:11:04 INFO - 'tscrollx', 12:11:04 INFO - 'cart']}, 12:11:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'tests': ['tp5o']}, 12:11:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'talos_options': ['--e10s'], 12:11:04 INFO - 'tests': ['tp5o']}, 12:11:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'talos_options': ['--xperf_path', 12:11:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:04 INFO - 'tests': ['tp5n']}, 12:11:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:04 INFO - 'talos_options': ['--e10s', 12:11:04 INFO - '--xperf_path', 12:11:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:04 INFO - 'tests': ['tp5n']}}, 12:11:04 INFO - 'talos.zip': {'path': '', 12:11:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:11:04 INFO - Running post-action listener: _resource_record_post_action 12:11:04 INFO - ##### 12:11:04 INFO - ##### Running create-virtualenv step. 12:11:04 INFO - ##### 12:11:04 INFO - Running pre-action listener: _resource_record_pre_action 12:11:04 INFO - Running main action method: create_virtualenv 12:11:04 INFO - Creating virtualenv /builds/slave/test/build/venv 12:11:04 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 12:11:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:11:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:11:04 INFO - Using real prefix '/tools/python27' 12:11:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:11:05 INFO - Installing distribute.............................................................................................................................................................................................done. 12:11:08 INFO - Installing pip.................done. 12:11:08 INFO - Return code: 0 12:11:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:08 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 0x10cc30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0d228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96b05856e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb549f0>, '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 0x10c8b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb478c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/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.DYGH0cfsM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:11:08 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 12:11:08 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 12:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/Render', 12:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:08 INFO - 'HOME': '/Users/cltbld', 12:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:08 INFO - 'LOGNAME': 'cltbld', 12:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:08 INFO - 'MOZ_NO_REMOTE': '1', 12:11:08 INFO - 'NO_EM_RESTART': '1', 12:11:08 INFO - 'PAGER': '/bin/cat', 12:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:08 INFO - 'PWD': '/builds/slave/test', 12:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:08 INFO - 'SHELL': '/bin/bash', 12:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYGH0cfsM6/Listeners', 12:11:08 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 12:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:08 INFO - 'USER': 'cltbld', 12:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:08 INFO - 'XPC_FLAGS': '0x0', 12:11:08 INFO - 'XPC_SERVICE_NAME': '0', 12:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:08 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 12:11:08 INFO - Cleaning up... 12:11:08 INFO - Return code: 0 12:11:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:08 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 0x10cc30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0d228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96b05856e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb549f0>, '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 0x10c8b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb478c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/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.DYGH0cfsM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:11:08 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 12:11:08 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 12:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/Render', 12:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:08 INFO - 'HOME': '/Users/cltbld', 12:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:08 INFO - 'LOGNAME': 'cltbld', 12:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:08 INFO - 'MOZ_NO_REMOTE': '1', 12:11:08 INFO - 'NO_EM_RESTART': '1', 12:11:08 INFO - 'PAGER': '/bin/cat', 12:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:08 INFO - 'PWD': '/builds/slave/test', 12:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:08 INFO - 'SHELL': '/bin/bash', 12:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYGH0cfsM6/Listeners', 12:11:08 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 12:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:08 INFO - 'USER': 'cltbld', 12:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:08 INFO - 'XPC_FLAGS': '0x0', 12:11:08 INFO - 'XPC_SERVICE_NAME': '0', 12:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:08 INFO - Downloading/unpacking psutil>=0.7.1 12:11:08 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 12:11:08 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 12:11:08 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 12:11:08 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 12:11:08 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 12:11:08 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 12:11:11 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 12:11:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 12:11:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:11:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:11 INFO - Installing collected packages: psutil 12:11:11 INFO - Running setup.py install for psutil 12:11:11 INFO - building 'psutil._psutil_osx' extension 12:11:11 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 12:11:11 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 12:11:12 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 12:11:12 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 12:11:12 INFO - building 'psutil._psutil_posix' extension 12:11:12 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 12:11:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:11:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:11:12 INFO - ^ 12:11:12 INFO - 1 warning generated. 12:11:12 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 12:11:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:12 INFO - Successfully installed psutil 12:11:12 INFO - Cleaning up... 12:11:12 INFO - Return code: 0 12:11:12 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cc30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0d228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96b05856e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb549f0>, '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 0x10c8b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb478c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/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.DYGH0cfsM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:11:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 12:11:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 12:11:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/Render', 12:11:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:12 INFO - 'HOME': '/Users/cltbld', 12:11:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:12 INFO - 'LOGNAME': 'cltbld', 12:11:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:12 INFO - 'MOZ_NO_REMOTE': '1', 12:11:12 INFO - 'NO_EM_RESTART': '1', 12:11:12 INFO - 'PAGER': '/bin/cat', 12:11:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:12 INFO - 'PWD': '/builds/slave/test', 12:11:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:12 INFO - 'SHELL': '/bin/bash', 12:11:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYGH0cfsM6/Listeners', 12:11:12 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 12:11:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:12 INFO - 'USER': 'cltbld', 12:11:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:12 INFO - 'XPC_FLAGS': '0x0', 12:11:12 INFO - 'XPC_SERVICE_NAME': '0', 12:11:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:12 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:11:12 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 12:11:12 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 12:11:12 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 12:11:12 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 12:11:12 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 12:11:12 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 12:11:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:11:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 12:11:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:11:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:11:15 INFO - Installing collected packages: mozsystemmonitor 12:11:15 INFO - Running setup.py install for mozsystemmonitor 12:11:15 INFO - Successfully installed mozsystemmonitor 12:11:15 INFO - Cleaning up... 12:11:15 INFO - Return code: 0 12:11:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:11: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')]} 12:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11: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')]} 12:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:15 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 0x10cc30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0d228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96b05856e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb549f0>, '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 0x10c8b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb478c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/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.DYGH0cfsM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:11:15 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 12:11:15 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 12:11:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/Render', 12:11:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:15 INFO - 'HOME': '/Users/cltbld', 12:11:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:15 INFO - 'LOGNAME': 'cltbld', 12:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:15 INFO - 'MOZ_NO_REMOTE': '1', 12:11:15 INFO - 'NO_EM_RESTART': '1', 12:11:15 INFO - 'PAGER': '/bin/cat', 12:11:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:15 INFO - 'PWD': '/builds/slave/test', 12:11:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:15 INFO - 'SHELL': '/bin/bash', 12:11:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYGH0cfsM6/Listeners', 12:11:15 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 12:11:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:15 INFO - 'USER': 'cltbld', 12:11:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:15 INFO - 'XPC_FLAGS': '0x0', 12:11:15 INFO - 'XPC_SERVICE_NAME': '0', 12:11:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:15 INFO - Downloading/unpacking blobuploader==1.2.4 12:11:15 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 12:11:15 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 12:11:15 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 12:11:15 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 12:11:15 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 12:11:15 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 12:11:17 INFO - Downloading blobuploader-1.2.4.tar.gz 12:11:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 12:11:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:11:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 12:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:11:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:11:18 INFO - Downloading docopt-0.6.1.tar.gz 12:11:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 12:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:11:18 INFO - Installing collected packages: blobuploader, requests, docopt 12:11:18 INFO - Running setup.py install for blobuploader 12:11:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:11:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:11:18 INFO - Running setup.py install for requests 12:11:19 INFO - Running setup.py install for docopt 12:11:19 INFO - Successfully installed blobuploader requests docopt 12:11:19 INFO - Cleaning up... 12:11:19 INFO - Return code: 0 12:11:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:19 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 0x10cc30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0d228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96b05856e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb549f0>, '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 0x10c8b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb478c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/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.DYGH0cfsM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:11:19 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 12:11:19 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 12:11:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/Render', 12:11:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:19 INFO - 'HOME': '/Users/cltbld', 12:11:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:19 INFO - 'LOGNAME': 'cltbld', 12:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:19 INFO - 'MOZ_NO_REMOTE': '1', 12:11:19 INFO - 'NO_EM_RESTART': '1', 12:11:19 INFO - 'PAGER': '/bin/cat', 12:11:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:19 INFO - 'PWD': '/builds/slave/test', 12:11:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:19 INFO - 'SHELL': '/bin/bash', 12:11:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYGH0cfsM6/Listeners', 12:11:19 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 12:11:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:19 INFO - 'USER': 'cltbld', 12:11:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:19 INFO - 'XPC_FLAGS': '0x0', 12:11:19 INFO - 'XPC_SERVICE_NAME': '0', 12:11:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:19 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-p1mPbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:20 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-sQ41jh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:20 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-V5urdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:20 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-0etlYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:20 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-N3qDIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:20 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-qJ8Ih8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:20 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-j3bFwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:20 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-_wZlaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:20 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-LOLJzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:20 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-zyjjWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:20 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-uplq1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:21 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-bqHbNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:21 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-mTOccc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:21 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-an0z1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:21 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-SMfBkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:21 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-j_zo2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:21 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-bs5tSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:11:21 INFO - Running setup.py install for manifestparser 12:11:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:11:21 INFO - Running setup.py install for mozcrash 12:11:21 INFO - Running setup.py install for mozdebug 12:11:22 INFO - Running setup.py install for mozdevice 12:11:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:11:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:11:22 INFO - Running setup.py install for mozfile 12:11:22 INFO - Running setup.py install for mozhttpd 12:11:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:11:22 INFO - Running setup.py install for mozinfo 12:11:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:11:22 INFO - Running setup.py install for mozInstall 12:11:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:11:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:11:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:11:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:11:22 INFO - Running setup.py install for mozleak 12:11:22 INFO - Running setup.py install for mozlog 12:11:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:11:23 INFO - Running setup.py install for moznetwork 12:11:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:11:23 INFO - Running setup.py install for mozprocess 12:11:23 INFO - Running setup.py install for mozprofile 12:11:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:11:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:11:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:11:23 INFO - Running setup.py install for mozrunner 12:11:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:11:23 INFO - Running setup.py install for mozscreenshot 12:11:23 INFO - Running setup.py install for moztest 12:11:24 INFO - Running setup.py install for mozversion 12:11:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:11:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:11:24 INFO - Cleaning up... 12:11:24 INFO - Return code: 0 12:11:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:24 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')]} 12:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:24 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')]} 12:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:24 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 0x10cc30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0d228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96b05856e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb549f0>, '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 0x10c8b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb478c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/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.DYGH0cfsM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:11:24 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 12:11:24 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 12:11:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/Render', 12:11:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:24 INFO - 'HOME': '/Users/cltbld', 12:11:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:24 INFO - 'LOGNAME': 'cltbld', 12:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:24 INFO - 'MOZ_NO_REMOTE': '1', 12:11:24 INFO - 'NO_EM_RESTART': '1', 12:11:24 INFO - 'PAGER': '/bin/cat', 12:11:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:24 INFO - 'PWD': '/builds/slave/test', 12:11:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:24 INFO - 'SHELL': '/bin/bash', 12:11:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYGH0cfsM6/Listeners', 12:11:24 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 12:11:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:24 INFO - 'USER': 'cltbld', 12:11:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:24 INFO - 'XPC_FLAGS': '0x0', 12:11:24 INFO - 'XPC_SERVICE_NAME': '0', 12:11:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:11:24 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-9LhiMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:11:24 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)) 12:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:11:24 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-rAyEc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:11:24 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)) 12:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:11:24 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-u0Szw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:11:24 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)) 12:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:11:24 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-2bEBDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:24 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)) 12:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:24 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-h7m7Rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:24 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)) 12:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:24 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-bIpoCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:25 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)) 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:25 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-KrgZ4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:25 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)) 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:25 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-By3WBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:25 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)) 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:25 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-6mowjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:25 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)) 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:25 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-rg5jGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:25 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)) 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:25 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-nqbdDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:25 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)) 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:25 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-zcvTky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:25 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)) 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:25 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-YNOw1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:25 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)) 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:25 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-tYgKQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:25 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)) 12:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:25 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-t9eQq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:26 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)) 12:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:26 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-bY73pN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:26 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)) 12:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:26 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-eSD1WA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:26 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)) 12:11:26 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)) 12:11:26 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)) 12:11:26 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)) 12:11:26 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)) 12:11:26 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)) 12:11:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:11:26 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 12:11:26 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 12:11:26 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 12:11:26 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 12:11:26 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 12:11:26 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 12:11:28 INFO - Downloading blessings-1.5.1.tar.gz 12:11:28 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 12:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:11:28 INFO - Installing collected packages: blessings 12:11:28 INFO - Running setup.py install for blessings 12:11:28 INFO - Successfully installed blessings 12:11:28 INFO - Cleaning up... 12:11:28 INFO - Return code: 0 12:11:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:11:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:28 INFO - Reading from file tmpfile_stdout 12:11:28 INFO - Current package versions: 12:11:28 INFO - blessings == 1.5.1 12:11:28 INFO - blobuploader == 1.2.4 12:11:28 INFO - docopt == 0.6.1 12:11:28 INFO - manifestparser == 1.1 12:11:28 INFO - mozInstall == 1.12 12:11:28 INFO - mozcrash == 0.16 12:11:28 INFO - mozdebug == 0.1 12:11:28 INFO - mozdevice == 0.47 12:11:28 INFO - mozfile == 1.2 12:11:28 INFO - mozhttpd == 0.7 12:11:28 INFO - mozinfo == 0.9 12:11:28 INFO - mozleak == 0.1 12:11:28 INFO - mozlog == 3.0 12:11:28 INFO - moznetwork == 0.27 12:11:28 INFO - mozprocess == 0.22 12:11:28 INFO - mozprofile == 0.27 12:11:28 INFO - mozrunner == 6.11 12:11:28 INFO - mozscreenshot == 0.1 12:11:28 INFO - mozsystemmonitor == 0.0 12:11:28 INFO - moztest == 0.7 12:11:28 INFO - mozversion == 1.4 12:11:28 INFO - psutil == 3.1.1 12:11:28 INFO - requests == 1.2.3 12:11:28 INFO - wsgiref == 0.1.2 12:11:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:11:29 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 0x10cc30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc0d228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96b05856e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb549f0>, '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 0x10c8b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb478c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/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.DYGH0cfsM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:11:29 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 12:11:29 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 12:11:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/Render', 12:11:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:29 INFO - 'HOME': '/Users/cltbld', 12:11:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:29 INFO - 'LOGNAME': 'cltbld', 12:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:29 INFO - 'MOZ_NO_REMOTE': '1', 12:11:29 INFO - 'NO_EM_RESTART': '1', 12:11:29 INFO - 'PAGER': '/bin/cat', 12:11:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:29 INFO - 'PWD': '/builds/slave/test', 12:11:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:29 INFO - 'SHELL': '/bin/bash', 12:11:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYGH0cfsM6/Listeners', 12:11:29 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 12:11:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:29 INFO - 'USER': 'cltbld', 12:11:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:29 INFO - 'XPC_FLAGS': '0x0', 12:11:29 INFO - 'XPC_SERVICE_NAME': '0', 12:11:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:29 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)) 12:11:29 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)) 12:11:29 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)) 12:11:29 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)) 12:11:29 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)) 12:11:29 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)) 12:11:29 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)) 12:11:29 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)) 12:11:29 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)) 12:11:29 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)) 12:11:29 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)) 12:11:29 INFO - Cleaning up... 12:11:29 INFO - Return code: 0 12:11:29 INFO - Running post-action listener: _resource_record_post_action 12:11:29 INFO - Running post-action listener: _start_resource_monitoring 12:11:29 INFO - Starting resource monitoring. 12:11:29 INFO - ##### 12:11:29 INFO - ##### Running install step. 12:11:29 INFO - ##### 12:11:29 INFO - Running pre-action listener: _resource_record_pre_action 12:11:29 INFO - Running main action method: install 12:11:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:29 INFO - Reading from file tmpfile_stdout 12:11:29 INFO - Detecting whether we're running mozinstall >=1.0... 12:11:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:11:29 INFO - Reading from file tmpfile_stdout 12:11:29 INFO - Output received: 12:11:29 INFO - Usage: mozinstall [options] installer 12:11:29 INFO - Options: 12:11:29 INFO - -h, --help show this help message and exit 12:11:29 INFO - -d DEST, --destination=DEST 12:11:29 INFO - Directory to install application into. [default: 12:11:29 INFO - "/builds/slave/test"] 12:11:29 INFO - --app=APP Application being installed. [default: firefox] 12:11:29 INFO - mkdir: /builds/slave/test/build/application 12:11:29 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'] 12:11:29 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 12:11:59 INFO - Reading from file tmpfile_stdout 12:11:59 INFO - Output received: 12:11:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:11:59 INFO - Running post-action listener: _resource_record_post_action 12:11:59 INFO - ##### 12:11:59 INFO - ##### Running run-tests step. 12:11:59 INFO - ##### 12:11:59 INFO - Running pre-action listener: _resource_record_pre_action 12:11:59 INFO - Running main action method: run_tests 12:11:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:11:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:11:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:11:59 INFO - 2015-11-23 12:11:59.593 screenresolution[1637:7366] starting screenresolution argv=screenresolution get 12:11:59 INFO - 2015-11-23 12:11:59.595 screenresolution[1637:7366] Display 0: 1600x1200x32@60 12:11:59 INFO - 2015-11-23 12:11:59.601 screenresolution[1638:7369] starting screenresolution argv=screenresolution list 12:11:59 INFO - Available Modes on Display 0 12:11:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:11:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:11:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:11:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:11:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:11:59 INFO - Intel Iris: 12:11:59 INFO - Chipset Model: Intel Iris 12:11:59 INFO - Type: GPU 12:11:59 INFO - Bus: Built-In 12:11:59 INFO - VRAM (Dynamic, Max): 1536 MB 12:11:59 INFO - Vendor: Intel (0x8086) 12:11:59 INFO - Device ID: 0x0a2e 12:11:59 INFO - Revision ID: 0x0009 12:11:59 INFO - Displays: 12:11:59 INFO - Display: 12:11:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:11:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:11:59 INFO - Display Serial Number: 1600x1200 60 12:11:59 INFO - Main Display: Yes 12:11:59 INFO - Mirror: Off 12:11:59 INFO - Online: Yes 12:11:59 INFO - Rotation: Supported 12:11:59 INFO - Return code: 0 12:11:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:11:59 INFO - Python 2.7.3 12:11:59 INFO - Return code: 0 12:11:59 INFO - grabbing minidump binary from tooltool 12:11: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')]} 12:11:59 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 0x10cb549f0>, '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 0x10c8b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb478c8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:11:59 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 12:11:59 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 12:11:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:11:59 INFO - Return code: 0 12:11:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:11:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:11:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:11:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:11:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:11:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--webServer', 'localhost'] in /builds/slave/test/build 12:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0018 --webServer localhost 12:11:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IYKFbnqFOL/Render', 12:11:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:59 INFO - 'HOME': '/Users/cltbld', 12:11:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:59 INFO - 'LOGNAME': 'cltbld', 12:11:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:11:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:59 INFO - 'MOZ_NO_REMOTE': '1', 12:11:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:11:59 INFO - 'NO_EM_RESTART': '1', 12:11:59 INFO - 'PAGER': '/bin/cat', 12:11:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:59 INFO - 'PWD': '/builds/slave/test', 12:11:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:11:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:59 INFO - 'SHELL': '/bin/bash', 12:11:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DYGH0cfsM6/Listeners', 12:11:59 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 12:11:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:59 INFO - 'USER': 'cltbld', 12:11:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:59 INFO - 'XPC_FLAGS': '0x0', 12:11:59 INFO - 'XPC_SERVICE_NAME': '0', 12:11:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--webServer', 'localhost'] with output_timeout 3600 12:12:00 INFO - mozversion INFO | application_buildid: 20151121014332 12:12:00 INFO - mozversion INFO | application_changeset: 489332006384610e3322739f66729448da0e98d9 12:12:00 INFO - mozversion INFO | application_display_name: Nightly 12:12:00 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:12:00 INFO - mozversion INFO | application_name: Firefox 12:12:00 INFO - mozversion INFO | application_remotingname: firefox 12:12:00 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 12:12:00 INFO - mozversion INFO | application_vendor: Mozilla 12:12:00 INFO - mozversion INFO | application_version: 45.0a1 12:12:00 INFO - mozversion INFO | platform_buildid: 20151121014332 12:12:00 INFO - mozversion INFO | platform_changeset: 489332006384610e3322739f66729448da0e98d9 12:12:00 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 12:12:00 INFO - mozversion INFO | platform_version: 45.0a1 12:12:00 INFO - 2015-11-23 12:12:00,147 DEBUG : using testdate: 1448309520 12:12:00 INFO - 2015-11-23 12:12:00,147 DEBUG : actual date: 1448309520 12:12:00 INFO - 2015-11-23 12:12:00,538 INFO : starting webserver on 'localhost:49264' 12:12:00 INFO - 2015-11-23 12:12:00,539 INFO : Starting test suite t-yosemite-r7-0018 12:12:00 INFO - 2015-11-23 12:12:00,539 INFO : Starting test dromaeo_css 12:12:00 INFO - 2015-11-23 12:12:00,539 DEBUG : operating with platform_type : mac_ 12:12:00 INFO - 2015-11-23 12:12:00,540 INFO : Initialising browser for dromaeo_css test... 12:12:00 INFO - 2015-11-23 12:12:00,552 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpIK2B8E/profile http://localhost:49264/getInfo.html 12:12:02 INFO - 2015-11-23 12:12:02,677 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:12:02 INFO - 2015-11-23 12:12:02,677 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:12:02 INFO - 2015-11-23 12:12:02,677 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 12:12:02 INFO - 2015-11-23 12:12:02,678 DEBUG : BROWSER_OUTPUT: __metrics 12:12:02 INFO - 2015-11-23 12:12:02,698 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:12:03 INFO - 2015-11-23 12:12:03,487 INFO : Browser initialized. 12:12:03 INFO - 2015-11-23 12:12:03,487 INFO : Running cycle 1/1 for dromaeo_css test... 12:12:03 INFO - 2015-11-23 12:12:03,487 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpIK2B8E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:12:15 INFO - 2015-11-23 12:12:15,330 DEBUG : BROWSER_OUTPUT: RSS: Main: 225980416 12:12:15 INFO - 2015-11-23 12:12:15,330 DEBUG : BROWSER_OUTPUT: 12:13:06 INFO - 2015-11-23 12:13:06,316 DEBUG : BROWSER_OUTPUT: 1448309586315 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] 12:15:17 INFO - 2015-11-23 12:15:17,681 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49264/tests/dromaeo/cssquery-ext.html) 12:15:19 INFO - 2015-11-23 12:15:19,119 DEBUG : BROWSER_OUTPUT: RSS: Main: 336224256 12:15:19 INFO - 2015-11-23 12:15:19,119 DEBUG : BROWSER_OUTPUT: 12:18:21 INFO - 2015-11-23 12:18:21,701 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-ext.html (next: http://localhost:49264/tests/dromaeo/cssquery-jquery.html) 12:18:22 INFO - 2015-11-23 12:18:22,291 DEBUG : BROWSER_OUTPUT: RSS: Main: 363339776 12:18:22 INFO - 2015-11-23 12:18:22,292 DEBUG : BROWSER_OUTPUT: 12:21:24 INFO - 2015-11-23 12:21:24,353 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49264/tests/dromaeo/cssquery-mootools.html) 12:21:24 INFO - 2015-11-23 12:21:24,938 DEBUG : BROWSER_OUTPUT: RSS: Main: 339398656 12:21:24 INFO - 2015-11-23 12:21:24,938 DEBUG : BROWSER_OUTPUT: 12:24:27 INFO - 2015-11-23 12:24:27,000 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49264/tests/dromaeo/cssquery-prototype.html) 12:24:27 INFO - 2015-11-23 12:24:27,591 DEBUG : BROWSER_OUTPUT: RSS: Main: 314392576 12:24:27 INFO - 2015-11-23 12:24:27,591 DEBUG : BROWSER_OUTPUT: 12:27:30 INFO - 2015-11-23 12:27:30,493 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49264/tests/dromaeo/cssquery-yui.html) 12:27:31 INFO - 2015-11-23 12:27:31,101 DEBUG : BROWSER_OUTPUT: RSS: Main: 317169664 12:27:31 INFO - 2015-11-23 12:27:31,101 DEBUG : BROWSER_OUTPUT: 12:30:33 INFO - 2015-11-23 12:30:33,827 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-yui.html (next: http://localhost:49264/tests/dromaeo/cssquery-dojo.html) 12:30:34 INFO - 2015-11-23 12:30:34,084 DEBUG : BROWSER_OUTPUT: RSS: Main: 366690304 12:30:34 INFO - 2015-11-23 12:30:34,084 DEBUG : BROWSER_OUTPUT: 12:30:34 INFO - 2015-11-23 12:30:34,084 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:30:34 INFO - 2015-11-23 12:30:34,084 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:30:34 INFO - 2015-11-23 12:30:34,085 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:30:34 INFO - 2015-11-23 12:30:34,085 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:30:34 INFO - 2015-11-23 12:30:34,086 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3294.9999999999995;3516.9120771980697;3525.171584677602;3539.876104336349;3575.4815551744996;5733.340665823432;5831.212786273857;5867.618604790697;5907.232059832221;5991.430814072667;5780.508656764169;5912.231409916705;5916.349201587833;6047.667378294192;6078.939210607904;5136.152534831748;5289.603279754015;5289.709066001368;5364.651297665659;5447.1829225616275;4909.386326709161;5430.321209848769;5461.126219804832;5468.945310546884;5490.149026900836;5077.517635824591;5160.380754309469;5190.532852043334;5420.972895135519;5459.935312614042;5348.491893270133;5362;5365.919511207335;5372.838814835562;5382.165764306539;5262.500062494095;5319.468053194688;5321.5210631043;5336.119540275849;5340.519353258186;5741.224934633813;5750.626209296424;5768.480836724673;5781.017227071408;5791.739371728282;6218.31598524162;6305.842353941142;6327.145835312266;6339.651319177444;6349.206349206349;6325.746970121189;6360.459360954324;6362.077498762654;6390.073439351315;6406;5816.94183058164;6116.633002019848;6139.079138129272;6154.415330543591;6162.4453799157845;6292.433680968735;6373.553851230369;6412.775552855627;6543.901841472381;6545.8690826184165;6366.363363663597;6379.904301435482;6385.169927909435;6388.39310265524;6391.808245752635;6201.937980620226;6363.968180159162;6382.712777925005;6384.744610215539;6401.871962560723;6222.066689996446;6299.527535434769;6334.3032266450655;6339.873202535923;6379.202599675041;6320.778772742932;6348.809535713936;6362.936370636327;6363.554551181465;6369;4942.258661200849;5073.985202959423;5075.365579302587;5079.6698214615935;5126.282320475137;4464.196444639931;4500.032493014018;4507.88730281739;4535.818567257273;4592.563706447882;5891.852703682443;5900.970495147582;5928.170056192136;5936.940630593639;5937.168796368512;6192.907106393408;6362.713677884506;6372.267189273293;6393.264774550893;6404.967975160094;5844.386339434383;5868.794592189253;5889.057750759858;5960.761569537257;6014.1580178775;5590.357108932443;5598.076317407664;5621.747021384048;5635.690037048002;5650.180723795027;6313.2424109107205;6383.840403989938;6402.391772781485;6408.775692850728;6416.550841441148;5453.809116680897;6205.28639206479;6348.93651063502;6395.456386207038;6401.3598640135615;6209.720562574786;6313.936860631335;6389.48884089281;6394.328595497405;6409.942359510815;6297.4017468340435;6325.209348831396;6350.523710721622;6359.395857393541;6363.331850155852;6159.753609855735;6333.746650133943;6337.746490140343;6341.27075386318;6347.301796802256;6120.9652521188555;6218.81343559694;6221.2845522763655;6254.186955695731;6347.809565713036;6288.622682639057;6288.874222515433;6300.936990630218;6311.495080393465;6321.367863213826;5951.791687290924;6177.2587289525545;6185;6193.876122477435;6193.938060619516;6112.716259964451;6137.619035717106;6238.812835614939;6251.843703907257;6262.1546091278915;6070.2412198475195;6175.475084617858;6177.752889884354;6208.565400421927;6213.751449942132 12:30:34 INFO - 2015-11-23 12:30:34,089 DEBUG : BROWSER_OUTPUT: |1;ext.html;578.3769912552556;585.3970410477209;606.8240210339002;607.9696015199239;640.4588123036036;32750.67249327506;33132.33735325295;33345;33354.49968250478;33402.49896251558;27671.86164069182;43526.12947741043;44014.779926100404;44020.3396949046;44112;9759.853602195955;9885.406875587452;9917;9935.254855885838;9935.254855885838;8047.637856296451;8887.20015198632;9011.098890111001;9515.524223788818;9604.903950960506;9991.200703943667;12190.878091219107;12198.26810391375;12199.26804391735;12204;11873.287602743863;11944.462499187513;11984.340861252627;12010.939945300217;12078.456469458895;8896.243819275369;9028.413153145024;11757.412129393582;11956.70108247292;12097;38938.442462301755;41890.37164442505;42238.1552368954;42244.36633450501;42320.57679423197;3040.3159289159985;3169.889053883103;3806.371948628501;3883.475730776337;4090.0592863641527;29347.972820951163;29702;29841.552376714368;29866.850665746533;29969.700302997127;2843.24653966699;2843.545032794743;2869.540873460257;2893.030834670388;2938.38293958269;520296;521944;523291;523710.38144809415;524444;63146;63153;63708;69995.95006074914;70557.2944270561;75564.24435755682;75597.24402756013;76485.2351476478;76830;76915.61542192253;16628.6674266514;16814.915925420293;16829.074400907823;16834.57913552171;16862.83137168637;12628.431720572597;12685.93657031709;12686.49254029847;12697.174683645533;12697.492100315883;21585.568288634455;21725.782742172374;21731.456713582287;21830.672539911913;21870.125194992022;7779.144294127642;7919.198342631951;9328.486933218737;9582.185514231229;9584.466485362309;866.4281574161149;913.5340976102236;994.2692121290845;1058.9682309530726;1112.1214240749716;29936.70063299339;30140.54789178164;30173.943911963415;30980.53529197064;30984.380312393627;878.8198419324024;1121.147927575049;1131.4003578103618;1171.0221964659604;1171.9238249513755;12199.451024703909;15216.619584510254;15297.923510382378;15422.074675519505;15693.843061569467;536415;540115;543660;545477;548261;61026.69486652538;61219.08171377433;61539;61612.38387616067;61746;1452.0924422236103;1456.431991523328;1466.6919946811076;1468.8384277729442;1620.2708781048575;26409.075682351024;28637.568121594253;30937.381252374376;31307.217319566284;31608.841955790995;43457;57403.42596573981;57623.42376576348;57689;57795.71102144462;428419;428488;429236;431930;434439;29355.706442935298;29491.55762663519;31333.58998845012;31776.523352149274;34228.48657270093;42064;43752.34371484494;45044.54955450403;45180;45397.86505337393;35002.7743480287;36446.81776591206;37619;37712.43431348587;37847.81076094712;34658.30683386369;34857.65142348544;36226.63773362338;36230;36305;36960.31432613388;37181.07047323838;37702.62297376991;37954.43068353922;38042.23915521729;33961.66038339585;34210.48684269788;34480.31039379248;34590.30819383548;34779.478307824895;23544.764552354256;24026.51946961086;24293.75706242915;24357.025718971043;24902.252932412055 12:30:34 INFO - 2015-11-23 12:30:34,091 DEBUG : BROWSER_OUTPUT: |2;jquery.html;360.50610663391154;364.775662967275;370.7312198655973;371.0834239428612;375.8045816175589;15801.446949356774;18332.450026499195;19345.516362090937;21263.893680531593;21270.042848071836;21781.346559603197;22421.887890560563;22888.65667014996;22907.770922290812;22927;4691.29630555416;6601.240857301424;6761.661916904135;6787.72849086036;6788.083608712835;4028;4743.833965811197;7405.22245164258;7600.847983040336;7642;5608.2428872102155;5808.941910580904;5810.448007439308;7489.400847932152;7582.886256706126;5802.216700745387;5808.1578171165365;5814.563907706939;5819.301683797929;5819.67991760453;5392.730363481849;5540.030494663418;5555.3333599967855;5559.916601250984;5582.246396736429;13411.597652070453;13621.910247180202;20197.49506262355;25945.96216151352;26470.676466176807;1979.8218160365493;2026.4022113476412;2027.2614622057001;2036.7963203679662;2279;9924.751881203028;10151;10154.390736555757;10233.744156396075;12066.276023438622;1607.7025750236244;1676.639522502668;1701.4300209429732;1718.2525601363448;1727.870409719276;86930;107164;108103;108442;108582;11255.887441125484;11291.322520648788;11329.03703185222;11332.886671133349;11381.203315767816;13309.334533273492;13413.39639716215;13428.93285533566;13439.798403023962;13454.52909148175;8528.829423411496;8552.828943421096;8559.315254779603;8610.225079742853;8612.741617751477;8726.520041397656;8757.4745515268;8777.51723655205;8826;8855.645774168961;3544.2734239480847;4091.3453847384276;4271.8718438446895;4358.476982762026;4430.62339701129;1540.8690261327688;1835.6145209506153;1837.5150176722473;1843.4285371534868;1845.4924895653535;397.16198820488745;405.2462419898986;407.03329592218483;421.2817146764759;1014.9188064954786;11358.26171298863;11720.296782193096;11759.824432480018;11764.294142351315;11775.352355620524;439.7537379067732;453.09833431471134;456.1606643775403;456.78950780431455;458.2233114870263;5317.574594032468;5987.730552125165;6072.908906366408;6079.483243924316;6089.238845144357;763571;775637;783240.3348941214;787062;787585;115433.42283288529;120713.39643301904;120798.50417949726;122142;122357;492.68468180364596;496.08967544556145;496.3002166944663;496.35721740345616;496.607679932849;10161.593536258762;10225.312823384356;11036.724081898017;11075.446227688422;11078.612248571262;50095.49904501054;50511.74744126256;50570;50669.746651266505;51037;70626.64686676534;70765;70995.64502177456;71540.64229678818;73849.63075184589;9551.761205969906;9804.65683701067;9850.310478266452;9926.751831204278;9951.054649808257;11855.466504007169;12916;19759.308424205083;20309.28917487881;20405.48986275355;15712.13583252932;15721.606959826096;15870.7619385707;16142.596435088746;16260.430884918971;12181.330026848254;14046.508372206923;15280.847191527942;15482.993605415611;16264.268107934935;14205;14301.64245893861;15452.227388630748;16297.511074667778;16581.502554923372;9764.365316254421;9781.755456113655;9784.364016338915;9967.900320996698;9997.450140242356;8209.384296177692;15482.380704772133;16237.269322880735;16283.837161628233;16581 12:30:34 INFO - 2015-11-23 12:30:34,092 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1564.3664315952028;1566.0916668332368;1705.9829401705995;1764.267828851027;1893.4319704088773;7691.692332306708;7964.163762804902;8230.465019773717;8311.625976831043;8326.75019749407;8370.162983701626;8520.275776559;8585.87121193181;8593.355498337618;8605.91394086059;2110.387987483628;2212.126210146991;2410.7348191698898;2434.549608322457;2437.049550675229;2026.554158085219;2219.889005549724;2227.3206671965127;2451.9264422067276;2458.40998160442;2364.2316247219637;2406.8676222807826;2415.311636183688;2417.0694282701197;2417.903283868643;2165.4261620670536;2225.3880182949656;2397.1969390254285;2422.103821586016;2430.501747141832;2195.7365116186;2372.916947906815;2422.5760491913843;2423.7697418745365;2425.333033415807;8519.36104792137;8699.043105258479;8702.129787021311;8705.042445331008;8709.303255739525;5252.842414727565;5297.841064768063;5312.335958005249;5347.7058761768085;5352.384475785257;3489.1277180704824;3507.438809790421;3580.946285805715;3586.748927575071;3589.5333606631234;1054.3146954479573;1063.988316991033;1066.4667666166918;1096.5613754498265;1655.5116240708899;11355.205135640508;11417.771644567061;11435.256708313933;11442.370669613169;11442.599509017144;4525.592696657317;4626.537346265347;4673.392458980311;4679.157751604744;4683.297505374153;9282.767930801783;9284.72145835626;9298;9299.117273645192;9327.160555550034;5185.507376799198;5196.298499702568;5257.6056795739705;5268.578513718893;5283.498067683564;4787.2101103318255;4862.805487780449;4918.90162196754;4923.212286034217;4925.926111108264;3682.1715114099115;3695.224002959355;3707.4068149096015;3708.721845861571;3748.981255093762;1152.5562658376584;1166.3235323512342;1175.9176857620055;1179.687382843547;1179.8584169899666;1288.3815768431205;1423.003897271893;1427.9071860329045;1428.8428272889973;1442.314900422291;8048.678052877936;8133.10535841057;8191.754247372588;8205.097439281673;8218.137095604994;510.9233614957786;520.1106108554415;523.5183631059358;525.036557916228;525.3511912787704;5493.478119578634;6026.306974697965;6155.938440615626;6169.321374648785;6466.482681385478;9848.2613803965;9914.40513569188;10003.34978226413;10184.388936663823;10211.693649190536;9827.164691001202;9933.15568176713;9946.403215807075;9999.200063994864;10072.59709611622;7755.1469338372735;7784.455088143889;7785;7840.33357164636;7843.882341764878;8061.717839875576;8083.514989100673;8099.109097999333;8099.271065604124;8112.716054938049;7766.844663106819;8977.730668079968;9325.393849399767;9420.152186303265;9451.480168590793;9400.576974036048;9524.428534287965;9538.380005299634;9659.758506037406;9692.51537423112;7070.787876363717;7162.498625096193;7163.176234733072;7182.784516464514;7210.387117095106;8277.710280140167;8318.792030199293;8336.999560052833;8381.916180838114;8408.36937229698;7726.111497177896;7825.1783562725095;7834.255745704149;7963.920360796318;7968.0438347398685;7618.200088990798;7651.502652327581;7749.108852482037;7777.533347999138;7844.529328240324;7609.581473019037;7805.609719514116;7830.725924592612;7834.725784597512;7878.684852605831;7114.715411383487;7135.714571417086;7137.928620713934;7144.1784194818265;7158.355747982707;6415.454686351713;7154.534955227893;7210.89183662256;7231.4214862811905;7257.854842903218 12:30:34 INFO - 2015-11-23 12:30:34,095 DEBUG : BROWSER_OUTPUT: |4;prototype.html;326.710860888114;333.8147328232831;337.2613975393887;346.6135259186008;347.22741899274104;5981.252343457068;6305.810825675231;6711.328867113286;6875.381215690587;6938.9653051734795;5401.27082843816;6641.203055633331;6782.762603308885;6843.418309443703;6851.486138539604;5003.274525193845;5049.141645920184;5064.772085256154;5072.1630930896545;5072.949270507284;4795.064962332347;4876.609871210295;4985.152524070917;5052.292679024939;5073.0107629012355;4966.627502937276;4998.650094493387;5005.924911126336;5016.322796422491;5034.295198672189;4998.575406009288;5016.974915125437;5028.290381270367;5034.798608055673;5062.0888240116765;4235.322348424268;4239.300515414953;4239.533651298355;4241.172971270604;4247.853079668472;6277.7175027123885;6496.577722448073;6503.93496065038;6518.902216466757;6539.34606539347;5926.614770039933;5926.762929482816;5931.673757943311;5937.228160339171;5941.732622031976;7312.232215617337;7317.41460683144;7322.926770732278;7337.0461839961;7339.412846972229;4654.883627909329;4795.664303498756;4801.783919723586;4817.9036419271415;4819.614430845559;7777.572233527154;7798.688052477894;7803.297703206738;7813.101493328283;7816.7264145754625;4855.781489832966;4863.148948933923;4863.8540843774745;4882.536159064884;4883.951160488421;4375.16871794358;4812.446568644581;4934.5312195341385;4940.086084074458;4972.154733695281;4825.95174048262;5809.7095145242565;5923.689760993216;5956.136360227742;5970.701464926737;7395.297446742551;7410.5183163094225;7424.888626670604;7425.7029718810645;7432.888506672404;1709.1283445442914;1735.8350956659096;1736.791585009807;1737.643783024477;1738.400251913088;1045.5033858916954;1087.9129669626411;1111.6220485035126;1125.0268517732102;1132.4394424759632;6866.690998905061;6874;6875.243723190445;6875.381215690511;6910.963355496716;6489.961606142994;7427.665755041036;7464.066991626047;7638.007059082283;7675.117361503499;6903.995682504135;6948.200956890022;6958.512904096766;6958.826029349307;6959.652017399211;4000.57993910641;4101.159262351211;4105.958940410617;4106.178764247103;4111.732737372061;7726.373033168749;7830.686772529149;7846.764597062097;7856.214378562212;7867.095284042409;8021.8395632088195;8031.437799353989;8036.959815201004;8041.47730397534;8062.355011599058;7568;7808.882866757003;7814.804629884298;7824.256695613907;7854.960725196337;6206.689665516797;7754.573498457639;7762.068551773597;7767.1067827200595;7781.144074151952;7621.35218506411;7622.733204337822;7627.084749830056;7631.3513351365755;7650.31147196755;7536.321731044232;7808.88286675689;7822.295993360625;7826.647800849089;7831.451798374059;4889.266610008527;4951.554360107608;4960.47914968923;4968.3292755479;4972.477889821661;6439.420452159328;7565.394768418403;7766.883496747666;7771.572563509061;7775.144734079134;7366.263373662805;7436.516626419266;7460.738874139379;7466.887996680162;7467.066616672916;6121.3878612140215;7397.519161254501;7405.8148546286775;7413.147488038944;7419.591922444318;7030.2969703029285;7218;7218.530795498275;7233.963830180815;7248.456365772694;7418.740344087931;7424.851502969802;7428.182899881117;7430.851382972202;7448.404127669665;5298.7350632469;6842.247352791289;6931.618760967994;7059.5764254144915;7061.505694601328 12:30:34 INFO - 2015-11-23 12:30:34,097 DEBUG : BROWSER_OUTPUT: |5;yui.html;738.9963050184749;818.2104269380051;820.2412768189423;822.292828167776;881.2201201936286;15186.772198417033;15252.923735381335;15476.922615386908;15515;15519.379224831007;14300.570982870531;14606.488772892952;14614.342354594042;14626.780598291034;14626.926865365658;802.1978021978022;803.1286054630733;987.1263931420691;1010.5806090472482;1013.8681971343715;794.0233512779284;984.1241295247236;990.3315262198023;991.7669347703284;992.1566668331897;1025.4513835098223;1035.3425574760045;1036.714903401563;1039.740064983754;1041.3283432186236;819.9877001844977;827.8385714785619;1029.1355261580234;1030.7423144213947;1036.2435422141841;770.4876257288896;771.6488997506177;772.3666593393419;776.1888826176659;781.468601351081;15221.695566088616;15415.845841541553;15432.459863904822;15433.459828905934;15435.922820385938;12278.938605306916;12375.133740638159;12635.11554191207;12852.164609300458;12961.416736246798;10062.597496100221;10171.898281017244;10184.643537476228;10311.79376412475;10965;3593.3711600469815;3609.7834129952025;3618.9087735479666;3659.121810765397;3665.5967843537455;74185.6290718543;75307;75471;76146.85779713308;76526.23473765302;7385.593792341366;7631.236876312435;9238.307126965505;9339.346245762867;9364.204042656314;21802.781972180073;21805.236816711338;21839.672404913937;21842.89078554597;21869.687818730927;7395.149557800814;7513.962430187814;7657.463977521631;7658.61706914652;7824.804379890548;4069.8372065117064;4148.211839750437;4165.708400412002;4200.579942005775;4348.152110338454;3482.773619714761;3549.911252218715;3550.55618047744;3554.680078792921;3563.750537462353;539.4174291764963;615.1080932647678;648.9513286503532;651.4625434016955;654.7871941618993;418.60232778859597;596.2278848890697;601.5969300568629;606.7451670298485;622.4895585619748;8562.52906090171;8589.828203436022;8762.992255890527;8830.205281524693;8939.642414303355;505.7344893930642;534.7406507843692;551.9448055194448;563.5485637477024;607.2015299880643;4017.77902215381;4075.449814275094;4147.6474499667265;4149.97925010373;4222.408862759216;92390.53804731068;92885;93782;97054.5147274259;97878.51060744793;82914.58542707248;85011.99999999876;85385.57307213548;85430.57284713537;85633;68057;72103;75361.62319188479;76336.9999999989;77086.6145669268;8830.293576513735;8879.822403552022;9000.819983600422;9022.413543119676;9106.863397048917;53159.20261196163;53621.46378536321;54100.376988690405;54894.72552637211;56211.71894140503;67086.36238726883;73009.26990730024;74091.25908740844;74262;75468.62265688636;10091.69724908254;10894.727631809268;11010.944945275221;11026.173037022096;11528.481218345027;12026.15816892809;12269.815952760537;12524.123311368117;12543.811842822182;12894;11276.830847537129;11864.703382415508;11869.050475962074;12257.448414821532;12307;11198.577579998864;11550.48022838991;11840.76318473643;11918.642440726791;11920.582779602672;11407.201495895208;11463.484143213742;11837.112216583619;11859.229150105215;12376.319302438453;9704.126628603459;9777.364471309342;9984.700458986243;10089.545970431494;10318.329308594917;9067.773305667148;9244.676436324966;9477.87230214014;9571.760705982406;10184.338018028804 12:30:34 INFO - 2015-11-23 12:30:34,097 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:30:34 INFO - 2015-11-23 12:30:34,097 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:30:34 INFO - 2015-11-23 12:30:34,097 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1209 12:30:34 INFO - 2015-11-23 12:30:34,097 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:30:34 INFO - 2015-11-23 12:30:34,097 DEBUG : BROWSER_OUTPUT: __startTimestamp1448310634083__endTimestamp 12:30:34 INFO - 2015-11-23 12:30:34,097 DEBUG : BROWSER_OUTPUT: 12:30:34 INFO - 2015-11-23 12:30:34,097 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:30:34 INFO - 2015-11-23 12:30:34,097 DEBUG : BROWSER_OUTPUT: Number of tests: 6 12:30:34 INFO - 2015-11-23 12:30:34,098 DEBUG : BROWSER_OUTPUT: 12:30:34 INFO - 2015-11-23 12:30:34,098 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5885.27 Median:6175.48 stddev:643.64 (10.4%) stddev-sans-first:612.70 12:30:34 INFO - 2015-11-23 12:30:34,098 DEBUG : BROWSER_OUTPUT: Values: 3295.0 3516.9 3525.2 3539.9 3575.5 5733.3 5831.2 5867.6 5907.2 5991.4 5780.5 5912.2 5916.3 6047.7 6078.9 5136.2 5289.6 5289.7 5364.7 5447.2 4909.4 5430.3 5461.1 5468.9 5490.1 5077.5 5160.4 5190.5 5421.0 5459.9 5348.5 5362.0 5365.9 5372.8 5382.2 5262.5 5319.5 5321.5 5336.1 5340.5 5741.2 5750.6 5768.5 5781.0 5791.7 6218.3 6305.8 6327.1 6339.7 6349.2 6325.7 6360.5 6362.1 6390.1 6406.0 5816.9 6116.6 6139.1 6154.4 6162.4 6292.4 6373.6 6412.8 6543.9 6545.9 6366.4 6379.9 6385.2 6388.4 6391.8 6201.9 6364.0 6382.7 6384.7 6401.9 6222.1 6299.5 6334.3 6339.9 6379.2 6320.8 6348.8 6362.9 6363.6 6369.0 4942.3 5074.0 5075.4 5079.7 5126.3 4464.2 4500.0 4507.9 4535.8 4592.6 5891.9 5901.0 5928.2 5936.9 5937.2 6192.9 6362.7 6372.3 6393.3 6405.0 5844.4 5868.8 5889.1 5960.8 6014.2 5590.4 5598.1 5621.7 5635.7 5650.2 6313.2 6383.8 6402.4 6408.8 6416.6 5453.8 6205.3 6348.9 6395.5 6401.4 6209.7 6313.9 6389.5 6394.3 6409.9 6297.4 6325.2 6350.5 6359.4 6363.3 6159.8 6333.7 6337.7 6341.3 6347.3 6121.0 6218.8 6221.3 6254.2 6347.8 6288.6 6288.9 6300.9 6311.5 6321.4 5951.8 6177.3 6185.0 6193.9 6193.9 6112.7 6137.6 6238.8 6251.8 6262.2 6070.2 6175.5 6177.8 6208.6 6213.8 12:30:34 INFO - 2015-11-23 12:30:34,098 DEBUG : BROWSER_OUTPUT: 12:30:34 INFO - 2015-11-23 12:30:34,100 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:65087.99 Median:36267.50 stddev:133237.76 (367.4%) stddev-sans-first:133523.49 12:30:34 INFO - 2015-11-23 12:30:34,100 DEBUG : BROWSER_OUTPUT: Values: 578.4 585.4 606.8 608.0 640.5 32750.7 33132.3 33345.0 33354.5 33402.5 27671.9 43526.1 44014.8 44020.3 44112.0 9759.9 9885.4 9917.0 9935.3 9935.3 8047.6 8887.2 9011.1 9515.5 9604.9 9991.2 12190.9 12198.3 12199.3 12204.0 11873.3 11944.5 11984.3 12010.9 12078.5 8896.2 9028.4 11757.4 11956.7 12097.0 38938.4 41890.4 42238.2 42244.4 42320.6 3040.3 3169.9 3806.4 3883.5 4090.1 29348.0 29702.0 29841.6 29866.9 29969.7 2843.2 2843.5 2869.5 2893.0 2938.4 520296.0 521944.0 523291.0 523710.4 524444.0 63146.0 63153.0 63708.0 69996.0 70557.3 75564.2 75597.2 76485.2 76830.0 76915.6 16628.7 16814.9 16829.1 16834.6 16862.8 12628.4 12685.9 12686.5 12697.2 12697.5 21585.6 21725.8 21731.5 21830.7 21870.1 7779.1 7919.2 9328.5 9582.2 9584.5 866.4 913.5 994.3 1059.0 1112.1 29936.7 30140.5 30173.9 30980.5 30984.4 878.8 1121.1 1131.4 1171.0 1171.9 12199.5 15216.6 15297.9 15422.1 15693.8 536415.0 540115.0 543660.0 545477.0 548261.0 61026.7 61219.1 61539.0 61612.4 61746.0 1452.1 1456.4 1466.7 1468.8 1620.3 26409.1 28637.6 30937.4 31307.2 31608.8 43457.0 57403.4 57623.4 57689.0 57795.7 428419.0 428488.0 429236.0 431930.0 434439.0 29355.7 29491.6 31333.6 31776.5 34228.5 42064.0 43752.3 45044.5 45180.0 45397.9 35002.8 36446.8 37619.0 37712.4 37847.8 34658.3 34857.7 36226.6 36230.0 36305.0 36960.3 37181.1 37702.6 37954.4 38042.2 33961.7 34210.5 34480.3 34590.3 34779.5 23544.8 24026.5 24293.8 24357.0 24902.3 12:30:34 INFO - 2015-11-23 12:30:34,100 DEBUG : BROWSER_OUTPUT: 12:30:34 INFO - 2015-11-23 12:30:34,100 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:39096.46 Median:367.75 stddev:128308.13 (34889.7%) stddev-sans-first:128635.09 12:30:34 INFO - 2015-11-23 12:30:34,101 DEBUG : BROWSER_OUTPUT: Values: 360.5 364.8 370.7 371.1 375.8 15801.4 18332.5 19345.5 21263.9 21270.0 21781.3 22421.9 22888.7 22907.8 22927.0 4691.3 6601.2 6761.7 6787.7 6788.1 4028.0 4743.8 7405.2 7600.8 7642.0 5608.2 5808.9 5810.4 7489.4 7582.9 5802.2 5808.2 5814.6 5819.3 5819.7 5392.7 5540.0 5555.3 5559.9 5582.2 13411.6 13621.9 20197.5 25946.0 26470.7 1979.8 2026.4 2027.3 2036.8 2279.0 9924.8 10151.0 10154.4 10233.7 12066.3 1607.7 1676.6 1701.4 1718.3 1727.9 86930.0 107164.0 108103.0 108442.0 108582.0 11255.9 11291.3 11329.0 11332.9 11381.2 13309.3 13413.4 13428.9 13439.8 13454.5 8528.8 8552.8 8559.3 8610.2 8612.7 8726.5 8757.5 8777.5 8826.0 8855.6 3544.3 4091.3 4271.9 4358.5 4430.6 1540.9 1835.6 1837.5 1843.4 1845.5 397.2 405.2 407.0 421.3 1014.9 11358.3 11720.3 11759.8 11764.3 11775.4 439.8 453.1 456.2 456.8 458.2 5317.6 5987.7 6072.9 6079.5 6089.2 763571.0 775637.0 783240.3 787062.0 787585.0 115433.4 120713.4 120798.5 122142.0 122357.0 492.7 496.1 496.3 496.4 496.6 10161.6 10225.3 11036.7 11075.4 11078.6 50095.5 50511.7 50570.0 50669.7 51037.0 70626.6 70765.0 70995.6 71540.6 73849.6 9551.8 9804.7 9850.3 9926.8 9951.1 11855.5 12916.0 19759.3 20309.3 20405.5 15712.1 15721.6 15870.8 16142.6 16260.4 12181.3 14046.5 15280.8 15483.0 16264.3 14205.0 14301.6 15452.2 16297.5 16581.5 9764.4 9781.8 9784.4 9967.9 9997.5 8209.4 15482.4 16237.3 16283.8 16581.0 12:30:34 INFO - 2015-11-23 12:30:34,101 DEBUG : BROWSER_OUTPUT: 12:30:34 INFO - 2015-11-23 12:30:34,102 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5910.18 Median:6091.12 stddev:3091.29 (50.8%) stddev-sans-first:3082.70 12:30:34 INFO - 2015-11-23 12:30:34,103 DEBUG : BROWSER_OUTPUT: Values: 1564.4 1566.1 1706.0 1764.3 1893.4 7691.7 7964.2 8230.5 8311.6 8326.8 8370.2 8520.3 8585.9 8593.4 8605.9 2110.4 2212.1 2410.7 2434.5 2437.0 2026.6 2219.9 2227.3 2451.9 2458.4 2364.2 2406.9 2415.3 2417.1 2417.9 2165.4 2225.4 2397.2 2422.1 2430.5 2195.7 2372.9 2422.6 2423.8 2425.3 8519.4 8699.0 8702.1 8705.0 8709.3 5252.8 5297.8 5312.3 5347.7 5352.4 3489.1 3507.4 3580.9 3586.7 3589.5 1054.3 1064.0 1066.5 1096.6 1655.5 11355.2 11417.8 11435.3 11442.4 11442.6 4525.6 4626.5 4673.4 4679.2 4683.3 9282.8 9284.7 9298.0 9299.1 9327.2 5185.5 5196.3 5257.6 5268.6 5283.5 4787.2 4862.8 4918.9 4923.2 4925.9 3682.2 3695.2 3707.4 3708.7 3749.0 1152.6 1166.3 1175.9 1179.7 1179.9 1288.4 1423.0 1427.9 1428.8 1442.3 8048.7 8133.1 8191.8 8205.1 8218.1 510.9 520.1 523.5 525.0 525.4 5493.5 6026.3 6155.9 6169.3 6466.5 9848.3 9914.4 10003.3 10184.4 10211.7 9827.2 9933.2 9946.4 9999.2 10072.6 7755.1 7784.5 7785.0 7840.3 7843.9 8061.7 8083.5 8099.1 8099.3 8112.7 7766.8 8977.7 9325.4 9420.2 9451.5 9400.6 9524.4 9538.4 9659.8 9692.5 7070.8 7162.5 7163.2 7182.8 7210.4 8277.7 8318.8 8337.0 8381.9 8408.4 7726.1 7825.2 7834.3 7963.9 7968.0 7618.2 7651.5 7749.1 7777.5 7844.5 7609.6 7805.6 7830.7 7834.7 7878.7 7114.7 7135.7 7137.9 7144.2 7158.4 6415.5 7154.5 7210.9 7231.4 7257.9 12:30:34 INFO - 2015-11-23 12:30:34,103 DEBUG : BROWSER_OUTPUT: 12:30:34 INFO - 2015-11-23 12:30:34,103 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5989.37 Median:6812.50 stddev:1919.31 (28.2%) stddev-sans-first:1877.04 12:30:34 INFO - 2015-11-23 12:30:34,104 DEBUG : BROWSER_OUTPUT: Values: 326.7 333.8 337.3 346.6 347.2 5981.3 6305.8 6711.3 6875.4 6939.0 5401.3 6641.2 6782.8 6843.4 6851.5 5003.3 5049.1 5064.8 5072.2 5072.9 4795.1 4876.6 4985.2 5052.3 5073.0 4966.6 4998.7 5005.9 5016.3 5034.3 4998.6 5017.0 5028.3 5034.8 5062.1 4235.3 4239.3 4239.5 4241.2 4247.9 6277.7 6496.6 6503.9 6518.9 6539.3 5926.6 5926.8 5931.7 5937.2 5941.7 7312.2 7317.4 7322.9 7337.0 7339.4 4654.9 4795.7 4801.8 4817.9 4819.6 7777.6 7798.7 7803.3 7813.1 7816.7 4855.8 4863.1 4863.9 4882.5 4884.0 4375.2 4812.4 4934.5 4940.1 4972.2 4826.0 5809.7 5923.7 5956.1 5970.7 7395.3 7410.5 7424.9 7425.7 7432.9 1709.1 1735.8 1736.8 1737.6 1738.4 1045.5 1087.9 1111.6 1125.0 1132.4 6866.7 6874.0 6875.2 6875.4 6911.0 6490.0 7427.7 7464.1 7638.0 7675.1 6904.0 6948.2 6958.5 6958.8 6959.7 4000.6 4101.2 4106.0 4106.2 4111.7 7726.4 7830.7 7846.8 7856.2 7867.1 8021.8 8031.4 8037.0 8041.5 8062.4 7568.0 7808.9 7814.8 7824.3 7855.0 6206.7 7754.6 7762.1 7767.1 7781.1 7621.4 7622.7 7627.1 7631.4 7650.3 7536.3 7808.9 7822.3 7826.6 7831.5 4889.3 4951.6 4960.5 4968.3 4972.5 6439.4 7565.4 7766.9 7771.6 7775.1 7366.3 7436.5 7460.7 7466.9 7467.1 6121.4 7397.5 7405.8 7413.1 7419.6 7030.3 7218.0 7218.5 7234.0 7248.5 7418.7 7424.9 7428.2 7430.9 7448.4 5298.7 6842.2 6931.6 7059.6 7061.5 12:30:34 INFO - 2015-11-23 12:30:34,104 DEBUG : BROWSER_OUTPUT: 12:30:34 INFO - 2015-11-23 12:30:34,104 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:18919.71 Median:26846.97 stddev:26638.45 (99.2%) stddev-sans-first:26678.20 12:30:34 INFO - 2015-11-23 12:30:34,104 DEBUG : BROWSER_OUTPUT: Values: 739.0 818.2 820.2 822.3 881.2 15186.8 15252.9 15476.9 15515.0 15519.4 14300.6 14606.5 14614.3 14626.8 14626.9 802.2 803.1 987.1 1010.6 1013.9 794.0 984.1 990.3 991.8 992.2 1025.5 1035.3 1036.7 1039.7 1041.3 820.0 827.8 1029.1 1030.7 1036.2 770.5 771.6 772.4 776.2 781.5 15221.7 15415.8 15432.5 15433.5 15435.9 12278.9 12375.1 12635.1 12852.2 12961.4 10062.6 10171.9 10184.6 10311.8 10965.0 3593.4 3609.8 3618.9 3659.1 3665.6 74185.6 75307.0 75471.0 76146.9 76526.2 7385.6 7631.2 9238.3 9339.3 9364.2 21802.8 21805.2 21839.7 21842.9 21869.7 7395.1 7514.0 7657.5 7658.6 7824.8 4069.8 4148.2 4165.7 4200.6 4348.2 3482.8 3549.9 3550.6 3554.7 3563.8 539.4 615.1 649.0 651.5 654.8 418.6 596.2 601.6 606.7 622.5 8562.5 8589.8 8763.0 8830.2 8939.6 505.7 534.7 551.9 563.5 607.2 4017.8 4075.4 4147.6 4150.0 4222.4 92390.5 92885.0 93782.0 97054.5 97878.5 82914.6 85012.0 85385.6 85430.6 85633.0 68057.0 72103.0 75361.6 76337.0 77086.6 8830.3 8879.8 9000.8 9022.4 9106.9 53159.2 53621.5 54100.4 54894.7 56211.7 67086.4 73009.3 74091.3 74262.0 75468.6 10091.7 10894.7 11010.9 11026.2 11528.5 12026.2 12269.8 12524.1 12543.8 12894.0 11276.8 11864.7 11869.1 12257.4 12307.0 11198.6 11550.5 11840.8 11918.6 11920.6 11407.2 11463.5 11837.1 11859.2 12376.3 9704.1 9777.4 9984.7 10089.5 10318.3 9067.8 9244.7 9477.9 9571.8 10184.3 12:30:34 INFO - 2015-11-23 12:30:34,105 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:30:34 INFO - 2015-11-23 12:30:34,105 DEBUG : BROWSER_OUTPUT: 12:30:34 INFO - 2015-11-23 12:30:34,528 INFO : Browser exited with error code: 0 12:30:34 INFO - 2015-11-23 12:30:34,551 INFO : Completed test dromaeo_css (00:18:34) 12:30:34 INFO - 2015-11-23 12:30:34,551 INFO : Starting test kraken 12:30:34 INFO - 2015-11-23 12:30:34,551 DEBUG : operating with platform_type : mac_ 12:30:34 INFO - 2015-11-23 12:30:34,552 INFO : Initialising browser for kraken test... 12:30:34 INFO - 2015-11-23 12:30:34,564 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpceeEJf/profile http://localhost:49264/getInfo.html 12:30:36 INFO - 2015-11-23 12:30:36,636 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:30:36 INFO - 2015-11-23 12:30:36,637 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:30:36 INFO - 2015-11-23 12:30:36,637 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 12:30:36 INFO - 2015-11-23 12:30:36,637 DEBUG : BROWSER_OUTPUT: __metrics 12:30:36 INFO - 2015-11-23 12:30:36,655 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:30:37 INFO - 2015-11-23 12:30:37,456 INFO : Browser initialized. 12:30:37 INFO - 2015-11-23 12:30:37,457 INFO : Running cycle 1/1 for kraken test... 12:30:37 INFO - 2015-11-23 12:30:37,457 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpceeEJf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:30:49 INFO - 2015-11-23 12:30:49,237 DEBUG : BROWSER_OUTPUT: RSS: Main: 225062912 12:30:49 INFO - 2015-11-23 12:30:49,237 DEBUG : BROWSER_OUTPUT: 12:30:51 INFO - 2015-11-23 12:30:51,741 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-beat-detection) 12:30:52 INFO - 2015-11-23 12:30:52,335 DEBUG : BROWSER_OUTPUT: RSS: Main: 274898944 12:30:52 INFO - 2015-11-23 12:30:52,335 DEBUG : BROWSER_OUTPUT: 12:30:54 INFO - 2015-11-23 12:30:54,902 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-dft) 12:30:55 INFO - 2015-11-23 12:30:55,495 DEBUG : BROWSER_OUTPUT: RSS: Main: 289312768 12:30:55 INFO - 2015-11-23 12:30:55,495 DEBUG : BROWSER_OUTPUT: 12:30:58 INFO - 2015-11-23 12:30:58,682 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-fft) 12:30:59 INFO - 2015-11-23 12:30:59,265 DEBUG : BROWSER_OUTPUT: RSS: Main: 274759680 12:30:59 INFO - 2015-11-23 12:30:59,265 DEBUG : BROWSER_OUTPUT: 12:31:01 INFO - 2015-11-23 12:31:01,152 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-oscillator) 12:31:01 INFO - 2015-11-23 12:31:01,761 DEBUG : BROWSER_OUTPUT: RSS: Main: 276959232 12:31:01 INFO - 2015-11-23 12:31:01,761 DEBUG : BROWSER_OUTPUT: 12:31:03 INFO - 2015-11-23 12:31:03,879 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:31:04 INFO - 2015-11-23 12:31:04,461 DEBUG : BROWSER_OUTPUT: RSS: Main: 277045248 12:31:04 INFO - 2015-11-23 12:31:04,461 DEBUG : BROWSER_OUTPUT: 12:31:07 INFO - 2015-11-23 12:31:07,311 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-darkroom) 12:31:07 INFO - 2015-11-23 12:31:07,900 DEBUG : BROWSER_OUTPUT: RSS: Main: 280453120 12:31:07 INFO - 2015-11-23 12:31:07,900 DEBUG : BROWSER_OUTPUT: 12:31:10 INFO - 2015-11-23 12:31:10,679 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-desaturate) 12:31:11 INFO - 2015-11-23 12:31:11,269 DEBUG : BROWSER_OUTPUT: RSS: Main: 278773760 12:31:11 INFO - 2015-11-23 12:31:11,269 DEBUG : BROWSER_OUTPUT: 12:31:13 INFO - 2015-11-23 12:31:13,947 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49264/tests/kraken/driver.html?testName=json-parse-financial) 12:31:14 INFO - 2015-11-23 12:31:14,545 DEBUG : BROWSER_OUTPUT: RSS: Main: 280399872 12:31:14 INFO - 2015-11-23 12:31:14,545 DEBUG : BROWSER_OUTPUT: 12:31:16 INFO - 2015-11-23 12:31:16,038 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49264/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:31:16 INFO - 2015-11-23 12:31:16,617 DEBUG : BROWSER_OUTPUT: RSS: Main: 314109952 12:31:16 INFO - 2015-11-23 12:31:16,617 DEBUG : BROWSER_OUTPUT: 12:31:18 INFO - 2015-11-23 12:31:18,376 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:31:19 INFO - 2015-11-23 12:31:19,004 DEBUG : BROWSER_OUTPUT: RSS: Main: 313356288 12:31:19 INFO - 2015-11-23 12:31:19,004 DEBUG : BROWSER_OUTPUT: 12:31:21 INFO - 2015-11-23 12:31:21,023 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:31:21 INFO - 2015-11-23 12:31:21,613 DEBUG : BROWSER_OUTPUT: RSS: Main: 321908736 12:31:21 INFO - 2015-11-23 12:31:21,613 DEBUG : BROWSER_OUTPUT: 12:31:24 INFO - 2015-11-23 12:31:24,101 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:31:24 INFO - 2015-11-23 12:31:24,709 DEBUG : BROWSER_OUTPUT: RSS: Main: 323829760 12:31:24 INFO - 2015-11-23 12:31:24,709 DEBUG : BROWSER_OUTPUT: 12:31:27 INFO - 2015-11-23 12:31:27,226 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:31:27 INFO - 2015-11-23 12:31:27,840 DEBUG : BROWSER_OUTPUT: RSS: Main: 322809856 12:31:27 INFO - 2015-11-23 12:31:27,840 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,492 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49264/tests/kraken/driver.html?testName=ai-astar) 12:31:29 INFO - 2015-11-23 12:31:29,748 DEBUG : BROWSER_OUTPUT: RSS: Main: 381976576 12:31:29 INFO - 2015-11-23 12:31:29,749 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,749 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:31:29 INFO - 2015-11-23 12:31:29,749 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:31:29 INFO - 2015-11-23 12:31:29,749 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:31:29 INFO - 2015-11-23 12:31:29,749 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:31:29 INFO - 2015-11-23 12:31:29,749 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;110;103;111;112;110;111;108;101;110 12:31:29 INFO - 2015-11-23 12:31:29,749 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;120;141;154;103;119;127;133;182;144 12:31:29 INFO - 2015-11-23 12:31:29,749 DEBUG : BROWSER_OUTPUT: |2;audio-dft;183;181;182;184;184;182;182;183;185;181 12:31:29 INFO - 2015-11-23 12:31:29,749 DEBUG : BROWSER_OUTPUT: |3;audio-fft;60;72;71;71;56;56;56;70;74;74 12:31:29 INFO - 2015-11-23 12:31:29,749 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;83;83;144;111;110;73;112;74;74;110 12:31:29 INFO - 2015-11-23 12:31:29,750 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;115;78;114;131;132;84;85;84;85;85 12:31:29 INFO - 2015-11-23 12:31:29,750 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;92;93;93;92;93;92;95;93;93;93 12:31:29 INFO - 2015-11-23 12:31:29,750 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;88;85;85;84;86;87;86;85;85;87 12:31:29 INFO - 2015-11-23 12:31:29,750 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;54;52;52;53;54;56;62;51;53 12:31:29 INFO - 2015-11-23 12:31:29,750 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;65;64;64;64;64;66;67;67;67;67 12:31:29 INFO - 2015-11-23 12:31:29,750 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;81;81;82;80;81;83;81;83;82 12:31:29 INFO - 2015-11-23 12:31:29,750 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;122;124;111;121;118;126;119;128;118;123 12:31:29 INFO - 2015-11-23 12:31:29,750 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;118;172;174;175;136;123;135;147;135;122 12:31:29 INFO - 2015-11-23 12:31:29,750 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;65;65;65;65;64;67;67;65;65 12:31:29 INFO - 2015-11-23 12:31:29,750 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:31:29 INFO - 2015-11-23 12:31:29,750 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:31:29 INFO - 2015-11-23 12:31:29,750 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1085 12:31:29 INFO - 2015-11-23 12:31:29,750 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:31:29 INFO - 2015-11-23 12:31:29,751 DEBUG : BROWSER_OUTPUT: __startTimestamp1448310689745__endTimestamp 12:31:29 INFO - 2015-11-23 12:31:29,751 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,751 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:31:29 INFO - 2015-11-23 12:31:29,751 DEBUG : BROWSER_OUTPUT: Number of tests: 14 12:31:29 INFO - 2015-11-23 12:31:29,751 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,751 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.60 Median:110.00 stddev:3.66 (3.3%) stddev-sans-first:3.84 12:31:29 INFO - 2015-11-23 12:31:29,751 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 103.0 111.0 112.0 110.0 111.0 108.0 101.0 110.0 12:31:29 INFO - 2015-11-23 12:31:29,751 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,751 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:137.20 Median:142.50 stddev:22.13 (15.5%) stddev-sans-first:23.06 12:31:29 INFO - 2015-11-23 12:31:29,751 DEBUG : BROWSER_OUTPUT: Values: 149.0 120.0 141.0 154.0 103.0 119.0 127.0 133.0 182.0 144.0 12:31:29 INFO - 2015-11-23 12:31:29,751 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,751 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.34 (0.7%) stddev-sans-first:1.41 12:31:29 INFO - 2015-11-23 12:31:29,752 DEBUG : BROWSER_OUTPUT: Values: 183.0 181.0 182.0 184.0 184.0 182.0 182.0 183.0 185.0 181.0 12:31:29 INFO - 2015-11-23 12:31:29,752 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,752 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:66.00 Median:71.00 stddev:7.93 (11.2%) stddev-sans-first:8.11 12:31:29 INFO - 2015-11-23 12:31:29,752 DEBUG : BROWSER_OUTPUT: Values: 60.0 72.0 71.0 71.0 56.0 56.0 56.0 70.0 74.0 74.0 12:31:29 INFO - 2015-11-23 12:31:29,752 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,752 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:97.40 Median:73.50 stddev:23.55 (32.0%) stddev-sans-first:24.40 12:31:29 INFO - 2015-11-23 12:31:29,752 DEBUG : BROWSER_OUTPUT: Values: 83.0 83.0 144.0 111.0 110.0 73.0 112.0 74.0 74.0 110.0 12:31:29 INFO - 2015-11-23 12:31:29,752 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,754 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:99.30 Median:84.00 stddev:21.27 (25.3%) stddev-sans-first:21.79 12:31:29 INFO - 2015-11-23 12:31:29,754 DEBUG : BROWSER_OUTPUT: Values: 115.0 78.0 114.0 131.0 132.0 84.0 85.0 84.0 85.0 85.0 12:31:29 INFO - 2015-11-23 12:31:29,754 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,754 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.88 (0.9%) stddev-sans-first:0.87 12:31:29 INFO - 2015-11-23 12:31:29,754 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 93.0 92.0 93.0 92.0 95.0 93.0 93.0 93.0 12:31:29 INFO - 2015-11-23 12:31:29,754 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,754 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.00 stddev:1.23 (1.4%) stddev-sans-first:1.01 12:31:29 INFO - 2015-11-23 12:31:29,754 DEBUG : BROWSER_OUTPUT: Values: 88.0 85.0 85.0 84.0 86.0 87.0 86.0 85.0 85.0 87.0 12:31:29 INFO - 2015-11-23 12:31:29,754 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,754 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:3.12 (5.8%) stddev-sans-first:3.30 12:31:29 INFO - 2015-11-23 12:31:29,754 DEBUG : BROWSER_OUTPUT: Values: 55.0 54.0 52.0 52.0 53.0 54.0 56.0 62.0 51.0 53.0 12:31:29 INFO - 2015-11-23 12:31:29,755 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,755 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:66.50 stddev:1.43 (2.2%) stddev-sans-first:1.51 12:31:29 INFO - 2015-11-23 12:31:29,755 DEBUG : BROWSER_OUTPUT: Values: 65.0 64.0 64.0 64.0 64.0 66.0 67.0 67.0 67.0 67.0 12:31:29 INFO - 2015-11-23 12:31:29,755 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,755 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:82.00 stddev:0.97 (1.2%) stddev-sans-first:1.01 12:31:29 INFO - 2015-11-23 12:31:29,755 DEBUG : BROWSER_OUTPUT: Values: 82.0 81.0 81.0 82.0 80.0 81.0 83.0 81.0 83.0 82.0 12:31:29 INFO - 2015-11-23 12:31:29,755 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,755 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.00 Median:122.50 stddev:4.83 (3.9%) stddev-sans-first:5.11 12:31:29 INFO - 2015-11-23 12:31:29,755 DEBUG : BROWSER_OUTPUT: Values: 122.0 124.0 111.0 121.0 118.0 126.0 119.0 128.0 118.0 123.0 12:31:29 INFO - 2015-11-23 12:31:29,755 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,755 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.70 Median:141.50 stddev:22.31 (15.8%) stddev-sans-first:21.64 12:31:29 INFO - 2015-11-23 12:31:29,755 DEBUG : BROWSER_OUTPUT: Values: 118.0 172.0 174.0 175.0 136.0 123.0 135.0 147.0 135.0 122.0 12:31:29 INFO - 2015-11-23 12:31:29,755 DEBUG : BROWSER_OUTPUT: 12:31:29 INFO - 2015-11-23 12:31:29,756 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.00 stddev:0.95 (1.5%) stddev-sans-first:1.00 12:31:29 INFO - 2015-11-23 12:31:29,756 DEBUG : BROWSER_OUTPUT: Values: 65.0 65.0 65.0 65.0 65.0 64.0 67.0 67.0 65.0 65.0 12:31:29 INFO - 2015-11-23 12:31:29,756 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:31:29 INFO - 2015-11-23 12:31:29,756 DEBUG : BROWSER_OUTPUT: 12:31:30 INFO - 2015-11-23 12:31:30,387 INFO : Browser exited with error code: 0 12:31:30 INFO - 2015-11-23 12:31:30,430 INFO : Completed test kraken (00:00:55) 12:31:30 INFO - 2015-11-23 12:31:30,430 INFO : Starting test v8_7 12:31:30 INFO - 2015-11-23 12:31:30,431 DEBUG : operating with platform_type : mac_ 12:31:30 INFO - 2015-11-23 12:31:30,431 INFO : Initialising browser for v8_7 test... 12:31:30 INFO - 2015-11-23 12:31:30,443 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpmyed9c/profile http://localhost:49264/getInfo.html 12:31:32 INFO - 2015-11-23 12:31:32,518 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:31:32 INFO - 2015-11-23 12:31:32,519 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:31:32 INFO - 2015-11-23 12:31:32,519 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 12:31:32 INFO - 2015-11-23 12:31:32,519 DEBUG : BROWSER_OUTPUT: __metrics 12:31:32 INFO - 2015-11-23 12:31:32,536 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:31:33 INFO - 2015-11-23 12:31:33,376 INFO : Browser initialized. 12:31:33 INFO - 2015-11-23 12:31:33,376 INFO : Running cycle 1/1 for v8_7 test... 12:31:33 INFO - 2015-11-23 12:31:33,376 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpmyed9c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:31:45 INFO - 2015-11-23 12:31:45,259 DEBUG : BROWSER_OUTPUT: RSS: Main: 224350208 12:31:45 INFO - 2015-11-23 12:31:45,259 DEBUG : BROWSER_OUTPUT: 12:32:06 INFO - 2015-11-23 12:32:06,741 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/v8_7/run.html (next: http://localhost:49264/tests/v8_7/run.html) 12:32:06 INFO - 2015-11-23 12:32:06,998 DEBUG : BROWSER_OUTPUT: RSS: Main: 703053824 12:32:06 INFO - 2015-11-23 12:32:06,998 DEBUG : BROWSER_OUTPUT: 12:32:06 INFO - 2015-11-23 12:32:06,998 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:32:06 INFO - 2015-11-23 12:32:06,998 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:32:06 INFO - 2015-11-23 12:32:06,998 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:32:06 INFO - 2015-11-23 12:32:06,998 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:32:06 INFO - 2015-11-23 12:32:06,998 DEBUG : BROWSER_OUTPUT: |0;Richards;146.41288433382138 12:32:06 INFO - 2015-11-23 12:32:06,998 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;161.68148746968473 12:32:07 INFO - 2015-11-23 12:32:06,998 DEBUG : BROWSER_OUTPUT: |2;Encrypt;276.62517289073304 12:32:07 INFO - 2015-11-23 12:32:06,998 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4682.242990654206 12:32:07 INFO - 2015-11-23 12:32:06,998 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1703.5775127768313 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: |5;Earley;784.3137254901961 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: |6;Boyer;12395.061728395061 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: |7;RegExp;26736.842105263157 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: |8;Splay;614.1636141636142 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4482.142857142857 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: __startTimestamp1448310726994__endTimestamp 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: Number of tests: 10 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:146.41 Median:146.41 stddev:0.00 (0.0%) 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: Values: 146.4 12:32:07 INFO - 2015-11-23 12:32:06,999 DEBUG : BROWSER_OUTPUT: 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:161.68 Median:161.68 stddev:0.00 (0.0%) 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: Values: 161.7 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:276.63 Median:276.63 stddev:0.00 (0.0%) 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: Values: 276.6 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4682.24 Median:4682.24 stddev:0.00 (0.0%) 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: Values: 4682.2 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1703.58 Median:1703.58 stddev:0.00 (0.0%) 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: Values: 1703.6 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:784.31 Median:784.31 stddev:0.00 (0.0%) 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: Values: 784.3 12:32:07 INFO - 2015-11-23 12:32:07,000 DEBUG : BROWSER_OUTPUT: 12:32:07 INFO - 2015-11-23 12:32:07,001 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12395.06 Median:12395.06 stddev:0.00 (0.0%) 12:32:07 INFO - 2015-11-23 12:32:07,001 DEBUG : BROWSER_OUTPUT: Values: 12395.1 12:32:07 INFO - 2015-11-23 12:32:07,001 DEBUG : BROWSER_OUTPUT: 12:32:07 INFO - 2015-11-23 12:32:07,001 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26736.84 Median:26736.84 stddev:0.00 (0.0%) 12:32:07 INFO - 2015-11-23 12:32:07,001 DEBUG : BROWSER_OUTPUT: Values: 26736.8 12:32:07 INFO - 2015-11-23 12:32:07,001 DEBUG : BROWSER_OUTPUT: 12:32:07 INFO - 2015-11-23 12:32:07,001 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:614.16 Median:614.16 stddev:0.00 (0.0%) 12:32:07 INFO - 2015-11-23 12:32:07,001 DEBUG : BROWSER_OUTPUT: Values: 614.2 12:32:07 INFO - 2015-11-23 12:32:07,001 DEBUG : BROWSER_OUTPUT: 12:32:07 INFO - 2015-11-23 12:32:07,001 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4482.14 Median:4482.14 stddev:0.00 (0.0%) 12:32:07 INFO - 2015-11-23 12:32:07,001 DEBUG : BROWSER_OUTPUT: Values: 4482.1 12:32:07 INFO - 2015-11-23 12:32:07,001 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:32:07 INFO - 2015-11-23 12:32:07,001 DEBUG : BROWSER_OUTPUT: 12:32:07 INFO - 2015-11-23 12:32:07,715 INFO : Browser exited with error code: 0 12:32:07 INFO - 2015-11-23 12:32:07,732 INFO : Completed test v8_7 (00:00:37) 12:32:08 INFO - 2015-11-23 12:32:08,039 INFO : Completed test suite (00:20:07) 12:32:08 INFO - 2015-11-23 12:32:08,039 DEBUG : Working with test: dromaeo_css 12:32:08 INFO - 2015-11-23 12:32:08,039 DEBUG : Generating results file: dromaeo_css 12:32:08 INFO - 2015-11-23 12:32:08,040 DEBUG : Working with test: kraken 12:32:08 INFO - 2015-11-23 12:32:08,040 DEBUG : Generating results file: kraken 12:32:08 INFO - 2015-11-23 12:32:08,040 INFO : javascript benchmark 12:32:08 INFO - 2015-11-23 12:32:08,040 DEBUG : Working with test: v8_7 12:32:08 INFO - 2015-11-23 12:32:08,040 DEBUG : Generating results file: v8_7 12:32:08 INFO - 2015-11-23 12:32:08,041 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:32:08 INFO - 2015-11-23 12:32:08,532 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 12:32:08 INFO - 2015-11-23 12:32:08,532 DEBUG : process_Request line: dromaeo_css 7609.55 graph.html#tests=[[72,64,59]] 12:32:08 INFO - 2015-11-23 12:32:08,532 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:32:08 INFO - 2015-11-23 12:32:08,562 DEBUG : process_Request line: kraken 1401.20 graph.html#tests=[[232,64,59]] 12:32:08 INFO - 2015-11-23 12:32:08,563 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:32:08 INFO - 2015-11-23 12:32:08,591 DEBUG : process_Request line: v8_7 20997.30 graph.html#tests=[[230,64,59]] 12:32:08 INFO - 2015-11-23 12:32:08,592 INFO : javascript benchmark 12:32:08 INFO - 2015-11-23 12:32:08,594 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3294.9999999999995, 3516.9120771980697, 3525.171584677602, 3539.876104336349, 3575.4815551744996, 5733.340665823432, 5831.212786273857, 5867.618604790697, 5907.232059832221, 5991.430814072667, 5780.508656764169, 5912.231409916705, 5916.349201587833, 6047.667378294192, 6078.939210607904, 5136.152534831748, 5289.603279754015, 5289.709066001368, 5364.651297665659, 5447.1829225616275, 4909.386326709161, 5430.321209848769, 5461.126219804832, 5468.945310546884, 5490.149026900836, 5077.517635824591, 5160.380754309469, 5190.532852043334, 5420.972895135519, 5459.935312614042, 5348.491893270133, 5362.0, 5365.919511207335, 5372.838814835562, 5382.165764306539, 5262.500062494095, 5319.468053194688, 5321.5210631043, 5336.119540275849, 5340.519353258186, 5741.224934633813, 5750.626209296424, 5768.480836724673, 5781.017227071408, 5791.739371728282, 6218.31598524162, 6305.842353941142, 6327.145835312266, 6339.651319177444, 6349.206349206349, 6325.746970121189, 6360.459360954324, 6362.077498762654, 6390.073439351315, 6406.0, 5816.94183058164, 6116.633002019848, 6139.079138129272, 6154.415330543591, 6162.4453799157845, 6292.433680968735, 6373.553851230369, 6412.775552855627, 6543.901841472381, 6545.8690826184165, 6366.363363663597, 6379.904301435482, 6385.169927909435, 6388.39310265524, 6391.808245752635, 6201.937980620226, 6363.968180159162, 6382.712777925005, 6384.744610215539, 6401.871962560723, 6222.066689996446, 6299.527535434769, 6334.3032266450655, 6339.873202535923, 6379.202599675041, 6320.778772742932, 6348.809535713936, 6362.936370636327, 6363.554551181465, 6369.0, 4942.258661200849, 5073.985202959423, 5075.365579302587, 5079.6698214615935, 5126.282320475137, 4464.196444639931, 4500.032493014018, 4507.88730281739, 4535.818567257273, 4592.563706447882, 5891.852703682443, 5900.970495147582, 5928.170056192136, 5936.940630593639, 5937.168796368512, 6192.907106393408, 6362.713677884506, 6372.267189273293, 6393.264774550893, 6404.967975160094, 5844.386339434383, 5868.794592189253, 5889.057750759858, 5960.761569537257, 6014.1580178775, 5590.357108932443, 5598.076317407664, 5621.747021384048, 5635.690037048002, 5650.180723795027, 6313.2424109107205, 6383.840403989938, 6402.391772781485, 6408.775692850728, 6416.550841441148, 5453.809116680897, 6205.28639206479, 6348.93651063502, 6395.456386207038, 6401.3598640135615, 6209.720562574786, 6313.936860631335, 6389.48884089281, 6394.328595497405, 6409.942359510815, 6297.4017468340435, 6325.209348831396, 6350.523710721622, 6359.395857393541, 6363.331850155852, 6159.753609855735, 6333.746650133943, 6337.746490140343, 6341.27075386318, 6347.301796802256, 6120.9652521188555, 6218.81343559694, 6221.2845522763655, 6254.186955695731, 6347.809565713036, 6288.622682639057, 6288.874222515433, 6300.936990630218, 6311.495080393465, 6321.367863213826, 5951.791687290924, 6177.2587289525545, 6185.0, 6193.876122477435, 6193.938060619516, 6112.716259964451, 6137.619035717106, 6238.812835614939, 6251.843703907257, 6262.1546091278915, 6070.2412198475195, 6175.475084617858, 6177.752889884354, 6208.565400421927, 6213.751449942132], "unit": "ms", "name": "dojo.html", "value": 5844.484035384628}, {"lowerIsBetter": false, "replicates": [578.3769912552556, 585.3970410477209, 606.8240210339002, 607.9696015199239, 640.4588123036036, 32750.67249327506, 33132.33735325295, 33345.0, 33354.49968250478, 33402.49896251558, 27671.86164069182, 43526.12947741043, 44014.779926100404, 44020.3396949046, 44112.0, 9759.853602195955, 9885.406875587452, 9917.0, 9935.254855885838, 9935.254855885838, 8047.637856296451, 8887.20015198632, 9011.098890111001, 9515.524223788818, 9604.903950960506, 9991.200703943667, 12190.878091219107, 12198.26810391375, 12199.26804391735, 12204.0, 11873.287602743863, 11944.462499187513, 11984.340861252627, 12010.939945300217, 12078.456469458895, 8896.243819275369, 9028.413153145024, 11757.412129393582, 11956.70108247292, 12097.0, 38938.442462301755, 41890.37164442505, 42238.1552368954, 42244.36633450501, 42320.57679423197, 3040.3159289159985, 3169.889053883103, 3806.371948628501, 3883.475730776337, 4090.0592863641527, 29347.972820951163, 29702.0, 29841.552376714368, 29866.850665746533, 29969.700302997127, 2843.24653966699, 2843.545032794743, 2869.540873460257, 2893.030834670388, 2938.38293958269, 520296.0, 521944.0, 523291.0, 523710.38144809415, 524444.0, 63146.0, 63153.0, 63708.0, 69995.95006074914, 70557.2944270561, 75564.24435755682, 75597.24402756013, 76485.2351476478, 76830.0, 76915.61542192253, 16628.6674266514, 16814.915925420293, 16829.074400907823, 16834.57913552171, 16862.83137168637, 12628.431720572597, 12685.93657031709, 12686.49254029847, 12697.174683645533, 12697.492100315883, 21585.568288634455, 21725.782742172374, 21731.456713582287, 21830.672539911913, 21870.125194992022, 7779.144294127642, 7919.198342631951, 9328.486933218737, 9582.185514231229, 9584.466485362309, 866.4281574161149, 913.5340976102236, 994.2692121290845, 1058.9682309530726, 1112.1214240749716, 29936.70063299339, 30140.54789178164, 30173.943911963415, 30980.53529197064, 30984.380312393627, 878.8198419324024, 1121.147927575049, 1131.4003578103618, 1171.0221964659604, 1171.9238249513755, 12199.451024703909, 15216.619584510254, 15297.923510382378, 15422.074675519505, 15693.843061569467, 536415.0, 540115.0, 543660.0, 545477.0, 548261.0, 61026.69486652538, 61219.08171377433, 61539.0, 61612.38387616067, 61746.0, 1452.0924422236103, 1456.431991523328, 1466.6919946811076, 1468.8384277729442, 1620.2708781048575, 26409.075682351024, 28637.568121594253, 30937.381252374376, 31307.217319566284, 31608.841955790995, 43457.0, 57403.42596573981, 57623.42376576348, 57689.0, 57795.71102144462, 428419.0, 428488.0, 429236.0, 431930.0, 434439.0, 29355.706442935298, 29491.55762663519, 31333.58998845012, 31776.523352149274, 34228.48657270093, 42064.0, 43752.34371484494, 45044.54955450403, 45180.0, 45397.86505337393, 35002.7743480287, 36446.81776591206, 37619.0, 37712.43431348587, 37847.81076094712, 34658.30683386369, 34857.65142348544, 36226.63773362338, 36230.0, 36305.0, 36960.31432613388, 37181.07047323838, 37702.62297376991, 37954.43068353922, 38042.23915521729, 33961.66038339585, 34210.48684269788, 34480.31039379248, 34590.30819383548, 34779.478307824895, 23544.764552354256, 24026.51946961086, 24293.75706242915, 24357.025718971043, 24902.252932412055], "unit": "ms", "name": "ext.html", "value": 20430.488037646403}, {"lowerIsBetter": false, "replicates": [360.50610663391154, 364.775662967275, 370.7312198655973, 371.0834239428612, 375.8045816175589, 15801.446949356774, 18332.450026499195, 19345.516362090937, 21263.893680531593, 21270.042848071836, 21781.346559603197, 22421.887890560563, 22888.65667014996, 22907.770922290812, 22927.0, 4691.29630555416, 6601.240857301424, 6761.661916904135, 6787.72849086036, 6788.083608712835, 4028.0, 4743.833965811197, 7405.22245164258, 7600.847983040336, 7642.0, 5608.2428872102155, 5808.941910580904, 5810.448007439308, 7489.400847932152, 7582.886256706126, 5802.216700745387, 5808.1578171165365, 5814.563907706939, 5819.301683797929, 5819.67991760453, 5392.730363481849, 5540.030494663418, 5555.3333599967855, 5559.916601250984, 5582.246396736429, 13411.597652070453, 13621.910247180202, 20197.49506262355, 25945.96216151352, 26470.676466176807, 1979.8218160365493, 2026.4022113476412, 2027.2614622057001, 2036.7963203679662, 2279.0, 9924.751881203028, 10151.0, 10154.390736555757, 10233.744156396075, 12066.276023438622, 1607.7025750236244, 1676.639522502668, 1701.4300209429732, 1718.2525601363448, 1727.870409719276, 86930.0, 107164.0, 108103.0, 108442.0, 108582.0, 11255.887441125484, 11291.322520648788, 11329.03703185222, 11332.886671133349, 11381.203315767816, 13309.334533273492, 13413.39639716215, 13428.93285533566, 13439.798403023962, 13454.52909148175, 8528.829423411496, 8552.828943421096, 8559.315254779603, 8610.225079742853, 8612.741617751477, 8726.520041397656, 8757.4745515268, 8777.51723655205, 8826.0, 8855.645774168961, 3544.2734239480847, 4091.3453847384276, 4271.8718438446895, 4358.476982762026, 4430.62339701129, 1540.8690261327688, 1835.6145209506153, 1837.5150176722473, 1843.4285371534868, 1845.4924895653535, 397.16198820488745, 405.2462419898986, 407.03329592218483, 421.2817146764759, 1014.9188064954786, 11358.26171298863, 11720.296782193096, 11759.824432480018, 11764.294142351315, 11775.352355620524, 439.7537379067732, 453.09833431471134, 456.1606643775403, 456.78950780431455, 458.2233114870263, 5317.574594032468, 5987.730552125165, 6072.908906366408, 6079.483243924316, 6089.238845144357, 763571.0, 775637.0, 783240.3348941214, 787062.0, 787585.0, 115433.42283288529, 120713.39643301904, 120798.50417949726, 122142.0, 122357.0, 492.68468180364596, 496.08967544556145, 496.3002166944663, 496.35721740345616, 496.607679932849, 10161.593536258762, 10225.312823384356, 11036.724081898017, 11075.446227688422, 11078.612248571262, 50095.49904501054, 50511.74744126256, 50570.0, 50669.746651266505, 51037.0, 70626.64686676534, 70765.0, 70995.64502177456, 71540.64229678818, 73849.63075184589, 9551.761205969906, 9804.65683701067, 9850.310478266452, 9926.751831204278, 9951.054649808257, 11855.466504007169, 12916.0, 19759.308424205083, 20309.28917487881, 20405.48986275355, 15712.13583252932, 15721.606959826096, 15870.7619385707, 16142.596435088746, 16260.430884918971, 12181.330026848254, 14046.508372206923, 15280.847191527942, 15482.993605415611, 16264.268107934935, 14205.0, 14301.64245893861, 15452.227388630748, 16297.511074667778, 16581.502554923372, 9764.365316254421, 9781.755456113655, 9784.364016338915, 9967.900320996698, 9997.450140242356, 8209.384296177692, 15482.380704772133, 16237.269322880735, 16283.837161628233, 16581.0], "unit": "ms", "name": "jquery.html", "value": 8992.48441686529}, {"lowerIsBetter": false, "replicates": [1564.3664315952028, 1566.0916668332368, 1705.9829401705995, 1764.267828851027, 1893.4319704088773, 7691.692332306708, 7964.163762804902, 8230.465019773717, 8311.625976831043, 8326.75019749407, 8370.162983701626, 8520.275776559, 8585.87121193181, 8593.355498337618, 8605.91394086059, 2110.387987483628, 2212.126210146991, 2410.7348191698898, 2434.549608322457, 2437.049550675229, 2026.554158085219, 2219.889005549724, 2227.3206671965127, 2451.9264422067276, 2458.40998160442, 2364.2316247219637, 2406.8676222807826, 2415.311636183688, 2417.0694282701197, 2417.903283868643, 2165.4261620670536, 2225.3880182949656, 2397.1969390254285, 2422.103821586016, 2430.501747141832, 2195.7365116186, 2372.916947906815, 2422.5760491913843, 2423.7697418745365, 2425.333033415807, 8519.36104792137, 8699.043105258479, 8702.129787021311, 8705.042445331008, 8709.303255739525, 5252.842414727565, 5297.841064768063, 5312.335958005249, 5347.7058761768085, 5352.384475785257, 3489.1277180704824, 3507.438809790421, 3580.946285805715, 3586.748927575071, 3589.5333606631234, 1054.3146954479573, 1063.988316991033, 1066.4667666166918, 1096.5613754498265, 1655.5116240708899, 11355.205135640508, 11417.771644567061, 11435.256708313933, 11442.370669613169, 11442.599509017144, 4525.592696657317, 4626.537346265347, 4673.392458980311, 4679.157751604744, 4683.297505374153, 9282.767930801783, 9284.72145835626, 9298.0, 9299.117273645192, 9327.160555550034, 5185.507376799198, 5196.298499702568, 5257.6056795739705, 5268.578513718893, 5283.498067683564, 4787.2101103318255, 4862.805487780449, 4918.90162196754, 4923.212286034217, 4925.926111108264, 3682.1715114099115, 3695.224002959355, 3707.4068149096015, 3708.721845861571, 3748.981255093762, 1152.5562658376584, 1166.3235323512342, 1175.9176857620055, 1179.687382843547, 1179.8584169899666, 1288.3815768431205, 1423.003897271893, 1427.9071860329045, 1428.8428272889973, 1442.314900422291, 8048.678052877936, 8133.10535841057, 8191.754247372588, 8205.097439281673, 8218.137095604994, 510.9233614957786, 520.1106108554415, 523.5183631059358, 525.036557916228, 525.3511912787704, 5493.478119578634, 6026.306974697965, 6155.938440615626, 6169.321374648785, 6466.482681385478, 9848.2613803965, 9914.40513569188, 10003.34978226413, 10184.388936663823, 10211.693649190536, 9827.164691001202, 9933.15568176713, 9946.403215807075, 9999.200063994864, 10072.59709611622, 7755.1469338372735, 7784.455088143889, 7785.0, 7840.33357164636, 7843.882341764878, 8061.717839875576, 8083.514989100673, 8099.109097999333, 8099.271065604124, 8112.716054938049, 7766.844663106819, 8977.730668079968, 9325.393849399767, 9420.152186303265, 9451.480168590793, 9400.576974036048, 9524.428534287965, 9538.380005299634, 9659.758506037406, 9692.51537423112, 7070.787876363717, 7162.498625096193, 7163.176234733072, 7182.784516464514, 7210.387117095106, 8277.710280140167, 8318.792030199293, 8336.999560052833, 8381.916180838114, 8408.36937229698, 7726.111497177896, 7825.1783562725095, 7834.255745704149, 7963.920360796318, 7968.0438347398685, 7618.200088990798, 7651.502652327581, 7749.108852482037, 7777.533347999138, 7844.529328240324, 7609.581473019037, 7805.609719514116, 7830.725924592612, 7834.725784597512, 7878.684852605831, 7114.715411383487, 7135.714571417086, 7137.928620713934, 7144.1784194818265, 7158.355747982707, 6415.454686351713, 7154.534955227893, 7210.89183662256, 7231.4214862811905, 7257.854842903218], "unit": "ms", "name": "mootools.html", "value": 4758.331434185652}, {"lowerIsBetter": false, "replicates": [326.710860888114, 333.8147328232831, 337.2613975393887, 346.6135259186008, 347.22741899274104, 5981.252343457068, 6305.810825675231, 6711.328867113286, 6875.381215690587, 6938.9653051734795, 5401.27082843816, 6641.203055633331, 6782.762603308885, 6843.418309443703, 6851.486138539604, 5003.274525193845, 5049.141645920184, 5064.772085256154, 5072.1630930896545, 5072.949270507284, 4795.064962332347, 4876.609871210295, 4985.152524070917, 5052.292679024939, 5073.0107629012355, 4966.627502937276, 4998.650094493387, 5005.924911126336, 5016.322796422491, 5034.295198672189, 4998.575406009288, 5016.974915125437, 5028.290381270367, 5034.798608055673, 5062.0888240116765, 4235.322348424268, 4239.300515414953, 4239.533651298355, 4241.172971270604, 4247.853079668472, 6277.7175027123885, 6496.577722448073, 6503.93496065038, 6518.902216466757, 6539.34606539347, 5926.614770039933, 5926.762929482816, 5931.673757943311, 5937.228160339171, 5941.732622031976, 7312.232215617337, 7317.41460683144, 7322.926770732278, 7337.0461839961, 7339.412846972229, 4654.883627909329, 4795.664303498756, 4801.783919723586, 4817.9036419271415, 4819.614430845559, 7777.572233527154, 7798.688052477894, 7803.297703206738, 7813.101493328283, 7816.7264145754625, 4855.781489832966, 4863.148948933923, 4863.8540843774745, 4882.536159064884, 4883.951160488421, 4375.16871794358, 4812.446568644581, 4934.5312195341385, 4940.086084074458, 4972.154733695281, 4825.95174048262, 5809.7095145242565, 5923.689760993216, 5956.136360227742, 5970.701464926737, 7395.297446742551, 7410.5183163094225, 7424.888626670604, 7425.7029718810645, 7432.888506672404, 1709.1283445442914, 1735.8350956659096, 1736.791585009807, 1737.643783024477, 1738.400251913088, 1045.5033858916954, 1087.9129669626411, 1111.6220485035126, 1125.0268517732102, 1132.4394424759632, 6866.690998905061, 6874.0, 6875.243723190445, 6875.381215690511, 6910.963355496716, 6489.961606142994, 7427.665755041036, 7464.066991626047, 7638.007059082283, 7675.117361503499, 6903.995682504135, 6948.200956890022, 6958.512904096766, 6958.826029349307, 6959.652017399211, 4000.57993910641, 4101.159262351211, 4105.958940410617, 4106.178764247103, 4111.732737372061, 7726.373033168749, 7830.686772529149, 7846.764597062097, 7856.214378562212, 7867.095284042409, 8021.8395632088195, 8031.437799353989, 8036.959815201004, 8041.47730397534, 8062.355011599058, 7568.0, 7808.882866757003, 7814.804629884298, 7824.256695613907, 7854.960725196337, 6206.689665516797, 7754.573498457639, 7762.068551773597, 7767.1067827200595, 7781.144074151952, 7621.35218506411, 7622.733204337822, 7627.084749830056, 7631.3513351365755, 7650.31147196755, 7536.321731044232, 7808.88286675689, 7822.295993360625, 7826.647800849089, 7831.451798374059, 4889.266610008527, 4951.554360107608, 4960.47914968923, 4968.3292755479, 4972.477889821661, 6439.420452159328, 7565.394768418403, 7766.883496747666, 7771.572563509061, 7775.144734079134, 7366.263373662805, 7436.516626419266, 7460.738874139379, 7466.887996680162, 7467.066616672916, 6121.3878612140215, 7397.519161254501, 7405.8148546286775, 7413.147488038944, 7419.591922444318, 7030.2969703029285, 7218.0, 7218.530795498275, 7233.963830180815, 7248.456365772694, 7418.740344087931, 7424.851502969802, 7428.182899881117, 7430.851382972202, 7448.404127669665, 5298.7350632469, 6842.247352791289, 6931.618760967994, 7059.5764254144915, 7061.505694601328], "unit": "ms", "name": "prototype.html", "value": 5348.796336768341}, {"lowerIsBetter": false, "replicates": [738.9963050184749, 818.2104269380051, 820.2412768189423, 822.292828167776, 881.2201201936286, 15186.772198417033, 15252.923735381335, 15476.922615386908, 15515.0, 15519.379224831007, 14300.570982870531, 14606.488772892952, 14614.342354594042, 14626.780598291034, 14626.926865365658, 802.1978021978022, 803.1286054630733, 987.1263931420691, 1010.5806090472482, 1013.8681971343715, 794.0233512779284, 984.1241295247236, 990.3315262198023, 991.7669347703284, 992.1566668331897, 1025.4513835098223, 1035.3425574760045, 1036.714903401563, 1039.740064983754, 1041.3283432186236, 819.9877001844977, 827.8385714785619, 1029.1355261580234, 1030.7423144213947, 1036.2435422141841, 770.4876257288896, 771.6488997506177, 772.3666593393419, 776.1888826176659, 781.468601351081, 15221.695566088616, 15415.845841541553, 15432.459863904822, 15433.459828905934, 15435.922820385938, 12278.938605306916, 12375.133740638159, 12635.11554191207, 12852.164609300458, 12961.416736246798, 10062.597496100221, 10171.898281017244, 10184.643537476228, 10311.79376412475, 10965.0, 3593.3711600469815, 3609.7834129952025, 3618.9087735479666, 3659.121810765397, 3665.5967843537455, 74185.6290718543, 75307.0, 75471.0, 76146.85779713308, 76526.23473765302, 7385.593792341366, 7631.236876312435, 9238.307126965505, 9339.346245762867, 9364.204042656314, 21802.781972180073, 21805.236816711338, 21839.672404913937, 21842.89078554597, 21869.687818730927, 7395.149557800814, 7513.962430187814, 7657.463977521631, 7658.61706914652, 7824.804379890548, 4069.8372065117064, 4148.211839750437, 4165.708400412002, 4200.579942005775, 4348.152110338454, 3482.773619714761, 3549.911252218715, 3550.55618047744, 3554.680078792921, 3563.750537462353, 539.4174291764963, 615.1080932647678, 648.9513286503532, 651.4625434016955, 654.7871941618993, 418.60232778859597, 596.2278848890697, 601.5969300568629, 606.7451670298485, 622.4895585619748, 8562.52906090171, 8589.828203436022, 8762.992255890527, 8830.205281524693, 8939.642414303355, 505.7344893930642, 534.7406507843692, 551.9448055194448, 563.5485637477024, 607.2015299880643, 4017.77902215381, 4075.449814275094, 4147.6474499667265, 4149.97925010373, 4222.408862759216, 92390.53804731068, 92885.0, 93782.0, 97054.5147274259, 97878.51060744793, 82914.58542707248, 85011.99999999876, 85385.57307213548, 85430.57284713537, 85633.0, 68057.0, 72103.0, 75361.62319188479, 76336.9999999989, 77086.6145669268, 8830.293576513735, 8879.822403552022, 9000.819983600422, 9022.413543119676, 9106.863397048917, 53159.20261196163, 53621.46378536321, 54100.376988690405, 54894.72552637211, 56211.71894140503, 67086.36238726883, 73009.26990730024, 74091.25908740844, 74262.0, 75468.62265688636, 10091.69724908254, 10894.727631809268, 11010.944945275221, 11026.173037022096, 11528.481218345027, 12026.15816892809, 12269.815952760537, 12524.123311368117, 12543.811842822182, 12894.0, 11276.830847537129, 11864.703382415508, 11869.050475962074, 12257.448414821532, 12307.0, 11198.577579998864, 11550.48022838991, 11840.76318473643, 11918.642440726791, 11920.582779602672, 11407.201495895208, 11463.484143213742, 11837.112216583619, 11859.229150105215, 12376.319302438453, 9704.126628603459, 9777.364471309342, 9984.700458986243, 10089.545970431494, 10318.329308594917, 9067.773305667148, 9244.676436324966, 9477.87230214014, 9571.760705982406, 10184.338018028804], "unit": "ms", "name": "yui.html", "value": 7104.02030269137}], "name": "dromaeo_css", "value": 7609.552113373088}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 103.0, 111.0, 112.0, 110.0, 111.0, 108.0, 101.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 108.6}, {"lowerIsBetter": true, "replicates": [149.0, 120.0, 141.0, 154.0, 103.0, 119.0, 127.0, 133.0, 182.0, 144.0], "unit": "ms", "name": "audio-beat-detection", "value": 137.2}, {"lowerIsBetter": true, "replicates": [183.0, 181.0, 182.0, 184.0, 184.0, 182.0, 182.0, 183.0, 185.0, 181.0], "unit": "ms", "name": "audio-dft", "value": 182.7}, {"lowerIsBetter": true, "replicates": [60.0, 72.0, 71.0, 71.0, 56.0, 56.0, 56.0, 70.0, 74.0, 74.0], "unit": "ms", "name": "audio-fft", "value": 66.0}, {"lowerIsBetter": true, "replicates": [83.0, 83.0, 144.0, 111.0, 110.0, 73.0, 112.0, 74.0, 74.0, 110.0], "unit": "ms", "name": "audio-oscillator", "value": 97.4}, {"lowerIsBetter": true, "replicates": [115.0, 78.0, 114.0, 131.0, 132.0, 84.0, 85.0, 84.0, 85.0, 85.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 99.3}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 93.0, 92.0, 93.0, 92.0, 95.0, 93.0, 93.0, 93.0], "unit": "ms", "name": "imaging-darkroom", "value": 92.9}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 85.0, 84.0, 86.0, 87.0, 86.0, 85.0, 85.0, 87.0], "unit": "ms", "name": "imaging-desaturate", "value": 85.8}, {"lowerIsBetter": true, "replicates": [55.0, 54.0, 52.0, 52.0, 53.0, 54.0, 56.0, 62.0, 51.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 54.2}, {"lowerIsBetter": true, "replicates": [65.0, 64.0, 64.0, 64.0, 64.0, 66.0, 67.0, 67.0, 67.0, 67.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 65.5}, {"lowerIsBetter": true, "replicates": [82.0, 81.0, 81.0, 82.0, 80.0, 81.0, 83.0, 81.0, 83.0, 82.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 81.6}, {"lowerIsBetter": true, "replicates": [122.0, 124.0, 111.0, 121.0, 118.0, 126.0, 119.0, 128.0, 118.0, 123.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 121.0}, {"lowerIsBetter": true, "replicates": [118.0, 172.0, 174.0, 175.0, 136.0, 123.0, 135.0, 147.0, 135.0, 122.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 143.7}, {"lowerIsBetter": true, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 64.0, 67.0, 67.0, 65.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.3}], "name": "kraken", "value": 1401.1999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [146.41288433382138], "unit": "score", "name": "Richards", "value": 241.11266000000003}, {"lowerIsBetter": false, "replicates": [161.68148746968473], "unit": "score", "name": "DeltaBlue", "value": 408.93982999999986}, {"lowerIsBetter": false, "replicates": [276.62517289073304], "unit": "score", "name": "Encrypt", "value": 962.244315}, {"lowerIsBetter": false, "replicates": [4682.242990654206], "unit": "score", "name": "Decrypt", "value": 56.84903592814368}, {"lowerIsBetter": false, "replicates": [1703.5775127768313], "unit": "score", "name": "RayTrace", "value": 434.3735429999998}, {"lowerIsBetter": false, "replicates": [784.3137254901961], "unit": "score", "name": "Earley", "value": 849.7403250000002}, {"lowerIsBetter": false, "replicates": [12395.061728395061], "unit": "score", "name": "Boyer", "value": 53.768429282868496}, {"lowerIsBetter": false, "replicates": [26736.842105263157], "unit": "score", "name": "RegExp", "value": 34.07227362204725}, {"lowerIsBetter": false, "replicates": [614.1636141636142], "unit": "score", "name": "Splay", "value": 132.68614115308148}, {"lowerIsBetter": false, "replicates": [4482.142857142857], "unit": "score", "name": "NavierStokes", "value": 331.0916334661356}], "name": "v8_7", "value": 20997.2951047845}]} 12:32:08 INFO - RETURN: dromaeo_css: 7609.55 12:32:08 INFO - RETURN: kraken: 1401.20 12:32:08 INFO - RETURN: v8_7: 20997.30 12:32:08 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,59]]", "result": "20997.30"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1401.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7609.55"}}} 12:32:08 INFO - Return code: 0 12:32:08 INFO - # TBPL SUCCESS # 12:32:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:32:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:32:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:32:08 INFO - 2015-11-23 12:32:08.677 screenresolution[1670:9995] starting screenresolution argv=screenresolution get 12:32:08 INFO - 2015-11-23 12:32:08.679 screenresolution[1670:9995] Display 0: 1600x1200x32@60 12:32:08 INFO - 2015-11-23 12:32:08.685 screenresolution[1671:9998] starting screenresolution argv=screenresolution list 12:32:08 INFO - Available Modes on Display 0 12:32:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:32:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:32:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:32:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:32:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:32:08 INFO - Intel Iris: 12:32:08 INFO - Chipset Model: Intel Iris 12:32:08 INFO - Type: GPU 12:32:08 INFO - Bus: Built-In 12:32:08 INFO - VRAM (Dynamic, Max): 1536 MB 12:32:08 INFO - Vendor: Intel (0x8086) 12:32:08 INFO - Device ID: 0x0a2e 12:32:08 INFO - Revision ID: 0x0009 12:32:08 INFO - Displays: 12:32:08 INFO - Display: 12:32:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:32:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:32:08 INFO - Display Serial Number: 1600x1200 60 12:32:08 INFO - Main Display: Yes 12:32:08 INFO - Mirror: Off 12:32:08 INFO - Online: Yes 12:32:08 INFO - Rotation: Supported 12:32:08 INFO - Return code: 0 12:32:08 INFO - Running post-action listener: _resource_record_post_action 12:32:08 INFO - Running post-run listener: _resource_record_post_run 12:32:09 INFO - Total resource usage - Wall time: 1239s; CPU: 53.0%; Read bytes: 39032320; Write bytes: 691920896; Read time: 425; Write time: 2194 12:32:09 INFO - install - Wall time: 30s; CPU: 54.0%; Read bytes: 341033472; Write bytes: 337958912; Read time: 26016; Write time: 708 12:32:09 INFO - run-tests - Wall time: 1209s; CPU: 53.0%; Read bytes: 37004800; Write bytes: 342542336; Read time: 402; Write time: 1446 12:32:09 INFO - Running post-run listener: _upload_blobber_files 12:32:09 INFO - Blob upload gear active. 12:32:09 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 12:32:09 INFO - Copying logs to upload dir... 12:32:09 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1281.222339 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2015-11-23 12:32:10.004729) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 12:32:10.008800) ========= 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.IYKFbnqFOL/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.DYGH0cfsM6/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False build_url:https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009041 build_url: 'https://queue.taskcluster.net/v1/task/Nl1e7m18QGeoKefNA5LIqw/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-23 12:32:10.033121) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 12:32:10.033451) ========= 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.IYKFbnqFOL/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.DYGH0cfsM6/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005213 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 12:32:10.082596) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 12:32:10.082897) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 12:32:10.083258) ========= ========= Total master_lag: 0.19 =========