builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0027 starttime: 1448331153.54 results: success (0) buildid: 20151123162931 builduid: 81a1f98460e14c89a33def60e16739b1 revision: 19d89caa664dd9309c796929225ee409df5d1ee4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.541591) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.542095) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.542415) ========= 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.HGpJLiTlre/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.AWCw9flAeN/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.005835 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.563763) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.564082) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.578775) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.579131) ========= 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.HGpJLiTlre/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.AWCw9flAeN/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.005299 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.627071) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.627395) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.627734) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.627999) ========= 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.HGpJLiTlre/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.AWCw9flAeN/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2015-11-23 18:12:33-- 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% 8.06M=0.001s 2015-11-23 18:12:33 (8.06 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.184647 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.825608) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.825909) ========= 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.HGpJLiTlre/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.AWCw9flAeN/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.019048 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:33.873948) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-11-23 18:12:33.874288) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 19d89caa664dd9309c796929225ee409df5d1ee4 --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 19d89caa664dd9309c796929225ee409df5d1ee4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HGpJLiTlre/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.AWCw9flAeN/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2015-11-23 18:12:33,946 truncating revision to first 12 chars 2015-11-23 18:12:33,946 Setting DEBUG logging. 2015-11-23 18:12:33,946 attempt 1/10 2015-11-23 18:12:33,946 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/19d89caa664d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-23 18:12:35,945 unpacking tar archive at: mozilla-central-19d89caa664d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.415712 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-11-23 18:12:36.304018) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:36.304360) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:36.315373) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:36.315772) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 18:12:36.316251) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2015-11-23 18:12:36.316616) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HGpJLiTlre/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.AWCw9flAeN/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 18:12:36 INFO - MultiFileLogger online at 20151123 18:12:36 in /builds/slave/test 18:12:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:12:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:12:36 INFO - {'append_to_log': False, 18:12:36 INFO - 'base_work_dir': '/builds/slave/test', 18:12:36 INFO - 'blob_upload_branch': 'Firefox', 18:12:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:12:36 INFO - 'branch': 'Firefox', 18:12:36 INFO - 'buildbot_json_path': 'buildprops.json', 18:12:36 INFO - 'config_files': ('talos/mac_config.py',), 18:12:36 INFO - 'default_actions': ('clobber', 18:12:36 INFO - 'read-buildbot-config', 18:12:36 INFO - 'download-and-extract', 18:12:36 INFO - 'populate-webroot', 18:12:36 INFO - 'create-virtualenv', 18:12:36 INFO - 'install', 18:12:36 INFO - 'run-tests'), 18:12:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:12:36 INFO - 'download_minidump_stackwalk': True, 18:12:36 INFO - 'download_symbols': 'ondemand', 18:12:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:12:36 INFO - 'tooltool.py': '/tools/tooltool.py', 18:12:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:12:36 INFO - '/tools/misc-python/virtualenv.py')}, 18:12:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:12:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:12:36 INFO - 'installer_path': 'installer.exe', 18:12:36 INFO - 'log_level': 'info', 18:12:36 INFO - 'log_name': 'talos', 18:12:36 INFO - 'log_to_console': True, 18:12:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:12:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:12:36 INFO - 'opt_config_files': (), 18:12:36 INFO - 'pip_index': False, 18:12:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:12:36 INFO - 'cmd': ('bash', 18:12:36 INFO - '-c', 18:12:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:12:36 INFO - 'enabled': True, 18:12:36 INFO - 'halt_on_failure': False, 18:12:36 INFO - 'name': 'check_screen_resolution'},), 18:12:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:12:36 INFO - 'cmd': ('bash', 18:12:36 INFO - '-c', 18:12:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:12:36 INFO - 'enabled': True, 18:12:36 INFO - 'halt_on_failure': False, 18:12:36 INFO - 'name': 'check_screen_resolution'},), 18:12:36 INFO - 'run_cmd_checks_enabled': True, 18:12:36 INFO - 'sps_profile': False, 18:12:36 INFO - 'sps_profile_interval': 0, 18:12:36 INFO - 'suite': 'dromaeojs', 18:12:36 INFO - 'system_bits': '64', 18:12:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:12:36 INFO - 'title': 't-yosemite-r7-0027', 18:12:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:12:36 INFO - 'use_talos_json': True, 18:12:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:12:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:12:36 INFO - 'work_dir': 'build'} 18:12:36 INFO - ##### 18:12:36 INFO - ##### Running clobber step. 18:12:36 INFO - ##### 18:12:36 INFO - Running pre-action listener: _resource_record_pre_action 18:12:36 INFO - Running main action method: clobber 18:12:36 INFO - rmtree: /builds/slave/test/build 18:12:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:12:37 INFO - Running post-action listener: _resource_record_post_action 18:12:37 INFO - ##### 18:12:37 INFO - ##### Running read-buildbot-config step. 18:12:37 INFO - ##### 18:12:37 INFO - Running pre-action listener: _resource_record_pre_action 18:12:37 INFO - Running main action method: read_buildbot_config 18:12:37 INFO - Using buildbot properties: 18:12:37 INFO - { 18:12:37 INFO - "properties": { 18:12:37 INFO - "buildnumber": 9, 18:12:37 INFO - "product": "firefox", 18:12:37 INFO - "script_repo_revision": "production", 18:12:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:12:37 INFO - "repository": "", 18:12:37 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:12:37 INFO - "buildid": "20151123162931", 18:12:37 INFO - "slavename": "t-yosemite-r7-0027", 18:12:37 INFO - "pgo_build": "False", 18:12:37 INFO - "basedir": "/builds/slave/test", 18:12:37 INFO - "project": "", 18:12:37 INFO - "platform": "yosemite_r7", 18:12:37 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:12:37 INFO - "slavebuilddir": "test", 18:12:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:12:37 INFO - "branch": "mozilla-central", 18:12:37 INFO - "repo_path": "mozilla-central", 18:12:37 INFO - "stage_platform": "macosx64", 18:12:37 INFO - "builduid": "81a1f98460e14c89a33def60e16739b1", 18:12:37 INFO - "revision": "19d89caa664dd9309c796929225ee409df5d1ee4" 18:12:37 INFO - }, 18:12:37 INFO - "sourcestamp": { 18:12:37 INFO - "repository": "", 18:12:37 INFO - "hasPatch": false, 18:12:37 INFO - "project": "", 18:12:37 INFO - "branch": "mozilla-central-macosx64-talos", 18:12:37 INFO - "changes": [ 18:12:37 INFO - { 18:12:37 INFO - "category": null, 18:12:37 INFO - "files": [ 18:12:37 INFO - { 18:12:37 INFO - "url": null, 18:12:37 INFO - "name": "https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 18:12:37 INFO - } 18:12:37 INFO - ], 18:12:37 INFO - "repository": "", 18:12:37 INFO - "rev": "19d89caa664dd9309c796929225ee409df5d1ee4", 18:12:37 INFO - "who": "kwierso@gmail.com", 18:12:37 INFO - "when": 1448330960, 18:12:37 INFO - "number": 6698428, 18:12:37 INFO - "comments": "Merge b2ginbound to central, a=merge", 18:12:37 INFO - "project": "", 18:12:37 INFO - "at": "Mon 23 Nov 2015 18:09:20", 18:12:37 INFO - "branch": "mozilla-central-macosx64-talos", 18:12:37 INFO - "revlink": "", 18:12:37 INFO - "properties": [ 18:12:37 INFO - [ 18:12:37 INFO - "buildid", 18:12:37 INFO - "20151123162931", 18:12:37 INFO - "Change" 18:12:37 INFO - ], 18:12:37 INFO - [ 18:12:37 INFO - "builduid", 18:12:37 INFO - "81a1f98460e14c89a33def60e16739b1", 18:12:37 INFO - "Change" 18:12:37 INFO - ], 18:12:37 INFO - [ 18:12:37 INFO - "pgo_build", 18:12:37 INFO - "False", 18:12:37 INFO - "Change" 18:12:37 INFO - ] 18:12:37 INFO - ], 18:12:37 INFO - "revision": "19d89caa664dd9309c796929225ee409df5d1ee4" 18:12:37 INFO - } 18:12:37 INFO - ], 18:12:37 INFO - "revision": "19d89caa664dd9309c796929225ee409df5d1ee4" 18:12:37 INFO - } 18:12:37 INFO - } 18:12:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 18:12:37 INFO - Running post-action listener: _resource_record_post_action 18:12:37 INFO - ##### 18:12:37 INFO - ##### Running download-and-extract step. 18:12:37 INFO - ##### 18:12:37 INFO - Running pre-action listener: _resource_record_pre_action 18:12:37 INFO - Running main action method: download_and_extract 18:12:37 INFO - mkdir: /builds/slave/test/build/tests 18:12:37 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')]} 18:12:37 INFO - https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:12:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/test_packages.json 18:12:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/test_packages.json 18:12:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 18:12:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 18:12:38 INFO - Downloaded 1149 bytes. 18:12:38 INFO - Reading from file /builds/slave/test/build/test_packages.json 18:12:38 INFO - Using the following test package requirements: 18:12:38 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 18:12:38 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:12:38 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 18:12:38 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:12:38 INFO - u'jsshell-mac.zip'], 18:12:38 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:12:38 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 18:12:38 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 18:12:38 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:12:38 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 18:12:38 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:12:38 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 18:12:38 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:12:38 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 18:12:38 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 18:12:38 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:12:38 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 18:12:38 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:12:38 INFO - https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:12:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 18:12:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 18:12:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/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 18:12:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/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 18:12:39 INFO - Downloaded 17527182 bytes. 18:12:39 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 18:12:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 18:12:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 18:12:42 INFO - Return code: 0 18:12:42 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 18:12:42 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')]} 18:12:42 INFO - https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:12:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 18:12:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 18:12:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/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 18:12:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/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 18:12:43 INFO - Downloaded 17527182 bytes. 18:12:43 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 18:12:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 18:12:43 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 18:12:46 INFO - Return code: 0 18:12: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')]} 18:12:46 INFO - https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:12:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 18:12:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 18:12:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/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 18:12:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/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 18:12:47 INFO - Downloaded 11130997 bytes. 18:12:47 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 18:12:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 18:12:47 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 18:12:48 INFO - Return code: 0 18:12:48 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')]} 18:12:48 INFO - https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:12:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 18:12:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 18:12:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg 18:12:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/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 18:12:51 INFO - Downloaded 109896796 bytes. 18:12:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 18:12:51 INFO - mkdir: /builds/slave/test/properties 18:12:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:12:51 INFO - Writing to file /builds/slave/test/properties/build_url 18:12:51 INFO - Contents: 18:12:51 INFO - build_url:https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 18:12:51 INFO - Running post-action listener: _resource_record_post_action 18:12:51 INFO - Running post-action listener: set_extra_try_arguments 18:12:51 INFO - ##### 18:12:51 INFO - ##### Running populate-webroot step. 18:12:51 INFO - ##### 18:12:51 INFO - Running pre-action listener: _resource_record_pre_action 18:12:51 INFO - Running main action method: populate_webroot 18:12:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:12:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:12:51 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:12:51 INFO - '../fennec_ids.txt'], 18:12:51 INFO - 'tests': ['tcheck2']}, 18:12:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:12:51 INFO - '--tppagecycles', 18:12:51 INFO - '7'], 18:12:51 INFO - 'tests': ['tsvgm']}}, 18:12:51 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:12:51 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:12:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:12:51 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:12:51 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:12:51 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:12:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:12:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:51 INFO - 'talos_options': ['--e10s'], 18:12:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:12:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:51 INFO - 'tests': ['damp', 'tps']}, 18:12:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:51 INFO - 'talos_options': ['--e10s'], 18:12:51 INFO - 'tests': ['damp', 'tps']}, 18:12:51 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:12:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:12:51 INFO - 'other': {'tests': ['a11yr', 18:12:51 INFO - 'ts_paint', 18:12:51 INFO - 'tpaint', 18:12:51 INFO - 'sessionrestore', 18:12:51 INFO - 'sessionrestore_no_auto_restore']}, 18:12:51 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:12:51 INFO - 'tests': ['a11yr', 18:12:51 INFO - 'ts_paint', 18:12:51 INFO - 'tpaint', 18:12:51 INFO - 'sessionrestore', 18:12:51 INFO - 'sessionrestore_no_auto_restore']}, 18:12:51 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:12:51 INFO - 'tests': ['a11yr', 18:12:51 INFO - 'ts_paint', 18:12:51 INFO - 'tpaint', 18:12:51 INFO - 'sessionrestore', 18:12:51 INFO - 'sessionrestore_no_auto_restore']}, 18:12:51 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:12:51 INFO - 'tests': ['a11yr', 18:12:51 INFO - 'ts_paint', 18:12:51 INFO - 'tpaint', 18:12:51 INFO - 'sessionrestore', 18:12:51 INFO - 'sessionrestore_no_auto_restore']}, 18:12:51 INFO - 'other_l64': {'tests': ['a11yr', 18:12:51 INFO - 'ts_paint', 18:12:51 INFO - 'tpaint', 18:12:51 INFO - 'sessionrestore', 18:12:51 INFO - 'sessionrestore_no_auto_restore']}, 18:12:51 INFO - 'other_nol64': {'tests': ['a11yr', 18:12:51 INFO - 'ts_paint', 18:12:51 INFO - 'tpaint', 18:12:51 INFO - 'sessionrestore', 18:12:51 INFO - 'sessionrestore_no_auto_restore']}, 18:12:51 INFO - 'svgr': {'tests': ['tsvgx', 18:12:51 INFO - 'tsvgr_opacity', 18:12:51 INFO - 'tart', 18:12:51 INFO - 'tscrollx', 18:12:51 INFO - 'cart']}, 18:12:51 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:12:51 INFO - 'tests': ['tsvgx', 18:12:51 INFO - 'tsvgr_opacity', 18:12:51 INFO - 'tart', 18:12:51 INFO - 'tscrollx', 18:12:51 INFO - 'cart']}, 18:12:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:51 INFO - 'tests': ['tp5o']}, 18:12:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:51 INFO - 'talos_options': ['--e10s'], 18:12:51 INFO - 'tests': ['tp5o']}, 18:12:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:51 INFO - 'talos_options': ['--xperf_path', 18:12:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:12:51 INFO - 'tests': ['tp5n']}, 18:12:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:12:51 INFO - 'talos_options': ['--e10s', 18:12:51 INFO - '--xperf_path', 18:12:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:12:51 INFO - 'tests': ['tp5n']}}, 18:12:51 INFO - 'talos.zip': {'path': '', 18:12:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:12:51 INFO - Running post-action listener: _resource_record_post_action 18:12:51 INFO - ##### 18:12:51 INFO - ##### Running create-virtualenv step. 18:12:51 INFO - ##### 18:12:51 INFO - Running pre-action listener: _resource_record_pre_action 18:12:51 INFO - Running main action method: create_virtualenv 18:12:51 INFO - Creating virtualenv /builds/slave/test/build/venv 18:12:51 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 18:12:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:12:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:12:51 INFO - Using real prefix '/tools/python27' 18:12:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:12:51 INFO - Installing distribute.............................................................................................................................................................................................done. 18:12:54 INFO - Installing pip.................done. 18:12:54 INFO - Return code: 0 18:12:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:54 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 0x103f8b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f68228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fa94a6c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eaf9f0>, '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 0x103c0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ea28c8>, '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.HGpJLiTlre/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.AWCw9flAeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 18:12:54 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 18:12:54 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 18:12:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGpJLiTlre/Render', 18:12:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:54 INFO - 'HOME': '/Users/cltbld', 18:12:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:54 INFO - 'LOGNAME': 'cltbld', 18:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:54 INFO - 'MOZ_NO_REMOTE': '1', 18:12:54 INFO - 'NO_EM_RESTART': '1', 18:12:54 INFO - 'PAGER': '/bin/cat', 18:12:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:54 INFO - 'PWD': '/builds/slave/test', 18:12:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:54 INFO - 'SHELL': '/bin/bash', 18:12:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWCw9flAeN/Listeners', 18:12:54 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 18:12:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:54 INFO - 'USER': 'cltbld', 18:12:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:54 INFO - 'XPC_FLAGS': '0x0', 18:12:54 INFO - 'XPC_SERVICE_NAME': '0', 18:12:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:54 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 18:12:54 INFO - Cleaning up... 18:12:55 INFO - Return code: 0 18:12:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--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 0x103f8b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f68228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fa94a6c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eaf9f0>, '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 0x103c0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ea28c8>, '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.HGpJLiTlre/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.AWCw9flAeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 18:12:55 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 18:12:55 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 18:12:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGpJLiTlre/Render', 18:12:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:55 INFO - 'HOME': '/Users/cltbld', 18:12:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:55 INFO - 'LOGNAME': 'cltbld', 18:12:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:55 INFO - 'MOZ_NO_REMOTE': '1', 18:12:55 INFO - 'NO_EM_RESTART': '1', 18:12:55 INFO - 'PAGER': '/bin/cat', 18:12:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:55 INFO - 'PWD': '/builds/slave/test', 18:12:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:55 INFO - 'SHELL': '/bin/bash', 18:12:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWCw9flAeN/Listeners', 18:12:55 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 18:12:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:55 INFO - 'USER': 'cltbld', 18:12:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:55 INFO - 'XPC_FLAGS': '0x0', 18:12:55 INFO - 'XPC_SERVICE_NAME': '0', 18:12:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:55 INFO - Downloading/unpacking psutil>=0.7.1 18:12:55 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 18:12:55 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 18:12:55 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 18:12:55 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 18:12:55 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 18:12:55 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 18:12:57 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 18:12:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:12:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:12:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:57 INFO - Installing collected packages: psutil 18:12:57 INFO - Running setup.py install for psutil 18:12:57 INFO - building 'psutil._psutil_osx' extension 18:12:57 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 18:12:57 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 18:12:57 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 18:12:58 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 18:12:58 INFO - building 'psutil._psutil_posix' extension 18:12:58 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 18:12:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:12:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:12:58 INFO - ^ 18:12:58 INFO - 1 warning generated. 18:12:58 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 18:12:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:58 INFO - Successfully installed psutil 18:12:58 INFO - Cleaning up... 18:12:58 INFO - Return code: 0 18:12:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:12:58 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')]} 18:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:58 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')]} 18:12:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:58 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 0x103f8b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f68228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fa94a6c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eaf9f0>, '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 0x103c0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ea28c8>, '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.HGpJLiTlre/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.AWCw9flAeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 18:12:58 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 18:12:58 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 18:12:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGpJLiTlre/Render', 18:12:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:58 INFO - 'HOME': '/Users/cltbld', 18:12:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:58 INFO - 'LOGNAME': 'cltbld', 18:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:58 INFO - 'MOZ_NO_REMOTE': '1', 18:12:58 INFO - 'NO_EM_RESTART': '1', 18:12:58 INFO - 'PAGER': '/bin/cat', 18:12:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:58 INFO - 'PWD': '/builds/slave/test', 18:12:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:58 INFO - 'SHELL': '/bin/bash', 18:12:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWCw9flAeN/Listeners', 18:12:58 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 18:12:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:58 INFO - 'USER': 'cltbld', 18:12:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:58 INFO - 'XPC_FLAGS': '0x0', 18:12:58 INFO - 'XPC_SERVICE_NAME': '0', 18:12:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:12:58 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 18:12:58 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 18:12:58 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 18:12:58 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 18:12:58 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 18:12:58 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 18:13:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:13:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:13:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:13:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:13:00 INFO - Installing collected packages: mozsystemmonitor 18:13:00 INFO - Running setup.py install for mozsystemmonitor 18:13:00 INFO - Successfully installed mozsystemmonitor 18:13:00 INFO - Cleaning up... 18:13:01 INFO - Return code: 0 18:13:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:13:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:01 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 0x103f8b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f68228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fa94a6c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eaf9f0>, '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 0x103c0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ea28c8>, '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.HGpJLiTlre/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.AWCw9flAeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 18:13:01 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 18:13:01 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 18:13:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGpJLiTlre/Render', 18:13:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:01 INFO - 'HOME': '/Users/cltbld', 18:13:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:01 INFO - 'LOGNAME': 'cltbld', 18:13:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:01 INFO - 'MOZ_NO_REMOTE': '1', 18:13:01 INFO - 'NO_EM_RESTART': '1', 18:13:01 INFO - 'PAGER': '/bin/cat', 18:13:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:01 INFO - 'PWD': '/builds/slave/test', 18:13:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:01 INFO - 'SHELL': '/bin/bash', 18:13:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWCw9flAeN/Listeners', 18:13:01 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 18:13:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:01 INFO - 'USER': 'cltbld', 18:13:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:01 INFO - 'XPC_FLAGS': '0x0', 18:13:01 INFO - 'XPC_SERVICE_NAME': '0', 18:13:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:01 INFO - Downloading/unpacking blobuploader==1.2.4 18:13:01 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 18:13:01 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 18:13:01 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 18:13:01 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 18:13:01 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 18:13:01 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 18:13:03 INFO - Downloading blobuploader-1.2.4.tar.gz 18:13:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:13:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:13:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:13:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:13:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:13:04 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 18:13:04 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 18:13:04 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 18:13:04 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 18:13:04 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 18:13:04 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 18:13:04 INFO - Downloading docopt-0.6.1.tar.gz 18:13:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:13:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:13:04 INFO - Installing collected packages: blobuploader, requests, docopt 18:13:04 INFO - Running setup.py install for blobuploader 18:13:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:13:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:13:04 INFO - Running setup.py install for requests 18:13:05 INFO - Running setup.py install for docopt 18:13:05 INFO - Successfully installed blobuploader requests docopt 18:13:05 INFO - Cleaning up... 18:13:05 INFO - Return code: 0 18:13:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:13:05 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')]} 18:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:05 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')]} 18:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:05 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 0x103f8b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f68228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fa94a6c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eaf9f0>, '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 0x103c0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ea28c8>, '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.HGpJLiTlre/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.AWCw9flAeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 18:13:05 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 18:13:05 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 18:13:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGpJLiTlre/Render', 18:13:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:05 INFO - 'HOME': '/Users/cltbld', 18:13:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:05 INFO - 'LOGNAME': 'cltbld', 18:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:05 INFO - 'MOZ_NO_REMOTE': '1', 18:13:05 INFO - 'NO_EM_RESTART': '1', 18:13:05 INFO - 'PAGER': '/bin/cat', 18:13:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:05 INFO - 'PWD': '/builds/slave/test', 18:13:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:05 INFO - 'SHELL': '/bin/bash', 18:13:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWCw9flAeN/Listeners', 18:13:05 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 18:13:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:05 INFO - 'USER': 'cltbld', 18:13:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:05 INFO - 'XPC_FLAGS': '0x0', 18:13:05 INFO - 'XPC_SERVICE_NAME': '0', 18:13:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:13:05 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-vAk5sE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:13:05 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-fkwWeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:13:05 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-zg8C5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:13:06 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-0Yas0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:13:06 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-BbwDUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:13:06 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-YkUBfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:13:06 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-f1B3mI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:13:06 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-kvGquG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:13:06 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-7wmzIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:13:06 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-kDZ8uH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:13:06 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-uMNA43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:13:06 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-4UYM1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:13:06 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-t_4JDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:13:07 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ZwZBYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:07 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-nQdjto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:13:07 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-rej9v1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:13:07 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-VOW2eD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:13:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:13:07 INFO - Running setup.py install for manifestparser 18:13:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:13:07 INFO - Running setup.py install for mozcrash 18:13:07 INFO - Running setup.py install for mozdebug 18:13:07 INFO - Running setup.py install for mozdevice 18:13:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:13:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:13:08 INFO - Running setup.py install for mozfile 18:13:08 INFO - Running setup.py install for mozhttpd 18:13:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:13:08 INFO - Running setup.py install for mozinfo 18:13:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:13:08 INFO - Running setup.py install for mozInstall 18:13:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:13:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:13:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:13:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:13:08 INFO - Running setup.py install for mozleak 18:13:08 INFO - Running setup.py install for mozlog 18:13:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:13:08 INFO - Running setup.py install for moznetwork 18:13:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:13:09 INFO - Running setup.py install for mozprocess 18:13:09 INFO - Running setup.py install for mozprofile 18:13:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:13:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:13:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:13:09 INFO - Running setup.py install for mozrunner 18:13:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:13:09 INFO - Running setup.py install for mozscreenshot 18:13:09 INFO - Running setup.py install for moztest 18:13:09 INFO - Running setup.py install for mozversion 18:13:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:13:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:13:10 INFO - Cleaning up... 18:13:10 INFO - Return code: 0 18:13:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:13:10 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')]} 18:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:10 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')]} 18:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:10 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 0x103f8b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f68228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fa94a6c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eaf9f0>, '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 0x103c0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ea28c8>, '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.HGpJLiTlre/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.AWCw9flAeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 18:13:10 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 18:13:10 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 18:13:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGpJLiTlre/Render', 18:13:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:10 INFO - 'HOME': '/Users/cltbld', 18:13:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:10 INFO - 'LOGNAME': 'cltbld', 18:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:10 INFO - 'MOZ_NO_REMOTE': '1', 18:13:10 INFO - 'NO_EM_RESTART': '1', 18:13:10 INFO - 'PAGER': '/bin/cat', 18:13:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:10 INFO - 'PWD': '/builds/slave/test', 18:13:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:10 INFO - 'SHELL': '/bin/bash', 18:13:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWCw9flAeN/Listeners', 18:13:10 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 18:13:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:10 INFO - 'USER': 'cltbld', 18:13:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:10 INFO - 'XPC_FLAGS': '0x0', 18:13:10 INFO - 'XPC_SERVICE_NAME': '0', 18:13:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:13:10 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-tPYkoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:13:10 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)) 18:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:13:10 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-blrtM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:13:10 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)) 18:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:13:10 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-pIGO1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:13:10 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)) 18:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:13:10 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-xc__Yb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:13:10 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)) 18:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:13:10 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-byNP6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:13:10 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)) 18:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:13:10 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-nfVQdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:13:10 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)) 18:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:13:10 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-dEmjBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:13:11 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)) 18:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:13:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-eKb3Me-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:13:11 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)) 18:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:13:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-7E98AC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:13:11 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)) 18:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:13:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-F3jfjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:13:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:13:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-fKZ7nO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:13:11 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)) 18:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:13:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-K2rcxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:13:11 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)) 18:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:13:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-OjuEr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:13:11 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)) 18:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:13:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-WEVv_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:13:11 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)) 18:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-NP3xeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:13:11 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)) 18:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:13:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-lX5OBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:13:12 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)) 18:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:13:12 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-brHiQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:13:12 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)) 18:13:12 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)) 18:13:12 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)) 18:13:12 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)) 18:13:12 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)) 18:13:12 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)) 18:13:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13:14 INFO - Downloading blessings-1.5.1.tar.gz 18:13:14 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 18:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:13:14 INFO - Installing collected packages: blessings 18:13:14 INFO - Running setup.py install for blessings 18:13:14 INFO - Successfully installed blessings 18:13:14 INFO - Cleaning up... 18:13:14 INFO - Return code: 0 18:13:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:13:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:13:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:13:14 INFO - Reading from file tmpfile_stdout 18:13:14 INFO - Current package versions: 18:13:14 INFO - blessings == 1.5.1 18:13:14 INFO - blobuploader == 1.2.4 18:13:14 INFO - docopt == 0.6.1 18:13:14 INFO - manifestparser == 1.1 18:13:14 INFO - mozInstall == 1.12 18:13:14 INFO - mozcrash == 0.16 18:13:14 INFO - mozdebug == 0.1 18:13:14 INFO - mozdevice == 0.47 18:13:14 INFO - mozfile == 1.2 18:13:14 INFO - mozhttpd == 0.7 18:13:14 INFO - mozinfo == 0.9 18:13:14 INFO - mozleak == 0.1 18:13:14 INFO - mozlog == 3.1 18:13:14 INFO - moznetwork == 0.27 18:13:14 INFO - mozprocess == 0.22 18:13:14 INFO - mozprofile == 0.27 18:13:14 INFO - mozrunner == 6.11 18:13:14 INFO - mozscreenshot == 0.1 18:13:14 INFO - mozsystemmonitor == 0.0 18:13:14 INFO - moztest == 0.7 18:13:14 INFO - mozversion == 1.4 18:13:14 INFO - psutil == 3.1.1 18:13:14 INFO - requests == 1.2.3 18:13:14 INFO - wsgiref == 0.1.2 18:13:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-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 0x103f8b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f68228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7fa94a6c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eaf9f0>, '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 0x103c0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ea28c8>, '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.HGpJLiTlre/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.AWCw9flAeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 18:13:14 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 18:13:14 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 18:13:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGpJLiTlre/Render', 18:13:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:14 INFO - 'HOME': '/Users/cltbld', 18:13:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:14 INFO - 'LOGNAME': 'cltbld', 18:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:14 INFO - 'MOZ_NO_REMOTE': '1', 18:13:14 INFO - 'NO_EM_RESTART': '1', 18:13:14 INFO - 'PAGER': '/bin/cat', 18:13:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:14 INFO - 'PWD': '/builds/slave/test', 18:13:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:14 INFO - 'SHELL': '/bin/bash', 18:13:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWCw9flAeN/Listeners', 18:13:14 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 18:13:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:14 INFO - 'USER': 'cltbld', 18:13:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:14 INFO - 'XPC_FLAGS': '0x0', 18:13:14 INFO - 'XPC_SERVICE_NAME': '0', 18:13:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:15 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)) 18:13:15 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)) 18:13:15 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)) 18:13:15 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)) 18:13:15 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)) 18:13:15 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)) 18:13:15 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)) 18:13:15 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)) 18:13:15 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)) 18:13:15 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)) 18:13:15 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)) 18:13:15 INFO - Cleaning up... 18:13:15 INFO - Return code: 0 18:13:15 INFO - Running post-action listener: _resource_record_post_action 18:13:15 INFO - Running post-action listener: _start_resource_monitoring 18:13:15 INFO - Starting resource monitoring. 18:13:15 INFO - ##### 18:13:15 INFO - ##### Running install step. 18:13:15 INFO - ##### 18:13:15 INFO - Running pre-action listener: _resource_record_pre_action 18:13:15 INFO - Running main action method: install 18:13:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:13:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:13:15 INFO - Reading from file tmpfile_stdout 18:13:15 INFO - Detecting whether we're running mozinstall >=1.0... 18:13:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:13:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:13:15 INFO - Reading from file tmpfile_stdout 18:13:15 INFO - Output received: 18:13:15 INFO - Usage: mozinstall [options] installer 18:13:15 INFO - Options: 18:13:15 INFO - -h, --help show this help message and exit 18:13:15 INFO - -d DEST, --destination=DEST 18:13:15 INFO - Directory to install application into. [default: 18:13:15 INFO - "/builds/slave/test"] 18:13:15 INFO - --app=APP Application being installed. [default: firefox] 18:13:15 INFO - mkdir: /builds/slave/test/build/application 18:13:15 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'] 18:13:15 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 18:13:45 INFO - Reading from file tmpfile_stdout 18:13:45 INFO - Output received: 18:13:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:13:45 INFO - Running post-action listener: _resource_record_post_action 18:13:45 INFO - ##### 18:13:45 INFO - ##### Running run-tests step. 18:13:45 INFO - ##### 18:13:45 INFO - Running pre-action listener: _resource_record_pre_action 18:13:45 INFO - Running main action method: run_tests 18:13:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:13:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:13:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:13:45 INFO - 2015-11-23 18:13:45.894 screenresolution[1932:11731] starting screenresolution argv=screenresolution get 18:13:45 INFO - 2015-11-23 18:13:45.897 screenresolution[1932:11731] Display 0: 1600x1200x32@60 18:13:45 INFO - 2015-11-23 18:13:45.903 screenresolution[1933:11733] starting screenresolution argv=screenresolution list 18:13:45 INFO - Available Modes on Display 0 18:13:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:13:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:13:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:13:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:13:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:13:46 INFO - Intel Iris: 18:13:46 INFO - Chipset Model: Intel Iris 18:13:46 INFO - Type: GPU 18:13:46 INFO - Bus: Built-In 18:13:46 INFO - VRAM (Dynamic, Max): 1536 MB 18:13:46 INFO - Vendor: Intel (0x8086) 18:13:46 INFO - Device ID: 0x0a2e 18:13:46 INFO - Revision ID: 0x0009 18:13:46 INFO - Displays: 18:13:46 INFO - Display: 18:13:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:13:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:13:46 INFO - Display Serial Number: 1600x1200 60 18:13:46 INFO - Main Display: Yes 18:13:46 INFO - Mirror: Off 18:13:46 INFO - Online: Yes 18:13:46 INFO - Rotation: Supported 18:13:46 INFO - Return code: 0 18:13:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:13:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:13:46 INFO - Python 2.7.3 18:13:46 INFO - Return code: 0 18:13:46 INFO - grabbing minidump binary from tooltool 18:13: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')]} 18:13:46 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 0x103eaf9f0>, '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 0x103c0edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ea28c8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:13:46 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 18:13:46 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 18:13:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:13:46 INFO - Return code: 0 18:13:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:13:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:13:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:13:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:13:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:13:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--webServer', 'localhost'] in /builds/slave/test/build 18:13:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0027 --webServer localhost 18:13:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HGpJLiTlre/Render', 18:13:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:46 INFO - 'HOME': '/Users/cltbld', 18:13:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:46 INFO - 'LOGNAME': 'cltbld', 18:13:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:13:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:13:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:46 INFO - 'MOZ_NO_REMOTE': '1', 18:13:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:13:46 INFO - 'NO_EM_RESTART': '1', 18:13:46 INFO - 'PAGER': '/bin/cat', 18:13:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:46 INFO - 'PWD': '/builds/slave/test', 18:13:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:13:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:46 INFO - 'SHELL': '/bin/bash', 18:13:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWCw9flAeN/Listeners', 18:13:46 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 18:13:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:46 INFO - 'USER': 'cltbld', 18:13:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:46 INFO - 'XPC_FLAGS': '0x0', 18:13:46 INFO - 'XPC_SERVICE_NAME': '0', 18:13:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--webServer', 'localhost'] with output_timeout 3600 18:13:46 INFO - mozversion INFO | application_buildid: 20151123162931 18:13:46 INFO - mozversion INFO | application_changeset: 19d89caa664dd9309c796929225ee409df5d1ee4 18:13:46 INFO - mozversion INFO | application_display_name: Nightly 18:13:46 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:13:46 INFO - mozversion INFO | application_name: Firefox 18:13:46 INFO - mozversion INFO | application_remotingname: firefox 18:13:46 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 18:13:46 INFO - mozversion INFO | application_vendor: Mozilla 18:13:46 INFO - mozversion INFO | application_version: 45.0a1 18:13:46 INFO - mozversion INFO | platform_buildid: 20151123162931 18:13:46 INFO - mozversion INFO | platform_changeset: 19d89caa664dd9309c796929225ee409df5d1ee4 18:13:46 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 18:13:46 INFO - mozversion INFO | platform_version: 45.0a1 18:13:46 INFO - 2015-11-23 18:13:46,437 DEBUG : using testdate: 1448331226 18:13:46 INFO - 2015-11-23 18:13:46,437 DEBUG : actual date: 1448331226 18:13:46 INFO - 2015-11-23 18:13:46,446 INFO : starting webserver on 'localhost:50518' 18:13:46 INFO - 2015-11-23 18:13:46,447 INFO : Starting test suite t-yosemite-r7-0027 18:13:46 INFO - 2015-11-23 18:13:46,447 INFO : Starting test dromaeo_css 18:13:46 INFO - 2015-11-23 18:13:46,447 DEBUG : operating with platform_type : mac_ 18:13:46 INFO - 2015-11-23 18:13:46,447 INFO : Initialising browser for dromaeo_css test... 18:13:46 INFO - 2015-11-23 18:13:46,460 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpDTPn7e/profile http://localhost:50518/getInfo.html 18:13:48 INFO - 2015-11-23 18:13:48,562 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:13:48 INFO - 2015-11-23 18:13:48,562 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:13:48 INFO - 2015-11-23 18:13:48,563 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 18:13:48 INFO - 2015-11-23 18:13:48,563 DEBUG : BROWSER_OUTPUT: __metrics 18:13:48 INFO - 2015-11-23 18:13:48,582 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:13:49 INFO - 2015-11-23 18:13:49,201 INFO : Browser initialized. 18:13:49 INFO - 2015-11-23 18:13:49,201 INFO : Running cycle 1/1 for dromaeo_css test... 18:13:49 INFO - 2015-11-23 18:13:49,201 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpDTPn7e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:14:00 INFO - 2015-11-23 18:14:00,985 DEBUG : BROWSER_OUTPUT: RSS: Main: 225611776 18:14:00 INFO - 2015-11-23 18:14:00,985 DEBUG : BROWSER_OUTPUT: 18:14:52 INFO - 2015-11-23 18:14:52,595 DEBUG : BROWSER_OUTPUT: 1448331292590 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] 18:17:02 INFO - 2015-11-23 18:17:02,604 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50518/tests/dromaeo/cssquery-ext.html) 18:17:03 INFO - 2015-11-23 18:17:03,859 DEBUG : BROWSER_OUTPUT: RSS: Main: 342147072 18:17:03 INFO - 2015-11-23 18:17:03,860 DEBUG : BROWSER_OUTPUT: 18:20:06 INFO - 2015-11-23 18:20:06,589 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/dromaeo/cssquery-ext.html (next: http://localhost:50518/tests/dromaeo/cssquery-jquery.html) 18:20:07 INFO - 2015-11-23 18:20:07,180 DEBUG : BROWSER_OUTPUT: RSS: Main: 360677376 18:20:07 INFO - 2015-11-23 18:20:07,180 DEBUG : BROWSER_OUTPUT: 18:23:09 INFO - 2015-11-23 18:23:09,268 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50518/tests/dromaeo/cssquery-mootools.html) 18:23:09 INFO - 2015-11-23 18:23:09,851 DEBUG : BROWSER_OUTPUT: RSS: Main: 358416384 18:23:09 INFO - 2015-11-23 18:23:09,851 DEBUG : BROWSER_OUTPUT: 18:26:11 INFO - 2015-11-23 18:26:11,907 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50518/tests/dromaeo/cssquery-prototype.html) 18:26:12 INFO - 2015-11-23 18:26:12,510 DEBUG : BROWSER_OUTPUT: RSS: Main: 316620800 18:26:12 INFO - 2015-11-23 18:26:12,510 DEBUG : BROWSER_OUTPUT: 18:29:15 INFO - 2015-11-23 18:29:15,596 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50518/tests/dromaeo/cssquery-yui.html) 18:29:16 INFO - 2015-11-23 18:29:16,209 DEBUG : BROWSER_OUTPUT: RSS: Main: 320876544 18:29:16 INFO - 2015-11-23 18:29:16,209 DEBUG : BROWSER_OUTPUT: 18:32:18 INFO - 2015-11-23 18:32:18,797 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/dromaeo/cssquery-yui.html (next: http://localhost:50518/tests/dromaeo/cssquery-dojo.html) 18:32:19 INFO - 2015-11-23 18:32:19,056 DEBUG : BROWSER_OUTPUT: RSS: Main: 363606016 18:32:19 INFO - 2015-11-23 18:32:19,056 DEBUG : BROWSER_OUTPUT: 18:32:19 INFO - 2015-11-23 18:32:19,056 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:32:19 INFO - 2015-11-23 18:32:19,056 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:32:19 INFO - 2015-11-23 18:32:19,056 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:32:19 INFO - 2015-11-23 18:32:19,057 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:32:19 INFO - 2015-11-23 18:32:19,057 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3357.9664203357966;3425.7430692698053;3432.656734326568;3466.895993120204;3496.3356962177186;5576.525995290395;5639.774409023639;5650.773969041238;5680.431956804317;5695.857603559908;5617.522510586605;5681.261436013312;5765.279340082489;5769.769209231626;5778.364379918216;5124.231365295217;5156.767945442455;5168.173092305232;5189.169732842746;5244.239585260135;4727.881802954919;5024.974875125619;5244.816431424901;5284.180951952453;5329.946700533003;3839.462475253467;5055.090083784918;5238.842834714965;5252.080885844981;5290.55030322423;5153.3558305211845;5171.095058364771;5179.223116532513;5180.6373553851245;5183.896322073538;5041.369828771403;5128.974205158983;5155.484451554852;5165.974170129163;5167;5655.8586035349035;5666.5183459406;5674.829755107354;5686.004949133885;5690.800821971253;6274.623522588659;6276.5920215186325;6280.057991301295;6289.0880822280515;6297.33877942814;6081.591288450819;6111.287884814254;6130.938690613081;6225.595336303126;6294.370562943669;5821.79623713168;5891.882162356772;5893.675847828367;5897.528197744213;5925.822225333247;6216.689165541705;6268.122462855204;6271.153394291712;6282.842928926722;6297.2128483939505;5977.731002104829;6049.98506422382;6101.176341193971;6128.693565321805;6188.845278868064;6279.96860015697;6293.244810622663;6303.117563541108;6306.8423289417215;6326.652034138075;6249.156363890907;6275.811725648146;6275.843103922439;6285.8742825143245;6288.685565721696;6039.758409663652;6185.226846644169;6203.627782333075;6207.317195108534;6272.560920735505;4165.166966606691;4963.429205641325;4973.825916092992;4986.975065124651;4987.426445958688;4460.264056430653;4465.218586747306;4479.283314669638;4493.910121797546;4497.865064048084;5728.942710572925;5736.225609542742;5742.712864356849;5769.826905192851;5777.595568310262;6099.573029887865;6160.44555989963;6165.414285642857;6178.227721534809;6184.721687524072;5628.493435590816;5719.084946408555;5733.627314224562;5735.8566035849435;5750.511206547407;5403.108487099585;5421.945780542223;5433.1850222466155;5437.293151890308;5451.972740136274;6275.1842260506755;6299.023651334051;6347.90478142819;6352.174217351715;6363.9045414318825;5074.746262686777;6117.296510901126;6144.631322120687;6208.9689551551955;6229.844253893689;6300.7479700811455;6351.714172862325;6364.917963946048;6387.233531976007;6400.423961843457;6193.4425901669065;6235.438810507076;6246.46905013079;6264.404881536247;6270.216222972129;6081.057436097412;6133.426475724657;6173.104899789595;6189.566730328834;6276.74893004293;6107.206063211932;6109.938900610937;6117.1130186123655;6120;6132.202813634199;6091.410991024236;6096.847578810565;6100.420460056288;6105.725242364016;6120.112583675431;5645.520130788929;5823.650580965155;5870.17817505541;5889.734961926809;6016.488598469179;4906.705597664151;6130.50955923534;6139.2325959255095;6175.660338681236;6191.597546159485;5986.2517185351835;6030.48740857032;6042.549368945911;6074.574779765374;6123.571350005457 18:32:19 INFO - 2015-11-23 18:32:19,058 DEBUG : BROWSER_OUTPUT: |1;ext.html;538.8920143309745;559.021712003993;584.9792112763228;638.9872202555949;643.3437893348787;32394.19014524635;32783;41447.79276103619;41868.79065604668;42036.36945445821;44206.55793442065;44543.554564454425;44607.88480287995;44673.77663111688;44913.326300105524;8746.956265218696;9764.828220613519;9981.001899810033;9988.200943924505;9997.750056248578;8742.694005709802;8817.206451419403;9513.38163019405;10408.427536485491;11543.07655387567;10622.78754424911;11910.34493102879;11928.52285908567;11945.044396448307;11983.880161198407;8889.777755556162;9032.277417806625;10140.543675534618;11769.058475321997;11913.463894124785;8770.868436973451;11840.822387664191;12039.337836418994;12053.337066461341;12055;42419.1516169678;42754.14491710148;42772.78613606943;42868;42909.78545107286;3292.3415316936707;3299.736021118305;3447.637998010198;3563.982180089109;3786.5266841644793;28896;29102.126936191948;29174.70825291741;29444.852775735984;29783.553246701318;2465.987670061656;2757.310672331917;2853.1583182961076;2887.826730396183;2957.3050333171686;491475.8835852395;495950;496134;496963.9999999964;498792;55299.723501382236;55724.72137639286;57152.142717859264;66246.33753662402;66595.33404665891;53232.467675323525;67041;67791.66104169447;68116.97824532636;68197;16112.516624501284;16131.919340403458;16291.266892989845;16306.429274975319;16317.755233671505;12250.81623775644;12320.691982700504;12330.383480825923;12334.259944403364;12352.938235308946;17054.403095891583;19810.811351318967;20098.39704808858;20339.8983005087;20410.28564000253;8991.595378207996;9132;9132.771680708036;9140.360174787835;9179.403338782959;872.7469033980075;970.3595626886221;985.6550207427343;1020.5662593397776;1093.8851420600722;29991.550126748116;30127.397452050835;30548.38903221923;30666.233344166576;30713;1054.1250761867784;1124.083871644607;1151.986252098165;1160.7968605494004;1168.5617893290016;14758.852411475962;15361.615959600877;15443.613909652124;15605.765913511306;15649.139297338528;516750;523500;524062;525172;525267;53325.66685832808;60391.39608603946;60594.09108863371;60653.41832270966;60844.695776520835;1435.777454494555;1454.7163303155783;1466.2595389328321;1473.8673519383092;1481.9629509262354;29404.705952940196;29983.70016299809;30245.848770756005;30572.388552229277;30755.84622076875;57108.714456429116;57309;57461.13808292795;57528.71235643795;57605;434607;437997;438618;438876;439776;28851.27871803138;31433.685663143075;31718.682813171574;34071.97784066482;34201.82899085488;43753.8701236438;44961.77519112515;45296.773516132205;45724.771376142904;45951.540484596066;36694.816525918264;37042.07394815151;37943.2411351777;38055.80972095122;38275.42586861143;36969.44545831761;37626;37864.17894877975;38116.618833811306;39352.40971385374;28242.576361354186;28582.714172858006;37766.811165944;37901;38388.848334550006;29874.103776886728;32250.838745806122;33105.83447082749;33469.83265083756;33695;24910.37724056913;25163.49673006566;25173.622395663715;25572.360690982874;25765.742342576334 18:32:19 INFO - 2015-11-23 18:32:19,062 DEBUG : BROWSER_OUTPUT: |2;jquery.html;354.1871405125239;358.65748210459014;360.12848905648326;366.4704501994617;368.6368926607293;16106.516804495855;17677.7348339775;19817.504562385948;20526.89736551317;21180.940952952347;11347.943260283708;11575.958163765259;11590.652280431601;15555.611109722262;19837.305694300703;3986.4618276532588;5257.684538927658;5398.379186393556;5411.810586629469;5412.972935135319;4083.8774836754947;4114.382842573608;4139.006638406776;5767.711614419283;5959.851003724898;5808.332041815182;5884.588078834483;5914.9408505915035;5932.6143800653035;5932.792352267671;5931.258592675916;5949.8215053548465;5960;5966.97016514919;5985.940140598582;5638.697886237333;5686.943130568683;5700.515456186229;5769.365369809318;5844.853878653025;13156.407961641653;13371.86628133716;13399.732005359936;20677.276295329742;26203;2156.439325775294;2159.805617494433;2197.3957161780477;2234.19568955176;2290.576243394977;9679.951600242024;9937.503124843728;10073.647422340184;11585.768284634345;14970.62573435662;1685.4859267923277;1710.563806229416;1722.6210233748554;1811;1823.7355583440326;79446.60276698637;91250.5437472815;92762.14475710515;99001;104258;10872.891271087346;11150.944245278723;11179.944100279447;11217.831732524019;11340.489677964511;13264.867351326557;13290.20258784476;13295.800562991562;13305.800412993813;13343.666408339675;8138.755837324889;8248.46384984974;8292.212239837207;8317.168283171635;8317.875231871527;6754.966225168842;8303.294219991247;8305.086440491541;8323.8335233295;8379.120192379713;4109.404136400205;4337.609615134653;4386.934195986999;4409.272470042407;4427.402300689366;1773.9645207095787;1789.4899953513186;1791.2476759760932;1819.2177363733456;1823.7355583440326;793.083987993868;875.916787905148;927.1007123090591;938.5307346326837;986.5041445143797;20478.385648430572;21253.574928501344;21316.680249796267;21407.67888481674;21536.353909382742;863.7303164348398;923.8106188231396;925.8148370325962;941.9529023548795;1017.3895662602528;11078.833817492745;11192.608258711069;11282.435878206057;11307.547698092148;11430.828537571942;779664;780453;783168;785145;799229;120318;122178.77821221852;124567;127175.72824271824;128844;1128.5316593613559;1136.772645470909;1196.3479903452464;1201.3512703140382;1226.8650448450662;19250.94119823423;19620.088991759352;19620.215191392756;19756.50608734793;19956.002199889655;50937;51052.48947510626;51639;51641;51769;70155.29844701488;70934;71710.64144679243;73323;75266;9618;9833.4099954003;10105.797884042131;10120.342177758423;10128.088472037258;11956.820647690465;16504.25730842091;20093.49766255855;20582.27962021322;20618.484537886674;11520.596779113026;12386.009119270617;15434;15819;16135.19324033817;15235.619109522351;15264.618384540476;15295.77056344178;15545.37818487293;15716.842831571996;11699.590514331958;16194.190290485192;16555.91722041382;16726.414575489798;16930.83069169342;9757.756056098655;9910.554025069034;9941.30410871261;9943.950280248553;9964.651237206954;13333.5999720028;14331.6211382196;15103.924480377527;15716;15999.280032398337 18:32:19 INFO - 2015-11-23 18:32:19,063 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1393.5679939218837;1598.7042397156526;1620.659661471091;1906.9713954290687;1931.6233334499768;6708.731650733971;7771.961140194298;8052.194780521945;8457.91542084579;8481.279091277249;8327.958360208206;8418.789530261747;8446.408751387406;8464.407491475598;8475.279601233902;2109.293386715452;2158.6330323844904;2324.1052194905;2345.44881952741;2347.5187586544803;2166.664167054101;2176.8911554422216;2176.9020394082304;2181.8472706910525;2314.594945884472;2350.4123969007746;2353.1175809071597;2355.6231003039516;2361.25620429565;2362.6692263083182;2272.8295377846725;2326.0579465316573;2341.1337805012085;2341.6487526870937;2343.2735851885886;2131.5949969505746;2176.967345489819;2188.3325585696357;2314.36355002374;2359.7994170495526;8428.199321064489;8484.448510846836;8497.95751021247;8513.914860851437;8518.914810851875;5146.8198613048735;5207.6354655174155;5216.400113986902;5217.713025783581;5230.163173892159;3431.673990970854;3499.212677147647;3557.875474358411;3562.786232826039;3566.1797786509246;1017.7099526634873;1039.3192458939407;1050.4905121016377;1053.4943227250885;1064.164630764846;11365.886341136647;11374.71563210918;11379.658610241706;11383.94308028463;11412.372319522423;3947.3684210526176;4618.1918164321105;4680.695754775997;4715.646326525524;4741.122892264942;9750.951245243728;9759.609615615438;9759.853602195974;9775.266854985763;9778.07108324708;5229.895402092013;5235.633505654644;5236.502532259429;5238.738063096906;5241.580673546107;4774.400575807074;4776.928346074742;4778.187708089633;4782.497837727058;4786.497417771154;3238.3037646906027;3748.756330838487;3847.0574709196426;3869.8258578364043;3875.7093218008763;1243.3339325304137;1261.9808306709192;1262.2426544073483;1269.7714411406037;1272.204871955028;1343.7715588350004;1359.7756370199004;1378.6553361659585;1409.5489443378226;1421.9004669673025;6570.671466426659;7815.3356964657505;7866.803329916798;7889.2110788920645;7891.895134681149;497.49255759126214;507.11761534928957;512.6385897941942;514.0567059445884;515.5978336897226;5835.066389377679;5960.910586341209;6155.692215389213;6234.438900498977;6236.438720515175;8972.775680608038;9717.757056073513;9859.26055545837;9912.405255684682;9960.252981026451;9835.852462213072;9914.058164474363;10037.096661300518;10299.021592948659;10315.123214526699;7868.803279917934;7960.761177164579;7973;8044.833499142592;8064.475809072392;7611.200823913631;7883.64523596451;7900.2889739923685;7903.3677305816545;7912.248336408032;9032.232260257952;9137.543122843965;9153.954230228806;9242.537873106183;9302.581383837609;9189.540522973959;9497.097775711296;9499.620015199314;9507.239420846177;9537.856932146162;7364.668589913574;7407.037085178892;7415.8146046349275;7461.664225109992;7501.924980750123;8032.718854840053;8083.19168083187;8083.272505474535;8088.272055515032;8110.229528194812;7471.3649339806725;7519.511231769918;7568.659410326659;7687.807804804925;7716.5370077795515;7414.221506741715;7431.442641801995;7449.8882516761205;7551.3958883288105;7565.924340756522;7586.431017673586;7617.809554761176;7628.771136865903;7635.885461717968;7644.541327520366;6865.366898234645;6891.586504809728;6920.619365934721;6927.064846245894;6990.755323563651;6994.440444764305;7012.474064445085;7029;7031.402330801735;7048.859022819617 18:32:19 INFO - 2015-11-23 18:32:19,064 DEBUG : BROWSER_OUTPUT: |4;prototype.html;323.8251344274091;328.63849765258203;329.9554560134381;333.4115286519293;339.30273288392374;6905.447564194865;6948.339907708772;7012.824679383011;7017.228104908469;7090;5650.378458369586;6179;6508.893488107022;7011.158660960692;7017.578945263288;4834.637402194832;5109.310243117187;5115.7186354750565;5117.078925793356;5126.436092029874;5002.074616196015;5025.271335656327;5036.118679231138;5044.798208071673;5051.1413059779925;4960.131976904046;4961.875953101166;4978.850634480971;4978.850634480971;4983;4726.90546189076;4833.299171620095;4841.419029716421;4847.272909063633;4968.428630707461;4107.075907920724;4244.320908654631;4250.4899412070445;4260.595243451898;4265.210935976854;6618.205815302146;6639.668016599199;6656.36764507371;6678.131842860445;6688.598684078971;5660.094384898396;5798.449147330997;5805.129230615399;5928.584999050068;5934.347221805598;7396.927445520423;7441.776746697607;7450.925490745078;7460.36586890115;7462.664180111908;3974.880753577397;4778.498257682928;4784.40194975628;4788.0663270662235;4789.114013907403;6918.757843475504;7490.550566966;7616.276453736886;7851;7934.841303173962;4804.351412559329;4836.903261934812;4839.491853355417;4852.733099679481;4865.416150061945;4910;4912.992836468516;4919.237518184687;4944.653874228841;4949;5332.520073193431;5796.507296879812;5836.212111364996;5892.469677729025;5974.4324289192455;7475;7510.962445187739;7521.435892308099;7528.849423011509;7560.319571238615;1702.3701230544777;1718.802337731152;1722.6210233748554;1723.086764015074;1724.6809340272089;968.3705591365668;1059.4490864750442;1079.838024296362;1092.1754075672975;1099.2854644481151;5751.849630074002;6700.899486507707;6940.444764418935;6948.791536253921;6993.230744618088;7784.610769461504;7804.531728096333;7816.140224575406;7818.843623127619;7828.491148075357;6736.562123461959;6947;6963.9205923082;6967.407770339477;6988.510804243654;4118.094019315746;4158.958410415918;4160;4169.249535083714;4173.8956526087095;6508.772192973223;7148.856183010694;7557.697692092254;8083.878741818759;8094.069182044022;7401.148867880155;7689.4232932530285;8020.358371330396;8029.919700802917;8048.114707382183;7658.578778167254;8038.517688938682;8126.431149819456;8164.959175204086;8167.142450042778;6889.379955804002;7545.359124306448;7854.253845884632;7883.172266911892;7889.408294377829;7728.149903510722;7734.651940662571;7735.845283094419;7738.264864837832;7750.728724494616;8131.983502062242;8142.267195952392;8146.75559733209;8151.918480815116;8154.103048664523;4670.50543825986;4862.5380588844;4865.099956508057;4865.878353041202;4903.877403064952;6396.680165991775;7825.060992680687;7829.843403132019;7830.608469576385;7840.490368126054;6082.787102451393;7513.849723005618;7516.060492438445;7525.473216874766;7540.886886696813;7296.233895440902;7497.512661676974;7573.431992600466;7586.582737949245;7593.544387336778;6016.789412370546;7379.667914943733;7384.409247260099;7394.260573942778;7403.407727381904;6137.330261336333;7338.889916651147;7560.206178351194;7581.3176814087;7595.088589369311;7390.815229619303;7395.889061664187;7397.223291554309;7398.3341499265325;7399.445041621792 18:32:19 INFO - 2015-11-23 18:32:19,068 DEBUG : BROWSER_OUTPUT: |5;yui.html;603.4056454392422;677.292229620047;789.7196495244189;801.9278264956152;860.8278344331131;14783.556493305192;15166.090034597919;15169.241537923115;15183.089014659128;15195.620109497268;13995.230262335568;14142.151470911753;14168.220747858864;14191.574252772434;14208.218547979857;734.5482528245144;743.9330460258576;745.0947099274381;923.3767207135173;925.5048549026272;929.1219797291562;929.1591110045385;943.217129782279;943.5942544705774;945.0927109974433;971.4899677669209;987.8765154355706;988.0317289056728;988.77258230607;990.7869807991281;771.3713323641242;779.7855589712817;781.562325097947;967.9758006049834;977.6431602465085;727.5525551785614;729.4711334282656;731.0934441292811;736.3851184261148;737.4837613670452;14899.478518251917;15018.549443516711;15051.096934183985;15072.698546029127;15081.245937703181;12382.31897245651;12473.31396773177;12602.68493287666;12654.55709050188;12800;10023.849642255373;10131.594736210543;10290.43402612856;10376.377417354983;10842.241043126984;3520.718342532617;3552.8578856845697;3561.1809283864595;3574;3576.8748093816594;77241.22758772396;77717.22282777156;79354.6032269835;79510.60244698798;79621.80567291495;9415.613382899628;9495.430274183433;9546.856797148048;9548.618055277711;9615.375000625078;21491.57016859654;21813.67279490809;21827.345179644635;21838.126474940826;21848.344549663536;7257.201707812136;7387.741429050065;7459.216782237787;7549.245075492517;7644.655990480442;3942.8619998299923;3954.5649978502342;3965.4250133730447;3975.3241948868763;4007.0783719744622;3324.9027820819074;3366.1752870546875;3556.608773034964;3605.0446631642635;3608.3144202601948;627.3162253144004;634.4638780230698;641.7785863876956;649.8505343770959;655.4494224851148;507.64464874587196;527.881226723984;533.8745394832212;541.3287523470867;543.8450041738085;8642.870356944652;8676.913230867736;8806.559672016501;8857.202851743374;8914.73255802327;489.380933119604;497.35344052731307;547.15464607182;571.4599155276572;626.9905951410733;4049.271131196354;4205.179989902001;4215.199112168678;4237.449131612871;4276.31578947367;103136;103399.44900826494;103482.48258758658;105285.47357263164;105609.47195263974;83926.580367099;84286.57856710677;84816;86012.5699371499;86301.27397452015;76112.61943690246;77351.83972240421;77460.22539774643;77803;78447.8232826508;8370.079291278076;8416.158384161536;8562.828743424972;8649.005364382925;8664.653413863627;53495;54157.18764218619;55329;56951.14573281321;60451;75386.869196961;75652;76132.23867761252;76800.23199767931;77775;11784.882151178379;11863.347515886364;11970;12273.509059637514;12343.567975120828;12110.576129835414;12245.387730613256;12248.203866748632;12836.55072072473;13139.277339746024;11587.478563464832;11950.402479876146;12292.754144916873;12404.441800119197;12489.750204995667;11633.59282425111;11703.707407314885;12385.566505172275;12424.503019879465;12664.303463309237;11454.370009649216;11669.35818529989;11757.588484403004;12095.516179352728;12442.626721198378;9700;9786.923438421625;9959.651412200537;10036.548355324174;10462.163026957673;9088.909110908806;9775.853362199716;10402.47987600632;10409.42748148859;10409.583616655249 18:32:19 INFO - 2015-11-23 18:32:19,068 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:32:19 INFO - 2015-11-23 18:32:19,068 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:32:19 INFO - 2015-11-23 18:32:19,068 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1063 18:32:19 INFO - 2015-11-23 18:32:19,068 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:32:19 INFO - 2015-11-23 18:32:19,068 DEBUG : BROWSER_OUTPUT: __startTimestamp1448332339053__endTimestamp 18:32:19 INFO - 2015-11-23 18:32:19,068 DEBUG : BROWSER_OUTPUT: 18:32:19 INFO - 2015-11-23 18:32:19,068 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:32:19 INFO - 2015-11-23 18:32:19,068 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:32:19 INFO - 2015-11-23 18:32:19,069 DEBUG : BROWSER_OUTPUT: 18:32:19 INFO - 2015-11-23 18:32:19,069 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5724.47 Median:5986.25 stddev:661.67 (11.1%) stddev-sans-first:637.12 18:32:19 INFO - 2015-11-23 18:32:19,069 DEBUG : BROWSER_OUTPUT: Values: 3358.0 3425.7 3432.7 3466.9 3496.3 5576.5 5639.8 5650.8 5680.4 5695.9 5617.5 5681.3 5765.3 5769.8 5778.4 5124.2 5156.8 5168.2 5189.2 5244.2 4727.9 5025.0 5244.8 5284.2 5329.9 3839.5 5055.1 5238.8 5252.1 5290.6 5153.4 5171.1 5179.2 5180.6 5183.9 5041.4 5129.0 5155.5 5166.0 5167.0 5655.9 5666.5 5674.8 5686.0 5690.8 6274.6 6276.6 6280.1 6289.1 6297.3 6081.6 6111.3 6130.9 6225.6 6294.4 5821.8 5891.9 5893.7 5897.5 5925.8 6216.7 6268.1 6271.2 6282.8 6297.2 5977.7 6050.0 6101.2 6128.7 6188.8 6280.0 6293.2 6303.1 6306.8 6326.7 6249.2 6275.8 6275.8 6285.9 6288.7 6039.8 6185.2 6203.6 6207.3 6272.6 4165.2 4963.4 4973.8 4987.0 4987.4 4460.3 4465.2 4479.3 4493.9 4497.9 5728.9 5736.2 5742.7 5769.8 5777.6 6099.6 6160.4 6165.4 6178.2 6184.7 5628.5 5719.1 5733.6 5735.9 5750.5 5403.1 5421.9 5433.2 5437.3 5452.0 6275.2 6299.0 6347.9 6352.2 6363.9 5074.7 6117.3 6144.6 6209.0 6229.8 6300.7 6351.7 6364.9 6387.2 6400.4 6193.4 6235.4 6246.5 6264.4 6270.2 6081.1 6133.4 6173.1 6189.6 6276.7 6107.2 6109.9 6117.1 6120.0 6132.2 6091.4 6096.8 6100.4 6105.7 6120.1 5645.5 5823.7 5870.2 5889.7 6016.5 4906.7 6130.5 6139.2 6175.7 6191.6 5986.3 6030.5 6042.5 6074.6 6123.6 18:32:19 INFO - 2015-11-23 18:32:19,069 DEBUG : BROWSER_OUTPUT: 18:32:19 INFO - 2015-11-23 18:32:19,069 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:63733.27 Median:37005.76 stddev:129262.34 (349.3%) stddev-sans-first:129537.88 18:32:19 INFO - 2015-11-23 18:32:19,070 DEBUG : BROWSER_OUTPUT: Values: 538.9 559.0 585.0 639.0 643.3 32394.2 32783.0 41447.8 41868.8 42036.4 44206.6 44543.6 44607.9 44673.8 44913.3 8747.0 9764.8 9981.0 9988.2 9997.8 8742.7 8817.2 9513.4 10408.4 11543.1 10622.8 11910.3 11928.5 11945.0 11983.9 8889.8 9032.3 10140.5 11769.1 11913.5 8770.9 11840.8 12039.3 12053.3 12055.0 42419.2 42754.1 42772.8 42868.0 42909.8 3292.3 3299.7 3447.6 3564.0 3786.5 28896.0 29102.1 29174.7 29444.9 29783.6 2466.0 2757.3 2853.2 2887.8 2957.3 491475.9 495950.0 496134.0 496964.0 498792.0 55299.7 55724.7 57152.1 66246.3 66595.3 53232.5 67041.0 67791.7 68117.0 68197.0 16112.5 16131.9 16291.3 16306.4 16317.8 12250.8 12320.7 12330.4 12334.3 12352.9 17054.4 19810.8 20098.4 20339.9 20410.3 8991.6 9132.0 9132.8 9140.4 9179.4 872.7 970.4 985.7 1020.6 1093.9 29991.6 30127.4 30548.4 30666.2 30713.0 1054.1 1124.1 1152.0 1160.8 1168.6 14758.9 15361.6 15443.6 15605.8 15649.1 516750.0 523500.0 524062.0 525172.0 525267.0 53325.7 60391.4 60594.1 60653.4 60844.7 1435.8 1454.7 1466.3 1473.9 1482.0 29404.7 29983.7 30245.8 30572.4 30755.8 57108.7 57309.0 57461.1 57528.7 57605.0 434607.0 437997.0 438618.0 438876.0 439776.0 28851.3 31433.7 31718.7 34072.0 34201.8 43753.9 44961.8 45296.8 45724.8 45951.5 36694.8 37042.1 37943.2 38055.8 38275.4 36969.4 37626.0 37864.2 38116.6 39352.4 28242.6 28582.7 37766.8 37901.0 38388.8 29874.1 32250.8 33105.8 33469.8 33695.0 24910.4 25163.5 25173.6 25572.4 25765.7 18:32:19 INFO - 2015-11-23 18:32:19,071 DEBUG : BROWSER_OUTPUT: 18:32:19 INFO - 2015-11-23 18:32:19,071 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:39461.71 Median:21472.02 stddev:129168.48 (601.6%) stddev-sans-first:129497.45 18:32:19 INFO - 2015-11-23 18:32:19,072 DEBUG : BROWSER_OUTPUT: Values: 354.2 358.7 360.1 366.5 368.6 16106.5 17677.7 19817.5 20526.9 21180.9 11347.9 11576.0 11590.7 15555.6 19837.3 3986.5 5257.7 5398.4 5411.8 5413.0 4083.9 4114.4 4139.0 5767.7 5959.9 5808.3 5884.6 5914.9 5932.6 5932.8 5931.3 5949.8 5960.0 5967.0 5985.9 5638.7 5686.9 5700.5 5769.4 5844.9 13156.4 13371.9 13399.7 20677.3 26203.0 2156.4 2159.8 2197.4 2234.2 2290.6 9680.0 9937.5 10073.6 11585.8 14970.6 1685.5 1710.6 1722.6 1811.0 1823.7 79446.6 91250.5 92762.1 99001.0 104258.0 10872.9 11150.9 11179.9 11217.8 11340.5 13264.9 13290.2 13295.8 13305.8 13343.7 8138.8 8248.5 8292.2 8317.2 8317.9 6755.0 8303.3 8305.1 8323.8 8379.1 4109.4 4337.6 4386.9 4409.3 4427.4 1774.0 1789.5 1791.2 1819.2 1823.7 793.1 875.9 927.1 938.5 986.5 20478.4 21253.6 21316.7 21407.7 21536.4 863.7 923.8 925.8 942.0 1017.4 11078.8 11192.6 11282.4 11307.5 11430.8 779664.0 780453.0 783168.0 785145.0 799229.0 120318.0 122178.8 124567.0 127175.7 128844.0 1128.5 1136.8 1196.3 1201.4 1226.9 19250.9 19620.1 19620.2 19756.5 19956.0 50937.0 51052.5 51639.0 51641.0 51769.0 70155.3 70934.0 71710.6 73323.0 75266.0 9618.0 9833.4 10105.8 10120.3 10128.1 11956.8 16504.3 20093.5 20582.3 20618.5 11520.6 12386.0 15434.0 15819.0 16135.2 15235.6 15264.6 15295.8 15545.4 15716.8 11699.6 16194.2 16555.9 16726.4 16930.8 9757.8 9910.6 9941.3 9944.0 9964.7 13333.6 14331.6 15103.9 15716.0 15999.3 18:32:19 INFO - 2015-11-23 18:32:19,072 DEBUG : BROWSER_OUTPUT: 18:32:19 INFO - 2015-11-23 18:32:19,072 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5840.94 Median:6195.07 stddev:3079.09 (49.7%) stddev-sans-first:3069.58 18:32:19 INFO - 2015-11-23 18:32:19,074 DEBUG : BROWSER_OUTPUT: Values: 1393.6 1598.7 1620.7 1907.0 1931.6 6708.7 7772.0 8052.2 8457.9 8481.3 8328.0 8418.8 8446.4 8464.4 8475.3 2109.3 2158.6 2324.1 2345.4 2347.5 2166.7 2176.9 2176.9 2181.8 2314.6 2350.4 2353.1 2355.6 2361.3 2362.7 2272.8 2326.1 2341.1 2341.6 2343.3 2131.6 2177.0 2188.3 2314.4 2359.8 8428.2 8484.4 8498.0 8513.9 8518.9 5146.8 5207.6 5216.4 5217.7 5230.2 3431.7 3499.2 3557.9 3562.8 3566.2 1017.7 1039.3 1050.5 1053.5 1064.2 11365.9 11374.7 11379.7 11383.9 11412.4 3947.4 4618.2 4680.7 4715.6 4741.1 9751.0 9759.6 9759.9 9775.3 9778.1 5229.9 5235.6 5236.5 5238.7 5241.6 4774.4 4776.9 4778.2 4782.5 4786.5 3238.3 3748.8 3847.1 3869.8 3875.7 1243.3 1262.0 1262.2 1269.8 1272.2 1343.8 1359.8 1378.7 1409.5 1421.9 6570.7 7815.3 7866.8 7889.2 7891.9 497.5 507.1 512.6 514.1 515.6 5835.1 5960.9 6155.7 6234.4 6236.4 8972.8 9717.8 9859.3 9912.4 9960.3 9835.9 9914.1 10037.1 10299.0 10315.1 7868.8 7960.8 7973.0 8044.8 8064.5 7611.2 7883.6 7900.3 7903.4 7912.2 9032.2 9137.5 9154.0 9242.5 9302.6 9189.5 9497.1 9499.6 9507.2 9537.9 7364.7 7407.0 7415.8 7461.7 7501.9 8032.7 8083.2 8083.3 8088.3 8110.2 7471.4 7519.5 7568.7 7687.8 7716.5 7414.2 7431.4 7449.9 7551.4 7565.9 7586.4 7617.8 7628.8 7635.9 7644.5 6865.4 6891.6 6920.6 6927.1 6990.8 6994.4 7012.5 7029.0 7031.4 7048.9 18:32:19 INFO - 2015-11-23 18:32:19,074 DEBUG : BROWSER_OUTPUT: 18:32:19 INFO - 2015-11-23 18:32:19,074 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:6031.90 Median:6897.41 stddev:1963.76 (28.5%) stddev-sans-first:1921.97 18:32:19 INFO - 2015-11-23 18:32:19,074 DEBUG : BROWSER_OUTPUT: Values: 323.8 328.6 330.0 333.4 339.3 6905.4 6948.3 7012.8 7017.2 7090.0 5650.4 6179.0 6508.9 7011.2 7017.6 4834.6 5109.3 5115.7 5117.1 5126.4 5002.1 5025.3 5036.1 5044.8 5051.1 4960.1 4961.9 4978.9 4978.9 4983.0 4726.9 4833.3 4841.4 4847.3 4968.4 4107.1 4244.3 4250.5 4260.6 4265.2 6618.2 6639.7 6656.4 6678.1 6688.6 5660.1 5798.4 5805.1 5928.6 5934.3 7396.9 7441.8 7450.9 7460.4 7462.7 3974.9 4778.5 4784.4 4788.1 4789.1 6918.8 7490.6 7616.3 7851.0 7934.8 4804.4 4836.9 4839.5 4852.7 4865.4 4910.0 4913.0 4919.2 4944.7 4949.0 5332.5 5796.5 5836.2 5892.5 5974.4 7475.0 7511.0 7521.4 7528.8 7560.3 1702.4 1718.8 1722.6 1723.1 1724.7 968.4 1059.4 1079.8 1092.2 1099.3 5751.8 6700.9 6940.4 6948.8 6993.2 7784.6 7804.5 7816.1 7818.8 7828.5 6736.6 6947.0 6963.9 6967.4 6988.5 4118.1 4159.0 4160.0 4169.2 4173.9 6508.8 7148.9 7557.7 8083.9 8094.1 7401.1 7689.4 8020.4 8029.9 8048.1 7658.6 8038.5 8126.4 8165.0 8167.1 6889.4 7545.4 7854.3 7883.2 7889.4 7728.1 7734.7 7735.8 7738.3 7750.7 8132.0 8142.3 8146.8 8151.9 8154.1 4670.5 4862.5 4865.1 4865.9 4903.9 6396.7 7825.1 7829.8 7830.6 7840.5 6082.8 7513.8 7516.1 7525.5 7540.9 7296.2 7497.5 7573.4 7586.6 7593.5 6016.8 7379.7 7384.4 7394.3 7403.4 6137.3 7338.9 7560.2 7581.3 7595.1 7390.8 7395.9 7397.2 7398.3 7399.4 18:32:19 INFO - 2015-11-23 18:32:19,074 DEBUG : BROWSER_OUTPUT: 18:32:19 INFO - 2015-11-23 18:32:19,074 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:19550.26 Median:27018.16 stddev:28063.91 (103.9%) stddev-sans-first:28106.57 18:32:19 INFO - 2015-11-23 18:32:19,075 DEBUG : BROWSER_OUTPUT: Values: 603.4 677.3 789.7 801.9 860.8 14783.6 15166.1 15169.2 15183.1 15195.6 13995.2 14142.2 14168.2 14191.6 14208.2 734.5 743.9 745.1 923.4 925.5 929.1 929.2 943.2 943.6 945.1 971.5 987.9 988.0 988.8 990.8 771.4 779.8 781.6 968.0 977.6 727.6 729.5 731.1 736.4 737.5 14899.5 15018.5 15051.1 15072.7 15081.2 12382.3 12473.3 12602.7 12654.6 12800.0 10023.8 10131.6 10290.4 10376.4 10842.2 3520.7 3552.9 3561.2 3574.0 3576.9 77241.2 77717.2 79354.6 79510.6 79621.8 9415.6 9495.4 9546.9 9548.6 9615.4 21491.6 21813.7 21827.3 21838.1 21848.3 7257.2 7387.7 7459.2 7549.2 7644.7 3942.9 3954.6 3965.4 3975.3 4007.1 3324.9 3366.2 3556.6 3605.0 3608.3 627.3 634.5 641.8 649.9 655.4 507.6 527.9 533.9 541.3 543.8 8642.9 8676.9 8806.6 8857.2 8914.7 489.4 497.4 547.2 571.5 627.0 4049.3 4205.2 4215.2 4237.4 4276.3 103136.0 103399.4 103482.5 105285.5 105609.5 83926.6 84286.6 84816.0 86012.6 86301.3 76112.6 77351.8 77460.2 77803.0 78447.8 8370.1 8416.2 8562.8 8649.0 8664.7 53495.0 54157.2 55329.0 56951.1 60451.0 75386.9 75652.0 76132.2 76800.2 77775.0 11784.9 11863.3 11970.0 12273.5 12343.6 12110.6 12245.4 12248.2 12836.6 13139.3 11587.5 11950.4 12292.8 12404.4 12489.8 11633.6 11703.7 12385.6 12424.5 12664.3 11454.4 11669.4 11757.6 12095.5 12442.6 9700.0 9786.9 9959.7 10036.5 10462.2 9088.9 9775.9 10402.5 10409.4 10409.6 18:32:19 INFO - 2015-11-23 18:32:19,075 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:32:19 INFO - 2015-11-23 18:32:19,075 DEBUG : BROWSER_OUTPUT: 18:32:19 INFO - 2015-11-23 18:32:19,498 INFO : Browser exited with error code: 0 18:32:19 INFO - 2015-11-23 18:32:19,519 INFO : Completed test dromaeo_css (00:18:33) 18:32:19 INFO - 2015-11-23 18:32:19,519 INFO : Starting test kraken 18:32:19 INFO - 2015-11-23 18:32:19,519 DEBUG : operating with platform_type : mac_ 18:32:19 INFO - 2015-11-23 18:32:19,519 INFO : Initialising browser for kraken test... 18:32:19 INFO - 2015-11-23 18:32:19,532 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpFrjeFb/profile http://localhost:50518/getInfo.html 18:32:21 INFO - 2015-11-23 18:32:21,384 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:32:21 INFO - 2015-11-23 18:32:21,384 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:32:21 INFO - 2015-11-23 18:32:21,384 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 18:32:21 INFO - 2015-11-23 18:32:21,385 DEBUG : BROWSER_OUTPUT: __metrics 18:32:21 INFO - 2015-11-23 18:32:21,401 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:32:21 INFO - 2015-11-23 18:32:21,998 INFO : Browser initialized. 18:32:21 INFO - 2015-11-23 18:32:21,998 INFO : Running cycle 1/1 for kraken test... 18:32:21 INFO - 2015-11-23 18:32:21,999 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpFrjeFb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:32:33 INFO - 2015-11-23 18:32:33,922 DEBUG : BROWSER_OUTPUT: RSS: Main: 226906112 18:32:33 INFO - 2015-11-23 18:32:33,922 DEBUG : BROWSER_OUTPUT: 18:32:36 INFO - 2015-11-23 18:32:36,585 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50518/tests/kraken/driver.html?testName=audio-beat-detection) 18:32:37 INFO - 2015-11-23 18:32:37,205 DEBUG : BROWSER_OUTPUT: RSS: Main: 275173376 18:32:37 INFO - 2015-11-23 18:32:37,205 DEBUG : BROWSER_OUTPUT: 18:32:39 INFO - 2015-11-23 18:32:39,721 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50518/tests/kraken/driver.html?testName=audio-dft) 18:32:40 INFO - 2015-11-23 18:32:40,314 DEBUG : BROWSER_OUTPUT: RSS: Main: 289193984 18:32:40 INFO - 2015-11-23 18:32:40,314 DEBUG : BROWSER_OUTPUT: 18:32:43 INFO - 2015-11-23 18:32:43,648 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50518/tests/kraken/driver.html?testName=audio-fft) 18:32:44 INFO - 2015-11-23 18:32:44,227 DEBUG : BROWSER_OUTPUT: RSS: Main: 276680704 18:32:44 INFO - 2015-11-23 18:32:44,227 DEBUG : BROWSER_OUTPUT: 18:32:46 INFO - 2015-11-23 18:32:46,076 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50518/tests/kraken/driver.html?testName=audio-oscillator) 18:32:46 INFO - 2015-11-23 18:32:46,660 DEBUG : BROWSER_OUTPUT: RSS: Main: 281001984 18:32:46 INFO - 2015-11-23 18:32:46,661 DEBUG : BROWSER_OUTPUT: 18:32:48 INFO - 2015-11-23 18:32:48,673 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50518/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:32:49 INFO - 2015-11-23 18:32:49,255 DEBUG : BROWSER_OUTPUT: RSS: Main: 280678400 18:32:49 INFO - 2015-11-23 18:32:49,255 DEBUG : BROWSER_OUTPUT: 18:32:52 INFO - 2015-11-23 18:32:52,032 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50518/tests/kraken/driver.html?testName=imaging-darkroom) 18:32:52 INFO - 2015-11-23 18:32:52,623 DEBUG : BROWSER_OUTPUT: RSS: Main: 282447872 18:32:52 INFO - 2015-11-23 18:32:52,624 DEBUG : BROWSER_OUTPUT: 18:32:55 INFO - 2015-11-23 18:32:55,381 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50518/tests/kraken/driver.html?testName=imaging-desaturate) 18:32:55 INFO - 2015-11-23 18:32:55,981 DEBUG : BROWSER_OUTPUT: RSS: Main: 283029504 18:32:55 INFO - 2015-11-23 18:32:55,981 DEBUG : BROWSER_OUTPUT: 18:32:58 INFO - 2015-11-23 18:32:58,687 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50518/tests/kraken/driver.html?testName=json-parse-financial) 18:32:59 INFO - 2015-11-23 18:32:59,272 DEBUG : BROWSER_OUTPUT: RSS: Main: 281665536 18:32:59 INFO - 2015-11-23 18:32:59,273 DEBUG : BROWSER_OUTPUT: 18:33:00 INFO - 2015-11-23 18:33:00,773 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50518/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:33:01 INFO - 2015-11-23 18:33:01,347 DEBUG : BROWSER_OUTPUT: RSS: Main: 314970112 18:33:01 INFO - 2015-11-23 18:33:01,348 DEBUG : BROWSER_OUTPUT: 18:33:03 INFO - 2015-11-23 18:33:03,097 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50518/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:33:03 INFO - 2015-11-23 18:33:03,718 DEBUG : BROWSER_OUTPUT: RSS: Main: 317231104 18:33:03 INFO - 2015-11-23 18:33:03,718 DEBUG : BROWSER_OUTPUT: 18:33:05 INFO - 2015-11-23 18:33:05,779 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50518/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:33:06 INFO - 2015-11-23 18:33:06,366 DEBUG : BROWSER_OUTPUT: RSS: Main: 322277376 18:33:06 INFO - 2015-11-23 18:33:06,367 DEBUG : BROWSER_OUTPUT: 18:33:08 INFO - 2015-11-23 18:33:08,953 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50518/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:33:09 INFO - 2015-11-23 18:33:09,556 DEBUG : BROWSER_OUTPUT: RSS: Main: 325447680 18:33:09 INFO - 2015-11-23 18:33:09,556 DEBUG : BROWSER_OUTPUT: 18:33:11 INFO - 2015-11-23 18:33:11,982 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50518/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:33:12 INFO - 2015-11-23 18:33:12,563 DEBUG : BROWSER_OUTPUT: RSS: Main: 323514368 18:33:12 INFO - 2015-11-23 18:33:12,563 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,208 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50518/tests/kraken/driver.html?testName=ai-astar) 18:33:14 INFO - 2015-11-23 18:33:14,466 DEBUG : BROWSER_OUTPUT: RSS: Main: 382988288 18:33:14 INFO - 2015-11-23 18:33:14,466 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,466 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:33:14 INFO - 2015-11-23 18:33:14,466 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:33:14 INFO - 2015-11-23 18:33:14,466 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:33:14 INFO - 2015-11-23 18:33:14,467 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:33:14 INFO - 2015-11-23 18:33:14,467 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;108;101;115;157;161;100;108;108;103 18:33:14 INFO - 2015-11-23 18:33:14,467 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;119;141;156;119;139;133;119;155;118 18:33:14 INFO - 2015-11-23 18:33:14,467 DEBUG : BROWSER_OUTPUT: |2;audio-dft;185;182;181;180;185;207;264;185;180;179 18:33:14 INFO - 2015-11-23 18:33:14,467 DEBUG : BROWSER_OUTPUT: |3;audio-fft;62;69;76;74;54;57;57;72;73;55 18:33:14 INFO - 2015-11-23 18:33:14,467 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;82;80;119;76;75;74;113;76;74;110 18:33:14 INFO - 2015-11-23 18:33:14,467 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;84;85;85;86;86;73;115;132;110;85 18:33:14 INFO - 2015-11-23 18:33:14,467 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;92;93;92;93;92;95;93;92;92;92 18:33:14 INFO - 2015-11-23 18:33:14,467 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;86;87;85;83;86;85;86;85;91;136 18:33:14 INFO - 2015-11-23 18:33:14,467 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;52;54;52;53;54;54;55;62;53;54 18:33:14 INFO - 2015-11-23 18:33:14,467 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;62;64;63;62;63;66;65;65;66;67 18:33:14 INFO - 2015-11-23 18:33:14,468 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;83;82;81;82;80;82;83;83;83 18:33:14 INFO - 2015-11-23 18:33:14,468 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;134;125;114;123;118;121;133;140;129 18:33:14 INFO - 2015-11-23 18:33:14,468 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;120;131;135;136;137;140;139;139;136 18:33:14 INFO - 2015-11-23 18:33:14,468 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;65;64;65;64;65;64;66;65;64 18:33:14 INFO - 2015-11-23 18:33:14,468 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:33:14 INFO - 2015-11-23 18:33:14,468 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:33:14 INFO - 2015-11-23 18:33:14,468 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1046 18:33:14 INFO - 2015-11-23 18:33:14,468 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:33:14 INFO - 2015-11-23 18:33:14,468 DEBUG : BROWSER_OUTPUT: __startTimestamp1448332394462__endTimestamp 18:33:14 INFO - 2015-11-23 18:33:14,468 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,468 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:33:14 INFO - 2015-11-23 18:33:14,468 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:33:14 INFO - 2015-11-23 18:33:14,469 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,469 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:117.00 Median:108.50 stddev:22.58 (20.8%) stddev-sans-first:23.76 18:33:14 INFO - 2015-11-23 18:33:14,469 DEBUG : BROWSER_OUTPUT: Values: 109.0 108.0 101.0 115.0 157.0 161.0 100.0 108.0 108.0 103.0 18:33:14 INFO - 2015-11-23 18:33:14,469 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,471 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:135.00 Median:140.00 stddev:15.67 (11.2%) stddev-sans-first:15.51 18:33:14 INFO - 2015-11-23 18:33:14,471 DEBUG : BROWSER_OUTPUT: Values: 151.0 119.0 141.0 156.0 119.0 139.0 133.0 119.0 155.0 118.0 18:33:14 INFO - 2015-11-23 18:33:14,471 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,471 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:192.80 Median:185.00 stddev:26.31 (14.2%) stddev-sans-first:27.75 18:33:14 INFO - 2015-11-23 18:33:14,471 DEBUG : BROWSER_OUTPUT: Values: 185.0 182.0 181.0 180.0 185.0 207.0 264.0 185.0 180.0 179.0 18:33:14 INFO - 2015-11-23 18:33:14,471 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,471 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:64.90 Median:70.50 stddev:8.75 (12.4%) stddev-sans-first:9.22 18:33:14 INFO - 2015-11-23 18:33:14,471 DEBUG : BROWSER_OUTPUT: Values: 62.0 69.0 76.0 74.0 54.0 57.0 57.0 72.0 73.0 55.0 18:33:14 INFO - 2015-11-23 18:33:14,471 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,472 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:87.90 Median:75.50 stddev:18.31 (24.3%) stddev-sans-first:19.30 18:33:14 INFO - 2015-11-23 18:33:14,472 DEBUG : BROWSER_OUTPUT: Values: 82.0 80.0 119.0 76.0 75.0 74.0 113.0 76.0 74.0 110.0 18:33:14 INFO - 2015-11-23 18:33:14,472 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,472 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:94.10 Median:85.00 stddev:18.42 (21.7%) stddev-sans-first:19.17 18:33:14 INFO - 2015-11-23 18:33:14,472 DEBUG : BROWSER_OUTPUT: Values: 84.0 85.0 85.0 86.0 86.0 73.0 115.0 132.0 110.0 85.0 18:33:14 INFO - 2015-11-23 18:33:14,472 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,472 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:92.60 Median:92.50 stddev:0.97 (1.0%) stddev-sans-first:1.00 18:33:14 INFO - 2015-11-23 18:33:14,472 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 92.0 93.0 92.0 95.0 93.0 92.0 92.0 92.0 18:33:14 INFO - 2015-11-23 18:33:14,472 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,472 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:91.00 Median:86.00 stddev:15.94 (18.5%) stddev-sans-first:16.81 18:33:14 INFO - 2015-11-23 18:33:14,472 DEBUG : BROWSER_OUTPUT: Values: 86.0 87.0 85.0 83.0 86.0 85.0 86.0 85.0 91.0 136.0 18:33:14 INFO - 2015-11-23 18:33:14,472 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,473 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:2.87 (5.3%) stddev-sans-first:2.92 18:33:14 INFO - 2015-11-23 18:33:14,473 DEBUG : BROWSER_OUTPUT: Values: 52.0 54.0 52.0 53.0 54.0 54.0 55.0 62.0 53.0 54.0 18:33:14 INFO - 2015-11-23 18:33:14,473 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,473 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:65.00 stddev:1.77 (2.7%) stddev-sans-first:1.67 18:33:14 INFO - 2015-11-23 18:33:14,473 DEBUG : BROWSER_OUTPUT: Values: 62.0 64.0 63.0 62.0 63.0 66.0 65.0 65.0 66.0 67.0 18:33:14 INFO - 2015-11-23 18:33:14,473 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,473 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:83.00 stddev:1.03 (1.2%) stddev-sans-first:1.05 18:33:14 INFO - 2015-11-23 18:33:14,473 DEBUG : BROWSER_OUTPUT: Values: 83.0 83.0 82.0 81.0 82.0 80.0 82.0 83.0 83.0 83.0 18:33:14 INFO - 2015-11-23 18:33:14,473 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,473 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:127.00 stddev:8.14 (6.4%) stddev-sans-first:8.37 18:33:14 INFO - 2015-11-23 18:33:14,473 DEBUG : BROWSER_OUTPUT: Values: 120.0 134.0 125.0 114.0 123.0 118.0 121.0 133.0 140.0 129.0 18:33:14 INFO - 2015-11-23 18:33:14,474 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,474 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:135.20 Median:138.00 stddev:5.96 (4.3%) stddev-sans-first:6.16 18:33:14 INFO - 2015-11-23 18:33:14,474 DEBUG : BROWSER_OUTPUT: Values: 139.0 120.0 131.0 135.0 136.0 137.0 140.0 139.0 139.0 136.0 18:33:14 INFO - 2015-11-23 18:33:14,474 DEBUG : BROWSER_OUTPUT: 18:33:14 INFO - 2015-11-23 18:33:14,474 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 18:33:14 INFO - 2015-11-23 18:33:14,474 DEBUG : BROWSER_OUTPUT: Values: 65.0 65.0 64.0 65.0 64.0 65.0 64.0 66.0 65.0 64.0 18:33:14 INFO - 2015-11-23 18:33:14,474 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:33:14 INFO - 2015-11-23 18:33:14,474 DEBUG : BROWSER_OUTPUT: 18:33:15 INFO - 2015-11-23 18:33:15,108 INFO : Browser exited with error code: 0 18:33:15 INFO - 2015-11-23 18:33:15,157 INFO : Completed test kraken (00:00:55) 18:33:15 INFO - 2015-11-23 18:33:15,157 INFO : Starting test v8_7 18:33:15 INFO - 2015-11-23 18:33:15,157 DEBUG : operating with platform_type : mac_ 18:33:15 INFO - 2015-11-23 18:33:15,157 INFO : Initialising browser for v8_7 test... 18:33:15 INFO - 2015-11-23 18:33:15,170 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmp_gh8yZ/profile http://localhost:50518/getInfo.html 18:33:17 INFO - 2015-11-23 18:33:17,191 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:33:17 INFO - 2015-11-23 18:33:17,191 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:33:17 INFO - 2015-11-23 18:33:17,191 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 18:33:17 INFO - 2015-11-23 18:33:17,192 DEBUG : BROWSER_OUTPUT: __metrics 18:33:17 INFO - 2015-11-23 18:33:17,209 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:33:17 INFO - 2015-11-23 18:33:17,991 INFO : Browser initialized. 18:33:17 INFO - 2015-11-23 18:33:17,992 INFO : Running cycle 1/1 for v8_7 test... 18:33:17 INFO - 2015-11-23 18:33:17,992 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmp_gh8yZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:33:29 INFO - 2015-11-23 18:33:29,963 DEBUG : BROWSER_OUTPUT: RSS: Main: 225959936 18:33:29 INFO - 2015-11-23 18:33:29,964 DEBUG : BROWSER_OUTPUT: 18:33:51 INFO - 2015-11-23 18:33:51,512 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50518/tests/v8_7/run.html (next: http://localhost:50518/tests/v8_7/run.html) 18:33:51 INFO - 2015-11-23 18:33:51,771 DEBUG : BROWSER_OUTPUT: RSS: Main: 703516672 18:33:51 INFO - 2015-11-23 18:33:51,771 DEBUG : BROWSER_OUTPUT: 18:33:51 INFO - 2015-11-23 18:33:51,771 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:33:51 INFO - 2015-11-23 18:33:51,771 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:33:51 INFO - 2015-11-23 18:33:51,771 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:33:51 INFO - 2015-11-23 18:33:51,771 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: |0;Richards;172.74140611504578 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.0150893882237 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.39643652561244 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4622.119815668203 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1733.102253032929 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: |5;Earley;788.6435331230284 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: |6;Boyer;13302.631578947368 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: |7;RegExp;27837.837837837837 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: |8;Splay;728.862973760933 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4596.3302752293575 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: __startTimestamp1448332431764__endTimestamp 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: 18:33:51 INFO - 2015-11-23 18:33:51,772 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:172.74 Median:172.74 stddev:0.00 (0.0%) 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: Values: 172.7 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.02 Median:164.02 stddev:0.00 (0.0%) 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: Values: 164.0 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.40 Median:278.40 stddev:0.00 (0.0%) 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: Values: 278.4 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4622.12 Median:4622.12 stddev:0.00 (0.0%) 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: Values: 4622.1 18:33:51 INFO - 2015-11-23 18:33:51,773 DEBUG : BROWSER_OUTPUT: 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1733.10 Median:1733.10 stddev:0.00 (0.0%) 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: Values: 1733.1 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:788.64 Median:788.64 stddev:0.00 (0.0%) 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: Values: 788.6 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:13302.63 Median:13302.63 stddev:0.00 (0.0%) 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: Values: 13302.6 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:27837.84 Median:27837.84 stddev:0.00 (0.0%) 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: Values: 27837.8 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:728.86 Median:728.86 stddev:0.00 (0.0%) 18:33:51 INFO - 2015-11-23 18:33:51,774 DEBUG : BROWSER_OUTPUT: Values: 728.9 18:33:51 INFO - 2015-11-23 18:33:51,775 DEBUG : BROWSER_OUTPUT: 18:33:51 INFO - 2015-11-23 18:33:51,775 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4596.33 Median:4596.33 stddev:0.00 (0.0%) 18:33:51 INFO - 2015-11-23 18:33:51,775 DEBUG : BROWSER_OUTPUT: Values: 4596.3 18:33:51 INFO - 2015-11-23 18:33:51,775 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:33:51 INFO - 2015-11-23 18:33:51,775 DEBUG : BROWSER_OUTPUT: 18:33:52 INFO - 2015-11-23 18:33:52,481 INFO : Browser exited with error code: 0 18:33:52 INFO - 2015-11-23 18:33:52,499 INFO : Completed test v8_7 (00:00:37) 18:33:52 INFO - 2015-11-23 18:33:52,698 INFO : Completed test suite (00:20:06) 18:33:52 INFO - 2015-11-23 18:33:52,698 DEBUG : Working with test: dromaeo_css 18:33:52 INFO - 2015-11-23 18:33:52,698 DEBUG : Generating results file: dromaeo_css 18:33:52 INFO - 2015-11-23 18:33:52,699 DEBUG : Working with test: kraken 18:33:52 INFO - 2015-11-23 18:33:52,699 DEBUG : Generating results file: kraken 18:33:52 INFO - 2015-11-23 18:33:52,699 INFO : javascript benchmark 18:33:52 INFO - 2015-11-23 18:33:52,699 DEBUG : Working with test: v8_7 18:33:52 INFO - 2015-11-23 18:33:52,699 DEBUG : Generating results file: v8_7 18:33:52 INFO - 2015-11-23 18:33:52,699 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:33:52 INFO - 2015-11-23 18:33:52,751 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,59]] 18:33:52 INFO - 2015-11-23 18:33:52,751 DEBUG : process_Request line: dromaeo_css 7649.93 graph.html#tests=[[72,1,59]] 18:33:52 INFO - 2015-11-23 18:33:52,752 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:33:52 INFO - 2015-11-23 18:33:52,777 DEBUG : process_Request line: kraken 1401.70 graph.html#tests=[[232,1,59]] 18:33:52 INFO - 2015-11-23 18:33:52,777 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:33:52 INFO - 2015-11-23 18:33:52,807 DEBUG : process_Request line: v8_7 19971.08 graph.html#tests=[[230,1,59]] 18:33:52 INFO - 2015-11-23 18:33:52,808 INFO : javascript benchmark 18:33:52 INFO - 2015-11-23 18:33:52,810 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3357.9664203357966, 3425.7430692698053, 3432.656734326568, 3466.895993120204, 3496.3356962177186, 5576.525995290395, 5639.774409023639, 5650.773969041238, 5680.431956804317, 5695.857603559908, 5617.522510586605, 5681.261436013312, 5765.279340082489, 5769.769209231626, 5778.364379918216, 5124.231365295217, 5156.767945442455, 5168.173092305232, 5189.169732842746, 5244.239585260135, 4727.881802954919, 5024.974875125619, 5244.816431424901, 5284.180951952453, 5329.946700533003, 3839.462475253467, 5055.090083784918, 5238.842834714965, 5252.080885844981, 5290.55030322423, 5153.3558305211845, 5171.095058364771, 5179.223116532513, 5180.6373553851245, 5183.896322073538, 5041.369828771403, 5128.974205158983, 5155.484451554852, 5165.974170129163, 5167.0, 5655.8586035349035, 5666.5183459406, 5674.829755107354, 5686.004949133885, 5690.800821971253, 6274.623522588659, 6276.5920215186325, 6280.057991301295, 6289.0880822280515, 6297.33877942814, 6081.591288450819, 6111.287884814254, 6130.938690613081, 6225.595336303126, 6294.370562943669, 5821.79623713168, 5891.882162356772, 5893.675847828367, 5897.528197744213, 5925.822225333247, 6216.689165541705, 6268.122462855204, 6271.153394291712, 6282.842928926722, 6297.2128483939505, 5977.731002104829, 6049.98506422382, 6101.176341193971, 6128.693565321805, 6188.845278868064, 6279.96860015697, 6293.244810622663, 6303.117563541108, 6306.8423289417215, 6326.652034138075, 6249.156363890907, 6275.811725648146, 6275.843103922439, 6285.8742825143245, 6288.685565721696, 6039.758409663652, 6185.226846644169, 6203.627782333075, 6207.317195108534, 6272.560920735505, 4165.166966606691, 4963.429205641325, 4973.825916092992, 4986.975065124651, 4987.426445958688, 4460.264056430653, 4465.218586747306, 4479.283314669638, 4493.910121797546, 4497.865064048084, 5728.942710572925, 5736.225609542742, 5742.712864356849, 5769.826905192851, 5777.595568310262, 6099.573029887865, 6160.44555989963, 6165.414285642857, 6178.227721534809, 6184.721687524072, 5628.493435590816, 5719.084946408555, 5733.627314224562, 5735.8566035849435, 5750.511206547407, 5403.108487099585, 5421.945780542223, 5433.1850222466155, 5437.293151890308, 5451.972740136274, 6275.1842260506755, 6299.023651334051, 6347.90478142819, 6352.174217351715, 6363.9045414318825, 5074.746262686777, 6117.296510901126, 6144.631322120687, 6208.9689551551955, 6229.844253893689, 6300.7479700811455, 6351.714172862325, 6364.917963946048, 6387.233531976007, 6400.423961843457, 6193.4425901669065, 6235.438810507076, 6246.46905013079, 6264.404881536247, 6270.216222972129, 6081.057436097412, 6133.426475724657, 6173.104899789595, 6189.566730328834, 6276.74893004293, 6107.206063211932, 6109.938900610937, 6117.1130186123655, 6120.0, 6132.202813634199, 6091.410991024236, 6096.847578810565, 6100.420460056288, 6105.725242364016, 6120.112583675431, 5645.520130788929, 5823.650580965155, 5870.17817505541, 5889.734961926809, 6016.488598469179, 4906.705597664151, 6130.50955923534, 6139.2325959255095, 6175.660338681236, 6191.597546159485, 5986.2517185351835, 6030.48740857032, 6042.549368945911, 6074.574779765374, 6123.571350005457], "unit": "ms", "name": "dojo.html", "value": 5682.9853361148325}, {"lowerIsBetter": false, "replicates": [538.8920143309745, 559.021712003993, 584.9792112763228, 638.9872202555949, 643.3437893348787, 32394.19014524635, 32783.0, 41447.79276103619, 41868.79065604668, 42036.36945445821, 44206.55793442065, 44543.554564454425, 44607.88480287995, 44673.77663111688, 44913.326300105524, 8746.956265218696, 9764.828220613519, 9981.001899810033, 9988.200943924505, 9997.750056248578, 8742.694005709802, 8817.206451419403, 9513.38163019405, 10408.427536485491, 11543.07655387567, 10622.78754424911, 11910.34493102879, 11928.52285908567, 11945.044396448307, 11983.880161198407, 8889.777755556162, 9032.277417806625, 10140.543675534618, 11769.058475321997, 11913.463894124785, 8770.868436973451, 11840.822387664191, 12039.337836418994, 12053.337066461341, 12055.0, 42419.1516169678, 42754.14491710148, 42772.78613606943, 42868.0, 42909.78545107286, 3292.3415316936707, 3299.736021118305, 3447.637998010198, 3563.982180089109, 3786.5266841644793, 28896.0, 29102.126936191948, 29174.70825291741, 29444.852775735984, 29783.553246701318, 2465.987670061656, 2757.310672331917, 2853.1583182961076, 2887.826730396183, 2957.3050333171686, 491475.8835852395, 495950.0, 496134.0, 496963.9999999964, 498792.0, 55299.723501382236, 55724.72137639286, 57152.142717859264, 66246.33753662402, 66595.33404665891, 53232.467675323525, 67041.0, 67791.66104169447, 68116.97824532636, 68197.0, 16112.516624501284, 16131.919340403458, 16291.266892989845, 16306.429274975319, 16317.755233671505, 12250.81623775644, 12320.691982700504, 12330.383480825923, 12334.259944403364, 12352.938235308946, 17054.403095891583, 19810.811351318967, 20098.39704808858, 20339.8983005087, 20410.28564000253, 8991.595378207996, 9132.0, 9132.771680708036, 9140.360174787835, 9179.403338782959, 872.7469033980075, 970.3595626886221, 985.6550207427343, 1020.5662593397776, 1093.8851420600722, 29991.550126748116, 30127.397452050835, 30548.38903221923, 30666.233344166576, 30713.0, 1054.1250761867784, 1124.083871644607, 1151.986252098165, 1160.7968605494004, 1168.5617893290016, 14758.852411475962, 15361.615959600877, 15443.613909652124, 15605.765913511306, 15649.139297338528, 516750.0, 523500.0, 524062.0, 525172.0, 525267.0, 53325.66685832808, 60391.39608603946, 60594.09108863371, 60653.41832270966, 60844.695776520835, 1435.777454494555, 1454.7163303155783, 1466.2595389328321, 1473.8673519383092, 1481.9629509262354, 29404.705952940196, 29983.70016299809, 30245.848770756005, 30572.388552229277, 30755.84622076875, 57108.714456429116, 57309.0, 57461.13808292795, 57528.71235643795, 57605.0, 434607.0, 437997.0, 438618.0, 438876.0, 439776.0, 28851.27871803138, 31433.685663143075, 31718.682813171574, 34071.97784066482, 34201.82899085488, 43753.8701236438, 44961.77519112515, 45296.773516132205, 45724.771376142904, 45951.540484596066, 36694.816525918264, 37042.07394815151, 37943.2411351777, 38055.80972095122, 38275.42586861143, 36969.44545831761, 37626.0, 37864.17894877975, 38116.618833811306, 39352.40971385374, 28242.576361354186, 28582.714172858006, 37766.811165944, 37901.0, 38388.848334550006, 29874.103776886728, 32250.838745806122, 33105.83447082749, 33469.83265083756, 33695.0, 24910.37724056913, 25163.49673006566, 25173.622395663715, 25572.360690982874, 25765.742342576334], "unit": "ms", "name": "ext.html", "value": 20288.62961275827}, {"lowerIsBetter": false, "replicates": [354.1871405125239, 358.65748210459014, 360.12848905648326, 366.4704501994617, 368.6368926607293, 16106.516804495855, 17677.7348339775, 19817.504562385948, 20526.89736551317, 21180.940952952347, 11347.943260283708, 11575.958163765259, 11590.652280431601, 15555.611109722262, 19837.305694300703, 3986.4618276532588, 5257.684538927658, 5398.379186393556, 5411.810586629469, 5412.972935135319, 4083.8774836754947, 4114.382842573608, 4139.006638406776, 5767.711614419283, 5959.851003724898, 5808.332041815182, 5884.588078834483, 5914.9408505915035, 5932.6143800653035, 5932.792352267671, 5931.258592675916, 5949.8215053548465, 5960.0, 5966.97016514919, 5985.940140598582, 5638.697886237333, 5686.943130568683, 5700.515456186229, 5769.365369809318, 5844.853878653025, 13156.407961641653, 13371.86628133716, 13399.732005359936, 20677.276295329742, 26203.0, 2156.439325775294, 2159.805617494433, 2197.3957161780477, 2234.19568955176, 2290.576243394977, 9679.951600242024, 9937.503124843728, 10073.647422340184, 11585.768284634345, 14970.62573435662, 1685.4859267923277, 1710.563806229416, 1722.6210233748554, 1811.0, 1823.7355583440326, 79446.60276698637, 91250.5437472815, 92762.14475710515, 99001.0, 104258.0, 10872.891271087346, 11150.944245278723, 11179.944100279447, 11217.831732524019, 11340.489677964511, 13264.867351326557, 13290.20258784476, 13295.800562991562, 13305.800412993813, 13343.666408339675, 8138.755837324889, 8248.46384984974, 8292.212239837207, 8317.168283171635, 8317.875231871527, 6754.966225168842, 8303.294219991247, 8305.086440491541, 8323.8335233295, 8379.120192379713, 4109.404136400205, 4337.609615134653, 4386.934195986999, 4409.272470042407, 4427.402300689366, 1773.9645207095787, 1789.4899953513186, 1791.2476759760932, 1819.2177363733456, 1823.7355583440326, 793.083987993868, 875.916787905148, 927.1007123090591, 938.5307346326837, 986.5041445143797, 20478.385648430572, 21253.574928501344, 21316.680249796267, 21407.67888481674, 21536.353909382742, 863.7303164348398, 923.8106188231396, 925.8148370325962, 941.9529023548795, 1017.3895662602528, 11078.833817492745, 11192.608258711069, 11282.435878206057, 11307.547698092148, 11430.828537571942, 779664.0, 780453.0, 783168.0, 785145.0, 799229.0, 120318.0, 122178.77821221852, 124567.0, 127175.72824271824, 128844.0, 1128.5316593613559, 1136.772645470909, 1196.3479903452464, 1201.3512703140382, 1226.8650448450662, 19250.94119823423, 19620.088991759352, 19620.215191392756, 19756.50608734793, 19956.002199889655, 50937.0, 51052.48947510626, 51639.0, 51641.0, 51769.0, 70155.29844701488, 70934.0, 71710.64144679243, 73323.0, 75266.0, 9618.0, 9833.4099954003, 10105.797884042131, 10120.342177758423, 10128.088472037258, 11956.820647690465, 16504.25730842091, 20093.49766255855, 20582.27962021322, 20618.484537886674, 11520.596779113026, 12386.009119270617, 15434.0, 15819.0, 16135.19324033817, 15235.619109522351, 15264.618384540476, 15295.77056344178, 15545.37818487293, 15716.842831571996, 11699.590514331958, 16194.190290485192, 16555.91722041382, 16726.414575489798, 16930.83069169342, 9757.756056098655, 9910.554025069034, 9941.30410871261, 9943.950280248553, 9964.651237206954, 13333.5999720028, 14331.6211382196, 15103.924480377527, 15716.0, 15999.280032398337], "unit": "ms", "name": "jquery.html", "value": 9746.52287081277}, {"lowerIsBetter": false, "replicates": [1393.5679939218837, 1598.7042397156526, 1620.659661471091, 1906.9713954290687, 1931.6233334499768, 6708.731650733971, 7771.961140194298, 8052.194780521945, 8457.91542084579, 8481.279091277249, 8327.958360208206, 8418.789530261747, 8446.408751387406, 8464.407491475598, 8475.279601233902, 2109.293386715452, 2158.6330323844904, 2324.1052194905, 2345.44881952741, 2347.5187586544803, 2166.664167054101, 2176.8911554422216, 2176.9020394082304, 2181.8472706910525, 2314.594945884472, 2350.4123969007746, 2353.1175809071597, 2355.6231003039516, 2361.25620429565, 2362.6692263083182, 2272.8295377846725, 2326.0579465316573, 2341.1337805012085, 2341.6487526870937, 2343.2735851885886, 2131.5949969505746, 2176.967345489819, 2188.3325585696357, 2314.36355002374, 2359.7994170495526, 8428.199321064489, 8484.448510846836, 8497.95751021247, 8513.914860851437, 8518.914810851875, 5146.8198613048735, 5207.6354655174155, 5216.400113986902, 5217.713025783581, 5230.163173892159, 3431.673990970854, 3499.212677147647, 3557.875474358411, 3562.786232826039, 3566.1797786509246, 1017.7099526634873, 1039.3192458939407, 1050.4905121016377, 1053.4943227250885, 1064.164630764846, 11365.886341136647, 11374.71563210918, 11379.658610241706, 11383.94308028463, 11412.372319522423, 3947.3684210526176, 4618.1918164321105, 4680.695754775997, 4715.646326525524, 4741.122892264942, 9750.951245243728, 9759.609615615438, 9759.853602195974, 9775.266854985763, 9778.07108324708, 5229.895402092013, 5235.633505654644, 5236.502532259429, 5238.738063096906, 5241.580673546107, 4774.400575807074, 4776.928346074742, 4778.187708089633, 4782.497837727058, 4786.497417771154, 3238.3037646906027, 3748.756330838487, 3847.0574709196426, 3869.8258578364043, 3875.7093218008763, 1243.3339325304137, 1261.9808306709192, 1262.2426544073483, 1269.7714411406037, 1272.204871955028, 1343.7715588350004, 1359.7756370199004, 1378.6553361659585, 1409.5489443378226, 1421.9004669673025, 6570.671466426659, 7815.3356964657505, 7866.803329916798, 7889.2110788920645, 7891.895134681149, 497.49255759126214, 507.11761534928957, 512.6385897941942, 514.0567059445884, 515.5978336897226, 5835.066389377679, 5960.910586341209, 6155.692215389213, 6234.438900498977, 6236.438720515175, 8972.775680608038, 9717.757056073513, 9859.26055545837, 9912.405255684682, 9960.252981026451, 9835.852462213072, 9914.058164474363, 10037.096661300518, 10299.021592948659, 10315.123214526699, 7868.803279917934, 7960.761177164579, 7973.0, 8044.833499142592, 8064.475809072392, 7611.200823913631, 7883.64523596451, 7900.2889739923685, 7903.3677305816545, 7912.248336408032, 9032.232260257952, 9137.543122843965, 9153.954230228806, 9242.537873106183, 9302.581383837609, 9189.540522973959, 9497.097775711296, 9499.620015199314, 9507.239420846177, 9537.856932146162, 7364.668589913574, 7407.037085178892, 7415.8146046349275, 7461.664225109992, 7501.924980750123, 8032.718854840053, 8083.19168083187, 8083.272505474535, 8088.272055515032, 8110.229528194812, 7471.3649339806725, 7519.511231769918, 7568.659410326659, 7687.807804804925, 7716.5370077795515, 7414.221506741715, 7431.442641801995, 7449.8882516761205, 7551.3958883288105, 7565.924340756522, 7586.431017673586, 7617.809554761176, 7628.771136865903, 7635.885461717968, 7644.541327520366, 6865.366898234645, 6891.586504809728, 6920.619365934721, 6927.064846245894, 6990.755323563651, 6994.440444764305, 7012.474064445085, 7029.0, 7031.402330801735, 7048.859022819617], "unit": "ms", "name": "mootools.html", "value": 4689.142616898977}, {"lowerIsBetter": false, "replicates": [323.8251344274091, 328.63849765258203, 329.9554560134381, 333.4115286519293, 339.30273288392374, 6905.447564194865, 6948.339907708772, 7012.824679383011, 7017.228104908469, 7090.0, 5650.378458369586, 6179.0, 6508.893488107022, 7011.158660960692, 7017.578945263288, 4834.637402194832, 5109.310243117187, 5115.7186354750565, 5117.078925793356, 5126.436092029874, 5002.074616196015, 5025.271335656327, 5036.118679231138, 5044.798208071673, 5051.1413059779925, 4960.131976904046, 4961.875953101166, 4978.850634480971, 4978.850634480971, 4983.0, 4726.90546189076, 4833.299171620095, 4841.419029716421, 4847.272909063633, 4968.428630707461, 4107.075907920724, 4244.320908654631, 4250.4899412070445, 4260.595243451898, 4265.210935976854, 6618.205815302146, 6639.668016599199, 6656.36764507371, 6678.131842860445, 6688.598684078971, 5660.094384898396, 5798.449147330997, 5805.129230615399, 5928.584999050068, 5934.347221805598, 7396.927445520423, 7441.776746697607, 7450.925490745078, 7460.36586890115, 7462.664180111908, 3974.880753577397, 4778.498257682928, 4784.40194975628, 4788.0663270662235, 4789.114013907403, 6918.757843475504, 7490.550566966, 7616.276453736886, 7851.0, 7934.841303173962, 4804.351412559329, 4836.903261934812, 4839.491853355417, 4852.733099679481, 4865.416150061945, 4910.0, 4912.992836468516, 4919.237518184687, 4944.653874228841, 4949.0, 5332.520073193431, 5796.507296879812, 5836.212111364996, 5892.469677729025, 5974.4324289192455, 7475.0, 7510.962445187739, 7521.435892308099, 7528.849423011509, 7560.319571238615, 1702.3701230544777, 1718.802337731152, 1722.6210233748554, 1723.086764015074, 1724.6809340272089, 968.3705591365668, 1059.4490864750442, 1079.838024296362, 1092.1754075672975, 1099.2854644481151, 5751.849630074002, 6700.899486507707, 6940.444764418935, 6948.791536253921, 6993.230744618088, 7784.610769461504, 7804.531728096333, 7816.140224575406, 7818.843623127619, 7828.491148075357, 6736.562123461959, 6947.0, 6963.9205923082, 6967.407770339477, 6988.510804243654, 4118.094019315746, 4158.958410415918, 4160.0, 4169.249535083714, 4173.8956526087095, 6508.772192973223, 7148.856183010694, 7557.697692092254, 8083.878741818759, 8094.069182044022, 7401.148867880155, 7689.4232932530285, 8020.358371330396, 8029.919700802917, 8048.114707382183, 7658.578778167254, 8038.517688938682, 8126.431149819456, 8164.959175204086, 8167.142450042778, 6889.379955804002, 7545.359124306448, 7854.253845884632, 7883.172266911892, 7889.408294377829, 7728.149903510722, 7734.651940662571, 7735.845283094419, 7738.264864837832, 7750.728724494616, 8131.983502062242, 8142.267195952392, 8146.75559733209, 8151.918480815116, 8154.103048664523, 4670.50543825986, 4862.5380588844, 4865.099956508057, 4865.878353041202, 4903.877403064952, 6396.680165991775, 7825.060992680687, 7829.843403132019, 7830.608469576385, 7840.490368126054, 6082.787102451393, 7513.849723005618, 7516.060492438445, 7525.473216874766, 7540.886886696813, 7296.233895440902, 7497.512661676974, 7573.431992600466, 7586.582737949245, 7593.544387336778, 6016.789412370546, 7379.667914943733, 7384.409247260099, 7394.260573942778, 7403.407727381904, 6137.330261336333, 7338.889916651147, 7560.206178351194, 7581.3176814087, 7595.088589369311, 7390.815229619303, 7395.889061664187, 7397.223291554309, 7398.3341499265325, 7399.445041621792], "unit": "ms", "name": "prototype.html", "value": 5369.208461190074}, {"lowerIsBetter": false, "replicates": [603.4056454392422, 677.292229620047, 789.7196495244189, 801.9278264956152, 860.8278344331131, 14783.556493305192, 15166.090034597919, 15169.241537923115, 15183.089014659128, 15195.620109497268, 13995.230262335568, 14142.151470911753, 14168.220747858864, 14191.574252772434, 14208.218547979857, 734.5482528245144, 743.9330460258576, 745.0947099274381, 923.3767207135173, 925.5048549026272, 929.1219797291562, 929.1591110045385, 943.217129782279, 943.5942544705774, 945.0927109974433, 971.4899677669209, 987.8765154355706, 988.0317289056728, 988.77258230607, 990.7869807991281, 771.3713323641242, 779.7855589712817, 781.562325097947, 967.9758006049834, 977.6431602465085, 727.5525551785614, 729.4711334282656, 731.0934441292811, 736.3851184261148, 737.4837613670452, 14899.478518251917, 15018.549443516711, 15051.096934183985, 15072.698546029127, 15081.245937703181, 12382.31897245651, 12473.31396773177, 12602.68493287666, 12654.55709050188, 12800.0, 10023.849642255373, 10131.594736210543, 10290.43402612856, 10376.377417354983, 10842.241043126984, 3520.718342532617, 3552.8578856845697, 3561.1809283864595, 3574.0, 3576.8748093816594, 77241.22758772396, 77717.22282777156, 79354.6032269835, 79510.60244698798, 79621.80567291495, 9415.613382899628, 9495.430274183433, 9546.856797148048, 9548.618055277711, 9615.375000625078, 21491.57016859654, 21813.67279490809, 21827.345179644635, 21838.126474940826, 21848.344549663536, 7257.201707812136, 7387.741429050065, 7459.216782237787, 7549.245075492517, 7644.655990480442, 3942.8619998299923, 3954.5649978502342, 3965.4250133730447, 3975.3241948868763, 4007.0783719744622, 3324.9027820819074, 3366.1752870546875, 3556.608773034964, 3605.0446631642635, 3608.3144202601948, 627.3162253144004, 634.4638780230698, 641.7785863876956, 649.8505343770959, 655.4494224851148, 507.64464874587196, 527.881226723984, 533.8745394832212, 541.3287523470867, 543.8450041738085, 8642.870356944652, 8676.913230867736, 8806.559672016501, 8857.202851743374, 8914.73255802327, 489.380933119604, 497.35344052731307, 547.15464607182, 571.4599155276572, 626.9905951410733, 4049.271131196354, 4205.179989902001, 4215.199112168678, 4237.449131612871, 4276.31578947367, 103136.0, 103399.44900826494, 103482.48258758658, 105285.47357263164, 105609.47195263974, 83926.580367099, 84286.57856710677, 84816.0, 86012.5699371499, 86301.27397452015, 76112.61943690246, 77351.83972240421, 77460.22539774643, 77803.0, 78447.8232826508, 8370.079291278076, 8416.158384161536, 8562.828743424972, 8649.005364382925, 8664.653413863627, 53495.0, 54157.18764218619, 55329.0, 56951.14573281321, 60451.0, 75386.869196961, 75652.0, 76132.23867761252, 76800.23199767931, 77775.0, 11784.882151178379, 11863.347515886364, 11970.0, 12273.509059637514, 12343.567975120828, 12110.576129835414, 12245.387730613256, 12248.203866748632, 12836.55072072473, 13139.277339746024, 11587.478563464832, 11950.402479876146, 12292.754144916873, 12404.441800119197, 12489.750204995667, 11633.59282425111, 11703.707407314885, 12385.566505172275, 12424.503019879465, 12664.303463309237, 11454.370009649216, 11669.35818529989, 11757.588484403004, 12095.516179352728, 12442.626721198378, 9700.0, 9786.923438421625, 9959.651412200537, 10036.548355324174, 10462.163026957673, 9088.909110908806, 9775.853362199716, 10402.47987600632, 10409.42748148859, 10409.583616655249], "unit": "ms", "name": "yui.html", "value": 7083.109858766418}], "name": "dromaeo_css", "value": 7649.9254209217825}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 108.0, 101.0, 115.0, 157.0, 161.0, 100.0, 108.0, 108.0, 103.0], "unit": "ms", "name": "ai-astar", "value": 117.0}, {"lowerIsBetter": true, "replicates": [151.0, 119.0, 141.0, 156.0, 119.0, 139.0, 133.0, 119.0, 155.0, 118.0], "unit": "ms", "name": "audio-beat-detection", "value": 135.0}, {"lowerIsBetter": true, "replicates": [185.0, 182.0, 181.0, 180.0, 185.0, 207.0, 264.0, 185.0, 180.0, 179.0], "unit": "ms", "name": "audio-dft", "value": 192.8}, {"lowerIsBetter": true, "replicates": [62.0, 69.0, 76.0, 74.0, 54.0, 57.0, 57.0, 72.0, 73.0, 55.0], "unit": "ms", "name": "audio-fft", "value": 64.9}, {"lowerIsBetter": true, "replicates": [82.0, 80.0, 119.0, 76.0, 75.0, 74.0, 113.0, 76.0, 74.0, 110.0], "unit": "ms", "name": "audio-oscillator", "value": 87.9}, {"lowerIsBetter": true, "replicates": [84.0, 85.0, 85.0, 86.0, 86.0, 73.0, 115.0, 132.0, 110.0, 85.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 94.1}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 92.0, 93.0, 92.0, 95.0, 93.0, 92.0, 92.0, 92.0], "unit": "ms", "name": "imaging-darkroom", "value": 92.6}, {"lowerIsBetter": true, "replicates": [86.0, 87.0, 85.0, 83.0, 86.0, 85.0, 86.0, 85.0, 91.0, 136.0], "unit": "ms", "name": "imaging-desaturate", "value": 91.0}, {"lowerIsBetter": true, "replicates": [52.0, 54.0, 52.0, 53.0, 54.0, 54.0, 55.0, 62.0, 53.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 54.3}, {"lowerIsBetter": true, "replicates": [62.0, 64.0, 63.0, 62.0, 63.0, 66.0, 65.0, 65.0, 66.0, 67.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.3}, {"lowerIsBetter": true, "replicates": [83.0, 83.0, 82.0, 81.0, 82.0, 80.0, 82.0, 83.0, 83.0, 83.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 82.2}, {"lowerIsBetter": true, "replicates": [120.0, 134.0, 125.0, 114.0, 123.0, 118.0, 121.0, 133.0, 140.0, 129.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 125.7}, {"lowerIsBetter": true, "replicates": [139.0, 120.0, 131.0, 135.0, 136.0, 137.0, 140.0, 139.0, 139.0, 136.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 135.2}, {"lowerIsBetter": true, "replicates": [65.0, 65.0, 64.0, 65.0, 64.0, 65.0, 64.0, 66.0, 65.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.7}], "name": "kraken", "value": 1401.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [172.74140611504578], "unit": "score", "name": "Richards", "value": 204.36327800000004}, {"lowerIsBetter": false, "replicates": [164.0150893882237], "unit": "score", "name": "DeltaBlue", "value": 403.121446}, {"lowerIsBetter": false, "replicates": [278.39643652561244], "unit": "score", "name": "Encrypt", "value": 956.1221520000003}, {"lowerIsBetter": false, "replicates": [4622.119815668203], "unit": "score", "name": "Decrypt", "value": 57.58851146560316}, {"lowerIsBetter": false, "replicates": [1733.102253032929], "unit": "score", "name": "RayTrace", "value": 426.97365299999996}, {"lowerIsBetter": false, "replicates": [788.6435331230284], "unit": "score", "name": "Earley", "value": 845.075084}, {"lowerIsBetter": false, "replicates": [13302.631578947368], "unit": "score", "name": "Boyer", "value": 50.100087042532174}, {"lowerIsBetter": false, "replicates": [27837.837837837837], "unit": "score", "name": "RegExp", "value": 32.724703883495124}, {"lowerIsBetter": false, "replicates": [728.862973760933], "unit": "score", "name": "Splay", "value": 111.80565200000001}, {"lowerIsBetter": false, "replicates": [4596.3302752293575], "unit": "score", "name": "NavierStokes", "value": 322.86626746506977}], "name": "v8_7", "value": 19971.077223002376}]} 18:33:52 INFO - RETURN: dromaeo_css: 7649.93 18:33:52 INFO - RETURN: kraken: 1401.70 18:33:52 INFO - RETURN: v8_7: 19971.08 18:33:52 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,59]]", "result": "19971.08"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,59]]", "result": "1401.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,59]]", "result": "7649.93"}}} 18:33:52 INFO - Return code: 0 18:33:52 INFO - # TBPL SUCCESS # 18:33:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:33:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:33:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:33:52 INFO - 2015-11-23 18:33:52.911 screenresolution[1959:13988] starting screenresolution argv=screenresolution get 18:33:52 INFO - 2015-11-23 18:33:52.915 screenresolution[1959:13988] Display 0: 1600x1200x32@60 18:33:52 INFO - 2015-11-23 18:33:52.920 screenresolution[1960:13991] starting screenresolution argv=screenresolution list 18:33:52 INFO - Available Modes on Display 0 18:33:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:33:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:33:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:33:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:33:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:33:53 INFO - Intel Iris: 18:33:53 INFO - Chipset Model: Intel Iris 18:33:53 INFO - Type: GPU 18:33:53 INFO - Bus: Built-In 18:33:53 INFO - VRAM (Dynamic, Max): 1536 MB 18:33:53 INFO - Vendor: Intel (0x8086) 18:33:53 INFO - Device ID: 0x0a2e 18:33:53 INFO - Revision ID: 0x0009 18:33:53 INFO - Displays: 18:33:53 INFO - Display: 18:33:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:33:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:33:53 INFO - Display Serial Number: 1600x1200 60 18:33:53 INFO - Main Display: Yes 18:33:53 INFO - Mirror: Off 18:33:53 INFO - Online: Yes 18:33:53 INFO - Rotation: Supported 18:33:53 INFO - Return code: 0 18:33:53 INFO - Running post-action listener: _resource_record_post_action 18:33:53 INFO - Running post-run listener: _resource_record_post_run 18:33:54 INFO - Total resource usage - Wall time: 1237s; CPU: 53.0%; Read bytes: 3072000; Write bytes: 687993344; Read time: 47; Write time: 1975 18:33:54 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 336908800; Write bytes: 336250368; Read time: 26340; Write time: 585 18:33:54 INFO - run-tests - Wall time: 1207s; CPU: 53.0%; Read bytes: 3072000; Write bytes: 341310464; Read time: 47; Write time: 1356 18:33:54 INFO - Running post-run listener: _upload_blobber_files 18:33:54 INFO - Blob upload gear active. 18:33:54 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:33:54 INFO - Copying logs to upload dir... 18:33:54 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1277.918270 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2015-11-23 18:33:54.265372) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:33:54.269517) ========= 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.HGpJLiTlre/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.AWCw9flAeN/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009696 build_url: 'https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/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-23 18:33:54.293144) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:33:54.293489) ========= 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.HGpJLiTlre/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.AWCw9flAeN/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.005178 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:33:54.342462) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 18:33:54.342775) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 18:33:54.343155) ========= ========= Total master_lag: 0.20 =========