builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0038 starttime: 1447953341.59 results: success (0) buildid: 20151119065326 builduid: 3fcf9953c55f45268bec00dfe185599f revision: a523d4c7efe2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.586646) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.587093) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.587950) ========= 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.F293yiVd4N/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.4bPKjQktwe/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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.005672 basedir: '/builds/slave/test' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.608301) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.608607) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.624237) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.624531) ========= 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.F293yiVd4N/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.4bPKjQktwe/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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.005428 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.673112) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.673429) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.673785) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.674060) ========= 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.F293yiVd4N/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.4bPKjQktwe/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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-19 09:15:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 1.41G=0s 2015-11-19 09:15:41 (1.41 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.182878 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.871008) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.871354) ========= 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.F293yiVd4N/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.4bPKjQktwe/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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.019947 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:41.919852) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 09:15:41.920171) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a523d4c7efe2 --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 a523d4c7efe2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.F293yiVd4N/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.4bPKjQktwe/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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-19 09:15:42,006 Setting DEBUG logging. 2015-11-19 09:15:42,006 attempt 1/10 2015-11-19 09:15:42,006 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a523d4c7efe2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-19 09:15:43,207 unpacking tar archive at: mozilla-central-a523d4c7efe2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.447505 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 09:15:43.384697) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:43.385014) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:43.399376) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:43.399764) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 09:15:43.400212) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2015-11-19 09:15:43.400528) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.F293yiVd4N/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.4bPKjQktwe/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 09:15:43 INFO - MultiFileLogger online at 20151119 09:15:43 in /builds/slave/test 09:15:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 09:15:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:43 INFO - {'append_to_log': False, 09:15:43 INFO - 'base_work_dir': '/builds/slave/test', 09:15:43 INFO - 'blob_upload_branch': 'Firefox', 09:15:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:43 INFO - 'branch': 'Firefox', 09:15:43 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:43 INFO - 'config_files': ('talos/mac_config.py',), 09:15:43 INFO - 'default_actions': ('clobber', 09:15:43 INFO - 'read-buildbot-config', 09:15:43 INFO - 'download-and-extract', 09:15:43 INFO - 'populate-webroot', 09:15:43 INFO - 'create-virtualenv', 09:15:43 INFO - 'install', 09:15:43 INFO - 'run-tests'), 09:15:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:43 INFO - 'download_minidump_stackwalk': True, 09:15:43 INFO - 'download_symbols': 'ondemand', 09:15:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:43 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:43 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:43 INFO - 'installer_path': 'installer.exe', 09:15:43 INFO - 'log_level': 'info', 09:15:43 INFO - 'log_name': 'talos', 09:15:43 INFO - 'log_to_console': True, 09:15:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:15:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:15:43 INFO - 'opt_config_files': (), 09:15:43 INFO - 'pip_index': False, 09:15:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:43 INFO - 'cmd': ('bash', 09:15:43 INFO - '-c', 09:15:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:43 INFO - 'enabled': True, 09:15:43 INFO - 'halt_on_failure': False, 09:15:43 INFO - 'name': 'check_screen_resolution'},), 09:15:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:43 INFO - 'cmd': ('bash', 09:15:43 INFO - '-c', 09:15:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:43 INFO - 'enabled': True, 09:15:43 INFO - 'halt_on_failure': False, 09:15:43 INFO - 'name': 'check_screen_resolution'},), 09:15:43 INFO - 'run_cmd_checks_enabled': True, 09:15:43 INFO - 'sps_profile': False, 09:15:43 INFO - 'sps_profile_interval': 0, 09:15:43 INFO - 'suite': 'dromaeojs-e10s', 09:15:43 INFO - 'system_bits': '64', 09:15:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:43 INFO - 'title': 't-yosemite-r7-0038', 09:15:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:43 INFO - 'use_talos_json': True, 09:15:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:43 INFO - 'work_dir': 'build'} 09:15:43 INFO - ##### 09:15:43 INFO - ##### Running clobber step. 09:15:43 INFO - ##### 09:15:43 INFO - Running pre-action listener: _resource_record_pre_action 09:15:43 INFO - Running main action method: clobber 09:15:43 INFO - rmtree: /builds/slave/test/build 09:15:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:44 INFO - Running post-action listener: _resource_record_post_action 09:15:44 INFO - ##### 09:15:44 INFO - ##### Running read-buildbot-config step. 09:15:44 INFO - ##### 09:15:44 INFO - Running pre-action listener: _resource_record_pre_action 09:15:44 INFO - Running main action method: read_buildbot_config 09:15:44 INFO - Using buildbot properties: 09:15:44 INFO - { 09:15:44 INFO - "properties": { 09:15:44 INFO - "buildnumber": 1, 09:15:44 INFO - "product": "firefox", 09:15:44 INFO - "script_repo_revision": "production", 09:15:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:15:44 INFO - "repository": "", 09:15:44 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:15:44 INFO - "buildid": "20151119065326", 09:15:44 INFO - "slavename": "t-yosemite-r7-0038", 09:15:44 INFO - "stage_platform": "macosx64", 09:15:44 INFO - "basedir": "/builds/slave/test", 09:15:44 INFO - "nightly_build": "True", 09:15:44 INFO - "project": "", 09:15:44 INFO - "platform": "yosemite_r7", 09:15:44 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:15:44 INFO - "slavebuilddir": "test", 09:15:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:15:44 INFO - "branch": "mozilla-central", 09:15:44 INFO - "repo_path": "mozilla-central", 09:15:44 INFO - "pgo_build": "False", 09:15:44 INFO - "builduid": "3fcf9953c55f45268bec00dfe185599f", 09:15:44 INFO - "revision": "a523d4c7efe2" 09:15:44 INFO - }, 09:15:44 INFO - "sourcestamp": { 09:15:44 INFO - "repository": "", 09:15:44 INFO - "hasPatch": false, 09:15:44 INFO - "project": "", 09:15:44 INFO - "branch": "mozilla-central-macosx64-talos", 09:15:44 INFO - "changes": [ 09:15:44 INFO - { 09:15:44 INFO - "category": null, 09:15:44 INFO - "files": [ 09:15:44 INFO - { 09:15:44 INFO - "url": null, 09:15:44 INFO - "name": "https://queue.taskcluster.net/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 09:15:44 INFO - } 09:15:44 INFO - ], 09:15:44 INFO - "repository": "", 09:15:44 INFO - "rev": "a523d4c7efe2", 09:15:44 INFO - "who": "sendchange", 09:15:44 INFO - "when": 1447952705, 09:15:44 INFO - "number": 6683731, 09:15:44 INFO - "comments": "", 09:15:44 INFO - "project": "", 09:15:44 INFO - "at": "Thu 19 Nov 2015 09:05:05", 09:15:44 INFO - "branch": "mozilla-central-macosx64-talos", 09:15:44 INFO - "revlink": "", 09:15:44 INFO - "properties": [ 09:15:44 INFO - [ 09:15:44 INFO - "buildid", 09:15:44 INFO - "20151119065326", 09:15:44 INFO - "Change" 09:15:44 INFO - ], 09:15:44 INFO - [ 09:15:44 INFO - "builduid", 09:15:44 INFO - "3fcf9953c55f45268bec00dfe185599f", 09:15:44 INFO - "Change" 09:15:44 INFO - ], 09:15:44 INFO - [ 09:15:44 INFO - "nightly_build", 09:15:44 INFO - "True", 09:15:44 INFO - "Change" 09:15:44 INFO - ], 09:15:44 INFO - [ 09:15:44 INFO - "pgo_build", 09:15:44 INFO - "False", 09:15:44 INFO - "Change" 09:15:44 INFO - ] 09:15:44 INFO - ], 09:15:44 INFO - "revision": "a523d4c7efe2" 09:15:44 INFO - } 09:15:44 INFO - ], 09:15:44 INFO - "revision": "a523d4c7efe2" 09:15:44 INFO - } 09:15:44 INFO - } 09:15:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 09:15:44 INFO - Running post-action listener: _resource_record_post_action 09:15:44 INFO - ##### 09:15:44 INFO - ##### Running download-and-extract step. 09:15:44 INFO - ##### 09:15:44 INFO - Running pre-action listener: _resource_record_pre_action 09:15:44 INFO - Running main action method: download_and_extract 09:15:44 INFO - mkdir: /builds/slave/test/build/tests 09:15:44 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')]} 09:15:44 INFO - https://queue.taskcluster.net/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:15:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/test_packages.json 09:15:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/test_packages.json 09:15:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 09:15:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 09:15:46 INFO - Downloaded 1149 bytes. 09:15:46 INFO - Reading from file /builds/slave/test/build/test_packages.json 09:15:46 INFO - Using the following test package requirements: 09:15:46 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 09:15:46 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:46 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 09:15:46 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:46 INFO - u'jsshell-mac.zip'], 09:15:46 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:46 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 09:15:46 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 09:15:46 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:46 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 09:15:46 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:46 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 09:15:46 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:46 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 09:15:46 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 09:15:46 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:46 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 09:15:46 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:15: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')]} 09:15:46 INFO - https://queue.taskcluster.net/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:15:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 09:15:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 09:15:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/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 09:15:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/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 09:15:47 INFO - Downloaded 17522271 bytes. 09:15:47 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 09:15:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 09:15:47 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 09:15:50 INFO - Return code: 0 09:15:50 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 09:15: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')]} 09:15:50 INFO - https://queue.taskcluster.net/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:15:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 09:15:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 09:15:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/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 09:15:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/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 09:15:51 INFO - Downloaded 17522271 bytes. 09:15:51 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 09:15:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 09:15:51 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 09:15:54 INFO - Return code: 0 09:15:54 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')]} 09:15:54 INFO - https://queue.taskcluster.net/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:15:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 09:15:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 09:15:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/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 09:15:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/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 09:15:55 INFO - Downloaded 11130997 bytes. 09:15:55 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 09:15:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 09:15:55 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 09:15:55 INFO - Return code: 0 09:15: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')]} 09:15:55 INFO - https://queue.taskcluster.net/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:15:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 09:15:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 09:15:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg 09:15:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AhyXgK1cQcCBL53ZKsJA2g/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 09:15:58 INFO - Downloaded 109497901 bytes. 09:15:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 09:15:58 INFO - mkdir: /builds/slave/test/properties 09:15:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:15:58 INFO - Writing to file /builds/slave/test/properties/build_url 09:15:58 INFO - Contents: 09:15:58 INFO - build_url:https://queue.taskcluster.net/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 09:15:58 INFO - Running post-action listener: _resource_record_post_action 09:15:58 INFO - Running post-action listener: set_extra_try_arguments 09:15:58 INFO - ##### 09:15:58 INFO - ##### Running populate-webroot step. 09:15:58 INFO - ##### 09:15:58 INFO - Running pre-action listener: _resource_record_pre_action 09:15:58 INFO - Running main action method: populate_webroot 09:15:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:15:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:15:58 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 09:15:58 INFO - '../fennec_ids.txt'], 09:15:58 INFO - 'tests': ['tcheck2']}, 09:15:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:15:58 INFO - '--tppagecycles', 09:15:58 INFO - '7'], 09:15:58 INFO - 'tests': ['tsvgm']}}, 09:15:58 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:15:58 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:15:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:15:58 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:15:58 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:15:58 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:15:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:58 INFO - 'talos_options': ['--e10s'], 09:15:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:58 INFO - 'tests': ['damp', 'tps']}, 09:15:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:58 INFO - 'talos_options': ['--e10s'], 09:15:58 INFO - 'tests': ['damp', 'tps']}, 09:15:58 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:15:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:15:58 INFO - 'other': {'tests': ['a11yr', 09:15:58 INFO - 'ts_paint', 09:15:58 INFO - 'tpaint', 09:15:58 INFO - 'sessionrestore', 09:15:58 INFO - 'sessionrestore_no_auto_restore']}, 09:15:58 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:15:58 INFO - 'tests': ['a11yr', 09:15:58 INFO - 'ts_paint', 09:15:58 INFO - 'tpaint', 09:15:58 INFO - 'sessionrestore', 09:15:58 INFO - 'sessionrestore_no_auto_restore']}, 09:15:58 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:15:58 INFO - 'tests': ['a11yr', 09:15:58 INFO - 'ts_paint', 09:15:58 INFO - 'tpaint', 09:15:58 INFO - 'sessionrestore', 09:15:58 INFO - 'sessionrestore_no_auto_restore']}, 09:15:58 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:15:58 INFO - 'tests': ['a11yr', 09:15:58 INFO - 'ts_paint', 09:15:58 INFO - 'tpaint', 09:15:58 INFO - 'sessionrestore', 09:15:58 INFO - 'sessionrestore_no_auto_restore']}, 09:15:58 INFO - 'other_l64': {'tests': ['a11yr', 09:15:58 INFO - 'ts_paint', 09:15:58 INFO - 'tpaint', 09:15:58 INFO - 'sessionrestore', 09:15:58 INFO - 'sessionrestore_no_auto_restore']}, 09:15:58 INFO - 'other_nol64': {'tests': ['a11yr', 09:15:58 INFO - 'ts_paint', 09:15:58 INFO - 'tpaint', 09:15:58 INFO - 'sessionrestore', 09:15:58 INFO - 'sessionrestore_no_auto_restore']}, 09:15:58 INFO - 'svgr': {'tests': ['tsvgx', 09:15:58 INFO - 'tsvgr_opacity', 09:15:58 INFO - 'tart', 09:15:58 INFO - 'tscrollx', 09:15:58 INFO - 'cart']}, 09:15:58 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:15:58 INFO - 'tests': ['tsvgx', 09:15:58 INFO - 'tsvgr_opacity', 09:15:58 INFO - 'tart', 09:15:58 INFO - 'tscrollx', 09:15:59 INFO - 'cart']}, 09:15:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:59 INFO - 'tests': ['tp5o']}, 09:15:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:59 INFO - 'talos_options': ['--e10s'], 09:15:59 INFO - 'tests': ['tp5o']}, 09:15:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:59 INFO - 'talos_options': ['--xperf_path', 09:15:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:59 INFO - 'tests': ['tp5n']}, 09:15:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:59 INFO - 'talos_options': ['--e10s', 09:15:59 INFO - '--xperf_path', 09:15:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:59 INFO - 'tests': ['tp5n']}}, 09:15:59 INFO - 'talos.zip': {'path': '', 09:15:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:15:59 INFO - Running post-action listener: _resource_record_post_action 09:15:59 INFO - ##### 09:15:59 INFO - ##### Running create-virtualenv step. 09:15:59 INFO - ##### 09:15:59 INFO - Running pre-action listener: _resource_record_pre_action 09:15:59 INFO - Running main action method: create_virtualenv 09:15:59 INFO - Creating virtualenv /builds/slave/test/build/venv 09:15:59 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 09:15:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:15:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:15:59 INFO - Using real prefix '/tools/python27' 09:15:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:15:59 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:02 INFO - Installing pip.................done. 09:16:02 INFO - Return code: 0 09:16:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:16:02 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')]} 09:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:02 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')]} 09:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:02 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 0x107255490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107232228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9031cc8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071799f0>, '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 0x106ed8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716c8c8>, '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.F293yiVd4N/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.4bPKjQktwe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 09:16:02 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 09:16:02 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 09:16:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F293yiVd4N/Render', 09:16:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:02 INFO - 'HOME': '/Users/cltbld', 09:16:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:02 INFO - 'LOGNAME': 'cltbld', 09:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:02 INFO - 'MOZ_NO_REMOTE': '1', 09:16:02 INFO - 'NO_EM_RESTART': '1', 09:16:02 INFO - 'PAGER': '/bin/cat', 09:16:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:02 INFO - 'PWD': '/builds/slave/test', 09:16:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:02 INFO - 'SHELL': '/bin/bash', 09:16:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bPKjQktwe/Listeners', 09:16:02 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 09:16:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:02 INFO - 'USER': 'cltbld', 09:16:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:02 INFO - 'XPC_FLAGS': '0x0', 09:16:02 INFO - 'XPC_SERVICE_NAME': '0', 09:16:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:03 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 09:16:03 INFO - Cleaning up... 09:16:03 INFO - Return code: 0 09:16:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:16:03 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')]} 09:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 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')]} 09:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 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 0x107255490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107232228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9031cc8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071799f0>, '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 0x106ed8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716c8c8>, '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.F293yiVd4N/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.4bPKjQktwe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 09:16:03 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 09:16:03 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 09:16:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F293yiVd4N/Render', 09:16:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:03 INFO - 'HOME': '/Users/cltbld', 09:16:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:03 INFO - 'LOGNAME': 'cltbld', 09:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:03 INFO - 'MOZ_NO_REMOTE': '1', 09:16:03 INFO - 'NO_EM_RESTART': '1', 09:16:03 INFO - 'PAGER': '/bin/cat', 09:16:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:03 INFO - 'PWD': '/builds/slave/test', 09:16:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:03 INFO - 'SHELL': '/bin/bash', 09:16:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bPKjQktwe/Listeners', 09:16:03 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 09:16:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:03 INFO - 'USER': 'cltbld', 09:16:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:03 INFO - 'XPC_FLAGS': '0x0', 09:16:03 INFO - 'XPC_SERVICE_NAME': '0', 09:16:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:03 INFO - Downloading/unpacking psutil>=0.7.1 09:16:03 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 09:16:03 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 09:16:03 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 09:16:03 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 09:16:03 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 09:16:03 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 09:16:05 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 09:16:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 09:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:05 INFO - Installing collected packages: psutil 09:16:05 INFO - Running setup.py install for psutil 09:16:05 INFO - building 'psutil._psutil_osx' extension 09:16:05 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 09:16:06 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 09:16:06 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 09:16:06 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 09:16:06 INFO - building 'psutil._psutil_posix' extension 09:16:06 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 09:16:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:16:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:16:06 INFO - ^ 09:16:06 INFO - 1 warning generated. 09:16:06 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 09:16:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:06 INFO - Successfully installed psutil 09:16:06 INFO - Cleaning up... 09:16:06 INFO - Return code: 0 09:16:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:16:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:06 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 0x107255490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107232228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9031cc8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071799f0>, '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 0x106ed8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716c8c8>, '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.F293yiVd4N/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.4bPKjQktwe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 09:16:06 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 09:16:06 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 09:16:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F293yiVd4N/Render', 09:16:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:06 INFO - 'HOME': '/Users/cltbld', 09:16:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:06 INFO - 'LOGNAME': 'cltbld', 09:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:06 INFO - 'MOZ_NO_REMOTE': '1', 09:16:06 INFO - 'NO_EM_RESTART': '1', 09:16:06 INFO - 'PAGER': '/bin/cat', 09:16:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:06 INFO - 'PWD': '/builds/slave/test', 09:16:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:06 INFO - 'SHELL': '/bin/bash', 09:16:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bPKjQktwe/Listeners', 09:16:06 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 09:16:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:06 INFO - 'USER': 'cltbld', 09:16:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:06 INFO - 'XPC_FLAGS': '0x0', 09:16:06 INFO - 'XPC_SERVICE_NAME': '0', 09:16:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:16:07 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 09:16:07 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 09:16:07 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 09:16:07 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 09:16:07 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 09:16:07 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 09:16:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:16:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 09:16:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:16:09 INFO - Installing collected packages: mozsystemmonitor 09:16:09 INFO - Running setup.py install for mozsystemmonitor 09:16:09 INFO - Successfully installed mozsystemmonitor 09:16:09 INFO - Cleaning up... 09:16:09 INFO - Return code: 0 09:16:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:16:09 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')]} 09:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:09 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')]} 09:16:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:09 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 0x107255490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107232228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9031cc8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071799f0>, '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 0x106ed8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716c8c8>, '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.F293yiVd4N/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.4bPKjQktwe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 09:16:09 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 09:16:09 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 09:16:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F293yiVd4N/Render', 09:16:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:09 INFO - 'HOME': '/Users/cltbld', 09:16:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:09 INFO - 'LOGNAME': 'cltbld', 09:16:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:09 INFO - 'MOZ_NO_REMOTE': '1', 09:16:09 INFO - 'NO_EM_RESTART': '1', 09:16:09 INFO - 'PAGER': '/bin/cat', 09:16:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:09 INFO - 'PWD': '/builds/slave/test', 09:16:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:09 INFO - 'SHELL': '/bin/bash', 09:16:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bPKjQktwe/Listeners', 09:16:09 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 09:16:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:09 INFO - 'USER': 'cltbld', 09:16:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:09 INFO - 'XPC_FLAGS': '0x0', 09:16:09 INFO - 'XPC_SERVICE_NAME': '0', 09:16:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:09 INFO - Downloading/unpacking blobuploader==1.2.4 09:16:09 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 09:16:09 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 09:16:09 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 09:16:09 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 09:16:09 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 09:16:09 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 09:16:11 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 09:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16:11 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 09:16:11 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 09:16:11 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 09:16:11 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 09:16:11 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 09:16:11 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 09:16:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 09:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:12 INFO - Downloading docopt-0.6.1.tar.gz 09:16:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 09:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:13 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:13 INFO - Running setup.py install for blobuploader 09:16:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:16:13 INFO - Running setup.py install for requests 09:16:13 INFO - Running setup.py install for docopt 09:16:13 INFO - Successfully installed blobuploader requests docopt 09:16:13 INFO - Cleaning up... 09:16:13 INFO - Return code: 0 09:16:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:13 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 0x107255490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107232228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9031cc8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071799f0>, '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 0x106ed8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716c8c8>, '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.F293yiVd4N/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.4bPKjQktwe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 09:16:13 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 09:16:13 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 09:16:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F293yiVd4N/Render', 09:16:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:13 INFO - 'HOME': '/Users/cltbld', 09:16:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:13 INFO - 'LOGNAME': 'cltbld', 09:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:13 INFO - 'MOZ_NO_REMOTE': '1', 09:16:13 INFO - 'NO_EM_RESTART': '1', 09:16:13 INFO - 'PAGER': '/bin/cat', 09:16:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:13 INFO - 'PWD': '/builds/slave/test', 09:16:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:13 INFO - 'SHELL': '/bin/bash', 09:16:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bPKjQktwe/Listeners', 09:16:13 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 09:16:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:13 INFO - 'USER': 'cltbld', 09:16:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:13 INFO - 'XPC_FLAGS': '0x0', 09:16:13 INFO - 'XPC_SERVICE_NAME': '0', 09:16:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:14 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-vcbopA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:14 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-RjhEuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:14 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-0oi_MO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:14 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-bS146V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:14 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-2o8Wi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:14 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-ofwqAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:14 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-UDZbeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:14 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-QjQEPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:15 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-ChBXSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:15 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-_SSDNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:15 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-F5xbJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:15 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-tuWpvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:15 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-JB2DhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:15 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-KELfJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:15 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-JBjwlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:15 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-68xKnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:15 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-WEBYoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:15 INFO - Running setup.py install for manifestparser 09:16:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:16 INFO - Running setup.py install for mozcrash 09:16:16 INFO - Running setup.py install for mozdebug 09:16:16 INFO - Running setup.py install for mozdevice 09:16:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:16:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:16 INFO - Running setup.py install for mozfile 09:16:16 INFO - Running setup.py install for mozhttpd 09:16:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:16 INFO - Running setup.py install for mozinfo 09:16:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:16 INFO - Running setup.py install for mozInstall 09:16:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Running setup.py install for mozleak 09:16:17 INFO - Running setup.py install for mozlog 09:16:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Running setup.py install for moznetwork 09:16:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Running setup.py install for mozprocess 09:16:17 INFO - Running setup.py install for mozprofile 09:16:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:16:17 INFO - Running setup.py install for mozrunner 09:16:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:16:18 INFO - Running setup.py install for mozscreenshot 09:16:18 INFO - Running setup.py install for moztest 09:16:18 INFO - Running setup.py install for mozversion 09:16:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:16:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:18 INFO - Cleaning up... 09:16:18 INFO - Return code: 0 09:16:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16: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')]} 09:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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')]} 09:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-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 0x107255490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107232228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9031cc8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071799f0>, '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 0x106ed8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716c8c8>, '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.F293yiVd4N/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.4bPKjQktwe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 09:16:18 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 09:16:18 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 09:16:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F293yiVd4N/Render', 09:16:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:18 INFO - 'HOME': '/Users/cltbld', 09:16:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:18 INFO - 'LOGNAME': 'cltbld', 09:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:18 INFO - 'MOZ_NO_REMOTE': '1', 09:16:18 INFO - 'NO_EM_RESTART': '1', 09:16:18 INFO - 'PAGER': '/bin/cat', 09:16:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:18 INFO - 'PWD': '/builds/slave/test', 09:16:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:18 INFO - 'SHELL': '/bin/bash', 09:16:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bPKjQktwe/Listeners', 09:16:18 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 09:16:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:18 INFO - 'USER': 'cltbld', 09:16:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:18 INFO - 'XPC_FLAGS': '0x0', 09:16:18 INFO - 'XPC_SERVICE_NAME': '0', 09:16:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:18 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-Y7Ag9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:18 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)) 09:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:18 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-JlzlaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:19 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-h32_Mz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:19 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-hSZWXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:19 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-cw4bLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:19 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-d7UkJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:19 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-D6Jdv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:19 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-DBIQ5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:19 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-xJ237B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:19 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-IJzqNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:19 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-KZ9zZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:19 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)) 09:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:20 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-39FeW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:20 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)) 09:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:20 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-Bpb2sR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:20 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)) 09:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:20 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-ngSSdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:20 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)) 09:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:20 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-g2yuYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:20 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)) 09:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:20 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-vOKakG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:20 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)) 09:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:20 INFO - Running setup.py (path:/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/pip-QTn329-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:20 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)) 09:16:20 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)) 09:16:20 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)) 09:16:20 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)) 09:16:20 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)) 09:16:20 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)) 09:16:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:20 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 09:16:20 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 09:16:20 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 09:16:20 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 09:16:20 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 09:16:20 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 09:16:22 INFO - Downloading blessings-1.5.1.tar.gz 09:16:22 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 09:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:22 INFO - Installing collected packages: blessings 09:16:22 INFO - Running setup.py install for blessings 09:16:23 INFO - Successfully installed blessings 09:16:23 INFO - Cleaning up... 09:16:23 INFO - Return code: 0 09:16:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:23 INFO - Reading from file tmpfile_stdout 09:16:23 INFO - Current package versions: 09:16:23 INFO - blessings == 1.5.1 09:16:23 INFO - blobuploader == 1.2.4 09:16:23 INFO - docopt == 0.6.1 09:16:23 INFO - manifestparser == 1.1 09:16:23 INFO - mozInstall == 1.12 09:16:23 INFO - mozcrash == 0.16 09:16:23 INFO - mozdebug == 0.1 09:16:23 INFO - mozdevice == 0.47 09:16:23 INFO - mozfile == 1.2 09:16:23 INFO - mozhttpd == 0.7 09:16:23 INFO - mozinfo == 0.9 09:16:23 INFO - mozleak == 0.1 09:16:23 INFO - mozlog == 3.0 09:16:23 INFO - moznetwork == 0.27 09:16:23 INFO - mozprocess == 0.22 09:16:23 INFO - mozprofile == 0.27 09:16:23 INFO - mozrunner == 6.11 09:16:23 INFO - mozscreenshot == 0.1 09:16:23 INFO - mozsystemmonitor == 0.0 09:16:23 INFO - moztest == 0.7 09:16:23 INFO - mozversion == 1.4 09:16:23 INFO - psutil == 3.1.1 09:16:23 INFO - requests == 1.2.3 09:16:23 INFO - wsgiref == 0.1.2 09:16:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:23 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 0x107255490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107232228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9031cc8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071799f0>, '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 0x106ed8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716c8c8>, '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.F293yiVd4N/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.4bPKjQktwe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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 09:16:23 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 09:16:23 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 09:16:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F293yiVd4N/Render', 09:16:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:23 INFO - 'HOME': '/Users/cltbld', 09:16:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:23 INFO - 'LOGNAME': 'cltbld', 09:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:23 INFO - 'MOZ_NO_REMOTE': '1', 09:16:23 INFO - 'NO_EM_RESTART': '1', 09:16:23 INFO - 'PAGER': '/bin/cat', 09:16:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:23 INFO - 'PWD': '/builds/slave/test', 09:16:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:23 INFO - 'SHELL': '/bin/bash', 09:16:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bPKjQktwe/Listeners', 09:16:23 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 09:16:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:23 INFO - 'USER': 'cltbld', 09:16:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:23 INFO - 'XPC_FLAGS': '0x0', 09:16:23 INFO - 'XPC_SERVICE_NAME': '0', 09:16:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:23 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)) 09:16:23 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)) 09:16:23 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)) 09:16:23 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)) 09:16:23 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)) 09:16:23 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)) 09:16:23 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)) 09:16:23 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)) 09:16:23 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)) 09:16:23 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)) 09:16:23 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)) 09:16:23 INFO - Cleaning up... 09:16:23 INFO - Return code: 0 09:16:23 INFO - Running post-action listener: _resource_record_post_action 09:16:23 INFO - Running post-action listener: _start_resource_monitoring 09:16:23 INFO - Starting resource monitoring. 09:16:23 INFO - ##### 09:16:23 INFO - ##### Running install step. 09:16:23 INFO - ##### 09:16:23 INFO - Running pre-action listener: _resource_record_pre_action 09:16:23 INFO - Running main action method: install 09:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:23 INFO - Reading from file tmpfile_stdout 09:16:23 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:24 INFO - Reading from file tmpfile_stdout 09:16:24 INFO - Output received: 09:16:24 INFO - Usage: mozinstall [options] installer 09:16:24 INFO - Options: 09:16:24 INFO - -h, --help show this help message and exit 09:16:24 INFO - -d DEST, --destination=DEST 09:16:24 INFO - Directory to install application into. [default: 09:16:24 INFO - "/builds/slave/test"] 09:16:24 INFO - --app=APP Application being installed. [default: firefox] 09:16:24 INFO - mkdir: /builds/slave/test/build/application 09:16:24 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'] 09:16:24 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 09:16:54 INFO - Reading from file tmpfile_stdout 09:16:54 INFO - Output received: 09:16:54 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 09:16:54 INFO - Running post-action listener: _resource_record_post_action 09:16:54 INFO - ##### 09:16:54 INFO - ##### Running run-tests step. 09:16:54 INFO - ##### 09:16:54 INFO - Running pre-action listener: _resource_record_pre_action 09:16:54 INFO - Running main action method: run_tests 09:16:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:16:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:16:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:16:54 INFO - 2015-11-19 09:16:54.491 screenresolution[3108:79277] starting screenresolution argv=screenresolution get 09:16:54 INFO - 2015-11-19 09:16:54.494 screenresolution[3108:79277] Display 0: 1600x1200x32@60 09:16:54 INFO - 2015-11-19 09:16:54.500 screenresolution[3109:79279] starting screenresolution argv=screenresolution list 09:16:54 INFO - Available Modes on Display 0 09:16:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:16:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:16:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:16:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:16:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:16:54 INFO - Intel Iris: 09:16:54 INFO - Chipset Model: Intel Iris 09:16:54 INFO - Type: GPU 09:16:54 INFO - Bus: Built-In 09:16:54 INFO - VRAM (Dynamic, Max): 1536 MB 09:16:54 INFO - Vendor: Intel (0x8086) 09:16:54 INFO - Device ID: 0x0a2e 09:16:54 INFO - Revision ID: 0x0009 09:16:54 INFO - Displays: 09:16:54 INFO - Display: 09:16:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:16:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:16:54 INFO - Display Serial Number: 1600x1200 60 09:16:54 INFO - Main Display: Yes 09:16:54 INFO - Mirror: Off 09:16:54 INFO - Online: Yes 09:16:54 INFO - Rotation: Supported 09:16:54 INFO - Return code: 0 09:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:16:54 INFO - Python 2.7.3 09:16:54 INFO - Return code: 0 09:16:54 INFO - grabbing minidump binary from tooltool 09:16:54 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')]} 09:16:54 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 0x1071799f0>, '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 0x106ed8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716c8c8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:16:54 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 09:16:54 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 09:16:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:16:54 INFO - Return code: 0 09:16:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:16:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:16:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:16:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:16:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:16:54 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0038', '--webServer', 'localhost'] in /builds/slave/test/build 09:16:54 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-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0038 --webServer localhost 09:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F293yiVd4N/Render', 09:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:54 INFO - 'HOME': '/Users/cltbld', 09:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:54 INFO - 'LOGNAME': 'cltbld', 09:16:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:16:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:54 INFO - 'MOZ_NO_REMOTE': '1', 09:16:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:16:54 INFO - 'NO_EM_RESTART': '1', 09:16:54 INFO - 'PAGER': '/bin/cat', 09:16:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:54 INFO - 'PWD': '/builds/slave/test', 09:16:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:54 INFO - 'SHELL': '/bin/bash', 09:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4bPKjQktwe/Listeners', 09:16:54 INFO - 'TMPDIR': '/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/', 09:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:54 INFO - 'USER': 'cltbld', 09:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:54 INFO - 'XPC_FLAGS': '0x0', 09:16:54 INFO - 'XPC_SERVICE_NAME': '0', 09:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0038', '--webServer', 'localhost'] with output_timeout 3600 09:16:55 INFO - mozversion INFO | application_buildid: 20151119065326 09:16:55 INFO - mozversion INFO | application_changeset: a523d4c7efe2f43dd6b25a176c07b729918d550f 09:16:55 INFO - mozversion INFO | application_display_name: Nightly 09:16:55 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:16:55 INFO - mozversion INFO | application_name: Firefox 09:16:55 INFO - mozversion INFO | application_remotingname: firefox 09:16:55 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 09:16:55 INFO - mozversion INFO | application_vendor: Mozilla 09:16:55 INFO - mozversion INFO | application_version: 45.0a1 09:16:55 INFO - mozversion INFO | platform_buildid: 20151119065326 09:16:55 INFO - mozversion INFO | platform_changeset: a523d4c7efe2f43dd6b25a176c07b729918d550f 09:16:55 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 09:16:55 INFO - mozversion INFO | platform_version: 45.0a1 09:16:55 INFO - 2015-11-19 09:16:55,043 DEBUG : using testdate: 1447953415 09:16:55 INFO - 2015-11-19 09:16:55,043 DEBUG : actual date: 1447953415 09:16:55 INFO - 2015-11-19 09:16:55,527 INFO : starting webserver on 'localhost:54344' 09:16:55 INFO - 2015-11-19 09:16:55,528 INFO : Starting test suite t-yosemite-r7-0038.e 09:16:55 INFO - 2015-11-19 09:16:55,528 INFO : Starting test dromaeo_css 09:16:55 INFO - 2015-11-19 09:16:55,529 DEBUG : operating with platform_type : mac_ 09:16:55 INFO - 2015-11-19 09:16:55,530 INFO : Initialising browser for dromaeo_css test... 09:16:55 INFO - 2015-11-19 09:16:55,541 DEBUG : command line: /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmplOAIhN/profile http://localhost:54344/getInfo.html 09:16:58 INFO - 2015-11-19 09:16:58,617 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:16:58 INFO - 2015-11-19 09:16:58,617 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:16:58 INFO - 2015-11-19 09:16:58,617 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/663 09:16:58 INFO - 2015-11-19 09:16:58,617 DEBUG : BROWSER_OUTPUT: __metrics 09:16:59 INFO - 2015-11-19 09:16:59,224 INFO : Browser initialized. 09:16:59 INFO - 2015-11-19 09:16:59,224 INFO : Running cycle 1/1 for dromaeo_css test... 09:16:59 INFO - 2015-11-19 09:16:59,224 DEBUG : command line: /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmplOAIhN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:17:11 INFO - 2015-11-19 09:17:11,198 DEBUG : BROWSER_OUTPUT: RSS: Main: 237117440 09:17:11 INFO - 2015-11-19 09:17:11,198 DEBUG : BROWSER_OUTPUT: 09:18:00 INFO - 2015-11-19 09:18:00,894 DEBUG : BROWSER_OUTPUT: 1447953480892 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] 09:20:12 INFO - 2015-11-19 09:20:12,023 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54344/tests/dromaeo/cssquery-ext.html) 09:20:13 INFO - 2015-11-19 09:20:13,485 DEBUG : BROWSER_OUTPUT: RSS: Main: 253108224 09:20:13 INFO - 2015-11-19 09:20:13,485 DEBUG : BROWSER_OUTPUT: 09:23:15 INFO - 2015-11-19 09:23:15,582 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/dromaeo/cssquery-ext.html (next: http://localhost:54344/tests/dromaeo/cssquery-jquery.html) 09:23:16 INFO - 2015-11-19 09:23:16,165 DEBUG : BROWSER_OUTPUT: RSS: Main: 261877760 09:23:16 INFO - 2015-11-19 09:23:16,165 DEBUG : BROWSER_OUTPUT: 09:26:17 INFO - 2015-11-19 09:26:17,809 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54344/tests/dromaeo/cssquery-mootools.html) 09:26:18 INFO - 2015-11-19 09:26:18,410 DEBUG : BROWSER_OUTPUT: RSS: Main: 262742016 09:26:18 INFO - 2015-11-19 09:26:18,410 DEBUG : BROWSER_OUTPUT: 09:29:20 INFO - 2015-11-19 09:29:20,017 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54344/tests/dromaeo/cssquery-prototype.html) 09:29:20 INFO - 2015-11-19 09:29:20,599 DEBUG : BROWSER_OUTPUT: RSS: Main: 266072064 09:29:20 INFO - 2015-11-19 09:29:20,600 DEBUG : BROWSER_OUTPUT: 09:32:23 INFO - 2015-11-19 09:32:23,149 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54344/tests/dromaeo/cssquery-yui.html) 09:32:23 INFO - 2015-11-19 09:32:23,751 DEBUG : BROWSER_OUTPUT: RSS: Main: 268644352 09:32:23 INFO - 2015-11-19 09:32:23,752 DEBUG : BROWSER_OUTPUT: 09:35:26 INFO - 2015-11-19 09:35:26,027 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/dromaeo/cssquery-yui.html (next: http://localhost:54344/tests/dromaeo/cssquery-dojo.html) 09:35:26 INFO - 2015-11-19 09:35:26,284 DEBUG : BROWSER_OUTPUT: RSS: Main: 271486976 09:35:26 INFO - 2015-11-19 09:35:26,284 DEBUG : BROWSER_OUTPUT: 09:35:26 INFO - 2015-11-19 09:35:26,284 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:35:26 INFO - 2015-11-19 09:35:26,284 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:35:26 INFO - 2015-11-19 09:35:26,284 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:35:26 INFO - 2015-11-19 09:35:26,284 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:35:26 INFO - 2015-11-19 09:35:26,285 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3274.230555819383;3483.5819701635805;3511.6839484446396;3535.71714262859;3561.3945629243026;5813.854653633662;5831.737571809268;5852.063669812831;5929.34777174511;5933;5799.420057994209;5894.941050589504;5914.373547274502;5935.584509084366;5973.850653733659;5191.454897235773;5299.390570084442;5405.837824865261;5460.972695136519;5466.726663666821;5339.866503337409;5361.169018802092;5392.541633961098;5392.676439413648;5406.702631355274;5395.892082158335;5437.238786569884;5455.5908306876945;5461.481159289881;5519.31008623922;5101.540861322462;5144.356955380577;5250.396204436501;5330.200469929503;5432.239486471897;4426.822927082912;5236.502532259429;5288.286081379003;5325.227841962933;5338.97330513345;5411.161270003156;5494.203340515643;5659.688717120557;5718.313802343704;5780.92048132986;6168.259808822925;6212.4408803207925;6242.282137554194;6245.250569931637;6289.7169627366875;6340.175777148941;6356;6358.968205158991;6378.808635740888;6392.3927226913365;5878.764849406061;5936.821895343147;5992.880142397127;6109.961306577848;6138.294096178907;6382.138411314506;6517.315681853337;6530.314316996647;6540.509461790385;6552.115464412243;6327.841803954938;6331;6346.904796428057;6369.17200763907;6372.585781924253;4930.8767280818265;6082.753035627686;6204.658743769136;6286.214223222098;6311.905321420182;6254.74981000764;6269.905951410732;6275.905861412082;6299.275583307978;6303.338149494329;6250.937490625127;6301.590396624205;6314.305426403092;6329.936700633027;6336.841578960563;4937.753112344369;5024.296598476216;5029.899402012013;5080.669756465892;5126.769295381717;4495.168393847083;4497.955020449819;4499.347594598765;4518.864434066983;4561.475430325555;4578.496365399803;5910.438508341701;6041.486473649789;6071.696415179224;6073.119397687311;6136.243373036804;6225;6242.313345531988;6353.841153971188;6366.808995730135;5464.453554644504;5556.722163891789;5990.520758339322;5997.280326360865;6019.247594050744;5376.596755243372;5433.402325744165;5502.477264659851;5508.586855985817;5510.476504732044;6406.711697973602;6433.839154021187;6479.222493300834;6600.933990660032;6605.372489613479;5666.886662266649;5880.235569376125;6307.779227727007;6320.178376810985;6335.366463353628;6338.714757835816;6367.3314301997625;6367.968160159169;6374.649394283173;6377.043443483329;6205.5345849060595;6247.468965137832;6281.466075383643;6297.4017468340435;6312.905306420499;6238;6330.082138090043;6336.651484168231;6360.141380913517;6365.077063825626;6133.815985520441;6199.28708198563;6254.718537665907;6292.4651404629285;6312.242530896138;6168.136460895568;6256.468200202851;6261.311255761953;6274.1843560336865;6297.496200303873;6029.366916473882;6145.846353841011;6231.688415579293;6251.749930002749;6261.96869015652;6072.908906366496;6143.082036032849;6177.81466556004;6286.717097730704;6288.937110628835;6084;6134.969325153525;6144.941444616104;6174.320824709368;6248.687565621792 09:35:26 INFO - 2015-11-19 09:35:26,285 DEBUG : BROWSER_OUTPUT: |1;ext.html;535.258666746556;559.7537083683181;602.864355520008;614.8348878874532;617.8609812792122;40663.796681016625;41204.175916481654;42145.157096858085;42732.57267427329;42848.78575607125;36656;46369.536304636946;46482;46658;46745.065098698;9852.211823054156;10039.34744241622;10047.145992590638;10058.949705251463;10059.597616095347;9855.852162217572;9927.106560409562;9967.700968970907;9974.800503989953;10003.449810260468;9876.407415555053;9886.752831179243;9925.205983521337;9947.1544918682;9973.501324933724;9834.213262938947;9901.801963960752;9907.900920990805;9952.80094398108;9986.600535978552;9812.607495700164;9941.552630131662;9943.254255930835;9964.05341492557;9967.65113221041;40966;41200.38199427011;41201.58798412007;41386.586134138575;41577.792111039555;3309.470484722433;3335.616404113534;3457.9308413831836;3654.872079477232;3829.6936245100605;29355.266118346997;29422.846889952238;29833.552496712568;30837.61230744622;30917.84541077303;2511.460036092231;2546.9939373947186;2560.385507478192;2581.7805486533666;2588.4693637804203;523891;527374;527418;527693;528643;72494;72913;73363.2663673367;73541;73648.52702946018;65993.6700316505;66896.99654505186;77625;82390.17609823945;83521.58239208766;14628.268586570628;17148.31406743741;17316.047617380915;17463.47609571715;17498.12509374526;13016.088873778745;13034.152780069266;13067;13178.60464186076;13217.669558261121;21109.577808443744;22881.313560593208;22920.770792292198;23098.884505577364;23101.653475197887;7048;8191.344692424711;9579.90420095804;9592.95203523978;9593.232541396672;952.3571589177278;989.8267803134422;997.2072202598952;1102.4322473925934;1153.4636394076626;26348;30294.697053029628;30654.080377588707;30718.38563228723;30928.381432371225;1062.1449732964884;1066.7439814444479;1104.718296834302;1140.310112381997;1224.6387315741877;14084.507042253472;17146.971181728888;17615.48859028379;17631.471055868344;17676.646467070845;694605;705979;709066;709456;712587;64627.35372646307;65009.34990650033;65080.67459662671;65082.69834603376;65173;1409.3587417724912;1412.180935057664;1425.209009000015;1436.3608194353412;1443.8628330308604;31770.68229317677;32136.83931580327;32335.51496727598;33177.83411082929;33358;54717.45282547283;54768;54865.72567137139;55000.17499737427;55466.72266638641;511564;514586;517952;519397.43314118084;521674;29313.922038186538;29374;34591;46901.76549117233;49851.75074124606;48065.79835504141;48510.5148948506;49316.50683493119;50666.22668206595;51079.21222757186;39416.60583394129;40136;40476.59523404728;40556.1888762229;40967.18065638611;39131.80434097811;39548.209035819695;39795.80102099471;40118.398224027245;40165.79917100396;39569.60430395774;40397;40646;40857;40888.79555602203;34301;35625.46561801627;35977.46033809547;36040.819795900854;36238.8188059058;23543.822808859146;24253.272401827973;26273.60589591196;26338.341541461617;26679.33301667474 09:35:26 INFO - 2015-11-19 09:35:26,286 DEBUG : BROWSER_OUTPUT: |2;jquery.html;352.7319287162649;359.277851518448;360.1787923534343;360.9440536716809;361.8462153584726;16084.517464476085;19718.21127154912;19749.802501974977;20875.895620521896;21606.24378146763;10356.430396328198;11521.423928803568;12382.62852114438;12524.24854508728;18968.24127034917;4170.603792639695;4214;4360.215161270962;4575.771211439432;4646.837360692376;4685.976570117162;4842.249451335048;4929.507049295078;4968.478309777475;4970.378702662168;4208.305629571117;4828.782704778276;4871.829485967992;4897.657163998521;4928.162212423878;4943.233798761198;4961.776720047588;4962.05720913029;4994.250862370637;4998.700077995314;3743.9438408423894;4095.4266402703647;4154.833806647731;4203.453551038376;4236.04688944988;11769.88230117705;13895.166290022631;13951.790723139162;13955.302234888215;14188.290585470686;1901.7052356884567;1907.8569107317007;1959.7354357161744;2056.228914157191;2111.947201319964;9961.701148965543;10008.199344052458;10159.441230732307;10246.641367552174;10252.282340236186;1429.4425174182077;1466.7579849324954;1555.587769241152;1587.055701857393;1608.02714357813;92959;93385.59921601182;93940.53029734876;94747.52626236893;95126.0487395131;6756.324367563205;6850.6574671266235;6857.55425897315;6908.1364829396325;6928.272531384233;7723;7771.72798952034;7818.335441487424;7821.178776228528;7877.724279650185;5712.485876271156;5774.364819869811;5833.912491312633;5838.5329173666005;5859.648421094748;5646.491815736604;5896.351401345849;6212.937870621236;6261.78083767066;6296.527760417987;2501.199616122823;2587.6118582212443;3051.6795736447352;3064.2798942248555;3084.0593618946436;988.2933702402817;1083.9403832789114;1103.0679076180616;1110.0453609895483;1133.8752737198902;404.6499777692334;442.3762494882265;463.46932761987335;470.3109943932156;481.1411630240005;12135.635930922086;12437.564685235973;12506.749865002648;12596.992240620728;12614;466.14927756843895;472.1218533527636;478.1298037571656;480.2388214679702;488.5114885114885;5512.3385193777;6555.180602424697;6565.244996825426;6616.4376028037195;6617.139771829728;661305;761314;763784;778401;790197;117346;117519.41240293744;121686.3915680416;122193.38903305604;122526;519.4104691175543;534.8796520782871;535.6866233253512;536.9140937449989;538.8545092824916;10711.410872402093;11608.767824643292;11942.761144777229;11948.940255298668;12128.393580321124;47127;47358.28962565495;48845.267320990926;49933.75033124956;50297.74255643638;64803;66874;70252;70639.64680176566;71573;6365.554411191172;6529.95520716683;6547.181602299713;6557.672116394066;6561.671916404065;10535.315204511682;10808.027277544745;12086.456109474919;12463.626091217278;12571.811422828849;10304.536295866554;10629.415382154055;10823;10872.836907446554;10976.835347469634;10550.736231594272;10638.73403164927;10666.8933310669;10719.088877445505;10795.946020269848;10770.976757208053;10841.349519028883;10965.890341096487;11083.778324433628;11103.777924441627;6353.36466353361;6356.237251529846;6387.329330420239;6445.291017988111;6512.381323774234;10742.086922611665;11016.283941543774;11062.834057489305;11154.996050355507;11171.776564468502 09:35:26 INFO - 2015-11-19 09:35:26,291 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1555.237933412628;1586.2703156547987;1891.2056936086838;1906.9713954290685;1909.5130741660873;7589.924100758991;7652.005239318881;8175.591220438977;8324.542150181745;8401.621927013284;8273.420860539765;8350.248477637011;8483.66065357385;8527;8530.976282846068;2364.64530320452;2379.286214135761;2408.6627872097833;2411.9397015074587;2413.6741539892155;2394.1859767678984;2418.0811291709215;2428.344347026302;2442.9877850610724;2452.7179374372;2372.6559648850907;2374.157174203159;2397.0411835265945;2405.5429468401035;2429.259075981825;2073.9170433182653;2350.659154422616;2401.615741481372;2407.735149133594;2414.2998530426157;2403.9399015024587;2419.1049311754587;2429.137656132083;2442.255112190781;2445.5231229909996;8440.860483834727;8478.279346255576;8482.872756908651;8485.151484851527;8487.44831585951;4687.273472611751;5114.667546609458;5194.298769666085;5216.58267338612;5219.765110570033;3264.0860559043504;3456.7753096048677;3463.9480407793903;3527.523784289114;3616.1682812952904;1047.9677517645114;1060.7083052160717;1083.1497274639448;1090.4765712458066;1100.2463312630794;11093.057090147347;11188.832167517494;11195.664130076111;11217.439128043647;11222.438878056146;4688.789004494806;4726.905461890743;4765.451973023078;4814.614830813526;4830.637702172281;9601.567929443192;9638.325317227727;9644.276679249084;9647.13175814166;9671.371360861522;5189.610779191576;5191.636585439059;5196.272521846944;5207.010667973073;5216.817411390583;4698.483166851644;4700.858974230779;4718.764061796896;4743.407074115736;4749.976250118796;3193.5050067239613;3343.849526771301;3487.442009278554;3885.261800257942;4061.472008638858;1080.4057768227444;1115.4311301236428;1281.7564662714055;1284.184542815319;1286.2346903592347;1346.414309775251;1358.3547814788094;1378.1593228130832;1396.9231692257024;1412.0750908155176;6738.427233685094;6921.619310937846;7754.612269386621;7783.104942931635;7944.483608565425;500.15473849194746;515.5720751776017;515.649358436267;516.1483552139001;517.4153206876206;5517.586181036439;6016.308124565643;6074.908876366858;6109.816705498843;6117.816465506042;8971.282297416192;10044.447555384524;10193.898061019296;10202.846957295647;10211.97880211973;9957.10386065243;10079.042490963493;10085.848712269322;10090.646827361154;10172.898271017342;7942.801429964182;7969.521828690297;7973;7979.561124138112;7981.161977992228;7639.732609358646;7686.730964416219;7726.18875018115;7751.992241008633;7759.185285544936;8971.237444817003;9038.141376569216;9097.454152750855;9125.406848554823;9222.123898229658;9108.22580080674;9315.534223288672;9370.250379969722;9425.104615061558;9441;7129.607871567114;7158.212596614264;7203;7227.710891564278;7232.312930271616;7963.960180199062;8022.83954320922;8035.156308587514;8039.919600804151;8100.959495202486;7467.589282589292;7470.4023678106705;7479.58862262559;7578.848423031619;7590.468667193243;7328.047353843966;7353.5955522446775;7425.7029718810645;7447.627618619155;7448.217937116525;7372.557646541225;7480.700771969278;7482.551046937201;7510.511816731895;7528.811779705551;5794;6777.525573209828;6803.965980170067;6806.8638627228165;6846.657667116525;6829.078074460083;6872.175338959357;6925.445964322741;6956.339147780953;6960.234374218933 09:35:26 INFO - 2015-11-19 09:35:26,291 DEBUG : BROWSER_OUTPUT: |4;prototype.html;280.65479460263873;324.8327111537557;330.6329973729162;331.3555135261915;331.7180396662837;6866.244713081557;6984.2317345092;7054.047703560018;7133.6789844457;7151.535150215239;6804.795856124324;6912.481563882704;6931.688074036668;6941.72233110675;6985.755498557552;5101.974490127544;5112.872178195537;5119.948800512003;5131.127708289581;5132.4610915853855;4927.753612319388;5130.3074084998425;5148.150555158394;5153.2785410042625;5166.9224961625605;4585.79363928624;4631.467381251148;5024.321716568272;5027.4972502749615;5046.343975283208;3600.7299452541033;4953.702777833323;4982.451930287647;5014.997000599895;5023.271625614302;3681.0429288384944;3910.237503686782;4014.879553613396;4154.4183814266025;4224.38746381776;5459.918101228484;5556.972215138939;5981.773736384031;6258.4680302174365;6618.874791285493;5306.548943339782;5597.580181486364;5873.119032145212;5949.613275137102;5957.165996760457;7261.673224704901;7465.589392583406;7473.439492038119;7474.103107627119;7477.700891964315;3944.309745794503;4738.147133515966;4741.881452963704;4751.073540659539;4767.713937163747;6524.380183882524;7879.960600196962;7925.564093974829;7947.602619868983;7977.720779772738;4630.560096790799;4786.8085276588545;4793;4810.206315957827;4835.347228124228;4896.95103048972;4902.313676085351;4909.018196360671;4911.8280860170435;4914.877128071756;5889.293284805765;5897;5903.704814759245;5922.555808314393;5943.732532036069;6112.480439162632;6394.520410969196;7265.600391978492;7526.360259378014;7540.057492813398;1645.983540164607;1683.0154359699468;1701.3279754497153;1707.5645710343783;1708.8462038416603;940.3652534539159;1065.2383545764815;1083.5828206140534;1089.9155340436157;1100.350793032115;7023.68393422287;7045.225025247219;7082.185548661867;7083.610401427868;7085.0435191248525;6340.429361357407;7708.03649543807;7786.26030527099;7847.921520784833;7853.646585903648;6934.583924964519;6949.513534052568;7061.152661680631;7070.964645176741;7088.539244949062;3361.83190840457;4051.7974101294817;4097.282975479279;4120.114175452292;4121.351459416198;8119.025716914008;8161.428699990944;8193.385496087818;8208.220219079178;8234.258916697407;5863.589548731633;8157;8160.67357305713;8167.183281671785;8167.305779008851;6680.766173183915;7898.091719452336;8190.83618327642;8211.425200235926;8212.260896519223;7886.487378320391;7887.211278872067;7905;7911.564863932539;7915.604219788872;7477.663505142173;7677.731279405194;7711.1517733048195;7745.496542724705;7756.612169391395;8125.634346454542;8173.836523269621;8180.14108518597;8191;8200.630971606173;3749.587545370062;4229.746215227096;4824.324594556695;4844.2007068833245;4844.806207751651;7494.812629684301;7806.141324454419;7829.804254893445;7841.725539606086;7866.803329916798;7513.924860751323;7520.285572870787;7548.509346892434;7561.483909981885;7570.735024274124;6076.392360763888;7248.710051597877;7497.175310715926;7509.699612015458;7519.699212031457;5972.492338151132;7349.742759003409;7354.889676654748;7358.521696089952;7396.408287337108;7473.364763994904;7496.512726672749;7510.586917719578;7510.774676759706;7521.247875212435;7147.2852714728115;7320.633968301457;7324;7369.226231245642;7374.520656157332 09:35:26 INFO - 2015-11-19 09:35:26,292 DEBUG : BROWSER_OUTPUT: |5;yui.html;707.1125737199814;742.1761844352767;774.8334108166744;814.3363159025394;828.4656396624177;14850.702985940276;15130.924345378258;15139.772903406458;15203.54389368318;15231.619209519768;13962.650933726662;14066.92966535166;14087.718245635082;15494.922525387385;15497.147656878868;942.2462030375672;953.8473844184914;960.2893858544661;966.6568368229283;968.5109019944917;945.6028468043404;947.3321308477541;956.7034219391976;963.6097380560884;966.2608104799833;993.6224234790769;996.3075662414625;1000.959961601535;1004.5328922051244;1007.9344842585245;982.7002764156929;989.6931951095183;990.4305024610842;994.4480813148699;1003.5183112106157;728.6429649471773;735.3161559749431;736.0982796074819;746.5259560179271;748.8951546783454;14632;14696.632584185374;14744.262786860614;14751;14861.628459288497;10496.422696751752;10649.148068154529;10708.875568065398;10963.287386319864;11113.44432778366;9691.854622180674;9755.756106097333;9781.364211326241;10157.492125393775;10462.058414742709;3130.4521708700886;3157.842107894596;3181.6659250778566;3188.3782662380845;3197.648258691542;64921.67539162321;66517;66722.66638666824;71363.2159196019;74567;7313.305236002571;7332.303431174137;7441.479096463196;7453.515521491086;7471.290227428494;12773.425195866208;12815.487380504861;12826;12891.0331725121;12894;6607.900881486781;6692.83267918306;6841.384275415137;6857;6955.130608673916;4022.6379625833815;4047.817848196838;4068.7558746474715;4069.6744260459686;4099.36459848718;3162.6995435433596;3169.0334447993123;3186.0441867439677;3190.3459790743304;3195.632502262223;444.4533025072765;478.19424270104986;488.59934853419855;495.69762444908577;501.0905207049194;465.3804759437178;499.1090902738594;499.5529001543598;510.77270614576156;572.8224250482281;7339.88990165148;8568.22885940256;8576.785580360542;8679.17547832955;8869.778255543662;586.6714639801639;600.4295918876996;615.3999850146072;621.5245337316957;637.7002808679913;3920.9607903921165;4017.9196416071513;4073.45008423859;4389.758563279051;4448.955510444919;91162;92456.53771731099;92661.07338926659;101564.49217753865;101648.49175754075;68495.65752171207;85818;86977.5651121753;87244.56377718071;88410;58566.70716646389;68627;70212.2978770216;70617.2938270621;71297.64351178314;8634.266087382643;9020.188183063556;9026.368154229142;9155.176034156957;9340.703038015103;52409;52456.737716311174;52899.413017609535;53113;54950;65461;78828.60585697035;82003.17996819955;82081.58959205166;82483;9655.806883862142;11768.352740599006;11988.520459181884;12012.039036876855;12056.216345937837;11593.420328983686;11992.520299187934;12075.27548347102;12270.631881043582;13135.934320328337;11540.538378464767;11780.52877884875;11887.945636992094;11946.044316454836;12018.579349723068;11682.598386447567;11683.00694440982;11752.529898803952;11922.45008148935;11937.164398492023;11289.491972861077;11293.830592540955;11463.942680286546;11773.823392649289;11898.821517677068;9412.482313472825;9477;9503.524823758922;9891.455969921435;10137.087662110445;8789.604467798836;9191.264698823945;9305.953470232605;9651.613935442503;9985.850212246676 09:35:26 INFO - 2015-11-19 09:35:26,292 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:35:26 INFO - 2015-11-19 09:35:26,292 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:35:26 INFO - 2015-11-19 09:35:26,293 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,330 09:35:26 INFO - 2015-11-19 09:35:26,293 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:35:26 INFO - 2015-11-19 09:35:26,294 DEBUG : BROWSER_OUTPUT: __startTimestamp1447954526279__endTimestamp 09:35:26 INFO - 2015-11-19 09:35:26,294 DEBUG : BROWSER_OUTPUT: 09:35:26 INFO - 2015-11-19 09:35:26,294 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:35:26 INFO - 2015-11-19 09:35:26,294 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:35:26 INFO - 2015-11-19 09:35:26,294 DEBUG : BROWSER_OUTPUT: 09:35:26 INFO - 2015-11-19 09:35:26,294 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5844.42 Median:6134.97 stddev:660.28 (10.8%) stddev-sans-first:630.77 09:35:26 INFO - 2015-11-19 09:35:26,294 DEBUG : BROWSER_OUTPUT: Values: 3274.2 3483.6 3511.7 3535.7 3561.4 5813.9 5831.7 5852.1 5929.3 5933.0 5799.4 5894.9 5914.4 5935.6 5973.9 5191.5 5299.4 5405.8 5461.0 5466.7 5339.9 5361.2 5392.5 5392.7 5406.7 5395.9 5437.2 5455.6 5461.5 5519.3 5101.5 5144.4 5250.4 5330.2 5432.2 4426.8 5236.5 5288.3 5325.2 5339.0 5411.2 5494.2 5659.7 5718.3 5780.9 6168.3 6212.4 6242.3 6245.3 6289.7 6340.2 6356.0 6359.0 6378.8 6392.4 5878.8 5936.8 5992.9 6110.0 6138.3 6382.1 6517.3 6530.3 6540.5 6552.1 6327.8 6331.0 6346.9 6369.2 6372.6 4930.9 6082.8 6204.7 6286.2 6311.9 6254.7 6269.9 6275.9 6299.3 6303.3 6250.9 6301.6 6314.3 6329.9 6336.8 4937.8 5024.3 5029.9 5080.7 5126.8 4495.2 4498.0 4499.3 4518.9 4561.5 4578.5 5910.4 6041.5 6071.7 6073.1 6136.2 6225.0 6242.3 6353.8 6366.8 5464.5 5556.7 5990.5 5997.3 6019.2 5376.6 5433.4 5502.5 5508.6 5510.5 6406.7 6433.8 6479.2 6600.9 6605.4 5666.9 5880.2 6307.8 6320.2 6335.4 6338.7 6367.3 6368.0 6374.6 6377.0 6205.5 6247.5 6281.5 6297.4 6312.9 6238.0 6330.1 6336.7 6360.1 6365.1 6133.8 6199.3 6254.7 6292.5 6312.2 6168.1 6256.5 6261.3 6274.2 6297.5 6029.4 6145.8 6231.7 6251.7 6262.0 6072.9 6143.1 6177.8 6286.7 6288.9 6084.0 6135.0 6144.9 6174.3 6248.7 09:35:26 INFO - 2015-11-19 09:35:26,294 DEBUG : BROWSER_OUTPUT: 09:35:26 INFO - 2015-11-19 09:35:26,296 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:73507.11 Median:40927.40 stddev:157560.02 (385.0%) stddev-sans-first:157906.76 09:35:26 INFO - 2015-11-19 09:35:26,296 DEBUG : BROWSER_OUTPUT: Values: 535.3 559.8 602.9 614.8 617.9 40663.8 41204.2 42145.2 42732.6 42848.8 36656.0 46369.5 46482.0 46658.0 46745.1 9852.2 10039.3 10047.1 10058.9 10059.6 9855.9 9927.1 9967.7 9974.8 10003.4 9876.4 9886.8 9925.2 9947.2 9973.5 9834.2 9901.8 9907.9 9952.8 9986.6 9812.6 9941.6 9943.3 9964.1 9967.7 40966.0 41200.4 41201.6 41386.6 41577.8 3309.5 3335.6 3457.9 3654.9 3829.7 29355.3 29422.8 29833.6 30837.6 30917.8 2511.5 2547.0 2560.4 2581.8 2588.5 523891.0 527374.0 527418.0 527693.0 528643.0 72494.0 72913.0 73363.3 73541.0 73648.5 65993.7 66897.0 77625.0 82390.2 83521.6 14628.3 17148.3 17316.0 17463.5 17498.1 13016.1 13034.2 13067.0 13178.6 13217.7 21109.6 22881.3 22920.8 23098.9 23101.7 7048.0 8191.3 9579.9 9593.0 9593.2 952.4 989.8 997.2 1102.4 1153.5 26348.0 30294.7 30654.1 30718.4 30928.4 1062.1 1066.7 1104.7 1140.3 1224.6 14084.5 17147.0 17615.5 17631.5 17676.6 694605.0 705979.0 709066.0 709456.0 712587.0 64627.4 65009.3 65080.7 65082.7 65173.0 1409.4 1412.2 1425.2 1436.4 1443.9 31770.7 32136.8 32335.5 33177.8 33358.0 54717.5 54768.0 54865.7 55000.2 55466.7 511564.0 514586.0 517952.0 519397.4 521674.0 29313.9 29374.0 34591.0 46901.8 49851.8 48065.8 48510.5 49316.5 50666.2 51079.2 39416.6 40136.0 40476.6 40556.2 40967.2 39131.8 39548.2 39795.8 40118.4 40165.8 39569.6 40397.0 40646.0 40857.0 40888.8 34301.0 35625.5 35977.5 36040.8 36238.8 23543.8 24253.3 26273.6 26338.3 26679.3 09:35:26 INFO - 2015-11-19 09:35:26,296 DEBUG : BROWSER_OUTPUT: 09:35:26 INFO - 2015-11-19 09:35:26,296 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:36017.89 Median:4225.02 stddev:124176.64 (2939.1%) stddev-sans-first:124496.11 09:35:26 INFO - 2015-11-19 09:35:26,297 DEBUG : BROWSER_OUTPUT: Values: 352.7 359.3 360.2 360.9 361.8 16084.5 19718.2 19749.8 20875.9 21606.2 10356.4 11521.4 12382.6 12524.2 18968.2 4170.6 4214.0 4360.2 4575.8 4646.8 4686.0 4842.2 4929.5 4968.5 4970.4 4208.3 4828.8 4871.8 4897.7 4928.2 4943.2 4961.8 4962.1 4994.3 4998.7 3743.9 4095.4 4154.8 4203.5 4236.0 11769.9 13895.2 13951.8 13955.3 14188.3 1901.7 1907.9 1959.7 2056.2 2111.9 9961.7 10008.2 10159.4 10246.6 10252.3 1429.4 1466.8 1555.6 1587.1 1608.0 92959.0 93385.6 93940.5 94747.5 95126.0 6756.3 6850.7 6857.6 6908.1 6928.3 7723.0 7771.7 7818.3 7821.2 7877.7 5712.5 5774.4 5833.9 5838.5 5859.6 5646.5 5896.4 6212.9 6261.8 6296.5 2501.2 2587.6 3051.7 3064.3 3084.1 988.3 1083.9 1103.1 1110.0 1133.9 404.6 442.4 463.5 470.3 481.1 12135.6 12437.6 12506.7 12597.0 12614.0 466.1 472.1 478.1 480.2 488.5 5512.3 6555.2 6565.2 6616.4 6617.1 661305.0 761314.0 763784.0 778401.0 790197.0 117346.0 117519.4 121686.4 122193.4 122526.0 519.4 534.9 535.7 536.9 538.9 10711.4 11608.8 11942.8 11948.9 12128.4 47127.0 47358.3 48845.3 49933.8 50297.7 64803.0 66874.0 70252.0 70639.6 71573.0 6365.6 6530.0 6547.2 6557.7 6561.7 10535.3 10808.0 12086.5 12463.6 12571.8 10304.5 10629.4 10823.0 10872.8 10976.8 10550.7 10638.7 10666.9 10719.1 10795.9 10771.0 10841.3 10965.9 11083.8 11103.8 6353.4 6356.2 6387.3 6445.3 6512.4 10742.1 11016.3 11062.8 11155.0 11171.8 09:35:26 INFO - 2015-11-19 09:35:26,298 DEBUG : BROWSER_OUTPUT: 09:35:26 INFO - 2015-11-19 09:35:26,298 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5809.77 Median:5216.70 stddev:3032.19 (58.1%) stddev-sans-first:3023.84 09:35:26 INFO - 2015-11-19 09:35:26,299 DEBUG : BROWSER_OUTPUT: Values: 1555.2 1586.3 1891.2 1907.0 1909.5 7589.9 7652.0 8175.6 8324.5 8401.6 8273.4 8350.2 8483.7 8527.0 8531.0 2364.6 2379.3 2408.7 2411.9 2413.7 2394.2 2418.1 2428.3 2443.0 2452.7 2372.7 2374.2 2397.0 2405.5 2429.3 2073.9 2350.7 2401.6 2407.7 2414.3 2403.9 2419.1 2429.1 2442.3 2445.5 8440.9 8478.3 8482.9 8485.2 8487.4 4687.3 5114.7 5194.3 5216.6 5219.8 3264.1 3456.8 3463.9 3527.5 3616.2 1048.0 1060.7 1083.1 1090.5 1100.2 11093.1 11188.8 11195.7 11217.4 11222.4 4688.8 4726.9 4765.5 4814.6 4830.6 9601.6 9638.3 9644.3 9647.1 9671.4 5189.6 5191.6 5196.3 5207.0 5216.8 4698.5 4700.9 4718.8 4743.4 4750.0 3193.5 3343.8 3487.4 3885.3 4061.5 1080.4 1115.4 1281.8 1284.2 1286.2 1346.4 1358.4 1378.2 1396.9 1412.1 6738.4 6921.6 7754.6 7783.1 7944.5 500.2 515.6 515.6 516.1 517.4 5517.6 6016.3 6074.9 6109.8 6117.8 8971.3 10044.4 10193.9 10202.8 10212.0 9957.1 10079.0 10085.8 10090.6 10172.9 7942.8 7969.5 7973.0 7979.6 7981.2 7639.7 7686.7 7726.2 7752.0 7759.2 8971.2 9038.1 9097.5 9125.4 9222.1 9108.2 9315.5 9370.3 9425.1 9441.0 7129.6 7158.2 7203.0 7227.7 7232.3 7964.0 8022.8 8035.2 8039.9 8101.0 7467.6 7470.4 7479.6 7578.8 7590.5 7328.0 7353.6 7425.7 7447.6 7448.2 7372.6 7480.7 7482.6 7510.5 7528.8 5794.0 6777.5 6804.0 6806.9 6846.7 6829.1 6872.2 6925.4 6956.3 6960.2 09:35:26 INFO - 2015-11-19 09:35:26,299 DEBUG : BROWSER_OUTPUT: 09:35:26 INFO - 2015-11-19 09:35:26,299 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:6018.07 Median:6933.14 stddev:2014.40 (29.1%) stddev-sans-first:1973.49 09:35:26 INFO - 2015-11-19 09:35:26,299 DEBUG : BROWSER_OUTPUT: Values: 280.7 324.8 330.6 331.4 331.7 6866.2 6984.2 7054.0 7133.7 7151.5 6804.8 6912.5 6931.7 6941.7 6985.8 5102.0 5112.9 5119.9 5131.1 5132.5 4927.8 5130.3 5148.2 5153.3 5166.9 4585.8 4631.5 5024.3 5027.5 5046.3 3600.7 4953.7 4982.5 5015.0 5023.3 3681.0 3910.2 4014.9 4154.4 4224.4 5459.9 5557.0 5981.8 6258.5 6618.9 5306.5 5597.6 5873.1 5949.6 5957.2 7261.7 7465.6 7473.4 7474.1 7477.7 3944.3 4738.1 4741.9 4751.1 4767.7 6524.4 7880.0 7925.6 7947.6 7977.7 4630.6 4786.8 4793.0 4810.2 4835.3 4897.0 4902.3 4909.0 4911.8 4914.9 5889.3 5897.0 5903.7 5922.6 5943.7 6112.5 6394.5 7265.6 7526.4 7540.1 1646.0 1683.0 1701.3 1707.6 1708.8 940.4 1065.2 1083.6 1089.9 1100.4 7023.7 7045.2 7082.2 7083.6 7085.0 6340.4 7708.0 7786.3 7847.9 7853.6 6934.6 6949.5 7061.2 7071.0 7088.5 3361.8 4051.8 4097.3 4120.1 4121.4 8119.0 8161.4 8193.4 8208.2 8234.3 5863.6 8157.0 8160.7 8167.2 8167.3 6680.8 7898.1 8190.8 8211.4 8212.3 7886.5 7887.2 7905.0 7911.6 7915.6 7477.7 7677.7 7711.2 7745.5 7756.6 8125.6 8173.8 8180.1 8191.0 8200.6 3749.6 4229.7 4824.3 4844.2 4844.8 7494.8 7806.1 7829.8 7841.7 7866.8 7513.9 7520.3 7548.5 7561.5 7570.7 6076.4 7248.7 7497.2 7509.7 7519.7 5972.5 7349.7 7354.9 7358.5 7396.4 7473.4 7496.5 7510.6 7510.8 7521.2 7147.3 7320.6 7324.0 7369.2 7374.5 09:35:26 INFO - 2015-11-19 09:35:26,300 DEBUG : BROWSER_OUTPUT: 09:35:26 INFO - 2015-11-19 09:35:26,300 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:18238.02 Median:54031.50 stddev:26342.32 (48.8%) stddev-sans-first:26383.32 09:35:26 INFO - 2015-11-19 09:35:26,301 DEBUG : BROWSER_OUTPUT: Values: 707.1 742.2 774.8 814.3 828.5 14850.7 15130.9 15139.8 15203.5 15231.6 13962.7 14066.9 14087.7 15494.9 15497.1 942.2 953.8 960.3 966.7 968.5 945.6 947.3 956.7 963.6 966.3 993.6 996.3 1001.0 1004.5 1007.9 982.7 989.7 990.4 994.4 1003.5 728.6 735.3 736.1 746.5 748.9 14632.0 14696.6 14744.3 14751.0 14861.6 10496.4 10649.1 10708.9 10963.3 11113.4 9691.9 9755.8 9781.4 10157.5 10462.1 3130.5 3157.8 3181.7 3188.4 3197.6 64921.7 66517.0 66722.7 71363.2 74567.0 7313.3 7332.3 7441.5 7453.5 7471.3 12773.4 12815.5 12826.0 12891.0 12894.0 6607.9 6692.8 6841.4 6857.0 6955.1 4022.6 4047.8 4068.8 4069.7 4099.4 3162.7 3169.0 3186.0 3190.3 3195.6 444.5 478.2 488.6 495.7 501.1 465.4 499.1 499.6 510.8 572.8 7339.9 8568.2 8576.8 8679.2 8869.8 586.7 600.4 615.4 621.5 637.7 3921.0 4017.9 4073.5 4389.8 4449.0 91162.0 92456.5 92661.1 101564.5 101648.5 68495.7 85818.0 86977.6 87244.6 88410.0 58566.7 68627.0 70212.3 70617.3 71297.6 8634.3 9020.2 9026.4 9155.2 9340.7 52409.0 52456.7 52899.4 53113.0 54950.0 65461.0 78828.6 82003.2 82081.6 82483.0 9655.8 11768.4 11988.5 12012.0 12056.2 11593.4 11992.5 12075.3 12270.6 13135.9 11540.5 11780.5 11887.9 11946.0 12018.6 11682.6 11683.0 11752.5 11922.5 11937.2 11289.5 11293.8 11463.9 11773.8 11898.8 9412.5 9477.0 9503.5 9891.5 10137.1 8789.6 9191.3 9306.0 9651.6 9985.9 09:35:26 INFO - 2015-11-19 09:35:26,301 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:35:26 INFO - 2015-11-19 09:35:26,301 DEBUG : BROWSER_OUTPUT: 09:35:26 INFO - 2015-11-19 09:35:26,720 INFO : Browser exited with error code: 0 09:35:26 INFO - 2015-11-19 09:35:26,745 INFO : Completed test dromaeo_css (00:18:31) 09:35:26 INFO - 2015-11-19 09:35:26,745 INFO : Starting test kraken 09:35:26 INFO - 2015-11-19 09:35:26,745 DEBUG : operating with platform_type : mac_ 09:35:26 INFO - 2015-11-19 09:35:26,746 INFO : Initialising browser for kraken test... 09:35:26 INFO - 2015-11-19 09:35:26,758 DEBUG : command line: /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmpWp7VOS/profile http://localhost:54344/getInfo.html 09:35:29 INFO - 2015-11-19 09:35:29,458 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:35:29 INFO - 2015-11-19 09:35:29,459 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:35:29 INFO - 2015-11-19 09:35:29,459 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/663 09:35:29 INFO - 2015-11-19 09:35:29,459 DEBUG : BROWSER_OUTPUT: __metrics 09:35:30 INFO - 2015-11-19 09:35:30,057 INFO : Browser initialized. 09:35:30 INFO - 2015-11-19 09:35:30,057 INFO : Running cycle 1/1 for kraken test... 09:35:30 INFO - 2015-11-19 09:35:30,057 DEBUG : command line: /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmpWp7VOS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:35:41 INFO - 2015-11-19 09:35:41,959 DEBUG : BROWSER_OUTPUT: RSS: Main: 235765760 09:35:41 INFO - 2015-11-19 09:35:41,959 DEBUG : BROWSER_OUTPUT: 09:35:44 INFO - 2015-11-19 09:35:44,685 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54344/tests/kraken/driver.html?testName=audio-beat-detection) 09:35:45 INFO - 2015-11-19 09:35:45,296 DEBUG : BROWSER_OUTPUT: RSS: Main: 249024512 09:35:45 INFO - 2015-11-19 09:35:45,297 DEBUG : BROWSER_OUTPUT: 09:35:48 INFO - 2015-11-19 09:35:48,047 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54344/tests/kraken/driver.html?testName=audio-dft) 09:35:48 INFO - 2015-11-19 09:35:48,653 DEBUG : BROWSER_OUTPUT: RSS: Main: 258654208 09:35:48 INFO - 2015-11-19 09:35:48,653 DEBUG : BROWSER_OUTPUT: 09:35:51 INFO - 2015-11-19 09:35:51,857 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54344/tests/kraken/driver.html?testName=audio-fft) 09:35:52 INFO - 2015-11-19 09:35:52,438 DEBUG : BROWSER_OUTPUT: RSS: Main: 261226496 09:35:52 INFO - 2015-11-19 09:35:52,438 DEBUG : BROWSER_OUTPUT: 09:35:54 INFO - 2015-11-19 09:35:54,490 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54344/tests/kraken/driver.html?testName=audio-oscillator) 09:35:55 INFO - 2015-11-19 09:35:55,093 DEBUG : BROWSER_OUTPUT: RSS: Main: 262987776 09:35:55 INFO - 2015-11-19 09:35:55,093 DEBUG : BROWSER_OUTPUT: 09:35:57 INFO - 2015-11-19 09:35:57,166 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54344/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:35:57 INFO - 2015-11-19 09:35:57,751 DEBUG : BROWSER_OUTPUT: RSS: Main: 264318976 09:35:57 INFO - 2015-11-19 09:35:57,751 DEBUG : BROWSER_OUTPUT: 09:36:00 INFO - 2015-11-19 09:36:00,533 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54344/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:01 INFO - 2015-11-19 09:36:01,134 DEBUG : BROWSER_OUTPUT: RSS: Main: 265449472 09:36:01 INFO - 2015-11-19 09:36:01,134 DEBUG : BROWSER_OUTPUT: 09:36:03 INFO - 2015-11-19 09:36:03,939 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54344/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:04 INFO - 2015-11-19 09:36:04,541 DEBUG : BROWSER_OUTPUT: RSS: Main: 265322496 09:36:04 INFO - 2015-11-19 09:36:04,542 DEBUG : BROWSER_OUTPUT: 09:36:07 INFO - 2015-11-19 09:36:07,331 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54344/tests/kraken/driver.html?testName=json-parse-financial) 09:36:07 INFO - 2015-11-19 09:36:07,930 DEBUG : BROWSER_OUTPUT: RSS: Main: 263454720 09:36:07 INFO - 2015-11-19 09:36:07,930 DEBUG : BROWSER_OUTPUT: 09:36:09 INFO - 2015-11-19 09:36:09,611 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54344/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:10 INFO - 2015-11-19 09:36:10,193 DEBUG : BROWSER_OUTPUT: RSS: Main: 265441280 09:36:10 INFO - 2015-11-19 09:36:10,193 DEBUG : BROWSER_OUTPUT: 09:36:11 INFO - 2015-11-19 09:36:11,963 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54344/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:12 INFO - 2015-11-19 09:36:12,597 DEBUG : BROWSER_OUTPUT: RSS: Main: 264785920 09:36:12 INFO - 2015-11-19 09:36:12,597 DEBUG : BROWSER_OUTPUT: 09:36:14 INFO - 2015-11-19 09:36:14,727 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54344/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:15 INFO - 2015-11-19 09:36:15,327 DEBUG : BROWSER_OUTPUT: RSS: Main: 264388608 09:36:15 INFO - 2015-11-19 09:36:15,327 DEBUG : BROWSER_OUTPUT: 09:36:17 INFO - 2015-11-19 09:36:17,900 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54344/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:18 INFO - 2015-11-19 09:36:18,505 DEBUG : BROWSER_OUTPUT: RSS: Main: 263749632 09:36:18 INFO - 2015-11-19 09:36:18,505 DEBUG : BROWSER_OUTPUT: 09:36:20 INFO - 2015-11-19 09:36:20,959 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54344/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:21 INFO - 2015-11-19 09:36:21,559 DEBUG : BROWSER_OUTPUT: RSS: Main: 263766016 09:36:21 INFO - 2015-11-19 09:36:21,559 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,358 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54344/tests/kraken/driver.html?testName=ai-astar) 09:36:23 INFO - 2015-11-19 09:36:23,616 DEBUG : BROWSER_OUTPUT: RSS: Main: 264241152 09:36:23 INFO - 2015-11-19 09:36:23,616 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,616 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:36:23 INFO - 2015-11-19 09:36:23,616 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:36:23 INFO - 2015-11-19 09:36:23,616 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:36:23 INFO - 2015-11-19 09:36:23,616 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:36:23 INFO - 2015-11-19 09:36:23,616 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;106;111;112;114;125;112;114;141;162 09:36:23 INFO - 2015-11-19 09:36:23,617 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;148;134;170;176;146;155;140;144;186;149 09:36:23 INFO - 2015-11-19 09:36:23,617 DEBUG : BROWSER_OUTPUT: |2;audio-dft;191;196;200;194;210;206;200;198;207;194 09:36:23 INFO - 2015-11-19 09:36:23,617 DEBUG : BROWSER_OUTPUT: |3;audio-fft;67;72;104;126;82;84;85;75;116;66 09:36:23 INFO - 2015-11-19 09:36:23,617 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;76;78;109;75;77;62;126;109;110;148 09:36:23 INFO - 2015-11-19 09:36:23,617 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;132;132;87;87;87;88;95;89;80;87 09:36:23 INFO - 2015-11-19 09:36:23,617 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;95;96;95;103;126;96;93;96;96;97 09:36:23 INFO - 2015-11-19 09:36:23,617 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;90;92;91;114;147;92;95;93;92;91 09:36:23 INFO - 2015-11-19 09:36:23,617 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;59;57;59;62;81;86;78;98;87;59 09:36:23 INFO - 2015-11-19 09:36:23,617 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;65;67;65;65;68;68;68;70;71;74 09:36:23 INFO - 2015-11-19 09:36:23,617 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;102;84;83;85;85;82;104;105;104 09:36:23 INFO - 2015-11-19 09:36:23,617 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;133;123;137;132;131;118;135;116;130;130 09:36:23 INFO - 2015-11-19 09:36:23,617 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;131;144;125;127;143;183;159;137;141;132 09:36:23 INFO - 2015-11-19 09:36:23,617 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;75;91;89;85;67;66;84;88;94;80 09:36:23 INFO - 2015-11-19 09:36:23,618 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:36:23 INFO - 2015-11-19 09:36:23,618 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:36:23 INFO - 2015-11-19 09:36:23,618 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,843 09:36:23 INFO - 2015-11-19 09:36:23,618 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:36:23 INFO - 2015-11-19 09:36:23,618 DEBUG : BROWSER_OUTPUT: __startTimestamp1447954583610__endTimestamp 09:36:23 INFO - 2015-11-19 09:36:23,618 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,618 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:36:23 INFO - 2015-11-19 09:36:23,618 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:36:23 INFO - 2015-11-19 09:36:23,618 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,618 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:121.00 Median:114.00 stddev:17.47 (15.3%) stddev-sans-first:18.29 09:36:23 INFO - 2015-11-19 09:36:23,618 DEBUG : BROWSER_OUTPUT: Values: 113.0 106.0 111.0 112.0 114.0 125.0 112.0 114.0 141.0 162.0 09:36:23 INFO - 2015-11-19 09:36:23,618 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,618 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:154.80 Median:152.00 stddev:16.93 (11.1%) stddev-sans-first:17.78 09:36:23 INFO - 2015-11-19 09:36:23,619 DEBUG : BROWSER_OUTPUT: Values: 148.0 134.0 170.0 176.0 146.0 155.0 140.0 144.0 186.0 149.0 09:36:23 INFO - 2015-11-19 09:36:23,619 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,619 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:199.60 Median:200.00 stddev:6.29 (3.1%) stddev-sans-first:5.85 09:36:23 INFO - 2015-11-19 09:36:23,619 DEBUG : BROWSER_OUTPUT: Values: 191.0 196.0 200.0 194.0 210.0 206.0 200.0 198.0 207.0 194.0 09:36:23 INFO - 2015-11-19 09:36:23,619 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,619 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.70 Median:73.50 stddev:20.80 (28.3%) stddev-sans-first:20.67 09:36:23 INFO - 2015-11-19 09:36:23,619 DEBUG : BROWSER_OUTPUT: Values: 67.0 72.0 104.0 126.0 82.0 84.0 85.0 75.0 116.0 66.0 09:36:23 INFO - 2015-11-19 09:36:23,619 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,619 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:97.00 Median:68.50 stddev:27.51 (40.2%) stddev-sans-first:28.11 09:36:23 INFO - 2015-11-19 09:36:23,619 DEBUG : BROWSER_OUTPUT: Values: 76.0 78.0 109.0 75.0 77.0 62.0 126.0 109.0 110.0 148.0 09:36:23 INFO - 2015-11-19 09:36:23,619 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,619 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:87.00 stddev:19.10 (22.0%) stddev-sans-first:15.31 09:36:23 INFO - 2015-11-19 09:36:23,620 DEBUG : BROWSER_OUTPUT: Values: 132.0 132.0 87.0 87.0 87.0 88.0 95.0 89.0 80.0 87.0 09:36:23 INFO - 2015-11-19 09:36:23,620 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,620 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:99.30 Median:96.00 stddev:9.73 (10.1%) stddev-sans-first:10.20 09:36:23 INFO - 2015-11-19 09:36:23,620 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 103.0 126.0 96.0 93.0 96.0 96.0 97.0 09:36:23 INFO - 2015-11-19 09:36:23,620 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,620 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:99.70 Median:92.00 stddev:18.05 (19.6%) stddev-sans-first:18.80 09:36:23 INFO - 2015-11-19 09:36:23,620 DEBUG : BROWSER_OUTPUT: Values: 90.0 92.0 91.0 114.0 147.0 92.0 95.0 93.0 92.0 91.0 09:36:23 INFO - 2015-11-19 09:36:23,620 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,620 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:72.60 Median:79.50 stddev:15.06 (18.9%) stddev-sans-first:15.15 09:36:23 INFO - 2015-11-19 09:36:23,620 DEBUG : BROWSER_OUTPUT: Values: 59.0 57.0 59.0 62.0 81.0 86.0 78.0 98.0 87.0 59.0 09:36:23 INFO - 2015-11-19 09:36:23,620 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,620 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:2.92 (4.3%) stddev-sans-first:2.88 09:36:23 INFO - 2015-11-19 09:36:23,620 DEBUG : BROWSER_OUTPUT: Values: 65.0 67.0 65.0 65.0 68.0 68.0 68.0 70.0 71.0 74.0 09:36:23 INFO - 2015-11-19 09:36:23,621 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,621 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:82.50 stddev:10.53 (12.8%) stddev-sans-first:10.58 09:36:23 INFO - 2015-11-19 09:36:23,621 DEBUG : BROWSER_OUTPUT: Values: 82.0 102.0 84.0 83.0 85.0 85.0 82.0 104.0 105.0 104.0 09:36:23 INFO - 2015-11-19 09:36:23,621 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,621 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:128.50 Median:131.50 stddev:7.11 (5.4%) stddev-sans-first:7.35 09:36:23 INFO - 2015-11-19 09:36:23,621 DEBUG : BROWSER_OUTPUT: Values: 133.0 123.0 137.0 132.0 131.0 118.0 135.0 116.0 130.0 130.0 09:36:23 INFO - 2015-11-19 09:36:23,621 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,621 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.20 Median:142.00 stddev:17.43 (12.3%) stddev-sans-first:18.01 09:36:23 INFO - 2015-11-19 09:36:23,621 DEBUG : BROWSER_OUTPUT: Values: 131.0 144.0 125.0 127.0 143.0 183.0 159.0 137.0 141.0 132.0 09:36:23 INFO - 2015-11-19 09:36:23,621 DEBUG : BROWSER_OUTPUT: 09:36:23 INFO - 2015-11-19 09:36:23,621 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.90 Median:86.50 stddev:9.76 (11.3%) stddev-sans-first:10.02 09:36:23 INFO - 2015-11-19 09:36:23,621 DEBUG : BROWSER_OUTPUT: Values: 75.0 91.0 89.0 85.0 67.0 66.0 84.0 88.0 94.0 80.0 09:36:23 INFO - 2015-11-19 09:36:23,621 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:36:23 INFO - 2015-11-19 09:36:23,622 DEBUG : BROWSER_OUTPUT: 09:36:24 INFO - 2015-11-19 09:36:24,223 INFO : Browser exited with error code: 0 09:36:24 INFO - 2015-11-19 09:36:24,271 INFO : Completed test kraken (00:00:57) 09:36:24 INFO - 2015-11-19 09:36:24,271 INFO : Starting test v8_7 09:36:24 INFO - 2015-11-19 09:36:24,271 DEBUG : operating with platform_type : mac_ 09:36:24 INFO - 2015-11-19 09:36:24,271 INFO : Initialising browser for v8_7 test... 09:36:24 INFO - 2015-11-19 09:36:24,283 DEBUG : command line: /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmp13Da5M/profile http://localhost:54344/getInfo.html 09:36:26 INFO - 2015-11-19 09:36:26,996 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:36:26 INFO - 2015-11-19 09:36:26,996 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:36:26 INFO - 2015-11-19 09:36:26,996 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/663 09:36:26 INFO - 2015-11-19 09:36:26,997 DEBUG : BROWSER_OUTPUT: __metrics 09:36:27 INFO - 2015-11-19 09:36:27,585 INFO : Browser initialized. 09:36:27 INFO - 2015-11-19 09:36:27,585 INFO : Running cycle 1/1 for v8_7 test... 09:36:27 INFO - 2015-11-19 09:36:27,585 DEBUG : command line: /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jf/19plt40543lf16nnblwl7x1c00000w/T/tmp13Da5M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:36:39 INFO - 2015-11-19 09:36:39,639 DEBUG : BROWSER_OUTPUT: RSS: Main: 237363200 09:36:39 INFO - 2015-11-19 09:36:39,640 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,058 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54344/tests/v8_7/run.html (next: http://localhost:54344/tests/v8_7/run.html) 09:37:01 INFO - 2015-11-19 09:37:01,310 DEBUG : BROWSER_OUTPUT: RSS: Main: 247853056 09:37:01 INFO - 2015-11-19 09:37:01,311 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,312 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:37:01 INFO - 2015-11-19 09:37:01,312 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:37:01 INFO - 2015-11-19 09:37:01,312 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:37:01 INFO - 2015-11-19 09:37:01,312 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:37:01 INFO - 2015-11-19 09:37:01,313 DEBUG : BROWSER_OUTPUT: |0;Richards;147.42739200943535 09:37:01 INFO - 2015-11-19 09:37:01,313 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;157.67896562598548 09:37:01 INFO - 2015-11-19 09:37:01,313 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.2058906825262 09:37:01 INFO - 2015-11-19 09:37:01,313 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4926.108374384236 09:37:01 INFO - 2015-11-19 09:37:01,313 DEBUG : BROWSER_OUTPUT: |4;RayTrace;956.0229445506692 09:37:01 INFO - 2015-11-19 09:37:01,313 DEBUG : BROWSER_OUTPUT: |5;Earley;717.3601147776184 09:37:01 INFO - 2015-11-19 09:37:01,313 DEBUG : BROWSER_OUTPUT: |6;Boyer;13581.081081081082 09:37:01 INFO - 2015-11-19 09:37:01,313 DEBUG : BROWSER_OUTPUT: |7;RegExp;26657.894736842107 09:37:01 INFO - 2015-11-19 09:37:01,313 DEBUG : BROWSER_OUTPUT: |8;Splay;762.1580547112462 09:37:01 INFO - 2015-11-19 09:37:01,313 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4504.504504504504 09:37:01 INFO - 2015-11-19 09:37:01,314 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:37:01 INFO - 2015-11-19 09:37:01,314 DEBUG : BROWSER_OUTPUT: __startTimestamp1447954621311__endTimestamp 09:37:01 INFO - 2015-11-19 09:37:01,314 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,314 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:37:01 INFO - 2015-11-19 09:37:01,314 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:37:01 INFO - 2015-11-19 09:37:01,314 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,314 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:147.43 Median:147.43 stddev:0.00 (0.0%) 09:37:01 INFO - 2015-11-19 09:37:01,314 DEBUG : BROWSER_OUTPUT: Values: 147.4 09:37:01 INFO - 2015-11-19 09:37:01,314 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,314 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:157.68 Median:157.68 stddev:0.00 (0.0%) 09:37:01 INFO - 2015-11-19 09:37:01,314 DEBUG : BROWSER_OUTPUT: Values: 157.7 09:37:01 INFO - 2015-11-19 09:37:01,314 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,315 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.21 Median:283.21 stddev:0.00 (0.0%) 09:37:01 INFO - 2015-11-19 09:37:01,315 DEBUG : BROWSER_OUTPUT: Values: 283.2 09:37:01 INFO - 2015-11-19 09:37:01,315 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,315 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4926.11 Median:4926.11 stddev:0.00 (0.0%) 09:37:01 INFO - 2015-11-19 09:37:01,315 DEBUG : BROWSER_OUTPUT: Values: 4926.1 09:37:01 INFO - 2015-11-19 09:37:01,315 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,315 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:956.02 Median:956.02 stddev:0.00 (0.0%) 09:37:01 INFO - 2015-11-19 09:37:01,315 DEBUG : BROWSER_OUTPUT: Values: 956.0 09:37:01 INFO - 2015-11-19 09:37:01,315 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,315 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:717.36 Median:717.36 stddev:0.00 (0.0%) 09:37:01 INFO - 2015-11-19 09:37:01,315 DEBUG : BROWSER_OUTPUT: Values: 717.4 09:37:01 INFO - 2015-11-19 09:37:01,315 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,317 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:13581.08 Median:13581.08 stddev:0.00 (0.0%) 09:37:01 INFO - 2015-11-19 09:37:01,317 DEBUG : BROWSER_OUTPUT: Values: 13581.1 09:37:01 INFO - 2015-11-19 09:37:01,317 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,317 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26657.89 Median:26657.89 stddev:0.00 (0.0%) 09:37:01 INFO - 2015-11-19 09:37:01,317 DEBUG : BROWSER_OUTPUT: Values: 26657.9 09:37:01 INFO - 2015-11-19 09:37:01,317 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,317 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:762.16 Median:762.16 stddev:0.00 (0.0%) 09:37:01 INFO - 2015-11-19 09:37:01,317 DEBUG : BROWSER_OUTPUT: Values: 762.2 09:37:01 INFO - 2015-11-19 09:37:01,317 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,318 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4504.50 Median:4504.50 stddev:0.00 (0.0%) 09:37:01 INFO - 2015-11-19 09:37:01,318 DEBUG : BROWSER_OUTPUT: Values: 4504.5 09:37:01 INFO - 2015-11-19 09:37:01,318 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:37:01 INFO - 2015-11-19 09:37:01,318 DEBUG : BROWSER_OUTPUT: 09:37:01 INFO - 2015-11-19 09:37:01,934 INFO : Browser exited with error code: 0 09:37:01 INFO - 2015-11-19 09:37:01,953 INFO : Completed test v8_7 (00:00:37) 09:37:02 INFO - 2015-11-19 09:37:02,399 INFO : Completed test suite (00:20:06) 09:37:02 INFO - 2015-11-19 09:37:02,399 DEBUG : Working with test: dromaeo_css 09:37:02 INFO - 2015-11-19 09:37:02,399 DEBUG : Generating results file: dromaeo_css 09:37:02 INFO - 2015-11-19 09:37:02,400 DEBUG : Working with test: kraken 09:37:02 INFO - 2015-11-19 09:37:02,400 DEBUG : Generating results file: kraken 09:37:02 INFO - 2015-11-19 09:37:02,400 INFO : javascript benchmark 09:37:02 INFO - 2015-11-19 09:37:02,400 DEBUG : Working with test: v8_7 09:37:02 INFO - 2015-11-19 09:37:02,400 DEBUG : Generating results file: v8_7 09:37:02 INFO - 2015-11-19 09:37:02,400 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:37:02 INFO - 2015-11-19 09:37:02,858 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,61]] 09:37:02 INFO - 2015-11-19 09:37:02,858 DEBUG : process_Request line: dromaeo_css 7300.02 graph.html#tests=[[72,1,61]] 09:37:02 INFO - 2015-11-19 09:37:02,858 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:37:02 INFO - 2015-11-19 09:37:02,888 DEBUG : process_Request line: kraken 1540.40 graph.html#tests=[[232,1,61]] 09:37:02 INFO - 2015-11-19 09:37:02,888 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:37:02 INFO - 2015-11-19 09:37:02,916 DEBUG : process_Request line: v8_7 21650.21 graph.html#tests=[[230,1,61]] 09:37:02 INFO - 2015-11-19 09:37:02,917 INFO : javascript benchmark 09:37:02 INFO - 2015-11-19 09:37:02,919 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3274.230555819383, 3483.5819701635805, 3511.6839484446396, 3535.71714262859, 3561.3945629243026, 5813.854653633662, 5831.737571809268, 5852.063669812831, 5929.34777174511, 5933.0, 5799.420057994209, 5894.941050589504, 5914.373547274502, 5935.584509084366, 5973.850653733659, 5191.454897235773, 5299.390570084442, 5405.837824865261, 5460.972695136519, 5466.726663666821, 5339.866503337409, 5361.169018802092, 5392.541633961098, 5392.676439413648, 5406.702631355274, 5395.892082158335, 5437.238786569884, 5455.5908306876945, 5461.481159289881, 5519.31008623922, 5101.540861322462, 5144.356955380577, 5250.396204436501, 5330.200469929503, 5432.239486471897, 4426.822927082912, 5236.502532259429, 5288.286081379003, 5325.227841962933, 5338.97330513345, 5411.161270003156, 5494.203340515643, 5659.688717120557, 5718.313802343704, 5780.92048132986, 6168.259808822925, 6212.4408803207925, 6242.282137554194, 6245.250569931637, 6289.7169627366875, 6340.175777148941, 6356.0, 6358.968205158991, 6378.808635740888, 6392.3927226913365, 5878.764849406061, 5936.821895343147, 5992.880142397127, 6109.961306577848, 6138.294096178907, 6382.138411314506, 6517.315681853337, 6530.314316996647, 6540.509461790385, 6552.115464412243, 6327.841803954938, 6331.0, 6346.904796428057, 6369.17200763907, 6372.585781924253, 4930.8767280818265, 6082.753035627686, 6204.658743769136, 6286.214223222098, 6311.905321420182, 6254.74981000764, 6269.905951410732, 6275.905861412082, 6299.275583307978, 6303.338149494329, 6250.937490625127, 6301.590396624205, 6314.305426403092, 6329.936700633027, 6336.841578960563, 4937.753112344369, 5024.296598476216, 5029.899402012013, 5080.669756465892, 5126.769295381717, 4495.168393847083, 4497.955020449819, 4499.347594598765, 4518.864434066983, 4561.475430325555, 4578.496365399803, 5910.438508341701, 6041.486473649789, 6071.696415179224, 6073.119397687311, 6136.243373036804, 6225.0, 6242.313345531988, 6353.841153971188, 6366.808995730135, 5464.453554644504, 5556.722163891789, 5990.520758339322, 5997.280326360865, 6019.247594050744, 5376.596755243372, 5433.402325744165, 5502.477264659851, 5508.586855985817, 5510.476504732044, 6406.711697973602, 6433.839154021187, 6479.222493300834, 6600.933990660032, 6605.372489613479, 5666.886662266649, 5880.235569376125, 6307.779227727007, 6320.178376810985, 6335.366463353628, 6338.714757835816, 6367.3314301997625, 6367.968160159169, 6374.649394283173, 6377.043443483329, 6205.5345849060595, 6247.468965137832, 6281.466075383643, 6297.4017468340435, 6312.905306420499, 6238.0, 6330.082138090043, 6336.651484168231, 6360.141380913517, 6365.077063825626, 6133.815985520441, 6199.28708198563, 6254.718537665907, 6292.4651404629285, 6312.242530896138, 6168.136460895568, 6256.468200202851, 6261.311255761953, 6274.1843560336865, 6297.496200303873, 6029.366916473882, 6145.846353841011, 6231.688415579293, 6251.749930002749, 6261.96869015652, 6072.908906366496, 6143.082036032849, 6177.81466556004, 6286.717097730704, 6288.937110628835, 6084.0, 6134.969325153525, 6144.941444616104, 6174.320824709368, 6248.687565621792], "unit": "ms", "name": "dojo.html", "value": 5803.540121092398}, {"lowerIsBetter": false, "replicates": [535.258666746556, 559.7537083683181, 602.864355520008, 614.8348878874532, 617.8609812792122, 40663.796681016625, 41204.175916481654, 42145.157096858085, 42732.57267427329, 42848.78575607125, 36656.0, 46369.536304636946, 46482.0, 46658.0, 46745.065098698, 9852.211823054156, 10039.34744241622, 10047.145992590638, 10058.949705251463, 10059.597616095347, 9855.852162217572, 9927.106560409562, 9967.700968970907, 9974.800503989953, 10003.449810260468, 9876.407415555053, 9886.752831179243, 9925.205983521337, 9947.1544918682, 9973.501324933724, 9834.213262938947, 9901.801963960752, 9907.900920990805, 9952.80094398108, 9986.600535978552, 9812.607495700164, 9941.552630131662, 9943.254255930835, 9964.05341492557, 9967.65113221041, 40966.0, 41200.38199427011, 41201.58798412007, 41386.586134138575, 41577.792111039555, 3309.470484722433, 3335.616404113534, 3457.9308413831836, 3654.872079477232, 3829.6936245100605, 29355.266118346997, 29422.846889952238, 29833.552496712568, 30837.61230744622, 30917.84541077303, 2511.460036092231, 2546.9939373947186, 2560.385507478192, 2581.7805486533666, 2588.4693637804203, 523891.0, 527374.0, 527418.0, 527693.0, 528643.0, 72494.0, 72913.0, 73363.2663673367, 73541.0, 73648.52702946018, 65993.6700316505, 66896.99654505186, 77625.0, 82390.17609823945, 83521.58239208766, 14628.268586570628, 17148.31406743741, 17316.047617380915, 17463.47609571715, 17498.12509374526, 13016.088873778745, 13034.152780069266, 13067.0, 13178.60464186076, 13217.669558261121, 21109.577808443744, 22881.313560593208, 22920.770792292198, 23098.884505577364, 23101.653475197887, 7048.0, 8191.344692424711, 9579.90420095804, 9592.95203523978, 9593.232541396672, 952.3571589177278, 989.8267803134422, 997.2072202598952, 1102.4322473925934, 1153.4636394076626, 26348.0, 30294.697053029628, 30654.080377588707, 30718.38563228723, 30928.381432371225, 1062.1449732964884, 1066.7439814444479, 1104.718296834302, 1140.310112381997, 1224.6387315741877, 14084.507042253472, 17146.971181728888, 17615.48859028379, 17631.471055868344, 17676.646467070845, 694605.0, 705979.0, 709066.0, 709456.0, 712587.0, 64627.35372646307, 65009.34990650033, 65080.67459662671, 65082.69834603376, 65173.0, 1409.3587417724912, 1412.180935057664, 1425.209009000015, 1436.3608194353412, 1443.8628330308604, 31770.68229317677, 32136.83931580327, 32335.51496727598, 33177.83411082929, 33358.0, 54717.45282547283, 54768.0, 54865.72567137139, 55000.17499737427, 55466.72266638641, 511564.0, 514586.0, 517952.0, 519397.43314118084, 521674.0, 29313.922038186538, 29374.0, 34591.0, 46901.76549117233, 49851.75074124606, 48065.79835504141, 48510.5148948506, 49316.50683493119, 50666.22668206595, 51079.21222757186, 39416.60583394129, 40136.0, 40476.59523404728, 40556.1888762229, 40967.18065638611, 39131.80434097811, 39548.209035819695, 39795.80102099471, 40118.398224027245, 40165.79917100396, 39569.60430395774, 40397.0, 40646.0, 40857.0, 40888.79555602203, 34301.0, 35625.46561801627, 35977.46033809547, 36040.819795900854, 36238.8188059058, 23543.822808859146, 24253.272401827973, 26273.60589591196, 26338.341541461617, 26679.33301667474], "unit": "ms", "name": "ext.html", "value": 21235.022803367207}, {"lowerIsBetter": false, "replicates": [352.7319287162649, 359.277851518448, 360.1787923534343, 360.9440536716809, 361.8462153584726, 16084.517464476085, 19718.21127154912, 19749.802501974977, 20875.895620521896, 21606.24378146763, 10356.430396328198, 11521.423928803568, 12382.62852114438, 12524.24854508728, 18968.24127034917, 4170.603792639695, 4214.0, 4360.215161270962, 4575.771211439432, 4646.837360692376, 4685.976570117162, 4842.249451335048, 4929.507049295078, 4968.478309777475, 4970.378702662168, 4208.305629571117, 4828.782704778276, 4871.829485967992, 4897.657163998521, 4928.162212423878, 4943.233798761198, 4961.776720047588, 4962.05720913029, 4994.250862370637, 4998.700077995314, 3743.9438408423894, 4095.4266402703647, 4154.833806647731, 4203.453551038376, 4236.04688944988, 11769.88230117705, 13895.166290022631, 13951.790723139162, 13955.302234888215, 14188.290585470686, 1901.7052356884567, 1907.8569107317007, 1959.7354357161744, 2056.228914157191, 2111.947201319964, 9961.701148965543, 10008.199344052458, 10159.441230732307, 10246.641367552174, 10252.282340236186, 1429.4425174182077, 1466.7579849324954, 1555.587769241152, 1587.055701857393, 1608.02714357813, 92959.0, 93385.59921601182, 93940.53029734876, 94747.52626236893, 95126.0487395131, 6756.324367563205, 6850.6574671266235, 6857.55425897315, 6908.1364829396325, 6928.272531384233, 7723.0, 7771.72798952034, 7818.335441487424, 7821.178776228528, 7877.724279650185, 5712.485876271156, 5774.364819869811, 5833.912491312633, 5838.5329173666005, 5859.648421094748, 5646.491815736604, 5896.351401345849, 6212.937870621236, 6261.78083767066, 6296.527760417987, 2501.199616122823, 2587.6118582212443, 3051.6795736447352, 3064.2798942248555, 3084.0593618946436, 988.2933702402817, 1083.9403832789114, 1103.0679076180616, 1110.0453609895483, 1133.8752737198902, 404.6499777692334, 442.3762494882265, 463.46932761987335, 470.3109943932156, 481.1411630240005, 12135.635930922086, 12437.564685235973, 12506.749865002648, 12596.992240620728, 12614.0, 466.14927756843895, 472.1218533527636, 478.1298037571656, 480.2388214679702, 488.5114885114885, 5512.3385193777, 6555.180602424697, 6565.244996825426, 6616.4376028037195, 6617.139771829728, 661305.0, 761314.0, 763784.0, 778401.0, 790197.0, 117346.0, 117519.41240293744, 121686.3915680416, 122193.38903305604, 122526.0, 519.4104691175543, 534.8796520782871, 535.6866233253512, 536.9140937449989, 538.8545092824916, 10711.410872402093, 11608.767824643292, 11942.761144777229, 11948.940255298668, 12128.393580321124, 47127.0, 47358.28962565495, 48845.267320990926, 49933.75033124956, 50297.74255643638, 64803.0, 66874.0, 70252.0, 70639.64680176566, 71573.0, 6365.554411191172, 6529.95520716683, 6547.181602299713, 6557.672116394066, 6561.671916404065, 10535.315204511682, 10808.027277544745, 12086.456109474919, 12463.626091217278, 12571.811422828849, 10304.536295866554, 10629.415382154055, 10823.0, 10872.836907446554, 10976.835347469634, 10550.736231594272, 10638.73403164927, 10666.8933310669, 10719.088877445505, 10795.946020269848, 10770.976757208053, 10841.349519028883, 10965.890341096487, 11083.778324433628, 11103.777924441627, 6353.36466353361, 6356.237251529846, 6387.329330420239, 6445.291017988111, 6512.381323774234, 10742.086922611665, 11016.283941543774, 11062.834057489305, 11154.996050355507, 11171.776564468502], "unit": "ms", "name": "jquery.html", "value": 7255.350848033956}, {"lowerIsBetter": false, "replicates": [1555.237933412628, 1586.2703156547987, 1891.2056936086838, 1906.9713954290685, 1909.5130741660873, 7589.924100758991, 7652.005239318881, 8175.591220438977, 8324.542150181745, 8401.621927013284, 8273.420860539765, 8350.248477637011, 8483.66065357385, 8527.0, 8530.976282846068, 2364.64530320452, 2379.286214135761, 2408.6627872097833, 2411.9397015074587, 2413.6741539892155, 2394.1859767678984, 2418.0811291709215, 2428.344347026302, 2442.9877850610724, 2452.7179374372, 2372.6559648850907, 2374.157174203159, 2397.0411835265945, 2405.5429468401035, 2429.259075981825, 2073.9170433182653, 2350.659154422616, 2401.615741481372, 2407.735149133594, 2414.2998530426157, 2403.9399015024587, 2419.1049311754587, 2429.137656132083, 2442.255112190781, 2445.5231229909996, 8440.860483834727, 8478.279346255576, 8482.872756908651, 8485.151484851527, 8487.44831585951, 4687.273472611751, 5114.667546609458, 5194.298769666085, 5216.58267338612, 5219.765110570033, 3264.0860559043504, 3456.7753096048677, 3463.9480407793903, 3527.523784289114, 3616.1682812952904, 1047.9677517645114, 1060.7083052160717, 1083.1497274639448, 1090.4765712458066, 1100.2463312630794, 11093.057090147347, 11188.832167517494, 11195.664130076111, 11217.439128043647, 11222.438878056146, 4688.789004494806, 4726.905461890743, 4765.451973023078, 4814.614830813526, 4830.637702172281, 9601.567929443192, 9638.325317227727, 9644.276679249084, 9647.13175814166, 9671.371360861522, 5189.610779191576, 5191.636585439059, 5196.272521846944, 5207.010667973073, 5216.817411390583, 4698.483166851644, 4700.858974230779, 4718.764061796896, 4743.407074115736, 4749.976250118796, 3193.5050067239613, 3343.849526771301, 3487.442009278554, 3885.261800257942, 4061.472008638858, 1080.4057768227444, 1115.4311301236428, 1281.7564662714055, 1284.184542815319, 1286.2346903592347, 1346.414309775251, 1358.3547814788094, 1378.1593228130832, 1396.9231692257024, 1412.0750908155176, 6738.427233685094, 6921.619310937846, 7754.612269386621, 7783.104942931635, 7944.483608565425, 500.15473849194746, 515.5720751776017, 515.649358436267, 516.1483552139001, 517.4153206876206, 5517.586181036439, 6016.308124565643, 6074.908876366858, 6109.816705498843, 6117.816465506042, 8971.282297416192, 10044.447555384524, 10193.898061019296, 10202.846957295647, 10211.97880211973, 9957.10386065243, 10079.042490963493, 10085.848712269322, 10090.646827361154, 10172.898271017342, 7942.801429964182, 7969.521828690297, 7973.0, 7979.561124138112, 7981.161977992228, 7639.732609358646, 7686.730964416219, 7726.18875018115, 7751.992241008633, 7759.185285544936, 8971.237444817003, 9038.141376569216, 9097.454152750855, 9125.406848554823, 9222.123898229658, 9108.22580080674, 9315.534223288672, 9370.250379969722, 9425.104615061558, 9441.0, 7129.607871567114, 7158.212596614264, 7203.0, 7227.710891564278, 7232.312930271616, 7963.960180199062, 8022.83954320922, 8035.156308587514, 8039.919600804151, 8100.959495202486, 7467.589282589292, 7470.4023678106705, 7479.58862262559, 7578.848423031619, 7590.468667193243, 7328.047353843966, 7353.5955522446775, 7425.7029718810645, 7447.627618619155, 7448.217937116525, 7372.557646541225, 7480.700771969278, 7482.551046937201, 7510.511816731895, 7528.811779705551, 5794.0, 6777.525573209828, 6803.965980170067, 6806.8638627228165, 6846.657667116525, 6829.078074460083, 6872.175338959357, 6925.445964322741, 6956.339147780953, 6960.234374218933], "unit": "ms", "name": "mootools.html", "value": 4693.94128025237}, {"lowerIsBetter": false, "replicates": [280.65479460263873, 324.8327111537557, 330.6329973729162, 331.3555135261915, 331.7180396662837, 6866.244713081557, 6984.2317345092, 7054.047703560018, 7133.6789844457, 7151.535150215239, 6804.795856124324, 6912.481563882704, 6931.688074036668, 6941.72233110675, 6985.755498557552, 5101.974490127544, 5112.872178195537, 5119.948800512003, 5131.127708289581, 5132.4610915853855, 4927.753612319388, 5130.3074084998425, 5148.150555158394, 5153.2785410042625, 5166.9224961625605, 4585.79363928624, 4631.467381251148, 5024.321716568272, 5027.4972502749615, 5046.343975283208, 3600.7299452541033, 4953.702777833323, 4982.451930287647, 5014.997000599895, 5023.271625614302, 3681.0429288384944, 3910.237503686782, 4014.879553613396, 4154.4183814266025, 4224.38746381776, 5459.918101228484, 5556.972215138939, 5981.773736384031, 6258.4680302174365, 6618.874791285493, 5306.548943339782, 5597.580181486364, 5873.119032145212, 5949.613275137102, 5957.165996760457, 7261.673224704901, 7465.589392583406, 7473.439492038119, 7474.103107627119, 7477.700891964315, 3944.309745794503, 4738.147133515966, 4741.881452963704, 4751.073540659539, 4767.713937163747, 6524.380183882524, 7879.960600196962, 7925.564093974829, 7947.602619868983, 7977.720779772738, 4630.560096790799, 4786.8085276588545, 4793.0, 4810.206315957827, 4835.347228124228, 4896.95103048972, 4902.313676085351, 4909.018196360671, 4911.8280860170435, 4914.877128071756, 5889.293284805765, 5897.0, 5903.704814759245, 5922.555808314393, 5943.732532036069, 6112.480439162632, 6394.520410969196, 7265.600391978492, 7526.360259378014, 7540.057492813398, 1645.983540164607, 1683.0154359699468, 1701.3279754497153, 1707.5645710343783, 1708.8462038416603, 940.3652534539159, 1065.2383545764815, 1083.5828206140534, 1089.9155340436157, 1100.350793032115, 7023.68393422287, 7045.225025247219, 7082.185548661867, 7083.610401427868, 7085.0435191248525, 6340.429361357407, 7708.03649543807, 7786.26030527099, 7847.921520784833, 7853.646585903648, 6934.583924964519, 6949.513534052568, 7061.152661680631, 7070.964645176741, 7088.539244949062, 3361.83190840457, 4051.7974101294817, 4097.282975479279, 4120.114175452292, 4121.351459416198, 8119.025716914008, 8161.428699990944, 8193.385496087818, 8208.220219079178, 8234.258916697407, 5863.589548731633, 8157.0, 8160.67357305713, 8167.183281671785, 8167.305779008851, 6680.766173183915, 7898.091719452336, 8190.83618327642, 8211.425200235926, 8212.260896519223, 7886.487378320391, 7887.211278872067, 7905.0, 7911.564863932539, 7915.604219788872, 7477.663505142173, 7677.731279405194, 7711.1517733048195, 7745.496542724705, 7756.612169391395, 8125.634346454542, 8173.836523269621, 8180.14108518597, 8191.0, 8200.630971606173, 3749.587545370062, 4229.746215227096, 4824.324594556695, 4844.2007068833245, 4844.806207751651, 7494.812629684301, 7806.141324454419, 7829.804254893445, 7841.725539606086, 7866.803329916798, 7513.924860751323, 7520.285572870787, 7548.509346892434, 7561.483909981885, 7570.735024274124, 6076.392360763888, 7248.710051597877, 7497.175310715926, 7509.699612015458, 7519.699212031457, 5972.492338151132, 7349.742759003409, 7354.889676654748, 7358.521696089952, 7396.408287337108, 7473.364763994904, 7496.512726672749, 7510.586917719578, 7510.774676759706, 7521.247875212435, 7147.2852714728115, 7320.633968301457, 7324.0, 7369.226231245642, 7374.520656157332], "unit": "ms", "name": "prototype.html", "value": 5336.895787516102}, {"lowerIsBetter": false, "replicates": [707.1125737199814, 742.1761844352767, 774.8334108166744, 814.3363159025394, 828.4656396624177, 14850.702985940276, 15130.924345378258, 15139.772903406458, 15203.54389368318, 15231.619209519768, 13962.650933726662, 14066.92966535166, 14087.718245635082, 15494.922525387385, 15497.147656878868, 942.2462030375672, 953.8473844184914, 960.2893858544661, 966.6568368229283, 968.5109019944917, 945.6028468043404, 947.3321308477541, 956.7034219391976, 963.6097380560884, 966.2608104799833, 993.6224234790769, 996.3075662414625, 1000.959961601535, 1004.5328922051244, 1007.9344842585245, 982.7002764156929, 989.6931951095183, 990.4305024610842, 994.4480813148699, 1003.5183112106157, 728.6429649471773, 735.3161559749431, 736.0982796074819, 746.5259560179271, 748.8951546783454, 14632.0, 14696.632584185374, 14744.262786860614, 14751.0, 14861.628459288497, 10496.422696751752, 10649.148068154529, 10708.875568065398, 10963.287386319864, 11113.44432778366, 9691.854622180674, 9755.756106097333, 9781.364211326241, 10157.492125393775, 10462.058414742709, 3130.4521708700886, 3157.842107894596, 3181.6659250778566, 3188.3782662380845, 3197.648258691542, 64921.67539162321, 66517.0, 66722.66638666824, 71363.2159196019, 74567.0, 7313.305236002571, 7332.303431174137, 7441.479096463196, 7453.515521491086, 7471.290227428494, 12773.425195866208, 12815.487380504861, 12826.0, 12891.0331725121, 12894.0, 6607.900881486781, 6692.83267918306, 6841.384275415137, 6857.0, 6955.130608673916, 4022.6379625833815, 4047.817848196838, 4068.7558746474715, 4069.6744260459686, 4099.36459848718, 3162.6995435433596, 3169.0334447993123, 3186.0441867439677, 3190.3459790743304, 3195.632502262223, 444.4533025072765, 478.19424270104986, 488.59934853419855, 495.69762444908577, 501.0905207049194, 465.3804759437178, 499.1090902738594, 499.5529001543598, 510.77270614576156, 572.8224250482281, 7339.88990165148, 8568.22885940256, 8576.785580360542, 8679.17547832955, 8869.778255543662, 586.6714639801639, 600.4295918876996, 615.3999850146072, 621.5245337316957, 637.7002808679913, 3920.9607903921165, 4017.9196416071513, 4073.45008423859, 4389.758563279051, 4448.955510444919, 91162.0, 92456.53771731099, 92661.07338926659, 101564.49217753865, 101648.49175754075, 68495.65752171207, 85818.0, 86977.5651121753, 87244.56377718071, 88410.0, 58566.70716646389, 68627.0, 70212.2978770216, 70617.2938270621, 71297.64351178314, 8634.266087382643, 9020.188183063556, 9026.368154229142, 9155.176034156957, 9340.703038015103, 52409.0, 52456.737716311174, 52899.413017609535, 53113.0, 54950.0, 65461.0, 78828.60585697035, 82003.17996819955, 82081.58959205166, 82483.0, 9655.806883862142, 11768.352740599006, 11988.520459181884, 12012.039036876855, 12056.216345937837, 11593.420328983686, 11992.520299187934, 12075.27548347102, 12270.631881043582, 13135.934320328337, 11540.538378464767, 11780.52877884875, 11887.945636992094, 11946.044316454836, 12018.579349723068, 11682.598386447567, 11683.00694440982, 11752.529898803952, 11922.45008148935, 11937.164398492023, 11289.491972861077, 11293.830592540955, 11463.942680286546, 11773.823392649289, 11898.821517677068, 9412.482313472825, 9477.0, 9503.524823758922, 9891.455969921435, 10137.087662110445, 8789.604467798836, 9191.264698823945, 9305.953470232605, 9651.613935442503, 9985.850212246676], "unit": "ms", "name": "yui.html", "value": 6755.828062085012}], "name": "dromaeo_css", "value": 7300.023272560023}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 106.0, 111.0, 112.0, 114.0, 125.0, 112.0, 114.0, 141.0, 162.0], "unit": "ms", "name": "ai-astar", "value": 121.0}, {"lowerIsBetter": true, "replicates": [148.0, 134.0, 170.0, 176.0, 146.0, 155.0, 140.0, 144.0, 186.0, 149.0], "unit": "ms", "name": "audio-beat-detection", "value": 154.8}, {"lowerIsBetter": true, "replicates": [191.0, 196.0, 200.0, 194.0, 210.0, 206.0, 200.0, 198.0, 207.0, 194.0], "unit": "ms", "name": "audio-dft", "value": 199.6}, {"lowerIsBetter": true, "replicates": [67.0, 72.0, 104.0, 126.0, 82.0, 84.0, 85.0, 75.0, 116.0, 66.0], "unit": "ms", "name": "audio-fft", "value": 87.7}, {"lowerIsBetter": true, "replicates": [76.0, 78.0, 109.0, 75.0, 77.0, 62.0, 126.0, 109.0, 110.0, 148.0], "unit": "ms", "name": "audio-oscillator", "value": 97.0}, {"lowerIsBetter": true, "replicates": [132.0, 132.0, 87.0, 87.0, 87.0, 88.0, 95.0, 89.0, 80.0, 87.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 96.4}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 103.0, 126.0, 96.0, 93.0, 96.0, 96.0, 97.0], "unit": "ms", "name": "imaging-darkroom", "value": 99.3}, {"lowerIsBetter": true, "replicates": [90.0, 92.0, 91.0, 114.0, 147.0, 92.0, 95.0, 93.0, 92.0, 91.0], "unit": "ms", "name": "imaging-desaturate", "value": 99.7}, {"lowerIsBetter": true, "replicates": [59.0, 57.0, 59.0, 62.0, 81.0, 86.0, 78.0, 98.0, 87.0, 59.0], "unit": "ms", "name": "json-parse-financial", "value": 72.6}, {"lowerIsBetter": true, "replicates": [65.0, 67.0, 65.0, 65.0, 68.0, 68.0, 68.0, 70.0, 71.0, 74.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.1}, {"lowerIsBetter": true, "replicates": [82.0, 102.0, 84.0, 83.0, 85.0, 85.0, 82.0, 104.0, 105.0, 104.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 91.6}, {"lowerIsBetter": true, "replicates": [133.0, 123.0, 137.0, 132.0, 131.0, 118.0, 135.0, 116.0, 130.0, 130.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 128.5}, {"lowerIsBetter": true, "replicates": [131.0, 144.0, 125.0, 127.0, 143.0, 183.0, 159.0, 137.0, 141.0, 132.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 142.2}, {"lowerIsBetter": true, "replicates": [75.0, 91.0, 89.0, 85.0, 67.0, 66.0, 84.0, 88.0, 94.0, 80.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 81.9}], "name": "kraken", "value": 1540.3999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [147.42739200943535], "unit": "score", "name": "Richards", "value": 239.45346599999996}, {"lowerIsBetter": false, "replicates": [157.67896562598548], "unit": "score", "name": "DeltaBlue", "value": 419.3203560000002}, {"lowerIsBetter": false, "replicates": [283.2058906825262], "unit": "score", "name": "Encrypt", "value": 939.8851109999996}, {"lowerIsBetter": false, "replicates": [4926.108374384236], "unit": "score", "name": "Decrypt", "value": 54.03474300000004}, {"lowerIsBetter": false, "replicates": [956.0229445506692], "unit": "score", "name": "RayTrace", "value": 774.0284939999998}, {"lowerIsBetter": false, "replicates": [717.3601147776184], "unit": "score", "name": "Earley", "value": 929.0494220000003}, {"lowerIsBetter": false, "replicates": [13581.081081081082], "unit": "score", "name": "Boyer", "value": 49.07289751243785}, {"lowerIsBetter": false, "replicates": [26657.894736842107], "unit": "score", "name": "RegExp", "value": 34.173178677196454}, {"lowerIsBetter": false, "replicates": [762.1580547112462], "unit": "score", "name": "Splay", "value": 106.9213918245264}, {"lowerIsBetter": false, "replicates": [4504.504504504504], "unit": "score", "name": "NavierStokes", "value": 329.44800000000015}], "name": "v8_7", "value": 21650.205805794012}]} 09:37:02 INFO - RETURN: dromaeo_css: 7300.02 09:37:02 INFO - RETURN: kraken: 1540.40 09:37:02 INFO - RETURN: v8_7: 21650.21 09:37:02 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,61]]", "result": "21650.21"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,61]]", "result": "1540.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,61]]", "result": "7300.02"}}} 09:37:03 INFO - Return code: 0 09:37:03 INFO - # TBPL SUCCESS # 09:37:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:03 INFO - 2015-11-19 09:37:03.028 screenresolution[3143:82368] starting screenresolution argv=screenresolution get 09:37:03 INFO - 2015-11-19 09:37:03.031 screenresolution[3143:82368] Display 0: 1600x1200x32@60 09:37:03 INFO - 2015-11-19 09:37:03.037 screenresolution[3144:82370] starting screenresolution argv=screenresolution list 09:37:03 INFO - Available Modes on Display 0 09:37:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:03 INFO - Intel Iris: 09:37:03 INFO - Chipset Model: Intel Iris 09:37:03 INFO - Type: GPU 09:37:03 INFO - Bus: Built-In 09:37:03 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:03 INFO - Vendor: Intel (0x8086) 09:37:03 INFO - Device ID: 0x0a2e 09:37:03 INFO - Revision ID: 0x0009 09:37:03 INFO - Displays: 09:37:03 INFO - Display: 09:37:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:03 INFO - Display Serial Number: 1600x1200 60 09:37:03 INFO - Main Display: Yes 09:37:03 INFO - Mirror: Off 09:37:03 INFO - Online: Yes 09:37:03 INFO - Rotation: Supported 09:37:03 INFO - Return code: 0 09:37:03 INFO - Running post-action listener: _resource_record_post_action 09:37:03 INFO - Running post-run listener: _resource_record_post_run 09:37:04 INFO - Total resource usage - Wall time: 1239s; CPU: 54.0%; Read bytes: 1712128; Write bytes: 695374336; Read time: 15; Write time: 2396 09:37:04 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 335227392; Write bytes: 338187264; Read time: 26176; Write time: 630 09:37:04 INFO - run-tests - Wall time: 1209s; CPU: 54.0%; Read bytes: 1712128; Write bytes: 346811904; Read time: 15; Write time: 1732 09:37:04 INFO - Running post-run listener: _upload_blobber_files 09:37:04 INFO - Blob upload gear active. 09:37:04 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:37:04 INFO - Copying logs to upload dir... 09:37:04 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1280.960940 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2015-11-19 09:37:04.396048) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 09:37:04.399800) ========= 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.F293yiVd4N/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.4bPKjQktwe/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009803 build_url: 'https://queue.taskcluster.net/v1/task/AhyXgK1cQcCBL53ZKsJA2g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg' ========= master_lag: 0.01 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 09:37:04.423405) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:37:04.423740) ========= 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.F293yiVd4N/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.4bPKjQktwe/Listeners TMPDIR=/var/folders/jf/19plt40543lf16nnblwl7x1c00000w/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.005256 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 09:37:04.474669) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 09:37:04.474982) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 09:37:04.475367) ========= ========= Total master_lag: 0.21 =========