builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0016 starttime: 1448369473.6 results: success (0) buildid: 20151124025732 builduid: 7da7e0e5028949b7911e621520fb31fb revision: c6893ed724af1209d22833aa68c740b35d926b9b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.604464) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.604892) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.605170) ========= 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.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.005669 basedir: '/builds/slave/test' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.625702) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.626008) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.641553) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.641943) ========= 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.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.005238 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.690425) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.690731) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.691104) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.691370) ========= 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.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2015-11-24 04:51:13-- 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% 10.3M=0.001s 2015-11-24 04:51:13 (10.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.181547 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.886047) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.886362) ========= 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.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.019315 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:13.934578) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-24 04:51:13.934928) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c6893ed724af1209d22833aa68c740b35d926b9b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c6893ed724af1209d22833aa68c740b35d926b9b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2015-11-24 04:51:14,012 truncating revision to first 12 chars 2015-11-24 04:51:14,012 Setting DEBUG logging. 2015-11-24 04:51:14,012 attempt 1/10 2015-11-24 04:51:14,013 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c6893ed724af?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 04:51:15,242 unpacking tar archive at: mozilla-central-c6893ed724af/testing/mozharness/ program finished with exit code 0 elapsedTime=1.475594 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-24 04:51:15.424508) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:15.424862) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:15.435537) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:15.435898) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 04:51:15.436335) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2015-11-24 04:51:15.436627) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 04:51:15 INFO - MultiFileLogger online at 20151124 04:51:15 in /builds/slave/test 04:51:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:51:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:51:15 INFO - {'append_to_log': False, 04:51:15 INFO - 'base_work_dir': '/builds/slave/test', 04:51:15 INFO - 'blob_upload_branch': 'Firefox', 04:51:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:51:15 INFO - 'branch': 'Firefox', 04:51:15 INFO - 'buildbot_json_path': 'buildprops.json', 04:51:15 INFO - 'config_files': ('talos/mac_config.py',), 04:51:15 INFO - 'default_actions': ('clobber', 04:51:15 INFO - 'read-buildbot-config', 04:51:15 INFO - 'download-and-extract', 04:51:15 INFO - 'populate-webroot', 04:51:15 INFO - 'create-virtualenv', 04:51:15 INFO - 'install', 04:51:15 INFO - 'run-tests'), 04:51:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:51:15 INFO - 'download_minidump_stackwalk': True, 04:51:15 INFO - 'download_symbols': 'ondemand', 04:51:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:51:15 INFO - 'tooltool.py': '/tools/tooltool.py', 04:51:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:51:15 INFO - '/tools/misc-python/virtualenv.py')}, 04:51:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:51:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:51:15 INFO - 'installer_path': 'installer.exe', 04:51:15 INFO - 'log_level': 'info', 04:51:15 INFO - 'log_name': 'talos', 04:51:15 INFO - 'log_to_console': True, 04:51:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:51:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:51:15 INFO - 'opt_config_files': (), 04:51:15 INFO - 'pip_index': False, 04:51:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:51:15 INFO - 'cmd': ('bash', 04:51:15 INFO - '-c', 04:51:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:51:15 INFO - 'enabled': True, 04:51:15 INFO - 'halt_on_failure': False, 04:51:15 INFO - 'name': 'check_screen_resolution'},), 04:51:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:51:15 INFO - 'cmd': ('bash', 04:51:15 INFO - '-c', 04:51:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:51:15 INFO - 'enabled': True, 04:51:15 INFO - 'halt_on_failure': False, 04:51:15 INFO - 'name': 'check_screen_resolution'},), 04:51:15 INFO - 'run_cmd_checks_enabled': True, 04:51:15 INFO - 'sps_profile': False, 04:51:15 INFO - 'sps_profile_interval': 0, 04:51:15 INFO - 'suite': 'dromaeojs', 04:51:15 INFO - 'system_bits': '64', 04:51:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:51:15 INFO - 'title': 't-yosemite-r7-0016', 04:51:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:51:15 INFO - 'use_talos_json': True, 04:51:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:51:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:51:15 INFO - 'work_dir': 'build'} 04:51:15 INFO - ##### 04:51:15 INFO - ##### Running clobber step. 04:51:15 INFO - ##### 04:51:15 INFO - Running pre-action listener: _resource_record_pre_action 04:51:15 INFO - Running main action method: clobber 04:51:15 INFO - rmtree: /builds/slave/test/build 04:51:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:51:16 INFO - Running post-action listener: _resource_record_post_action 04:51:16 INFO - ##### 04:51:16 INFO - ##### Running read-buildbot-config step. 04:51:16 INFO - ##### 04:51:16 INFO - Running pre-action listener: _resource_record_pre_action 04:51:16 INFO - Running main action method: read_buildbot_config 04:51:16 INFO - Using buildbot properties: 04:51:16 INFO - { 04:51:16 INFO - "properties": { 04:51:16 INFO - "buildnumber": 6, 04:51:16 INFO - "product": "firefox", 04:51:16 INFO - "script_repo_revision": "production", 04:51:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:51:16 INFO - "repository": "", 04:51:16 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:51:16 INFO - "buildid": "20151124025732", 04:51:16 INFO - "slavename": "t-yosemite-r7-0016", 04:51:16 INFO - "pgo_build": "False", 04:51:16 INFO - "basedir": "/builds/slave/test", 04:51:16 INFO - "project": "", 04:51:16 INFO - "platform": "yosemite_r7", 04:51:16 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:51:16 INFO - "slavebuilddir": "test", 04:51:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:51:16 INFO - "branch": "mozilla-central", 04:51:16 INFO - "repo_path": "mozilla-central", 04:51:16 INFO - "stage_platform": "macosx64", 04:51:16 INFO - "builduid": "7da7e0e5028949b7911e621520fb31fb", 04:51:16 INFO - "revision": "c6893ed724af1209d22833aa68c740b35d926b9b" 04:51:16 INFO - }, 04:51:16 INFO - "sourcestamp": { 04:51:16 INFO - "repository": "", 04:51:16 INFO - "hasPatch": false, 04:51:16 INFO - "project": "", 04:51:16 INFO - "branch": "mozilla-central-macosx64-talos", 04:51:16 INFO - "changes": [ 04:51:16 INFO - { 04:51:16 INFO - "category": null, 04:51:16 INFO - "files": [ 04:51:16 INFO - { 04:51:16 INFO - "url": null, 04:51:16 INFO - "name": "https://queue.taskcluster.net/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 04:51:16 INFO - } 04:51:16 INFO - ], 04:51:16 INFO - "repository": "", 04:51:16 INFO - "rev": "c6893ed724af1209d22833aa68c740b35d926b9b", 04:51:16 INFO - "who": "cbook@mozilla.com", 04:51:16 INFO - "when": 1448369310, 04:51:16 INFO - "number": 6699996, 04:51:16 INFO - "comments": "Bumping manifests a=b2g-bump", 04:51:16 INFO - "project": "", 04:51:16 INFO - "at": "Tue 24 Nov 2015 04:48:30", 04:51:16 INFO - "branch": "mozilla-central-macosx64-talos", 04:51:16 INFO - "revlink": "", 04:51:16 INFO - "properties": [ 04:51:16 INFO - [ 04:51:16 INFO - "buildid", 04:51:16 INFO - "20151124025732", 04:51:16 INFO - "Change" 04:51:16 INFO - ], 04:51:16 INFO - [ 04:51:16 INFO - "builduid", 04:51:16 INFO - "7da7e0e5028949b7911e621520fb31fb", 04:51:16 INFO - "Change" 04:51:16 INFO - ], 04:51:16 INFO - [ 04:51:16 INFO - "pgo_build", 04:51:16 INFO - "False", 04:51:16 INFO - "Change" 04:51:16 INFO - ] 04:51:16 INFO - ], 04:51:16 INFO - "revision": "c6893ed724af1209d22833aa68c740b35d926b9b" 04:51:16 INFO - } 04:51:16 INFO - ], 04:51:16 INFO - "revision": "c6893ed724af1209d22833aa68c740b35d926b9b" 04:51:16 INFO - } 04:51:16 INFO - } 04:51:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 04:51:16 INFO - Running post-action listener: _resource_record_post_action 04:51:16 INFO - ##### 04:51:16 INFO - ##### Running download-and-extract step. 04:51:16 INFO - ##### 04:51:16 INFO - Running pre-action listener: _resource_record_pre_action 04:51:16 INFO - Running main action method: download_and_extract 04:51:16 INFO - mkdir: /builds/slave/test/build/tests 04:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:16 INFO - https://queue.taskcluster.net/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 04:51:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/test_packages.json 04:51:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/test_packages.json 04:51:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 04:51:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 04:51:18 INFO - Downloaded 1149 bytes. 04:51:18 INFO - Reading from file /builds/slave/test/build/test_packages.json 04:51:18 INFO - Using the following test package requirements: 04:51:18 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 04:51:18 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 04:51:18 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 04:51:18 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 04:51:18 INFO - u'jsshell-mac.zip'], 04:51:18 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 04:51:18 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 04:51:18 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 04:51:18 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 04:51:18 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 04:51:18 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 04:51:18 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 04:51:18 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 04:51:18 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 04:51:18 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 04:51:18 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 04:51:18 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 04:51:18 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:51:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:18 INFO - https://queue.taskcluster.net/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:51:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 04:51:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 04:51:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/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 04:51:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/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 04:51:19 INFO - Downloaded 17527217 bytes. 04:51:19 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 04:51:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 04:51:19 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 04:51:22 INFO - Return code: 0 04:51:22 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 04:51:22 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')]} 04:51:22 INFO - https://queue.taskcluster.net/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:51:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 04:51:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 04:51:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/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 04:51:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/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 04:51:23 INFO - Downloaded 17527217 bytes. 04:51:23 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 04:51:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 04:51:23 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 04:51:26 INFO - Return code: 0 04:51:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:26 INFO - https://queue.taskcluster.net/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:51:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 04:51:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 04:51:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/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 04:51:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/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 04:51:27 INFO - Downloaded 11130997 bytes. 04:51:27 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 04:51:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 04:51:27 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 04:51:28 INFO - Return code: 0 04:51: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')]} 04:51:28 INFO - https://queue.taskcluster.net/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:51:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 04:51:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 04:51:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg 04:51:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/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 04:51:31 INFO - Downloaded 109898385 bytes. 04:51:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 04:51:31 INFO - mkdir: /builds/slave/test/properties 04:51:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:51:31 INFO - Writing to file /builds/slave/test/properties/build_url 04:51:31 INFO - Contents: 04:51:31 INFO - build_url:https://queue.taskcluster.net/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 04:51:31 INFO - Running post-action listener: _resource_record_post_action 04:51:31 INFO - Running post-action listener: set_extra_try_arguments 04:51:31 INFO - ##### 04:51:31 INFO - ##### Running populate-webroot step. 04:51:31 INFO - ##### 04:51:31 INFO - Running pre-action listener: _resource_record_pre_action 04:51:31 INFO - Running main action method: populate_webroot 04:51:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:51:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:51:31 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 04:51:31 INFO - '../fennec_ids.txt'], 04:51:31 INFO - 'tests': ['tcheck2']}, 04:51:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:51:31 INFO - '--tppagecycles', 04:51:31 INFO - '7'], 04:51:31 INFO - 'tests': ['tsvgm']}}, 04:51:31 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:51:31 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:51:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:51:31 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:51:31 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:51:31 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:51:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:51:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:31 INFO - 'talos_options': ['--e10s'], 04:51:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:51:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:31 INFO - 'tests': ['damp', 'tps']}, 04:51:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:31 INFO - 'talos_options': ['--e10s'], 04:51:31 INFO - 'tests': ['damp', 'tps']}, 04:51:31 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:51:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:51:31 INFO - 'other': {'tests': ['a11yr', 04:51:31 INFO - 'ts_paint', 04:51:31 INFO - 'tpaint', 04:51:31 INFO - 'sessionrestore', 04:51:31 INFO - 'sessionrestore_no_auto_restore']}, 04:51:31 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:51:31 INFO - 'tests': ['a11yr', 04:51:31 INFO - 'ts_paint', 04:51:31 INFO - 'tpaint', 04:51:31 INFO - 'sessionrestore', 04:51:31 INFO - 'sessionrestore_no_auto_restore']}, 04:51:31 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:51:31 INFO - 'tests': ['a11yr', 04:51:31 INFO - 'ts_paint', 04:51:31 INFO - 'tpaint', 04:51:31 INFO - 'sessionrestore', 04:51:31 INFO - 'sessionrestore_no_auto_restore']}, 04:51:31 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:51:31 INFO - 'tests': ['a11yr', 04:51:31 INFO - 'ts_paint', 04:51:31 INFO - 'tpaint', 04:51:31 INFO - 'sessionrestore', 04:51:31 INFO - 'sessionrestore_no_auto_restore']}, 04:51:31 INFO - 'other_l64': {'tests': ['a11yr', 04:51:31 INFO - 'ts_paint', 04:51:31 INFO - 'tpaint', 04:51:31 INFO - 'sessionrestore', 04:51:31 INFO - 'sessionrestore_no_auto_restore']}, 04:51:31 INFO - 'other_nol64': {'tests': ['a11yr', 04:51:31 INFO - 'ts_paint', 04:51:31 INFO - 'tpaint', 04:51:31 INFO - 'sessionrestore', 04:51:31 INFO - 'sessionrestore_no_auto_restore']}, 04:51:31 INFO - 'svgr': {'tests': ['tsvgx', 04:51:31 INFO - 'tsvgr_opacity', 04:51:31 INFO - 'tart', 04:51:31 INFO - 'tscrollx', 04:51:31 INFO - 'cart']}, 04:51:31 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:51:31 INFO - 'tests': ['tsvgx', 04:51:31 INFO - 'tsvgr_opacity', 04:51:31 INFO - 'tart', 04:51:31 INFO - 'tscrollx', 04:51:31 INFO - 'cart']}, 04:51:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:31 INFO - 'tests': ['tp5o']}, 04:51:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:31 INFO - 'talos_options': ['--e10s'], 04:51:31 INFO - 'tests': ['tp5o']}, 04:51:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:31 INFO - 'talos_options': ['--xperf_path', 04:51:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:51:31 INFO - 'tests': ['tp5n']}, 04:51:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:51:31 INFO - 'talos_options': ['--e10s', 04:51:31 INFO - '--xperf_path', 04:51:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:51:31 INFO - 'tests': ['tp5n']}}, 04:51:31 INFO - 'talos.zip': {'path': '', 04:51:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:51:31 INFO - Running post-action listener: _resource_record_post_action 04:51:31 INFO - ##### 04:51:31 INFO - ##### Running create-virtualenv step. 04:51:31 INFO - ##### 04:51:31 INFO - Running pre-action listener: _resource_record_pre_action 04:51:31 INFO - Running main action method: create_virtualenv 04:51:31 INFO - Creating virtualenv /builds/slave/test/build/venv 04:51:31 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 04:51:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:51:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:51:31 INFO - Using real prefix '/tools/python27' 04:51:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:51:31 INFO - Installing distribute.............................................................................................................................................................................................done. 04:51:34 INFO - Installing pip.................done. 04:51:34 INFO - Return code: 0 04:51:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:34 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 0x10891a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088f7228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc61bcafc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883e9f0>, '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 0x10859ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088318c8>, '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.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 04:51:34 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 04:51:34 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 04:51:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h7Z0zVFbb5/Render', 04:51:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:34 INFO - 'HOME': '/Users/cltbld', 04:51:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:34 INFO - 'LOGNAME': 'cltbld', 04:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:34 INFO - 'MOZ_NO_REMOTE': '1', 04:51:34 INFO - 'NO_EM_RESTART': '1', 04:51:34 INFO - 'PAGER': '/bin/cat', 04:51:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:34 INFO - 'PWD': '/builds/slave/test', 04:51:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:34 INFO - 'SHELL': '/bin/bash', 04:51:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYZTP4Bfq9/Listeners', 04:51:34 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 04:51:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:34 INFO - 'USER': 'cltbld', 04:51:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:34 INFO - 'XPC_FLAGS': '0x0', 04:51:34 INFO - 'XPC_SERVICE_NAME': '0', 04:51:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:35 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 04:51:35 INFO - Cleaning up... 04:51:35 INFO - Return code: 0 04:51:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10891a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088f7228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc61bcafc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883e9f0>, '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 0x10859ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088318c8>, '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.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 04:51:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:51:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:51:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h7Z0zVFbb5/Render', 04:51:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:35 INFO - 'HOME': '/Users/cltbld', 04:51:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:35 INFO - 'LOGNAME': 'cltbld', 04:51:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:35 INFO - 'MOZ_NO_REMOTE': '1', 04:51:35 INFO - 'NO_EM_RESTART': '1', 04:51:35 INFO - 'PAGER': '/bin/cat', 04:51:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:35 INFO - 'PWD': '/builds/slave/test', 04:51:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:35 INFO - 'SHELL': '/bin/bash', 04:51:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYZTP4Bfq9/Listeners', 04:51:35 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 04:51:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:35 INFO - 'USER': 'cltbld', 04:51:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:35 INFO - 'XPC_FLAGS': '0x0', 04:51:35 INFO - 'XPC_SERVICE_NAME': '0', 04:51:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:35 INFO - Downloading/unpacking psutil>=0.7.1 04:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:37 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 04:51:37 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:51:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:51:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:51:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:51:37 INFO - Installing collected packages: psutil 04:51:37 INFO - Running setup.py install for psutil 04:51:38 INFO - building 'psutil._psutil_osx' extension 04:51:38 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 04:51:38 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 04:51:38 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 04:51:38 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 04:51:38 INFO - building 'psutil._psutil_posix' extension 04:51:38 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 04:51:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:51:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:51:38 INFO - ^ 04:51:38 INFO - 1 warning generated. 04:51:38 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 04:51:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:51:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:51:38 INFO - Successfully installed psutil 04:51:38 INFO - Cleaning up... 04:51:38 INFO - Return code: 0 04:51:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:51:38 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')]} 04:51:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:38 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')]} 04:51:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:38 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 0x10891a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088f7228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc61bcafc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883e9f0>, '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 0x10859ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088318c8>, '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.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 04:51:38 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 04:51:38 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 04:51:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h7Z0zVFbb5/Render', 04:51:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:38 INFO - 'HOME': '/Users/cltbld', 04:51:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:38 INFO - 'LOGNAME': 'cltbld', 04:51:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:38 INFO - 'MOZ_NO_REMOTE': '1', 04:51:38 INFO - 'NO_EM_RESTART': '1', 04:51:38 INFO - 'PAGER': '/bin/cat', 04:51:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:38 INFO - 'PWD': '/builds/slave/test', 04:51:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:38 INFO - 'SHELL': '/bin/bash', 04:51:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYZTP4Bfq9/Listeners', 04:51:38 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 04:51:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:38 INFO - 'USER': 'cltbld', 04:51:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:38 INFO - 'XPC_FLAGS': '0x0', 04:51:38 INFO - 'XPC_SERVICE_NAME': '0', 04:51:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:51:41 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:51:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:51:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:51:41 INFO - Installing collected packages: mozsystemmonitor 04:51:41 INFO - Running setup.py install for mozsystemmonitor 04:51:41 INFO - Successfully installed mozsystemmonitor 04:51:41 INFO - Cleaning up... 04:51:41 INFO - Return code: 0 04:51:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:51:41 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')]} 04:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:41 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')]} 04:51:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:41 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 0x10891a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088f7228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc61bcafc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883e9f0>, '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 0x10859ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088318c8>, '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.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 04:51:41 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 04:51:41 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 04:51:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h7Z0zVFbb5/Render', 04:51:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:41 INFO - 'HOME': '/Users/cltbld', 04:51:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:41 INFO - 'LOGNAME': 'cltbld', 04:51:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:41 INFO - 'MOZ_NO_REMOTE': '1', 04:51:41 INFO - 'NO_EM_RESTART': '1', 04:51:41 INFO - 'PAGER': '/bin/cat', 04:51:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:41 INFO - 'PWD': '/builds/slave/test', 04:51:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:41 INFO - 'SHELL': '/bin/bash', 04:51:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYZTP4Bfq9/Listeners', 04:51:41 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 04:51:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:41 INFO - 'USER': 'cltbld', 04:51:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:41 INFO - 'XPC_FLAGS': '0x0', 04:51:41 INFO - 'XPC_SERVICE_NAME': '0', 04:51:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:41 INFO - Downloading/unpacking blobuploader==1.2.4 04:51:41 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 04:51:41 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 04:51:41 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 04:51:41 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 04:51:41 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 04:51:41 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 04:51:43 INFO - Downloading blobuploader-1.2.4.tar.gz 04:51:43 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:51:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:51:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:51:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:51:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:51:45 INFO - Downloading docopt-0.6.1.tar.gz 04:51:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:51:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:51:45 INFO - Installing collected packages: blobuploader, requests, docopt 04:51:45 INFO - Running setup.py install for blobuploader 04:51:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:51:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:51:45 INFO - Running setup.py install for requests 04:51:45 INFO - Running setup.py install for docopt 04:51:45 INFO - Successfully installed blobuploader requests docopt 04:51:45 INFO - Cleaning up... 04:51:46 INFO - Return code: 0 04:51:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:46 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 0x10891a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088f7228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc61bcafc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883e9f0>, '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 0x10859ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088318c8>, '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.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 04:51:46 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 04:51:46 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 04:51:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h7Z0zVFbb5/Render', 04:51:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:46 INFO - 'HOME': '/Users/cltbld', 04:51:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:46 INFO - 'LOGNAME': 'cltbld', 04:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:46 INFO - 'MOZ_NO_REMOTE': '1', 04:51:46 INFO - 'NO_EM_RESTART': '1', 04:51:46 INFO - 'PAGER': '/bin/cat', 04:51:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:46 INFO - 'PWD': '/builds/slave/test', 04:51:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:46 INFO - 'SHELL': '/bin/bash', 04:51:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYZTP4Bfq9/Listeners', 04:51:46 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 04:51:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:46 INFO - 'USER': 'cltbld', 04:51:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:46 INFO - 'XPC_FLAGS': '0x0', 04:51:46 INFO - 'XPC_SERVICE_NAME': '0', 04:51:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:51:46 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-bzQRKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:51:46 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-llfMxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:51:46 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ZBcTux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:51:46 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Vvd6zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:51:46 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-YMKjf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:51:46 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-hU_jBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:51:46 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-QImL4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:51:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-PBdGjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:51:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-k3H2Qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:51:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-veAASj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:51:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-56HA4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:51:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-aR_WLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:51:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-dsp4lF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:51:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-l3N9zc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-7NnK5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:51:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-5yjAhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:51:47 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-0542JL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:51:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:51:48 INFO - Running setup.py install for manifestparser 04:51:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:51:48 INFO - Running setup.py install for mozcrash 04:51:48 INFO - Running setup.py install for mozdebug 04:51:48 INFO - Running setup.py install for mozdevice 04:51:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:51:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:51:48 INFO - Running setup.py install for mozfile 04:51:48 INFO - Running setup.py install for mozhttpd 04:51:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:51:48 INFO - Running setup.py install for mozinfo 04:51:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:51:49 INFO - Running setup.py install for mozInstall 04:51:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:51:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:51:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:51:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:51:49 INFO - Running setup.py install for mozleak 04:51:49 INFO - Running setup.py install for mozlog 04:51:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:51:49 INFO - Running setup.py install for moznetwork 04:51:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:51:49 INFO - Running setup.py install for mozprocess 04:51:49 INFO - Running setup.py install for mozprofile 04:51:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:51:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:51:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:51:50 INFO - Running setup.py install for mozrunner 04:51:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:51:50 INFO - Running setup.py install for mozscreenshot 04:51:50 INFO - Running setup.py install for moztest 04:51:50 INFO - Running setup.py install for mozversion 04:51:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:51:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:51:50 INFO - Cleaning up... 04:51:50 INFO - Return code: 0 04:51:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:51: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')]} 04:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51: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')]} 04:51:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:50 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 0x10891a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088f7228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc61bcafc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883e9f0>, '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 0x10859ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088318c8>, '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.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 04:51:50 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 04:51:50 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 04:51:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h7Z0zVFbb5/Render', 04:51:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:50 INFO - 'HOME': '/Users/cltbld', 04:51:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:50 INFO - 'LOGNAME': 'cltbld', 04:51:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:50 INFO - 'MOZ_NO_REMOTE': '1', 04:51:50 INFO - 'NO_EM_RESTART': '1', 04:51:50 INFO - 'PAGER': '/bin/cat', 04:51:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:50 INFO - 'PWD': '/builds/slave/test', 04:51:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:50 INFO - 'SHELL': '/bin/bash', 04:51:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYZTP4Bfq9/Listeners', 04:51:50 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 04:51:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:50 INFO - 'USER': 'cltbld', 04:51:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:50 INFO - 'XPC_FLAGS': '0x0', 04:51:50 INFO - 'XPC_SERVICE_NAME': '0', 04:51:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:51:50 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-wZTZoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:51:51 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)) 04:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:51:51 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Z6UBS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:51:51 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)) 04:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:51:51 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Qfe86w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:51:51 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)) 04:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:51:51 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Icz0h5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:51:51 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)) 04:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:51:51 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-mUfHuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:51:51 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)) 04:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:51:51 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-H4OR15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:51:51 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)) 04:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:51:51 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-xURE5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:51:51 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)) 04:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:51:51 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-iJ0HoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:51:51 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)) 04:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:51:51 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-6F5mA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:51:51 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)) 04:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:51:51 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Lo3XPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:51:51 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-YMuA4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:51:52 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)) 04:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:51:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-bgq2ON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:51:52 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)) 04:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:51:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Mdrzvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:51:52 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)) 04:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:51:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Ml5BaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:51:52 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)) 04:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-pSTbMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:51:52 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)) 04:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:51:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ws63RC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:51:52 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)) 04:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:51:52 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Rof7hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:51:52 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)) 04:51:52 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)) 04:51:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:51:52 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)) 04:51:52 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)) 04:51:52 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)) 04:51:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:51:52 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 04:51:52 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 04:51:52 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 04:51:52 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 04:51:52 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 04:51:52 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 04:51:54 INFO - Downloading blessings-1.5.1.tar.gz 04:51:54 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 04:51:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:51:55 INFO - Installing collected packages: blessings 04:51:55 INFO - Running setup.py install for blessings 04:51:55 INFO - Successfully installed blessings 04:51:55 INFO - Cleaning up... 04:51:55 INFO - Return code: 0 04:51:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:51:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:51:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:51:55 INFO - Reading from file tmpfile_stdout 04:51:55 INFO - Current package versions: 04:51:55 INFO - blessings == 1.5.1 04:51:55 INFO - blobuploader == 1.2.4 04:51:55 INFO - docopt == 0.6.1 04:51:55 INFO - manifestparser == 1.1 04:51:55 INFO - mozInstall == 1.12 04:51:55 INFO - mozcrash == 0.16 04:51:55 INFO - mozdebug == 0.1 04:51:55 INFO - mozdevice == 0.47 04:51:55 INFO - mozfile == 1.2 04:51:55 INFO - mozhttpd == 0.7 04:51:55 INFO - mozinfo == 0.9 04:51:55 INFO - mozleak == 0.1 04:51:55 INFO - mozlog == 3.1 04:51:55 INFO - moznetwork == 0.27 04:51:55 INFO - mozprocess == 0.22 04:51:55 INFO - mozprofile == 0.27 04:51:55 INFO - mozrunner == 6.11 04:51:55 INFO - mozscreenshot == 0.1 04:51:55 INFO - mozsystemmonitor == 0.0 04:51:55 INFO - moztest == 0.7 04:51:55 INFO - mozversion == 1.4 04:51:55 INFO - psutil == 3.1.1 04:51:55 INFO - requests == 1.2.3 04:51:55 INFO - wsgiref == 0.1.2 04:51:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:51: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')]} 04:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:51:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51: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')]} 04:51:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:51:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:51:55 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 0x10891a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088f7228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc61bcafc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10883e9f0>, '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 0x10859ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088318c8>, '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.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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 04:51:55 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 04:51:55 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 04:51:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h7Z0zVFbb5/Render', 04:51:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:51:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:51:55 INFO - 'HOME': '/Users/cltbld', 04:51:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:51:55 INFO - 'LOGNAME': 'cltbld', 04:51:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:51:55 INFO - 'MOZ_NO_REMOTE': '1', 04:51:55 INFO - 'NO_EM_RESTART': '1', 04:51:55 INFO - 'PAGER': '/bin/cat', 04:51:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:51:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:51:55 INFO - 'PWD': '/builds/slave/test', 04:51:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:51:55 INFO - 'SHELL': '/bin/bash', 04:51:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYZTP4Bfq9/Listeners', 04:51:55 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 04:51:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:51:55 INFO - 'USER': 'cltbld', 04:51:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:51:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:51:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:51:55 INFO - 'XPC_FLAGS': '0x0', 04:51:55 INFO - 'XPC_SERVICE_NAME': '0', 04:51:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:51:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:51:55 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)) 04:51:55 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)) 04:51:55 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)) 04:51:55 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)) 04:51:55 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)) 04:51:55 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)) 04:51:55 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)) 04:51:55 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)) 04:51:55 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)) 04:51:55 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)) 04:51:55 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)) 04:51:55 INFO - Cleaning up... 04:51:55 INFO - Return code: 0 04:51:55 INFO - Running post-action listener: _resource_record_post_action 04:51:55 INFO - Running post-action listener: _start_resource_monitoring 04:51:55 INFO - Starting resource monitoring. 04:51:55 INFO - ##### 04:51:55 INFO - ##### Running install step. 04:51:55 INFO - ##### 04:51:55 INFO - Running pre-action listener: _resource_record_pre_action 04:51:55 INFO - Running main action method: install 04:51:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:51:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:51:56 INFO - Reading from file tmpfile_stdout 04:51:56 INFO - Detecting whether we're running mozinstall >=1.0... 04:51:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:51:56 INFO - Reading from file tmpfile_stdout 04:51:56 INFO - Output received: 04:51:56 INFO - Usage: mozinstall [options] installer 04:51:56 INFO - Options: 04:51:56 INFO - -h, --help show this help message and exit 04:51:56 INFO - -d DEST, --destination=DEST 04:51:56 INFO - Directory to install application into. [default: 04:51:56 INFO - "/builds/slave/test"] 04:51:56 INFO - --app=APP Application being installed. [default: firefox] 04:51:56 INFO - mkdir: /builds/slave/test/build/application 04:51:56 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'] 04:51:56 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 04:52:27 INFO - Reading from file tmpfile_stdout 04:52:27 INFO - Output received: 04:52:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:52:27 INFO - Running post-action listener: _resource_record_post_action 04:52:27 INFO - ##### 04:52:27 INFO - ##### Running run-tests step. 04:52:27 INFO - ##### 04:52:27 INFO - Running pre-action listener: _resource_record_pre_action 04:52:27 INFO - Running main action method: run_tests 04:52:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:52:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:52:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:52:27 INFO - 2015-11-24 04:52:27.273 screenresolution[1925:11788] starting screenresolution argv=screenresolution get 04:52:27 INFO - 2015-11-24 04:52:27.275 screenresolution[1925:11788] Display 0: 1600x1200x32@60 04:52:27 INFO - 2015-11-24 04:52:27.281 screenresolution[1926:11790] starting screenresolution argv=screenresolution list 04:52:27 INFO - Available Modes on Display 0 04:52:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:52:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:52:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:52:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:52:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:52:27 INFO - Intel Iris: 04:52:27 INFO - Chipset Model: Intel Iris 04:52:27 INFO - Type: GPU 04:52:27 INFO - Bus: Built-In 04:52:27 INFO - VRAM (Dynamic, Max): 1536 MB 04:52:27 INFO - Vendor: Intel (0x8086) 04:52:27 INFO - Device ID: 0x0a2e 04:52:27 INFO - Revision ID: 0x0009 04:52:27 INFO - Displays: 04:52:27 INFO - Display: 04:52:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:52:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:52:27 INFO - Display Serial Number: 1600x1200 60 04:52:27 INFO - Main Display: Yes 04:52:27 INFO - Mirror: Off 04:52:27 INFO - Online: Yes 04:52:27 INFO - Rotation: Supported 04:52:27 INFO - Return code: 0 04:52:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:52:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:52:27 INFO - Python 2.7.3 04:52:27 INFO - Return code: 0 04:52:27 INFO - grabbing minidump binary from tooltool 04:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:27 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 0x10883e9f0>, '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 0x10859ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088318c8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:52:27 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 04:52:27 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 04:52:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:52:27 INFO - Return code: 0 04:52:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:52:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:52:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:52:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:52:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:52:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--webServer', 'localhost'] in /builds/slave/test/build 04:52:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0016 --webServer localhost 04:52:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h7Z0zVFbb5/Render', 04:52:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:27 INFO - 'HOME': '/Users/cltbld', 04:52:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:27 INFO - 'LOGNAME': 'cltbld', 04:52:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:52:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:52:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:27 INFO - 'MOZ_NO_REMOTE': '1', 04:52:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:52:27 INFO - 'NO_EM_RESTART': '1', 04:52:27 INFO - 'PAGER': '/bin/cat', 04:52:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:27 INFO - 'PWD': '/builds/slave/test', 04:52:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:52:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:27 INFO - 'SHELL': '/bin/bash', 04:52:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FYZTP4Bfq9/Listeners', 04:52:27 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 04:52:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:27 INFO - 'USER': 'cltbld', 04:52:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:27 INFO - 'XPC_FLAGS': '0x0', 04:52:27 INFO - 'XPC_SERVICE_NAME': '0', 04:52:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--webServer', 'localhost'] with output_timeout 3600 04:52:27 INFO - mozversion INFO | application_buildid: 20151124025732 04:52:27 INFO - mozversion INFO | application_changeset: c6893ed724af1209d22833aa68c740b35d926b9b 04:52:27 INFO - mozversion INFO | application_display_name: Nightly 04:52:27 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:52:27 INFO - mozversion INFO | application_name: Firefox 04:52:27 INFO - mozversion INFO | application_remotingname: firefox 04:52:27 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 04:52:27 INFO - mozversion INFO | application_vendor: Mozilla 04:52:27 INFO - mozversion INFO | application_version: 45.0a1 04:52:27 INFO - mozversion INFO | platform_buildid: 20151124025732 04:52:27 INFO - mozversion INFO | platform_changeset: c6893ed724af1209d22833aa68c740b35d926b9b 04:52:27 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 04:52:27 INFO - mozversion INFO | platform_version: 45.0a1 04:52:27 INFO - 2015-11-24 04:52:27,821 DEBUG : using testdate: 1448369547 04:52:27 INFO - 2015-11-24 04:52:27,821 DEBUG : actual date: 1448369547 04:52:28 INFO - 2015-11-24 04:52:28,214 INFO : starting webserver on 'localhost:49660' 04:52:28 INFO - 2015-11-24 04:52:28,215 INFO : Starting test suite t-yosemite-r7-0016 04:52:28 INFO - 2015-11-24 04:52:28,215 INFO : Starting test dromaeo_css 04:52:28 INFO - 2015-11-24 04:52:28,215 DEBUG : operating with platform_type : mac_ 04:52:28 INFO - 2015-11-24 04:52:28,216 INFO : Initialising browser for dromaeo_css test... 04:52:28 INFO - 2015-11-24 04:52:28,230 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpIGTzuA/profile http://localhost:49660/getInfo.html 04:52:30 INFO - 2015-11-24 04:52:30,492 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:52:30 INFO - 2015-11-24 04:52:30,493 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:52:30 INFO - 2015-11-24 04:52:30,493 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 04:52:30 INFO - 2015-11-24 04:52:30,493 DEBUG : BROWSER_OUTPUT: __metrics 04:52:30 INFO - 2015-11-24 04:52:30,508 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:52:31 INFO - 2015-11-24 04:52:31,154 INFO : Browser initialized. 04:52:31 INFO - 2015-11-24 04:52:31,155 INFO : Running cycle 1/1 for dromaeo_css test... 04:52:31 INFO - 2015-11-24 04:52:31,155 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpIGTzuA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:52:42 INFO - 2015-11-24 04:52:42,986 DEBUG : BROWSER_OUTPUT: RSS: Main: 224964608 04:52:42 INFO - 2015-11-24 04:52:42,986 DEBUG : BROWSER_OUTPUT: 04:53:39 INFO - 2015-11-24 04:53:39,988 DEBUG : BROWSER_OUTPUT: 1448369619985 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] 04:55:45 INFO - 2015-11-24 04:55:45,385 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49660/tests/dromaeo/cssquery-ext.html) 04:55:46 INFO - 2015-11-24 04:55:46,903 DEBUG : BROWSER_OUTPUT: RSS: Main: 361603072 04:55:46 INFO - 2015-11-24 04:55:46,903 DEBUG : BROWSER_OUTPUT: 04:58:49 INFO - 2015-11-24 04:58:49,575 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/dromaeo/cssquery-ext.html (next: http://localhost:49660/tests/dromaeo/cssquery-jquery.html) 04:58:50 INFO - 2015-11-24 04:58:50,162 DEBUG : BROWSER_OUTPUT: RSS: Main: 367456256 04:58:50 INFO - 2015-11-24 04:58:50,162 DEBUG : BROWSER_OUTPUT: 05:01:52 INFO - 2015-11-24 05:01:52,273 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49660/tests/dromaeo/cssquery-mootools.html) 05:01:52 INFO - 2015-11-24 05:01:52,855 DEBUG : BROWSER_OUTPUT: RSS: Main: 339398656 05:01:52 INFO - 2015-11-24 05:01:52,855 DEBUG : BROWSER_OUTPUT: 05:04:54 INFO - 2015-11-24 05:04:54,848 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49660/tests/dromaeo/cssquery-prototype.html) 05:04:55 INFO - 2015-11-24 05:04:55,440 DEBUG : BROWSER_OUTPUT: RSS: Main: 319184896 05:04:55 INFO - 2015-11-24 05:04:55,440 DEBUG : BROWSER_OUTPUT: 05:07:58 INFO - 2015-11-24 05:07:58,305 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49660/tests/dromaeo/cssquery-yui.html) 05:07:58 INFO - 2015-11-24 05:07:58,920 DEBUG : BROWSER_OUTPUT: RSS: Main: 323248128 05:07:58 INFO - 2015-11-24 05:07:58,921 DEBUG : BROWSER_OUTPUT: 05:11:01 INFO - 2015-11-24 05:11:01,864 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/dromaeo/cssquery-yui.html (next: http://localhost:49660/tests/dromaeo/cssquery-dojo.html) 05:11:02 INFO - 2015-11-24 05:11:02,123 DEBUG : BROWSER_OUTPUT: RSS: Main: 374108160 05:11:02 INFO - 2015-11-24 05:11:02,123 DEBUG : BROWSER_OUTPUT: 05:11:02 INFO - 2015-11-24 05:11:02,123 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:11:02 INFO - 2015-11-24 05:11:02,123 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:11:02 INFO - 2015-11-24 05:11:02,123 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:11:02 INFO - 2015-11-24 05:11:02,123 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:11:02 INFO - 2015-11-24 05:11:02,124 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3169.2235402326432;3385.373705864415;3405.7275417966566;3415.8633654653813;3425.811580363079;5475.09660905951;5577.637453565515;5695.487406133447;5695.515881150102;5741.339745929219;5662.943370566303;5722.885542289152;5733.455321744427;5739.483446489815;5739.913901291484;5146.974265128679;5181.3264275644115;5208.140656791625;5244.895102097956;5263.736813159346;5273.499017593323;5277.366715994086;5277.973610131944;5279.2872962150195;5285.630005899569;5247.107991641411;5249.448807875176;5249.711265880375;5251.842444726664;5266.973665131669;5217.791288348461;5224.242484839715;5244.344456942882;5246.921296180561;5263.763130659129;5107.412647545506;5129.717865517395;5225.634205605609;5233.659812112239;5249.317588713443;5733.082706766897;5739.971300143473;5760.36635970043;5772.278465191851;5779.624324418899;6285.811425657238;6286.402791734778;6329.493640508748;6330.050492426173;6332.081848131995;6131.14164017038;6208.441240288351;6266.154069200645;6290.748370065192;6326.145970293997;5909.3204281507315;5915.852103697442;5934.198883150806;5983.670898100603;5992.13114098462;6201.226517853124;6341.3341599132345;6346.921023426029;6352.36476352361;6353.142325786052;6011.579189456696;6057.818265452043;6068.987474007817;6109.799438327889;6147.477464415575;6317.241930968313;6335.303116657164;6338.334474880164;6357.427831495188;6365.554411191172;6220;6277.121203031579;6295.18162638854;6302.369763023753;6321.178246827975;6067.666278354733;6128.086481311612;6167.537434692416;6178.073289006595;6201.565890387629;4878.414590249193;4898.118338698997;4921.729304888266;4972.776225069881;5020.5732512736095;4428.13651337986;4446.244138496463;4466.330050492387;4488.3940668010055;4495.595396414273;5646.153077038477;5786.566007549366;5792;5795.391483894215;5810.389909059572;6164.4760195383;6172.969135154295;6222.502199824004;6345.777897773556;6369.426751592287;5754.913676294859;5783.277090363705;5802.593818432753;5818.738156782955;5881.852953676107;5303.549198318109;5466.426025267369;5470.425605311465;5474.206240095164;5477;6195.411435913581;6212.81361559154;6290.528210384148;6302.999999999908;6310.05349197624;6295.055741638791;6321.1782468278825;6336.429721325103;6342.460890824332;6348.904766428415;6198.287196972406;6346.238451385863;6352.205974253218;6358.523110766619;6361.332060133619;6107.704691583656;6139.3246742857355;6143.283313595389;6211.409916057967;6231.40801623845;6155.630662160284;6284.057391391328;6304.779332723333;6311.652859092794;6318.368163183645;6126.356732543019;6139.662318572521;6144.969275153596;6150.907736384047;6152.0156774915795;5865.3254875689845;6014.308354539284;6054.969725151346;6065.636061836304;6098.976888192089;6010.639361638316;6021.909671355021;6028.8794224116145;6032.819015429544;6038.758449661965;6139.416755408229;6145.262568491809;6159.045347970892;6165.167702360124;6172.4136207060255;5797.5361971041375;5869.559783016376;5887.8233652990475;5939.940600594112;6029.306629737461 05:11:02 INFO - 2015-11-24 05:11:02,124 DEBUG : BROWSER_OUTPUT: |1;ext.html;521.8851852592429;559.6502186133666;612.3447910735512;628.4846425930735;629.8425393651585;32398.19004524888;41431.171376572485;42032.159356812845;42099.57900420995;42360.36459453111;44562.77718611402;44968.775156124175;44983;45045;45155;8618.78453038676;8754.86867696985;9693.418394896311;9874.950625246864;9876;8654.048054713976;11835.526578936875;11980.880191198064;11983.340916249603;11994.520219191221;11811.350375729331;11931.880681193206;11937.58218462354;11943.820842687366;11944.462499187513;8722.215000649909;8854.911450885473;8870;11709.7658046839;11829.467673954692;11735.23720958135;11921.523139074427;11944.522219111224;11944.581939632071;11998.820017699742;40889;41237;41269.58730412687;41294.79352603248;41385.79307103475;3093.0404098801823;3138.5146425774465;3195.888143914952;3263.0424969368614;3513.508108864761;28760.424791504054;28902.566461503095;29015.564766528518;29533.409331813244;29621.037896210422;2786.21985843964;2850.2589326774983;2855.600215969766;2893.7829662775166;2990.5065664165604;456109;460164;468503;471333;472357;57767.13349299764;58740.412595874346;58924.7053764737;70068;70693.58612827817;62398.37601624017;62476;74069.51860962859;75130;75213;15984.920075399781;16052.518424447286;16112;16147.192640367935;16170.595735106717;12191.939040304742;12254.75490490203;12273.938630306791;12280.508779648893;12282.078844086729;21324.04041818122;21974.560508789735;22021.22925697625;22036.449088772908;22061.227857024925;9215.723528294162;9443.102905223992;9445.858312125323;9463.716088517356;9465.810683786285;951.3245595627044;954.7899462118323;971.9562619682131;1012.018342208057;1063.6596289187542;29694.703052969628;29750.553741693893;30198.84900575483;30407;30637.387252255277;1028.218553899042;1091.2252300866473;1147.9081673466103;1173.3018853781987;1225.730339325347;15098;15129.39482420691;15171;15414.99802512833;15426;1019602;1028847;1030783;1033072;1035700;62769;63205.36794632087;63214;63424.682876586245;63477;1444.4366696987972;1445.161806152429;1459.058907004976;1462.597785608966;1602.8717702583765;27166;29855.552166717083;30140.547891781203;30628.387432250784;30932.0720378389;48662.78343041452;49502.51492455232;51693.7415312921;55099.622509437584;56237.43762562322;341637;344162;348125;348967;349116.25441872625;33396.332073358884;33481;33579.83210083934;33716;33769;34220.48669270013;36749.81625091964;43408.74434417494;44390.3341449872;44656.55343446524;33812.830935845166;34927.30145397129;35437.64562354344;40645.593544064184;41602.58397415987;37084;37291.254174915804;38190.42714359342;38353.84938451851;38541.6145838538;29921.70078299276;38054.619453805106;38329.61670383372;38578;38608.42087368636;29360.853195733886;30987.760489581065;33160.50259246161;34415;34487.65512344844;25228.621570676823;25631.871840640677;25680.357991049626;25733.970641174143;26323.868380657976 05:11:02 INFO - 2015-11-24 05:11:02,127 DEBUG : BROWSER_OUTPUT: |2;jquery.html;295.8846050040484;360.40353215428473;364.13699532108905;364.84129403709375;364.9653282938121;15180.772288415683;15942.362305507766;19550.21799128035;19917.800821991776;20722.48193795154;18478.30433913216;18843.62312753744;18927.432177034676;20038.49903752407;20055.097520611573;6551.705173267191;6722.630255335955;6728.966355168217;6743;6758.290379510155;4088.897777555555;4902.926456103143;4916.606671466274;6597.274299827015;7403.666834992411;7311.488195826294;7525.435592330569;7585.886211706828;7613.276738709814;7650.770476885702;7451.403887688999;7478.775636730907;7489;7493.587852668101;7505.662245198979;5347.502699244218;5525.475079867406;5526.198701188346;5531.170324451325;7191.424686025105;13034.478620855154;13826.377812998438;15459.458918937895;24943.126990555244;24999;1919.1363886251243;1997.1512107354317;2069.9793002069937;2226.2430773537076;2285.8857057147243;9492.905070949271;9991;10226.028527289896;12872.420741066675;14135.293235338295;1689.2989409395086;1727.680379129865;1741.7474466202457;1783.1262681286205;1811.8912865228128;96245;100396.49801751018;100751.99999999927;101672.49163754134;102814.48592756988;11211.383373914514;11234.988851003449;11255.66233013011;11268.042216411533;11331.150163737753;13299.268540230187;13316.800247996287;13326.466941322433;13338.333083345793;13361.465541378431;7785.688572457038;7972.521648701097;7980.082290536547;8054.275115239657;8075.717349892843;7873;7886.763397098096;7892;7935.960320198477;7955.204479551999;4060.817263223162;4362.432883725095;4394.626456751148;4399.472063352354;4407.955920440819;1745.5636090977255;1788.97316540252;1795.057594762734;1838.411708253346;1844.9446516604523;809.9028116626042;908.4776253654043;911.1435250864166;919.9264058875274;986.8075725233654;15233.923830380778;17227.913860430617;19639.116239769246;19831.60336793256;20900.268490602757;864.7578677970179;895.4269267668698;948.7618657651693;950.1306304731231;978.7650963768787;8908.331875109392;10677.359358438518;10714.035736783728;10734.624288150033;10933.453327333602;639883;640467;716075.4196229089;716331;717237;90946;114285;114773.40258183825;115247;118834.8116518841;1108.728361551425;1213.4296880466168;1221.908356873238;1233.463443402123;1257.43415463042;13847.884226049462;17843.018633975254;18063.909680451514;19206.327778528244;19231.13459894336;39412.29462912578;49546.25680614866;50277.99999999854;50426;50457.24314135365;63017.68491157515;65777;67097.3290267091;67227.31090756315;69277;7945.523268603903;9885.406875587487;10018.599256029676;10170.440625765357;10227.48862556884;11693.590724324948;11810.232334898204;15037.6992460148;15371;16245.918770406071;15308.617284567976;15515.836312276399;15774.447894323643;15800.288986995383;15848;14195.148291102567;14583.562493125222;14767.483138090116;15483.84516154869;15616.063036217864;15698.351673074163;15792.526224213292;15937.920310398375;16404.835951640332;16445.506634800975;9482.525873706149;9596.95201523988;9733.951330243304;9749.36629119105;9755.268354873555;10035.448050357301;10107.898921010696;13740;14269.571912842632;15808.367665293259 05:11:02 INFO - 2015-11-24 05:11:02,128 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1530.9846901530984;1589.8330675279087;1603.8396160383963;1865.962680746385;2010.2160157538553;7769.145394006662;8005.599720013998;8127.756167314975;8231.91768082319;8237.6293066812;8341.62462689179;8458.238758511732;8496.915030849705;8510;8514.403991720583;2152.8589847380913;2347.812175025994;2358.0685629176464;2361.504084142332;2362.4684445999683;2135.5835612055657;2231.7656646052174;2255.1881322723807;2259.67234750961;2261.2650888461235;2341.683872677184;2350.21267875262;2350.8471949323325;2368.810495160383;2381.833271670984;2323.76762323768;2351.623740201568;2360.84654497458;2361.244401791427;2361.586722323595;2379.9643005354933;2380.0955636857884;2383.0825132324007;2388.785009349167;2389.4265376309736;7561.357284630813;8377.874331885027;8411.70559030431;8417.368697347662;8440.873386899202;4768.094062128184;4831.879203019917;5018.172001619729;5096.184610462308;5147.639665223436;3446.8621255149765;3472.791632502058;3551.875684351061;3556.1643013891717;3562.2697347043795;1034.4310629153936;1035.6892932120334;1046.2623850185678;1059.9788004239872;1062.378508572486;11055.447227638586;11163.386013769239;11173;11176.776464470746;11181.72045698856;4509.098180363941;4522.34426008227;4575.679702420865;4584.381108550303;4591.517890621437;7583.658735356922;8993.775155621162;9049.683261085831;9057.77355566116;9059.682911098213;5072.923906141411;5074.82238121664;5100.6429549931145;5109.9744501277255;5110.156824124052;4708.4820669726305;4718.339432479455;4726.71639701619;4738.431388233434;4739.170645137087;3540.2919416116365;3591.1381268495375;3626.1841085755495;3630.1650620357464;3663.358912190356;1100.5212732461405;1138.7267055906689;1141.2182654881433;1145.4158379226487;1145.6906635208447;1331.3010669398643;1332.6401871494643;1377.7313423882447;1382.046387992282;1384.3078460769616;7813.453058285865;7887.881681774778;7927.682892684228;7935.444518883623;7941.245581669732;499.1340025056524;504.11527768766314;506.364512536764;507.94158671752484;511.3505847573635;6021.096835474714;6160.845978850564;6247.875042499125;6313.9368606314265;6317.557770955989;8197.426180167331;8439.240468357877;8515;8531;8532.061473237938;9779.924208337077;9824.06671366219;10157.94921025405;10190.949045254727;10212.948935255276;7758.922410775933;7843.137254901956;7862.2923936845955;7872.881906771403;7920.722774702857;7828.373730101692;7871.448998570045;7877.369810415038;7919.762407127795;7926.167752386147;7469.290417410337;8845.51349675774;9142.588583513625;9149.679761208326;9200.815983680423;9217.861732074158;9303.674371396968;9377;9416.576254068446;9421;6080.513558915188;6863.313668633096;6993.615351155532;7025.754098606524;7168.8924666129005;8093;8146.022477302761;8154.266116049584;8158.714444994397;8159.632816523389;7505.774826755206;7575.772726818204;7580.772576822704;7692.000039994765;7724.382049435919;7472.327490525879;7514.135874374517;7526.435517336112;7527;7578.962105189659;7612.733554325572;7675.53946763196;7689.231076892266;7693.807654808675;7696.769096927102;6878.65606719672;6930.13373328334;6945.652717364011;6975.197852247056;6976.19773726028;7038.310805406768;7060.258672839484;7081.575105493687;7089.220185779457;7098.78703638871 05:11:02 INFO - 2015-11-24 05:11:02,132 DEBUG : BROWSER_OUTPUT: |4;prototype.html;315.1946775987352;319.38358967193307;326.8104101072099;326.98201598912055;330.5669572859554;6040.305364883035;6219.471344935675;6792.898106528406;6834.316568343163;6913.446924246061;6804.795856124312;6814.046033555306;6818.1136452261135;6823.488238382116;6851.451883849292;4967.701937883729;4981.526754958273;4987.875303117415;4989.401271847365;4993.725345106018;3983.6613887819426;4736.786844592001;4910.656254062217;4932.3341348917975;4942.431620363668;4845.806167753286;4861.197902346126;4864.562189402953;4867.853964381075;4873.634477414191;4596.35651008859;4745.549172828576;4780.163471392527;4877.634177436688;4892.315075889361;4042.0501182222447;4122.546519882811;4134.131832315187;4146.688998325138;4155.625993660555;6548.410643042149;6553.606783593;6560.803175904683;6561.3438656134485;6563.212414510242;5538.944610553924;5648;5658.179563963202;5704;5813.854653633651;7283.781486555412;7296.087989001375;7320.743773967939;7323.194448610649;7326.2673732626845;4667.626589872836;4680.308822427084;4684.578387945101;4688.6249100072255;4695.530446955311;7548.094228692537;7685.385169186452;7773.0672319321475;7773.805654858617;7799.103103143211;3930.6855451563806;4754.857354279377;4756.001239739624;4756.072565849626;4759.428868535798;4820.517948205222;4835.661503694708;4838.2016967200725;4838.395200599925;4850.708957462563;4533.569310915524;5813.970930145322;5820;5828.417158284223;5828.766849326064;7344.706211751577;7361.889571656429;7363.447741419415;7364.926350736531;7366.484346095829;1640.196303811124;1652.512508809904;1653.7106006449064;1656.1470842516114;1658.0963374960675;1046.1160319529986;1053.4258828938248;1065.7548763784373;1067.524951396621;1083.090204228452;6817.522773405814;6844.21291551468;6848.7260509580055;6850.143732033496;6888.276730943279;7620;7621.847563048819;7638.618069096523;7640.885386719204;7644.082710074716;5517.586181036439;6217.222847144107;6738.629375384303;6840.110785597841;6971.337722916315;3377.307651931348;3637.5089362936196;3773.924431537054;4060.0661847775177;4061.898452538651;8035.437519373588;8038.678452861937;8049.83900322002;8058.717944871902;8058.758237252891;7786.221377862168;7975.122736498981;8013.038435387674;8017.518948863087;8019.35845132388;8011.278984891388;8029.397795165502;8029.478083924526;8042.115367309591;8042.437029407885;6049.18336024631;7434.714616964801;7713.537187768752;7738.110117336579;7738.303552680286;6153.4154255345675;7469.962650186932;7550.131734850563;7579.355754760907;7584.734534291273;6424.357564243538;7997.560134192675;8014.198580142172;8020.598970051357;8029.678812847421;4586.9770651147865;4721.976390118028;4735.479097299363;4772.665913386029;4776.259679749644;7526.24737526243;7619.276168763959;7619.657115429708;7620.314171724572;7634.923650763643;5571.526420254162;7312.561246325237;7317.341439270491;7332.633368331455;7345.77962661121;5666.291713535808;6322.893493638687;6872.725090996304;7296.306850849161;7303.634818259172;7078.1860086090755;7130;7131.108611423572;7132.714691412285;7139.82150446243;7036;7276.070921702221;7306.744263950736;7308.8903666443985;7318.853622927618;7157.749478768218;7159;7162.319579640134;7171.713131474683;7172.139343278631 05:11:02 INFO - 2015-11-24 05:11:02,132 DEBUG : BROWSER_OUTPUT: |5;yui.html;682.9214640316363;759.4255473943333;790.9564973926435;791.4301702774004;828.1635548096428;14727.926360368196;15379.846201537981;15383.230838458088;15389.307481163332;15407.845921540782;14116.788248176286;14212.71574568508;14229.644258893533;14238.857611423882;14244.14535127893;754.3851760814946;759.5366826235992;761.9009528761281;934.4253284230183;951.0394501553446;791.8891355210246;951.6288647427509;963.3834346018554;964.0070727151045;966.5650457294211;996.8305388084043;1009.6415772400802;1011.6358111080006;1011.9544620492059;1013.2350075692879;786.7718361675107;800.2597597222575;970.6166064404591;993.6820217530393;999.4952548962764;738.2027410396759;741.8961345411647;742.5618884857905;742.6435311050672;744.5793126883348;15106.622334441618;15286.159261240628;15298.694026119416;15309.923450382788;15324.846751532454;12003.159778815487;12345.197562158432;12633.2420054797;12879.613611591574;13001.95984321252;8135.308498777552;10150.755759820971;10263.332883362633;10460.68617941463;10986;3482.0598438421484;3561.9643803562153;3618.8371523281517;3618.945715814265;3648.1062139775927;70350.59298813995;82465.76301355484;82474.58762706208;82654.17345826524;83354.16645833525;9442.433453992782;9486.33595648298;9508.667196648223;9516.372896192192;9665.516724163763;20992.580148396948;21192.36422907315;21237.893810530848;21250.25624103149;21288.042038108324;7005.299470053056;7147.249538798351;7160.176579693402;7198.424126069902;7300.707971681073;3916.5104361954754;3953.9604603954167;3956.802159891994;3964.52425708911;3978.900527486778;3519.085037890116;3563.7505374624047;3567.0725611341236;3568.518250036212;3572.0891172751303;629.8047605242298;633.1357696744003;648.5298158834808;652.9608223506605;657.4149007383432;497.9925301120486;509.8648858052619;519.3430310656946;535.606329347929;559.905384972378;7258.3104605062435;7673.846523069507;8477;8503.914960850436;8523.360747943929;530.2761730238198;577.6995962099769;589.8171566814301;618.5762752514541;651.5797310734549;3852.5569559500454;3935.9212815743526;4343.739375637409;4381.277089280233;4403.735775853459;84778.57610711906;85379.57310213409;85388.57305713555;85727.57136214404;88167.67748483778;73123;74045.25954740384;74497.25502745011;75489.86765198526;75863.65499795019;66036.66981665061;67483;72577.63711181516;72808.54382912313;74497;7838.568878711726;9151.816963660822;9176.495292758962;9248.306377021616;9303.906960930304;52495.95008099893;52750.47249527455;53639.19541206963;53797.462025379245;54786;67502.64994700177;72074;74279.62860185665;75608.86586701094;75703;10746.140308775435;10979.1216702662;11020.50407731638;11078.11375090007;11335.376554289593;11919.880801192225;12447.626571202878;12585.685357866127;12800.35998200105;12832.230066196058;11226.607068752555;11515.654530364103;11804.291742495478;11881.405929703307;12444.253344799341;11214.495347709535;11733.823992640288;11739.295642261492;12232.082182131977;12331.50673973031;10979.890201098206;11626.70933226676;11702.531898723955;11711.238769479956;11885.524579016743;9615.471149086867;9748.415095094033;9886.901130988597;9969.950150249493;10164.0344167304;9230.584623691815;9950.55222515003;10056.54745536438;10066.396016239049;10085.344452610558 05:11:02 INFO - 2015-11-24 05:11:02,132 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:11:02 INFO - 2015-11-24 05:11:02,132 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:11:02 INFO - 2015-11-24 05:11:02,132 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1316 05:11:02 INFO - 2015-11-24 05:11:02,132 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:11:02 INFO - 2015-11-24 05:11:02,132 DEBUG : BROWSER_OUTPUT: __startTimestamp1448370662118__endTimestamp 05:11:02 INFO - 2015-11-24 05:11:02,132 DEBUG : BROWSER_OUTPUT: 05:11:02 INFO - 2015-11-24 05:11:02,132 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:11:02 INFO - 2015-11-24 05:11:02,132 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:11:02 INFO - 2015-11-24 05:11:02,133 DEBUG : BROWSER_OUTPUT: 05:11:02 INFO - 2015-11-24 05:11:02,133 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5775.69 Median:6014.31 stddev:641.25 (10.7%) stddev-sans-first:609.74 05:11:02 INFO - 2015-11-24 05:11:02,134 DEBUG : BROWSER_OUTPUT: Values: 3169.2 3385.4 3405.7 3415.9 3425.8 5475.1 5577.6 5695.5 5695.5 5741.3 5662.9 5722.9 5733.5 5739.5 5739.9 5147.0 5181.3 5208.1 5244.9 5263.7 5273.5 5277.4 5278.0 5279.3 5285.6 5247.1 5249.4 5249.7 5251.8 5267.0 5217.8 5224.2 5244.3 5246.9 5263.8 5107.4 5129.7 5225.6 5233.7 5249.3 5733.1 5740.0 5760.4 5772.3 5779.6 6285.8 6286.4 6329.5 6330.1 6332.1 6131.1 6208.4 6266.2 6290.7 6326.1 5909.3 5915.9 5934.2 5983.7 5992.1 6201.2 6341.3 6346.9 6352.4 6353.1 6011.6 6057.8 6069.0 6109.8 6147.5 6317.2 6335.3 6338.3 6357.4 6365.6 6220.0 6277.1 6295.2 6302.4 6321.2 6067.7 6128.1 6167.5 6178.1 6201.6 4878.4 4898.1 4921.7 4972.8 5020.6 4428.1 4446.2 4466.3 4488.4 4495.6 5646.2 5786.6 5792.0 5795.4 5810.4 6164.5 6173.0 6222.5 6345.8 6369.4 5754.9 5783.3 5802.6 5818.7 5881.9 5303.5 5466.4 5470.4 5474.2 5477.0 6195.4 6212.8 6290.5 6303.0 6310.1 6295.1 6321.2 6336.4 6342.5 6348.9 6198.3 6346.2 6352.2 6358.5 6361.3 6107.7 6139.3 6143.3 6211.4 6231.4 6155.6 6284.1 6304.8 6311.7 6318.4 6126.4 6139.7 6145.0 6150.9 6152.0 5865.3 6014.3 6055.0 6065.6 6099.0 6010.6 6021.9 6028.9 6032.8 6038.8 6139.4 6145.3 6159.0 6165.2 6172.4 5797.5 5869.6 5887.8 5939.9 6029.3 05:11:02 INFO - 2015-11-24 05:11:02,134 DEBUG : BROWSER_OUTPUT: 05:11:02 INFO - 2015-11-24 05:11:02,135 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:74709.63 Median:33742.50 stddev:185381.25 (549.4%) stddev-sans-first:185817.60 05:11:02 INFO - 2015-11-24 05:11:02,136 DEBUG : BROWSER_OUTPUT: Values: 521.9 559.7 612.3 628.5 629.8 32398.2 41431.2 42032.2 42099.6 42360.4 44562.8 44968.8 44983.0 45045.0 45155.0 8618.8 8754.9 9693.4 9875.0 9876.0 8654.0 11835.5 11980.9 11983.3 11994.5 11811.4 11931.9 11937.6 11943.8 11944.5 8722.2 8854.9 8870.0 11709.8 11829.5 11735.2 11921.5 11944.5 11944.6 11998.8 40889.0 41237.0 41269.6 41294.8 41385.8 3093.0 3138.5 3195.9 3263.0 3513.5 28760.4 28902.6 29015.6 29533.4 29621.0 2786.2 2850.3 2855.6 2893.8 2990.5 456109.0 460164.0 468503.0 471333.0 472357.0 57767.1 58740.4 58924.7 70068.0 70693.6 62398.4 62476.0 74069.5 75130.0 75213.0 15984.9 16052.5 16112.0 16147.2 16170.6 12191.9 12254.8 12273.9 12280.5 12282.1 21324.0 21974.6 22021.2 22036.4 22061.2 9215.7 9443.1 9445.9 9463.7 9465.8 951.3 954.8 972.0 1012.0 1063.7 29694.7 29750.6 30198.8 30407.0 30637.4 1028.2 1091.2 1147.9 1173.3 1225.7 15098.0 15129.4 15171.0 15415.0 15426.0 1019602.0 1028847.0 1030783.0 1033072.0 1035700.0 62769.0 63205.4 63214.0 63424.7 63477.0 1444.4 1445.2 1459.1 1462.6 1602.9 27166.0 29855.6 30140.5 30628.4 30932.1 48662.8 49502.5 51693.7 55099.6 56237.4 341637.0 344162.0 348125.0 348967.0 349116.3 33396.3 33481.0 33579.8 33716.0 33769.0 34220.5 36749.8 43408.7 44390.3 44656.6 33812.8 34927.3 35437.6 40645.6 41602.6 37084.0 37291.3 38190.4 38353.8 38541.6 29921.7 38054.6 38329.6 38578.0 38608.4 29360.9 30987.8 33160.5 34415.0 34487.7 25228.6 25631.9 25680.4 25734.0 26323.9 05:11:02 INFO - 2015-11-24 05:11:02,136 DEBUG : BROWSER_OUTPUT: 05:11:02 INFO - 2015-11-24 05:11:02,136 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:36260.05 Median:11062.54 stddev:113086.18 (1022.2%) stddev-sans-first:113371.17 05:11:02 INFO - 2015-11-24 05:11:02,136 DEBUG : BROWSER_OUTPUT: Values: 295.9 360.4 364.1 364.8 365.0 15180.8 15942.4 19550.2 19917.8 20722.5 18478.3 18843.6 18927.4 20038.5 20055.1 6551.7 6722.6 6729.0 6743.0 6758.3 4088.9 4902.9 4916.6 6597.3 7403.7 7311.5 7525.4 7585.9 7613.3 7650.8 7451.4 7478.8 7489.0 7493.6 7505.7 5347.5 5525.5 5526.2 5531.2 7191.4 13034.5 13826.4 15459.5 24943.1 24999.0 1919.1 1997.2 2070.0 2226.2 2285.9 9492.9 9991.0 10226.0 12872.4 14135.3 1689.3 1727.7 1741.7 1783.1 1811.9 96245.0 100396.5 100752.0 101672.5 102814.5 11211.4 11235.0 11255.7 11268.0 11331.2 13299.3 13316.8 13326.5 13338.3 13361.5 7785.7 7972.5 7980.1 8054.3 8075.7 7873.0 7886.8 7892.0 7936.0 7955.2 4060.8 4362.4 4394.6 4399.5 4408.0 1745.6 1789.0 1795.1 1838.4 1844.9 809.9 908.5 911.1 919.9 986.8 15233.9 17227.9 19639.1 19831.6 20900.3 864.8 895.4 948.8 950.1 978.8 8908.3 10677.4 10714.0 10734.6 10933.5 639883.0 640467.0 716075.4 716331.0 717237.0 90946.0 114285.0 114773.4 115247.0 118834.8 1108.7 1213.4 1221.9 1233.5 1257.4 13847.9 17843.0 18063.9 19206.3 19231.1 39412.3 49546.3 50278.0 50426.0 50457.2 63017.7 65777.0 67097.3 67227.3 69277.0 7945.5 9885.4 10018.6 10170.4 10227.5 11693.6 11810.2 15037.7 15371.0 16245.9 15308.6 15515.8 15774.4 15800.3 15848.0 14195.1 14583.6 14767.5 15483.8 15616.1 15698.4 15792.5 15937.9 16404.8 16445.5 9482.5 9597.0 9734.0 9749.4 9755.3 10035.4 10107.9 13740.0 14269.6 15808.4 05:11:02 INFO - 2015-11-24 05:11:02,136 DEBUG : BROWSER_OUTPUT: 05:11:02 INFO - 2015-11-24 05:11:02,138 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5744.37 Median:6204.36 stddev:2981.40 (48.1%) stddev-sans-first:2972.95 05:11:02 INFO - 2015-11-24 05:11:02,138 DEBUG : BROWSER_OUTPUT: Values: 1531.0 1589.8 1603.8 1866.0 2010.2 7769.1 8005.6 8127.8 8231.9 8237.6 8341.6 8458.2 8496.9 8510.0 8514.4 2152.9 2347.8 2358.1 2361.5 2362.5 2135.6 2231.8 2255.2 2259.7 2261.3 2341.7 2350.2 2350.8 2368.8 2381.8 2323.8 2351.6 2360.8 2361.2 2361.6 2380.0 2380.1 2383.1 2388.8 2389.4 7561.4 8377.9 8411.7 8417.4 8440.9 4768.1 4831.9 5018.2 5096.2 5147.6 3446.9 3472.8 3551.9 3556.2 3562.3 1034.4 1035.7 1046.3 1060.0 1062.4 11055.4 11163.4 11173.0 11176.8 11181.7 4509.1 4522.3 4575.7 4584.4 4591.5 7583.7 8993.8 9049.7 9057.8 9059.7 5072.9 5074.8 5100.6 5110.0 5110.2 4708.5 4718.3 4726.7 4738.4 4739.2 3540.3 3591.1 3626.2 3630.2 3663.4 1100.5 1138.7 1141.2 1145.4 1145.7 1331.3 1332.6 1377.7 1382.0 1384.3 7813.5 7887.9 7927.7 7935.4 7941.2 499.1 504.1 506.4 507.9 511.4 6021.1 6160.8 6247.9 6313.9 6317.6 8197.4 8439.2 8515.0 8531.0 8532.1 9779.9 9824.1 10157.9 10190.9 10212.9 7758.9 7843.1 7862.3 7872.9 7920.7 7828.4 7871.4 7877.4 7919.8 7926.2 7469.3 8845.5 9142.6 9149.7 9200.8 9217.9 9303.7 9377.0 9416.6 9421.0 6080.5 6863.3 6993.6 7025.8 7168.9 8093.0 8146.0 8154.3 8158.7 8159.6 7505.8 7575.8 7580.8 7692.0 7724.4 7472.3 7514.1 7526.4 7527.0 7579.0 7612.7 7675.5 7689.2 7693.8 7696.8 6878.7 6930.1 6945.7 6975.2 6976.2 7038.3 7060.3 7081.6 7089.2 7098.8 05:11:02 INFO - 2015-11-24 05:11:02,138 DEBUG : BROWSER_OUTPUT: 05:11:02 INFO - 2015-11-24 05:11:02,138 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5909.75 Median:6765.76 stddev:1958.21 (28.9%) stddev-sans-first:1918.16 05:11:02 INFO - 2015-11-24 05:11:02,139 DEBUG : BROWSER_OUTPUT: Values: 315.2 319.4 326.8 327.0 330.6 6040.3 6219.5 6792.9 6834.3 6913.4 6804.8 6814.0 6818.1 6823.5 6851.5 4967.7 4981.5 4987.9 4989.4 4993.7 3983.7 4736.8 4910.7 4932.3 4942.4 4845.8 4861.2 4864.6 4867.9 4873.6 4596.4 4745.5 4780.2 4877.6 4892.3 4042.1 4122.5 4134.1 4146.7 4155.6 6548.4 6553.6 6560.8 6561.3 6563.2 5538.9 5648.0 5658.2 5704.0 5813.9 7283.8 7296.1 7320.7 7323.2 7326.3 4667.6 4680.3 4684.6 4688.6 4695.5 7548.1 7685.4 7773.1 7773.8 7799.1 3930.7 4754.9 4756.0 4756.1 4759.4 4820.5 4835.7 4838.2 4838.4 4850.7 4533.6 5814.0 5820.0 5828.4 5828.8 7344.7 7361.9 7363.4 7364.9 7366.5 1640.2 1652.5 1653.7 1656.1 1658.1 1046.1 1053.4 1065.8 1067.5 1083.1 6817.5 6844.2 6848.7 6850.1 6888.3 7620.0 7621.8 7638.6 7640.9 7644.1 5517.6 6217.2 6738.6 6840.1 6971.3 3377.3 3637.5 3773.9 4060.1 4061.9 8035.4 8038.7 8049.8 8058.7 8058.8 7786.2 7975.1 8013.0 8017.5 8019.4 8011.3 8029.4 8029.5 8042.1 8042.4 6049.2 7434.7 7713.5 7738.1 7738.3 6153.4 7470.0 7550.1 7579.4 7584.7 6424.4 7997.6 8014.2 8020.6 8029.7 4587.0 4722.0 4735.5 4772.7 4776.3 7526.2 7619.3 7619.7 7620.3 7634.9 5571.5 7312.6 7317.3 7332.6 7345.8 5666.3 6322.9 6872.7 7296.3 7303.6 7078.2 7130.0 7131.1 7132.7 7139.8 7036.0 7276.1 7306.7 7308.9 7318.9 7157.7 7159.0 7162.3 7171.7 7172.1 05:11:02 INFO - 2015-11-24 05:11:02,139 DEBUG : BROWSER_OUTPUT: 05:11:02 INFO - 2015-11-24 05:11:02,139 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:18358.83 Median:54291.73 stddev:25404.81 (46.8%) stddev-sans-first:25441.41 05:11:02 INFO - 2015-11-24 05:11:02,141 DEBUG : BROWSER_OUTPUT: Values: 682.9 759.4 791.0 791.4 828.2 14727.9 15379.8 15383.2 15389.3 15407.8 14116.8 14212.7 14229.6 14238.9 14244.1 754.4 759.5 761.9 934.4 951.0 791.9 951.6 963.4 964.0 966.6 996.8 1009.6 1011.6 1012.0 1013.2 786.8 800.3 970.6 993.7 999.5 738.2 741.9 742.6 742.6 744.6 15106.6 15286.2 15298.7 15309.9 15324.8 12003.2 12345.2 12633.2 12879.6 13002.0 8135.3 10150.8 10263.3 10460.7 10986.0 3482.1 3562.0 3618.8 3618.9 3648.1 70350.6 82465.8 82474.6 82654.2 83354.2 9442.4 9486.3 9508.7 9516.4 9665.5 20992.6 21192.4 21237.9 21250.3 21288.0 7005.3 7147.2 7160.2 7198.4 7300.7 3916.5 3954.0 3956.8 3964.5 3978.9 3519.1 3563.8 3567.1 3568.5 3572.1 629.8 633.1 648.5 653.0 657.4 498.0 509.9 519.3 535.6 559.9 7258.3 7673.8 8477.0 8503.9 8523.4 530.3 577.7 589.8 618.6 651.6 3852.6 3935.9 4343.7 4381.3 4403.7 84778.6 85379.6 85388.6 85727.6 88167.7 73123.0 74045.3 74497.3 75489.9 75863.7 66036.7 67483.0 72577.6 72808.5 74497.0 7838.6 9151.8 9176.5 9248.3 9303.9 52496.0 52750.5 53639.2 53797.5 54786.0 67502.6 72074.0 74279.6 75608.9 75703.0 10746.1 10979.1 11020.5 11078.1 11335.4 11919.9 12447.6 12585.7 12800.4 12832.2 11226.6 11515.7 11804.3 11881.4 12444.3 11214.5 11733.8 11739.3 12232.1 12331.5 10979.9 11626.7 11702.5 11711.2 11885.5 9615.5 9748.4 9886.9 9970.0 10164.0 9230.6 9950.6 10056.5 10066.4 10085.3 05:11:02 INFO - 2015-11-24 05:11:02,141 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:11:02 INFO - 2015-11-24 05:11:02,141 DEBUG : BROWSER_OUTPUT: 05:11:02 INFO - 2015-11-24 05:11:02,549 INFO : Browser exited with error code: 0 05:11:02 INFO - 2015-11-24 05:11:02,574 INFO : Completed test dromaeo_css (00:18:34) 05:11:02 INFO - 2015-11-24 05:11:02,574 INFO : Starting test kraken 05:11:02 INFO - 2015-11-24 05:11:02,575 DEBUG : operating with platform_type : mac_ 05:11:02 INFO - 2015-11-24 05:11:02,575 INFO : Initialising browser for kraken test... 05:11:02 INFO - 2015-11-24 05:11:02,588 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpJ1bZit/profile http://localhost:49660/getInfo.html 05:11:04 INFO - 2015-11-24 05:11:04,915 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:11:04 INFO - 2015-11-24 05:11:04,915 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:11:04 INFO - 2015-11-24 05:11:04,915 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 05:11:04 INFO - 2015-11-24 05:11:04,915 DEBUG : BROWSER_OUTPUT: __metrics 05:11:04 INFO - 2015-11-24 05:11:04,932 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:11:05 INFO - 2015-11-24 05:11:05,794 INFO : Browser initialized. 05:11:05 INFO - 2015-11-24 05:11:05,794 INFO : Running cycle 1/1 for kraken test... 05:11:05 INFO - 2015-11-24 05:11:05,794 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpJ1bZit/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:11:17 INFO - 2015-11-24 05:11:17,728 DEBUG : BROWSER_OUTPUT: RSS: Main: 225951744 05:11:17 INFO - 2015-11-24 05:11:17,728 DEBUG : BROWSER_OUTPUT: 05:11:20 INFO - 2015-11-24 05:11:20,386 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49660/tests/kraken/driver.html?testName=audio-beat-detection) 05:11:20 INFO - 2015-11-24 05:11:20,984 DEBUG : BROWSER_OUTPUT: RSS: Main: 274817024 05:11:20 INFO - 2015-11-24 05:11:20,984 DEBUG : BROWSER_OUTPUT: 05:11:23 INFO - 2015-11-24 05:11:23,665 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49660/tests/kraken/driver.html?testName=audio-dft) 05:11:24 INFO - 2015-11-24 05:11:24,253 DEBUG : BROWSER_OUTPUT: RSS: Main: 289058816 05:11:24 INFO - 2015-11-24 05:11:24,253 DEBUG : BROWSER_OUTPUT: 05:11:27 INFO - 2015-11-24 05:11:27,455 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49660/tests/kraken/driver.html?testName=audio-fft) 05:11:28 INFO - 2015-11-24 05:11:28,038 DEBUG : BROWSER_OUTPUT: RSS: Main: 277905408 05:11:28 INFO - 2015-11-24 05:11:28,038 DEBUG : BROWSER_OUTPUT: 05:11:29 INFO - 2015-11-24 05:11:29,986 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49660/tests/kraken/driver.html?testName=audio-oscillator) 05:11:30 INFO - 2015-11-24 05:11:30,576 DEBUG : BROWSER_OUTPUT: RSS: Main: 281612288 05:11:30 INFO - 2015-11-24 05:11:30,577 DEBUG : BROWSER_OUTPUT: 05:11:32 INFO - 2015-11-24 05:11:32,581 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49660/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:11:33 INFO - 2015-11-24 05:11:33,164 DEBUG : BROWSER_OUTPUT: RSS: Main: 280440832 05:11:33 INFO - 2015-11-24 05:11:33,164 DEBUG : BROWSER_OUTPUT: 05:11:35 INFO - 2015-11-24 05:11:35,859 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49660/tests/kraken/driver.html?testName=imaging-darkroom) 05:11:36 INFO - 2015-11-24 05:11:36,444 DEBUG : BROWSER_OUTPUT: RSS: Main: 281722880 05:11:36 INFO - 2015-11-24 05:11:36,444 DEBUG : BROWSER_OUTPUT: 05:11:39 INFO - 2015-11-24 05:11:39,238 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49660/tests/kraken/driver.html?testName=imaging-desaturate) 05:11:39 INFO - 2015-11-24 05:11:39,827 DEBUG : BROWSER_OUTPUT: RSS: Main: 281272320 05:11:39 INFO - 2015-11-24 05:11:39,827 DEBUG : BROWSER_OUTPUT: 05:11:42 INFO - 2015-11-24 05:11:42,689 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49660/tests/kraken/driver.html?testName=json-parse-financial) 05:11:43 INFO - 2015-11-24 05:11:43,289 DEBUG : BROWSER_OUTPUT: RSS: Main: 281079808 05:11:43 INFO - 2015-11-24 05:11:43,289 DEBUG : BROWSER_OUTPUT: 05:11:44 INFO - 2015-11-24 05:11:44,804 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49660/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:11:45 INFO - 2015-11-24 05:11:45,384 DEBUG : BROWSER_OUTPUT: RSS: Main: 314761216 05:11:45 INFO - 2015-11-24 05:11:45,385 DEBUG : BROWSER_OUTPUT: 05:11:47 INFO - 2015-11-24 05:11:47,141 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49660/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:11:47 INFO - 2015-11-24 05:11:47,762 DEBUG : BROWSER_OUTPUT: RSS: Main: 316723200 05:11:47 INFO - 2015-11-24 05:11:47,762 DEBUG : BROWSER_OUTPUT: 05:11:49 INFO - 2015-11-24 05:11:49,801 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49660/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:11:50 INFO - 2015-11-24 05:11:50,386 DEBUG : BROWSER_OUTPUT: RSS: Main: 323682304 05:11:50 INFO - 2015-11-24 05:11:50,386 DEBUG : BROWSER_OUTPUT: 05:11:52 INFO - 2015-11-24 05:11:52,923 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49660/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:11:53 INFO - 2015-11-24 05:11:53,523 DEBUG : BROWSER_OUTPUT: RSS: Main: 325124096 05:11:53 INFO - 2015-11-24 05:11:53,523 DEBUG : BROWSER_OUTPUT: 05:11:55 INFO - 2015-11-24 05:11:55,953 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49660/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:11:56 INFO - 2015-11-24 05:11:56,545 DEBUG : BROWSER_OUTPUT: RSS: Main: 325562368 05:11:56 INFO - 2015-11-24 05:11:56,546 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,202 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49660/tests/kraken/driver.html?testName=ai-astar) 05:11:58 INFO - 2015-11-24 05:11:58,462 DEBUG : BROWSER_OUTPUT: RSS: Main: 384471040 05:11:58 INFO - 2015-11-24 05:11:58,463 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,463 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:11:58 INFO - 2015-11-24 05:11:58,463 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:11:58 INFO - 2015-11-24 05:11:58,463 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:11:58 INFO - 2015-11-24 05:11:58,463 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:11:58 INFO - 2015-11-24 05:11:58,463 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;111;103;113;150;148;100;109;109;104 05:11:58 INFO - 2015-11-24 05:11:58,463 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;118;142;159;138;170;167;147;155;117 05:11:58 INFO - 2015-11-24 05:11:58,463 DEBUG : BROWSER_OUTPUT: |2;audio-dft;185;181;183;180;189;182;184;181;182;184 05:11:58 INFO - 2015-11-24 05:11:58,463 DEBUG : BROWSER_OUTPUT: |3;audio-fft;60;67;75;69;60;78;82;93;78;54 05:11:58 INFO - 2015-11-24 05:11:58,463 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;85;83;114;76;75;74;112;76;74;110 05:11:58 INFO - 2015-11-24 05:11:58,463 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;88;89;88;89;88;88;89;89;89;89 05:11:58 INFO - 2015-11-24 05:11:58,463 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;95;96;95;96;94;96;96;95;95;95 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;91;133;136;87;88;91;87;87;89;86 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;54;54;55;53;54;54;65;54;53 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;62;63;62;63;63;68;65;66;65;67 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;86;83;83;81;82;84;82;81;83;83 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;124;132;130;121;117;133;122;106;119;127 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;138;126;140;139;136;125;139;137;134;126 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;65;66;64;65;65;65;64;65;65 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1034 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: __startTimestamp1448370718455__endTimestamp 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,464 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:115.60 Median:110.00 stddev:18.04 (16.4%) stddev-sans-first:18.97 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 103.0 113.0 150.0 148.0 100.0 109.0 109.0 104.0 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:146.30 Median:152.50 stddev:18.22 (11.9%) stddev-sans-first:19.28 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: Values: 150.0 118.0 142.0 159.0 138.0 170.0 167.0 147.0 155.0 117.0 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:183.10 Median:183.50 stddev:2.60 (1.4%) stddev-sans-first:2.67 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: Values: 185.0 181.0 183.0 180.0 189.0 182.0 184.0 181.0 182.0 184.0 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:71.60 Median:76.50 stddev:11.86 (15.5%) stddev-sans-first:11.82 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: Values: 60.0 67.0 75.0 69.0 60.0 78.0 82.0 93.0 78.0 54.0 05:11:58 INFO - 2015-11-24 05:11:58,465 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:87.90 Median:75.50 stddev:17.06 (22.6%) stddev-sans-first:18.06 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: Values: 85.0 83.0 114.0 76.0 75.0 74.0 112.0 76.0 74.0 110.0 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:89.00 stddev:0.52 (0.6%) stddev-sans-first:0.50 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: Values: 88.0 89.0 88.0 89.0 88.0 88.0 89.0 89.0 89.0 89.0 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 96.0 94.0 96.0 96.0 95.0 95.0 95.0 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:97.50 Median:87.50 stddev:19.59 (22.4%) stddev-sans-first:20.63 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: Values: 91.0 133.0 136.0 87.0 88.0 91.0 87.0 87.0 89.0 86.0 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.20 Median:54.00 stddev:3.55 (6.6%) stddev-sans-first:3.76 05:11:58 INFO - 2015-11-24 05:11:58,466 DEBUG : BROWSER_OUTPUT: Values: 56.0 54.0 54.0 55.0 53.0 54.0 54.0 65.0 54.0 53.0 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.40 Median:65.00 stddev:2.12 (3.3%) stddev-sans-first:2.06 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: Values: 62.0 63.0 62.0 63.0 63.0 68.0 65.0 66.0 65.0 67.0 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.00 stddev:1.48 (1.8%) stddev-sans-first:1.01 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: Values: 86.0 83.0 83.0 81.0 82.0 84.0 82.0 81.0 83.0 83.0 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.10 Median:125.50 stddev:8.12 (6.5%) stddev-sans-first:8.60 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: Values: 124.0 132.0 130.0 121.0 117.0 133.0 122.0 106.0 119.0 127.0 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.00 Median:137.50 stddev:6.00 (4.4%) stddev-sans-first:6.19 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: Values: 138.0 126.0 140.0 139.0 136.0 125.0 139.0 137.0 134.0 126.0 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 05:11:58 INFO - 2015-11-24 05:11:58,467 DEBUG : BROWSER_OUTPUT: Values: 65.0 65.0 66.0 64.0 65.0 65.0 65.0 64.0 65.0 65.0 05:11:58 INFO - 2015-11-24 05:11:58,468 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:11:58 INFO - 2015-11-24 05:11:58,468 DEBUG : BROWSER_OUTPUT: 05:11:59 INFO - 2015-11-24 05:11:59,064 INFO : Browser exited with error code: 0 05:11:59 INFO - 2015-11-24 05:11:59,112 INFO : Completed test kraken (00:00:56) 05:11:59 INFO - 2015-11-24 05:11:59,112 INFO : Starting test v8_7 05:11:59 INFO - 2015-11-24 05:11:59,112 DEBUG : operating with platform_type : mac_ 05:11:59 INFO - 2015-11-24 05:11:59,113 INFO : Initialising browser for v8_7 test... 05:11:59 INFO - 2015-11-24 05:11:59,126 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpZfsLSh/profile http://localhost:49660/getInfo.html 05:12:01 INFO - 2015-11-24 05:12:01,154 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:12:01 INFO - 2015-11-24 05:12:01,154 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:12:01 INFO - 2015-11-24 05:12:01,155 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 05:12:01 INFO - 2015-11-24 05:12:01,155 DEBUG : BROWSER_OUTPUT: __metrics 05:12:01 INFO - 2015-11-24 05:12:01,162 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:12:01 INFO - 2015-11-24 05:12:01,426 DEBUG : BROWSER_OUTPUT: console.error: 05:12:01 INFO - 2015-11-24 05:12:01,427 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction. 05:12:01 INFO - 2015-11-24 05:12:01,427 DEBUG : BROWSER_OUTPUT: Stack: 05:12:01 INFO - 2015-11-24 05:12:01,427 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 05:12:01 INFO - 2015-11-24 05:12:01,429 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 05:12:01 INFO - 2015-11-24 05:12:01,429 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 05:12:01 INFO - 2015-11-24 05:12:01,429 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 05:12:01 INFO - 2015-11-24 05:12:01,429 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 05:12:01 INFO - 2015-11-24 05:12:01,429 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 05:12:01 INFO - 2015-11-24 05:12:01,429 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 05:12:01 INFO - 2015-11-24 05:12:01,429 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7 05:12:01 INFO - 2015-11-24 05:12:01,429 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 05:12:01 INFO - 2015-11-24 05:12:01,429 DEBUG : BROWSER_OUTPUT: 05:12:01 INFO - 2015-11-24 05:12:01,846 INFO : Browser initialized. 05:12:01 INFO - 2015-11-24 05:12:01,847 INFO : Running cycle 1/1 for v8_7 test... 05:12:01 INFO - 2015-11-24 05:12:01,847 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpZfsLSh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:12:13 INFO - 2015-11-24 05:12:13,634 DEBUG : BROWSER_OUTPUT: RSS: Main: 225189888 05:12:13 INFO - 2015-11-24 05:12:13,634 DEBUG : BROWSER_OUTPUT: 05:12:35 INFO - 2015-11-24 05:12:35,153 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49660/tests/v8_7/run.html (next: http://localhost:49660/tests/v8_7/run.html) 05:12:35 INFO - 2015-11-24 05:12:35,397 DEBUG : BROWSER_OUTPUT: RSS: Main: 665538560 05:12:35 INFO - 2015-11-24 05:12:35,397 DEBUG : BROWSER_OUTPUT: 05:12:35 INFO - 2015-11-24 05:12:35,397 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:12:35 INFO - 2015-11-24 05:12:35,398 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:12:35 INFO - 2015-11-24 05:12:35,398 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:12:35 INFO - 2015-11-24 05:12:35,398 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:12:35 INFO - 2015-11-24 05:12:35,398 DEBUG : BROWSER_OUTPUT: |0;Richards;175.13134851138355 05:12:35 INFO - 2015-11-24 05:12:35,398 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;160.72002571520412 05:12:35 INFO - 2015-11-24 05:12:35,398 DEBUG : BROWSER_OUTPUT: |2;Encrypt;285.143997718848 05:12:35 INFO - 2015-11-24 05:12:35,398 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4830.917874396136 05:12:35 INFO - 2015-11-24 05:12:35,398 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1731.833910034602 05:12:35 INFO - 2015-11-24 05:12:35,398 DEBUG : BROWSER_OUTPUT: |5;Earley;792.3930269413629 05:12:35 INFO - 2015-11-24 05:12:35,398 DEBUG : BROWSER_OUTPUT: |6;Boyer;13413.333333333334 05:12:35 INFO - 2015-11-24 05:12:35,398 DEBUG : BROWSER_OUTPUT: |7;RegExp;28055.555555555555 05:12:35 INFO - 2015-11-24 05:12:35,398 DEBUG : BROWSER_OUTPUT: |8;Splay;636.9426751592357 05:12:35 INFO - 2015-11-24 05:12:35,398 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4542.986425339366 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: __startTimestamp1448370755396__endTimestamp 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:175.13 Median:175.13 stddev:0.00 (0.0%) 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: Values: 175.1 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:160.72 Median:160.72 stddev:0.00 (0.0%) 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: Values: 160.7 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:285.14 Median:285.14 stddev:0.00 (0.0%) 05:12:35 INFO - 2015-11-24 05:12:35,399 DEBUG : BROWSER_OUTPUT: Values: 285.1 05:12:35 INFO - 2015-11-24 05:12:35,400 DEBUG : BROWSER_OUTPUT: 05:12:35 INFO - 2015-11-24 05:12:35,400 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 05:12:35 INFO - 2015-11-24 05:12:35,400 DEBUG : BROWSER_OUTPUT: Values: 4830.9 05:12:35 INFO - 2015-11-24 05:12:35,400 DEBUG : BROWSER_OUTPUT: 05:12:35 INFO - 2015-11-24 05:12:35,400 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1731.83 Median:1731.83 stddev:0.00 (0.0%) 05:12:35 INFO - 2015-11-24 05:12:35,400 DEBUG : BROWSER_OUTPUT: Values: 1731.8 05:12:35 INFO - 2015-11-24 05:12:35,400 DEBUG : BROWSER_OUTPUT: 05:12:35 INFO - 2015-11-24 05:12:35,400 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:792.39 Median:792.39 stddev:0.00 (0.0%) 05:12:35 INFO - 2015-11-24 05:12:35,400 DEBUG : BROWSER_OUTPUT: Values: 792.4 05:12:35 INFO - 2015-11-24 05:12:35,400 DEBUG : BROWSER_OUTPUT: 05:12:35 INFO - 2015-11-24 05:12:35,400 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:13413.33 Median:13413.33 stddev:0.00 (0.0%) 05:12:35 INFO - 2015-11-24 05:12:35,401 DEBUG : BROWSER_OUTPUT: Values: 13413.3 05:12:35 INFO - 2015-11-24 05:12:35,401 DEBUG : BROWSER_OUTPUT: 05:12:35 INFO - 2015-11-24 05:12:35,401 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:28055.56 Median:28055.56 stddev:0.00 (0.0%) 05:12:35 INFO - 2015-11-24 05:12:35,401 DEBUG : BROWSER_OUTPUT: Values: 28055.6 05:12:35 INFO - 2015-11-24 05:12:35,401 DEBUG : BROWSER_OUTPUT: 05:12:35 INFO - 2015-11-24 05:12:35,401 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:636.94 Median:636.94 stddev:0.00 (0.0%) 05:12:35 INFO - 2015-11-24 05:12:35,401 DEBUG : BROWSER_OUTPUT: Values: 636.9 05:12:35 INFO - 2015-11-24 05:12:35,401 DEBUG : BROWSER_OUTPUT: 05:12:35 INFO - 2015-11-24 05:12:35,402 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4542.99 Median:4542.99 stddev:0.00 (0.0%) 05:12:35 INFO - 2015-11-24 05:12:35,402 DEBUG : BROWSER_OUTPUT: Values: 4543.0 05:12:35 INFO - 2015-11-24 05:12:35,402 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:12:35 INFO - 2015-11-24 05:12:35,402 DEBUG : BROWSER_OUTPUT: 05:12:36 INFO - 2015-11-24 05:12:36,070 INFO : Browser exited with error code: 0 05:12:36 INFO - 2015-11-24 05:12:36,087 INFO : Completed test v8_7 (00:00:36) 05:12:36 INFO - 2015-11-24 05:12:36,419 INFO : Completed test suite (00:20:08) 05:12:36 INFO - 2015-11-24 05:12:36,419 DEBUG : Working with test: dromaeo_css 05:12:36 INFO - 2015-11-24 05:12:36,419 DEBUG : Generating results file: dromaeo_css 05:12:36 INFO - 2015-11-24 05:12:36,420 DEBUG : Working with test: kraken 05:12:36 INFO - 2015-11-24 05:12:36,420 DEBUG : Generating results file: kraken 05:12:36 INFO - 2015-11-24 05:12:36,420 INFO : javascript benchmark 05:12:36 INFO - 2015-11-24 05:12:36,420 DEBUG : Working with test: v8_7 05:12:36 INFO - 2015-11-24 05:12:36,420 DEBUG : Generating results file: v8_7 05:12:36 INFO - 2015-11-24 05:12:36,421 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:12:36 INFO - 2015-11-24 05:12:36,816 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,59]] 05:12:36 INFO - 2015-11-24 05:12:36,816 DEBUG : process_Request line: dromaeo_css 7628.63 graph.html#tests=[[72,1,59]] 05:12:36 INFO - 2015-11-24 05:12:36,816 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:12:36 INFO - 2015-11-24 05:12:36,860 DEBUG : process_Request line: kraken 1410.30 graph.html#tests=[[232,1,59]] 05:12:36 INFO - 2015-11-24 05:12:36,860 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:12:36 INFO - 2015-11-24 05:12:36,897 DEBUG : process_Request line: v8_7 20101.70 graph.html#tests=[[230,1,59]] 05:12:36 INFO - 2015-11-24 05:12:36,898 INFO : javascript benchmark 05:12:36 INFO - 2015-11-24 05:12:36,900 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3169.2235402326432, 3385.373705864415, 3405.7275417966566, 3415.8633654653813, 3425.811580363079, 5475.09660905951, 5577.637453565515, 5695.487406133447, 5695.515881150102, 5741.339745929219, 5662.943370566303, 5722.885542289152, 5733.455321744427, 5739.483446489815, 5739.913901291484, 5146.974265128679, 5181.3264275644115, 5208.140656791625, 5244.895102097956, 5263.736813159346, 5273.499017593323, 5277.366715994086, 5277.973610131944, 5279.2872962150195, 5285.630005899569, 5247.107991641411, 5249.448807875176, 5249.711265880375, 5251.842444726664, 5266.973665131669, 5217.791288348461, 5224.242484839715, 5244.344456942882, 5246.921296180561, 5263.763130659129, 5107.412647545506, 5129.717865517395, 5225.634205605609, 5233.659812112239, 5249.317588713443, 5733.082706766897, 5739.971300143473, 5760.36635970043, 5772.278465191851, 5779.624324418899, 6285.811425657238, 6286.402791734778, 6329.493640508748, 6330.050492426173, 6332.081848131995, 6131.14164017038, 6208.441240288351, 6266.154069200645, 6290.748370065192, 6326.145970293997, 5909.3204281507315, 5915.852103697442, 5934.198883150806, 5983.670898100603, 5992.13114098462, 6201.226517853124, 6341.3341599132345, 6346.921023426029, 6352.36476352361, 6353.142325786052, 6011.579189456696, 6057.818265452043, 6068.987474007817, 6109.799438327889, 6147.477464415575, 6317.241930968313, 6335.303116657164, 6338.334474880164, 6357.427831495188, 6365.554411191172, 6220.0, 6277.121203031579, 6295.18162638854, 6302.369763023753, 6321.178246827975, 6067.666278354733, 6128.086481311612, 6167.537434692416, 6178.073289006595, 6201.565890387629, 4878.414590249193, 4898.118338698997, 4921.729304888266, 4972.776225069881, 5020.5732512736095, 4428.13651337986, 4446.244138496463, 4466.330050492387, 4488.3940668010055, 4495.595396414273, 5646.153077038477, 5786.566007549366, 5792.0, 5795.391483894215, 5810.389909059572, 6164.4760195383, 6172.969135154295, 6222.502199824004, 6345.777897773556, 6369.426751592287, 5754.913676294859, 5783.277090363705, 5802.593818432753, 5818.738156782955, 5881.852953676107, 5303.549198318109, 5466.426025267369, 5470.425605311465, 5474.206240095164, 5477.0, 6195.411435913581, 6212.81361559154, 6290.528210384148, 6302.999999999908, 6310.05349197624, 6295.055741638791, 6321.1782468278825, 6336.429721325103, 6342.460890824332, 6348.904766428415, 6198.287196972406, 6346.238451385863, 6352.205974253218, 6358.523110766619, 6361.332060133619, 6107.704691583656, 6139.3246742857355, 6143.283313595389, 6211.409916057967, 6231.40801623845, 6155.630662160284, 6284.057391391328, 6304.779332723333, 6311.652859092794, 6318.368163183645, 6126.356732543019, 6139.662318572521, 6144.969275153596, 6150.907736384047, 6152.0156774915795, 5865.3254875689845, 6014.308354539284, 6054.969725151346, 6065.636061836304, 6098.976888192089, 6010.639361638316, 6021.909671355021, 6028.8794224116145, 6032.819015429544, 6038.758449661965, 6139.416755408229, 6145.262568491809, 6159.045347970892, 6165.167702360124, 6172.4136207060255, 5797.5361971041375, 5869.559783016376, 5887.8233652990475, 5939.940600594112, 6029.306629737461], "unit": "ms", "name": "dojo.html", "value": 5733.5913327048265}, {"lowerIsBetter": false, "replicates": [521.8851852592429, 559.6502186133666, 612.3447910735512, 628.4846425930735, 629.8425393651585, 32398.19004524888, 41431.171376572485, 42032.159356812845, 42099.57900420995, 42360.36459453111, 44562.77718611402, 44968.775156124175, 44983.0, 45045.0, 45155.0, 8618.78453038676, 8754.86867696985, 9693.418394896311, 9874.950625246864, 9876.0, 8654.048054713976, 11835.526578936875, 11980.880191198064, 11983.340916249603, 11994.520219191221, 11811.350375729331, 11931.880681193206, 11937.58218462354, 11943.820842687366, 11944.462499187513, 8722.215000649909, 8854.911450885473, 8870.0, 11709.7658046839, 11829.467673954692, 11735.23720958135, 11921.523139074427, 11944.522219111224, 11944.581939632071, 11998.820017699742, 40889.0, 41237.0, 41269.58730412687, 41294.79352603248, 41385.79307103475, 3093.0404098801823, 3138.5146425774465, 3195.888143914952, 3263.0424969368614, 3513.508108864761, 28760.424791504054, 28902.566461503095, 29015.564766528518, 29533.409331813244, 29621.037896210422, 2786.21985843964, 2850.2589326774983, 2855.600215969766, 2893.7829662775166, 2990.5065664165604, 456109.0, 460164.0, 468503.0, 471333.0, 472357.0, 57767.13349299764, 58740.412595874346, 58924.7053764737, 70068.0, 70693.58612827817, 62398.37601624017, 62476.0, 74069.51860962859, 75130.0, 75213.0, 15984.920075399781, 16052.518424447286, 16112.0, 16147.192640367935, 16170.595735106717, 12191.939040304742, 12254.75490490203, 12273.938630306791, 12280.508779648893, 12282.078844086729, 21324.04041818122, 21974.560508789735, 22021.22925697625, 22036.449088772908, 22061.227857024925, 9215.723528294162, 9443.102905223992, 9445.858312125323, 9463.716088517356, 9465.810683786285, 951.3245595627044, 954.7899462118323, 971.9562619682131, 1012.018342208057, 1063.6596289187542, 29694.703052969628, 29750.553741693893, 30198.84900575483, 30407.0, 30637.387252255277, 1028.218553899042, 1091.2252300866473, 1147.9081673466103, 1173.3018853781987, 1225.730339325347, 15098.0, 15129.39482420691, 15171.0, 15414.99802512833, 15426.0, 1019602.0, 1028847.0, 1030783.0, 1033072.0, 1035700.0, 62769.0, 63205.36794632087, 63214.0, 63424.682876586245, 63477.0, 1444.4366696987972, 1445.161806152429, 1459.058907004976, 1462.597785608966, 1602.8717702583765, 27166.0, 29855.552166717083, 30140.547891781203, 30628.387432250784, 30932.0720378389, 48662.78343041452, 49502.51492455232, 51693.7415312921, 55099.622509437584, 56237.43762562322, 341637.0, 344162.0, 348125.0, 348967.0, 349116.25441872625, 33396.332073358884, 33481.0, 33579.83210083934, 33716.0, 33769.0, 34220.48669270013, 36749.81625091964, 43408.74434417494, 44390.3341449872, 44656.55343446524, 33812.830935845166, 34927.30145397129, 35437.64562354344, 40645.593544064184, 41602.58397415987, 37084.0, 37291.254174915804, 38190.42714359342, 38353.84938451851, 38541.6145838538, 29921.70078299276, 38054.619453805106, 38329.61670383372, 38578.0, 38608.42087368636, 29360.853195733886, 30987.760489581065, 33160.50259246161, 34415.0, 34487.65512344844, 25228.621570676823, 25631.871840640677, 25680.357991049626, 25733.970641174143, 26323.868380657976], "unit": "ms", "name": "ext.html", "value": 20701.526339941825}, {"lowerIsBetter": false, "replicates": [295.8846050040484, 360.40353215428473, 364.13699532108905, 364.84129403709375, 364.9653282938121, 15180.772288415683, 15942.362305507766, 19550.21799128035, 19917.800821991776, 20722.48193795154, 18478.30433913216, 18843.62312753744, 18927.432177034676, 20038.49903752407, 20055.097520611573, 6551.705173267191, 6722.630255335955, 6728.966355168217, 6743.0, 6758.290379510155, 4088.897777555555, 4902.926456103143, 4916.606671466274, 6597.274299827015, 7403.666834992411, 7311.488195826294, 7525.435592330569, 7585.886211706828, 7613.276738709814, 7650.770476885702, 7451.403887688999, 7478.775636730907, 7489.0, 7493.587852668101, 7505.662245198979, 5347.502699244218, 5525.475079867406, 5526.198701188346, 5531.170324451325, 7191.424686025105, 13034.478620855154, 13826.377812998438, 15459.458918937895, 24943.126990555244, 24999.0, 1919.1363886251243, 1997.1512107354317, 2069.9793002069937, 2226.2430773537076, 2285.8857057147243, 9492.905070949271, 9991.0, 10226.028527289896, 12872.420741066675, 14135.293235338295, 1689.2989409395086, 1727.680379129865, 1741.7474466202457, 1783.1262681286205, 1811.8912865228128, 96245.0, 100396.49801751018, 100751.99999999927, 101672.49163754134, 102814.48592756988, 11211.383373914514, 11234.988851003449, 11255.66233013011, 11268.042216411533, 11331.150163737753, 13299.268540230187, 13316.800247996287, 13326.466941322433, 13338.333083345793, 13361.465541378431, 7785.688572457038, 7972.521648701097, 7980.082290536547, 8054.275115239657, 8075.717349892843, 7873.0, 7886.763397098096, 7892.0, 7935.960320198477, 7955.204479551999, 4060.817263223162, 4362.432883725095, 4394.626456751148, 4399.472063352354, 4407.955920440819, 1745.5636090977255, 1788.97316540252, 1795.057594762734, 1838.411708253346, 1844.9446516604523, 809.9028116626042, 908.4776253654043, 911.1435250864166, 919.9264058875274, 986.8075725233654, 15233.923830380778, 17227.913860430617, 19639.116239769246, 19831.60336793256, 20900.268490602757, 864.7578677970179, 895.4269267668698, 948.7618657651693, 950.1306304731231, 978.7650963768787, 8908.331875109392, 10677.359358438518, 10714.035736783728, 10734.624288150033, 10933.453327333602, 639883.0, 640467.0, 716075.4196229089, 716331.0, 717237.0, 90946.0, 114285.0, 114773.40258183825, 115247.0, 118834.8116518841, 1108.728361551425, 1213.4296880466168, 1221.908356873238, 1233.463443402123, 1257.43415463042, 13847.884226049462, 17843.018633975254, 18063.909680451514, 19206.327778528244, 19231.13459894336, 39412.29462912578, 49546.25680614866, 50277.99999999854, 50426.0, 50457.24314135365, 63017.68491157515, 65777.0, 67097.3290267091, 67227.31090756315, 69277.0, 7945.523268603903, 9885.406875587487, 10018.599256029676, 10170.440625765357, 10227.48862556884, 11693.590724324948, 11810.232334898204, 15037.6992460148, 15371.0, 16245.918770406071, 15308.617284567976, 15515.836312276399, 15774.447894323643, 15800.288986995383, 15848.0, 14195.148291102567, 14583.562493125222, 14767.483138090116, 15483.84516154869, 15616.063036217864, 15698.351673074163, 15792.526224213292, 15937.920310398375, 16404.835951640332, 16445.506634800975, 9482.525873706149, 9596.95201523988, 9733.951330243304, 9749.36629119105, 9755.268354873555, 10035.448050357301, 10107.898921010696, 13740.0, 14269.571912842632, 15808.367665293259], "unit": "ms", "name": "jquery.html", "value": 9785.018671083466}, {"lowerIsBetter": false, "replicates": [1530.9846901530984, 1589.8330675279087, 1603.8396160383963, 1865.962680746385, 2010.2160157538553, 7769.145394006662, 8005.599720013998, 8127.756167314975, 8231.91768082319, 8237.6293066812, 8341.62462689179, 8458.238758511732, 8496.915030849705, 8510.0, 8514.403991720583, 2152.8589847380913, 2347.812175025994, 2358.0685629176464, 2361.504084142332, 2362.4684445999683, 2135.5835612055657, 2231.7656646052174, 2255.1881322723807, 2259.67234750961, 2261.2650888461235, 2341.683872677184, 2350.21267875262, 2350.8471949323325, 2368.810495160383, 2381.833271670984, 2323.76762323768, 2351.623740201568, 2360.84654497458, 2361.244401791427, 2361.586722323595, 2379.9643005354933, 2380.0955636857884, 2383.0825132324007, 2388.785009349167, 2389.4265376309736, 7561.357284630813, 8377.874331885027, 8411.70559030431, 8417.368697347662, 8440.873386899202, 4768.094062128184, 4831.879203019917, 5018.172001619729, 5096.184610462308, 5147.639665223436, 3446.8621255149765, 3472.791632502058, 3551.875684351061, 3556.1643013891717, 3562.2697347043795, 1034.4310629153936, 1035.6892932120334, 1046.2623850185678, 1059.9788004239872, 1062.378508572486, 11055.447227638586, 11163.386013769239, 11173.0, 11176.776464470746, 11181.72045698856, 4509.098180363941, 4522.34426008227, 4575.679702420865, 4584.381108550303, 4591.517890621437, 7583.658735356922, 8993.775155621162, 9049.683261085831, 9057.77355566116, 9059.682911098213, 5072.923906141411, 5074.82238121664, 5100.6429549931145, 5109.9744501277255, 5110.156824124052, 4708.4820669726305, 4718.339432479455, 4726.71639701619, 4738.431388233434, 4739.170645137087, 3540.2919416116365, 3591.1381268495375, 3626.1841085755495, 3630.1650620357464, 3663.358912190356, 1100.5212732461405, 1138.7267055906689, 1141.2182654881433, 1145.4158379226487, 1145.6906635208447, 1331.3010669398643, 1332.6401871494643, 1377.7313423882447, 1382.046387992282, 1384.3078460769616, 7813.453058285865, 7887.881681774778, 7927.682892684228, 7935.444518883623, 7941.245581669732, 499.1340025056524, 504.11527768766314, 506.364512536764, 507.94158671752484, 511.3505847573635, 6021.096835474714, 6160.845978850564, 6247.875042499125, 6313.9368606314265, 6317.557770955989, 8197.426180167331, 8439.240468357877, 8515.0, 8531.0, 8532.061473237938, 9779.924208337077, 9824.06671366219, 10157.94921025405, 10190.949045254727, 10212.948935255276, 7758.922410775933, 7843.137254901956, 7862.2923936845955, 7872.881906771403, 7920.722774702857, 7828.373730101692, 7871.448998570045, 7877.369810415038, 7919.762407127795, 7926.167752386147, 7469.290417410337, 8845.51349675774, 9142.588583513625, 9149.679761208326, 9200.815983680423, 9217.861732074158, 9303.674371396968, 9377.0, 9416.576254068446, 9421.0, 6080.513558915188, 6863.313668633096, 6993.615351155532, 7025.754098606524, 7168.8924666129005, 8093.0, 8146.022477302761, 8154.266116049584, 8158.714444994397, 8159.632816523389, 7505.774826755206, 7575.772726818204, 7580.772576822704, 7692.000039994765, 7724.382049435919, 7472.327490525879, 7514.135874374517, 7526.435517336112, 7527.0, 7578.962105189659, 7612.733554325572, 7675.53946763196, 7689.231076892266, 7693.807654808675, 7696.769096927102, 6878.65606719672, 6930.13373328334, 6945.652717364011, 6975.197852247056, 6976.19773726028, 7038.310805406768, 7060.258672839484, 7081.575105493687, 7089.220185779457, 7098.78703638871], "unit": "ms", "name": "mootools.html", "value": 4632.081688102702}, {"lowerIsBetter": false, "replicates": [315.1946775987352, 319.38358967193307, 326.8104101072099, 326.98201598912055, 330.5669572859554, 6040.305364883035, 6219.471344935675, 6792.898106528406, 6834.316568343163, 6913.446924246061, 6804.795856124312, 6814.046033555306, 6818.1136452261135, 6823.488238382116, 6851.451883849292, 4967.701937883729, 4981.526754958273, 4987.875303117415, 4989.401271847365, 4993.725345106018, 3983.6613887819426, 4736.786844592001, 4910.656254062217, 4932.3341348917975, 4942.431620363668, 4845.806167753286, 4861.197902346126, 4864.562189402953, 4867.853964381075, 4873.634477414191, 4596.35651008859, 4745.549172828576, 4780.163471392527, 4877.634177436688, 4892.315075889361, 4042.0501182222447, 4122.546519882811, 4134.131832315187, 4146.688998325138, 4155.625993660555, 6548.410643042149, 6553.606783593, 6560.803175904683, 6561.3438656134485, 6563.212414510242, 5538.944610553924, 5648.0, 5658.179563963202, 5704.0, 5813.854653633651, 7283.781486555412, 7296.087989001375, 7320.743773967939, 7323.194448610649, 7326.2673732626845, 4667.626589872836, 4680.308822427084, 4684.578387945101, 4688.6249100072255, 4695.530446955311, 7548.094228692537, 7685.385169186452, 7773.0672319321475, 7773.805654858617, 7799.103103143211, 3930.6855451563806, 4754.857354279377, 4756.001239739624, 4756.072565849626, 4759.428868535798, 4820.517948205222, 4835.661503694708, 4838.2016967200725, 4838.395200599925, 4850.708957462563, 4533.569310915524, 5813.970930145322, 5820.0, 5828.417158284223, 5828.766849326064, 7344.706211751577, 7361.889571656429, 7363.447741419415, 7364.926350736531, 7366.484346095829, 1640.196303811124, 1652.512508809904, 1653.7106006449064, 1656.1470842516114, 1658.0963374960675, 1046.1160319529986, 1053.4258828938248, 1065.7548763784373, 1067.524951396621, 1083.090204228452, 6817.522773405814, 6844.21291551468, 6848.7260509580055, 6850.143732033496, 6888.276730943279, 7620.0, 7621.847563048819, 7638.618069096523, 7640.885386719204, 7644.082710074716, 5517.586181036439, 6217.222847144107, 6738.629375384303, 6840.110785597841, 6971.337722916315, 3377.307651931348, 3637.5089362936196, 3773.924431537054, 4060.0661847775177, 4061.898452538651, 8035.437519373588, 8038.678452861937, 8049.83900322002, 8058.717944871902, 8058.758237252891, 7786.221377862168, 7975.122736498981, 8013.038435387674, 8017.518948863087, 8019.35845132388, 8011.278984891388, 8029.397795165502, 8029.478083924526, 8042.115367309591, 8042.437029407885, 6049.18336024631, 7434.714616964801, 7713.537187768752, 7738.110117336579, 7738.303552680286, 6153.4154255345675, 7469.962650186932, 7550.131734850563, 7579.355754760907, 7584.734534291273, 6424.357564243538, 7997.560134192675, 8014.198580142172, 8020.598970051357, 8029.678812847421, 4586.9770651147865, 4721.976390118028, 4735.479097299363, 4772.665913386029, 4776.259679749644, 7526.24737526243, 7619.276168763959, 7619.657115429708, 7620.314171724572, 7634.923650763643, 5571.526420254162, 7312.561246325237, 7317.341439270491, 7332.633368331455, 7345.77962661121, 5666.291713535808, 6322.893493638687, 6872.725090996304, 7296.306850849161, 7303.634818259172, 7078.1860086090755, 7130.0, 7131.108611423572, 7132.714691412285, 7139.82150446243, 7036.0, 7276.070921702221, 7306.744263950736, 7308.8903666443985, 7318.853622927618, 7157.749478768218, 7159.0, 7162.319579640134, 7171.713131474683, 7172.139343278631], "unit": "ms", "name": "prototype.html", "value": 5252.160910969925}, {"lowerIsBetter": false, "replicates": [682.9214640316363, 759.4255473943333, 790.9564973926435, 791.4301702774004, 828.1635548096428, 14727.926360368196, 15379.846201537981, 15383.230838458088, 15389.307481163332, 15407.845921540782, 14116.788248176286, 14212.71574568508, 14229.644258893533, 14238.857611423882, 14244.14535127893, 754.3851760814946, 759.5366826235992, 761.9009528761281, 934.4253284230183, 951.0394501553446, 791.8891355210246, 951.6288647427509, 963.3834346018554, 964.0070727151045, 966.5650457294211, 996.8305388084043, 1009.6415772400802, 1011.6358111080006, 1011.9544620492059, 1013.2350075692879, 786.7718361675107, 800.2597597222575, 970.6166064404591, 993.6820217530393, 999.4952548962764, 738.2027410396759, 741.8961345411647, 742.5618884857905, 742.6435311050672, 744.5793126883348, 15106.622334441618, 15286.159261240628, 15298.694026119416, 15309.923450382788, 15324.846751532454, 12003.159778815487, 12345.197562158432, 12633.2420054797, 12879.613611591574, 13001.95984321252, 8135.308498777552, 10150.755759820971, 10263.332883362633, 10460.68617941463, 10986.0, 3482.0598438421484, 3561.9643803562153, 3618.8371523281517, 3618.945715814265, 3648.1062139775927, 70350.59298813995, 82465.76301355484, 82474.58762706208, 82654.17345826524, 83354.16645833525, 9442.433453992782, 9486.33595648298, 9508.667196648223, 9516.372896192192, 9665.516724163763, 20992.580148396948, 21192.36422907315, 21237.893810530848, 21250.25624103149, 21288.042038108324, 7005.299470053056, 7147.249538798351, 7160.176579693402, 7198.424126069902, 7300.707971681073, 3916.5104361954754, 3953.9604603954167, 3956.802159891994, 3964.52425708911, 3978.900527486778, 3519.085037890116, 3563.7505374624047, 3567.0725611341236, 3568.518250036212, 3572.0891172751303, 629.8047605242298, 633.1357696744003, 648.5298158834808, 652.9608223506605, 657.4149007383432, 497.9925301120486, 509.8648858052619, 519.3430310656946, 535.606329347929, 559.905384972378, 7258.3104605062435, 7673.846523069507, 8477.0, 8503.914960850436, 8523.360747943929, 530.2761730238198, 577.6995962099769, 589.8171566814301, 618.5762752514541, 651.5797310734549, 3852.5569559500454, 3935.9212815743526, 4343.739375637409, 4381.277089280233, 4403.735775853459, 84778.57610711906, 85379.57310213409, 85388.57305713555, 85727.57136214404, 88167.67748483778, 73123.0, 74045.25954740384, 74497.25502745011, 75489.86765198526, 75863.65499795019, 66036.66981665061, 67483.0, 72577.63711181516, 72808.54382912313, 74497.0, 7838.568878711726, 9151.816963660822, 9176.495292758962, 9248.306377021616, 9303.906960930304, 52495.95008099893, 52750.47249527455, 53639.19541206963, 53797.462025379245, 54786.0, 67502.64994700177, 72074.0, 74279.62860185665, 75608.86586701094, 75703.0, 10746.140308775435, 10979.1216702662, 11020.50407731638, 11078.11375090007, 11335.376554289593, 11919.880801192225, 12447.626571202878, 12585.685357866127, 12800.35998200105, 12832.230066196058, 11226.607068752555, 11515.654530364103, 11804.291742495478, 11881.405929703307, 12444.253344799341, 11214.495347709535, 11733.823992640288, 11739.295642261492, 12232.082182131977, 12331.50673973031, 10979.890201098206, 11626.70933226676, 11702.531898723955, 11711.238769479956, 11885.524579016743, 9615.471149086867, 9748.415095094033, 9886.901130988597, 9969.950150249493, 10164.0344167304, 9230.584623691815, 9950.55222515003, 10056.54745536438, 10066.396016239049, 10085.344452610558], "unit": "ms", "name": "yui.html", "value": 6974.885892539271}], "name": "dromaeo_css", "value": 7628.633003207785}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 103.0, 113.0, 150.0, 148.0, 100.0, 109.0, 109.0, 104.0], "unit": "ms", "name": "ai-astar", "value": 115.6}, {"lowerIsBetter": true, "replicates": [150.0, 118.0, 142.0, 159.0, 138.0, 170.0, 167.0, 147.0, 155.0, 117.0], "unit": "ms", "name": "audio-beat-detection", "value": 146.3}, {"lowerIsBetter": true, "replicates": [185.0, 181.0, 183.0, 180.0, 189.0, 182.0, 184.0, 181.0, 182.0, 184.0], "unit": "ms", "name": "audio-dft", "value": 183.1}, {"lowerIsBetter": true, "replicates": [60.0, 67.0, 75.0, 69.0, 60.0, 78.0, 82.0, 93.0, 78.0, 54.0], "unit": "ms", "name": "audio-fft", "value": 71.6}, {"lowerIsBetter": true, "replicates": [85.0, 83.0, 114.0, 76.0, 75.0, 74.0, 112.0, 76.0, 74.0, 110.0], "unit": "ms", "name": "audio-oscillator", "value": 87.9}, {"lowerIsBetter": true, "replicates": [88.0, 89.0, 88.0, 89.0, 88.0, 88.0, 89.0, 89.0, 89.0, 89.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 88.6}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 96.0, 94.0, 96.0, 96.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-darkroom", "value": 95.3}, {"lowerIsBetter": true, "replicates": [91.0, 133.0, 136.0, 87.0, 88.0, 91.0, 87.0, 87.0, 89.0, 86.0], "unit": "ms", "name": "imaging-desaturate", "value": 97.5}, {"lowerIsBetter": true, "replicates": [56.0, 54.0, 54.0, 55.0, 53.0, 54.0, 54.0, 65.0, 54.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 55.2}, {"lowerIsBetter": true, "replicates": [62.0, 63.0, 62.0, 63.0, 63.0, 68.0, 65.0, 66.0, 65.0, 67.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.4}, {"lowerIsBetter": true, "replicates": [86.0, 83.0, 83.0, 81.0, 82.0, 84.0, 82.0, 81.0, 83.0, 83.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 82.8}, {"lowerIsBetter": true, "replicates": [124.0, 132.0, 130.0, 121.0, 117.0, 133.0, 122.0, 106.0, 119.0, 127.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 123.1}, {"lowerIsBetter": true, "replicates": [138.0, 126.0, 140.0, 139.0, 136.0, 125.0, 139.0, 137.0, 134.0, 126.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 134.0}, {"lowerIsBetter": true, "replicates": [65.0, 65.0, 66.0, 64.0, 65.0, 65.0, 65.0, 64.0, 65.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.9}], "name": "kraken", "value": 1410.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [175.13134851138355], "unit": "score", "name": "Richards", "value": 201.57442000000003}, {"lowerIsBetter": false, "replicates": [160.72002571520412], "unit": "score", "name": "DeltaBlue", "value": 411.38619600000015}, {"lowerIsBetter": false, "replicates": [285.143997718848], "unit": "score", "name": "Encrypt", "value": 933.4967669999996}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "Decrypt", "value": 55.099466999999976}, {"lowerIsBetter": false, "replicates": [1731.833910034602], "unit": "score", "name": "RayTrace", "value": 427.2863556443558}, {"lowerIsBetter": false, "replicates": [792.3930269413629], "unit": "score", "name": "Earley", "value": 841.0763060000003}, {"lowerIsBetter": false, "replicates": [13413.333333333334], "unit": "score", "name": "Boyer", "value": 49.68660536779327}, {"lowerIsBetter": false, "replicates": [28055.555555555555], "unit": "score", "name": "RegExp", "value": 32.470752475247544}, {"lowerIsBetter": false, "replicates": [636.9426751592357], "unit": "score", "name": "Splay", "value": 127.94086999999998}, {"lowerIsBetter": false, "replicates": [4542.986425339366], "unit": "score", "name": "NavierStokes", "value": 326.65737051792814}], "name": "v8_7", "value": 20101.70462381156}]} 05:12:36 INFO - RETURN: dromaeo_css: 7628.63 05:12:36 INFO - RETURN: kraken: 1410.30 05:12:36 INFO - RETURN: v8_7: 20101.70 05:12:36 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,59]]", "result": "20101.70"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,59]]", "result": "1410.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,59]]", "result": "7628.63"}}} 05:12:36 INFO - Return code: 0 05:12:36 INFO - # TBPL SUCCESS # 05:12:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:12:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:12:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:12:36 INFO - 2015-11-24 05:12:36.992 screenresolution[1951:14170] starting screenresolution argv=screenresolution get 05:12:36 INFO - 2015-11-24 05:12:36.994 screenresolution[1951:14170] Display 0: 1600x1200x32@60 05:12:37 INFO - 2015-11-24 05:12:37.000 screenresolution[1952:14173] starting screenresolution argv=screenresolution list 05:12:37 INFO - Available Modes on Display 0 05:12:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:12:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:12:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:12:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:12:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:12:37 INFO - Intel Iris: 05:12:37 INFO - Chipset Model: Intel Iris 05:12:37 INFO - Type: GPU 05:12:37 INFO - Bus: Built-In 05:12:37 INFO - VRAM (Dynamic, Max): 1536 MB 05:12:37 INFO - Vendor: Intel (0x8086) 05:12:37 INFO - Device ID: 0x0a2e 05:12:37 INFO - Revision ID: 0x0009 05:12:37 INFO - Displays: 05:12:37 INFO - Display: 05:12:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:12:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:12:37 INFO - Display Serial Number: 1600x1200 60 05:12:37 INFO - Main Display: Yes 05:12:37 INFO - Mirror: Off 05:12:37 INFO - Online: Yes 05:12:37 INFO - Rotation: Supported 05:12:37 INFO - Return code: 0 05:12:37 INFO - Running post-action listener: _resource_record_post_action 05:12:37 INFO - Running post-run listener: _resource_record_post_run 05:12:38 INFO - Total resource usage - Wall time: 1240s; CPU: 53.0%; Read bytes: 2781184; Write bytes: 688413184; Read time: 43; Write time: 2107 05:12:38 INFO - install - Wall time: 31s; CPU: 55.0%; Read bytes: 341103104; Write bytes: 337470976; Read time: 26947; Write time: 610 05:12:38 INFO - run-tests - Wall time: 1210s; CPU: 53.0%; Read bytes: 2781184; Write bytes: 339465216; Read time: 43; Write time: 1460 05:12:38 INFO - Running post-run listener: _upload_blobber_files 05:12:38 INFO - Blob upload gear active. 05:12:38 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:12:38 INFO - Copying logs to upload dir... 05:12:38 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1282.878437 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2015-11-24 05:12:38.363609) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:12:38.371040) ========= 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.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009484 build_url: 'https://queue.taskcluster.net/v1/task/Hm-ebYd2Rpa8jY8YyO_aAw/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:12:38.396756) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:12:38.397074) ========= 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.h7Z0zVFbb5/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.FYZTP4Bfq9/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.005068 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:12:38.445304) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 05:12:38.448639) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 05:12:38.449020) ========= ========= Total master_lag: 0.22 =========