builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0004 starttime: 1448331214.04 results: success (0) buildid: 20151123162931 builduid: 81a1f98460e14c89a33def60e16739b1 revision: 19d89caa664dd9309c796929225ee409df5d1ee4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:34.044556) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:34.045011) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:34.045363) ========= 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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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.005376 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:34.067579) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:34.067890) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:34.083742) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:34.084133) ========= 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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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.006193 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:34.132822) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:34.133355) ========= 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:13:34.133917) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:34.134197) ========= 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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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:13:34-- 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.18M=0.001s 2015-11-23 18:13:34 (8.18 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.190721 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:34.339395) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:34.339740) ========= 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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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.026554 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:34.388402) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 18:13:34.388740) ========= 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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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:13:34,463 truncating revision to first 12 chars 2015-11-23 18:13:34,463 Setting DEBUG logging. 2015-11-23 18:13:34,463 attempt 1/10 2015-11-23 18:13:34,463 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:13:35,526 unpacking tar archive at: mozilla-central-19d89caa664d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.306818 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 18:13:35.731620) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:35.731986) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:35.964211) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 18:13:35.964579) ========= 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:13:35.964994) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2015-11-23 18:13:35.965275) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ym4bkGDOC2/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.amaOLzAC6b/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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:13:36 INFO - MultiFileLogger online at 20151123 18:13:36 in /builds/slave/test 18:13:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:13:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:13:36 INFO - {'append_to_log': False, 18:13:36 INFO - 'base_work_dir': '/builds/slave/test', 18:13:36 INFO - 'blob_upload_branch': 'Firefox', 18:13:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:13:36 INFO - 'branch': 'Firefox', 18:13:36 INFO - 'buildbot_json_path': 'buildprops.json', 18:13:36 INFO - 'config_files': ('talos/mac_config.py',), 18:13:36 INFO - 'default_actions': ('clobber', 18:13:36 INFO - 'read-buildbot-config', 18:13:36 INFO - 'download-and-extract', 18:13:36 INFO - 'populate-webroot', 18:13:36 INFO - 'create-virtualenv', 18:13:36 INFO - 'install', 18:13:36 INFO - 'run-tests'), 18:13:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:13:36 INFO - 'download_minidump_stackwalk': True, 18:13:36 INFO - 'download_symbols': 'ondemand', 18:13:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:13:36 INFO - 'tooltool.py': '/tools/tooltool.py', 18:13:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:13:36 INFO - '/tools/misc-python/virtualenv.py')}, 18:13:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:13:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:13:36 INFO - 'installer_path': 'installer.exe', 18:13:36 INFO - 'log_level': 'info', 18:13:36 INFO - 'log_name': 'talos', 18:13:36 INFO - 'log_to_console': True, 18:13:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:13:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:13:36 INFO - 'opt_config_files': (), 18:13:36 INFO - 'pip_index': False, 18:13:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:13:36 INFO - 'cmd': ('bash', 18:13:36 INFO - '-c', 18:13:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:13:36 INFO - 'enabled': True, 18:13:36 INFO - 'halt_on_failure': False, 18:13:36 INFO - 'name': 'check_screen_resolution'},), 18:13:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:13:36 INFO - 'cmd': ('bash', 18:13:36 INFO - '-c', 18:13:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:13:36 INFO - 'enabled': True, 18:13:36 INFO - 'halt_on_failure': False, 18:13:36 INFO - 'name': 'check_screen_resolution'},), 18:13:36 INFO - 'run_cmd_checks_enabled': True, 18:13:36 INFO - 'sps_profile': False, 18:13:36 INFO - 'sps_profile_interval': 0, 18:13:36 INFO - 'suite': 'dromaeojs-e10s', 18:13:36 INFO - 'system_bits': '64', 18:13:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:13:36 INFO - 'title': 't-yosemite-r7-0004', 18:13:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:13:36 INFO - 'use_talos_json': True, 18:13:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:13:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:13:36 INFO - 'work_dir': 'build'} 18:13:36 INFO - ##### 18:13:36 INFO - ##### Running clobber step. 18:13:36 INFO - ##### 18:13:36 INFO - Running pre-action listener: _resource_record_pre_action 18:13:36 INFO - Running main action method: clobber 18:13:36 INFO - rmtree: /builds/slave/test/build 18:13:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:13:37 INFO - Running post-action listener: _resource_record_post_action 18:13:37 INFO - ##### 18:13:37 INFO - ##### Running read-buildbot-config step. 18:13:37 INFO - ##### 18:13:37 INFO - Running pre-action listener: _resource_record_pre_action 18:13:37 INFO - Running main action method: read_buildbot_config 18:13:37 INFO - Using buildbot properties: 18:13:37 INFO - { 18:13:37 INFO - "properties": { 18:13:37 INFO - "buildnumber": 13, 18:13:37 INFO - "product": "firefox", 18:13:37 INFO - "script_repo_revision": "production", 18:13:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:13:37 INFO - "repository": "", 18:13:37 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:13:37 INFO - "buildid": "20151123162931", 18:13:37 INFO - "slavename": "t-yosemite-r7-0004", 18:13:37 INFO - "pgo_build": "False", 18:13:37 INFO - "basedir": "/builds/slave/test", 18:13:37 INFO - "project": "", 18:13:37 INFO - "platform": "yosemite_r7", 18:13:37 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:13:37 INFO - "slavebuilddir": "test", 18:13:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:13:37 INFO - "branch": "mozilla-central", 18:13:37 INFO - "repo_path": "mozilla-central", 18:13:37 INFO - "stage_platform": "macosx64", 18:13:37 INFO - "builduid": "81a1f98460e14c89a33def60e16739b1", 18:13:37 INFO - "revision": "19d89caa664dd9309c796929225ee409df5d1ee4" 18:13:37 INFO - }, 18:13:37 INFO - "sourcestamp": { 18:13:37 INFO - "repository": "", 18:13:37 INFO - "hasPatch": false, 18:13:37 INFO - "project": "", 18:13:37 INFO - "branch": "mozilla-central-macosx64-talos", 18:13:37 INFO - "changes": [ 18:13:37 INFO - { 18:13:37 INFO - "category": null, 18:13:37 INFO - "files": [ 18:13:37 INFO - { 18:13:37 INFO - "url": null, 18:13:37 INFO - "name": "https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 18:13:37 INFO - } 18:13:37 INFO - ], 18:13:37 INFO - "repository": "", 18:13:37 INFO - "rev": "19d89caa664dd9309c796929225ee409df5d1ee4", 18:13:37 INFO - "who": "kwierso@gmail.com", 18:13:37 INFO - "when": 1448330960, 18:13:37 INFO - "number": 6698428, 18:13:37 INFO - "comments": "Merge b2ginbound to central, a=merge", 18:13:37 INFO - "project": "", 18:13:37 INFO - "at": "Mon 23 Nov 2015 18:09:20", 18:13:37 INFO - "branch": "mozilla-central-macosx64-talos", 18:13:37 INFO - "revlink": "", 18:13:37 INFO - "properties": [ 18:13:37 INFO - [ 18:13:37 INFO - "buildid", 18:13:37 INFO - "20151123162931", 18:13:37 INFO - "Change" 18:13:37 INFO - ], 18:13:37 INFO - [ 18:13:37 INFO - "builduid", 18:13:37 INFO - "81a1f98460e14c89a33def60e16739b1", 18:13:37 INFO - "Change" 18:13:37 INFO - ], 18:13:37 INFO - [ 18:13:37 INFO - "pgo_build", 18:13:37 INFO - "False", 18:13:37 INFO - "Change" 18:13:37 INFO - ] 18:13:37 INFO - ], 18:13:37 INFO - "revision": "19d89caa664dd9309c796929225ee409df5d1ee4" 18:13:37 INFO - } 18:13:37 INFO - ], 18:13:37 INFO - "revision": "19d89caa664dd9309c796929225ee409df5d1ee4" 18:13:37 INFO - } 18:13:37 INFO - } 18:13:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 18:13:37 INFO - Running post-action listener: _resource_record_post_action 18:13:37 INFO - ##### 18:13:37 INFO - ##### Running download-and-extract step. 18:13:37 INFO - ##### 18:13:37 INFO - Running pre-action listener: _resource_record_pre_action 18:13:37 INFO - Running main action method: download_and_extract 18:13:37 INFO - mkdir: /builds/slave/test/build/tests 18:13: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:13:37 INFO - https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:13:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/test_packages.json 18:13:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/test_packages.json 18:13: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:13: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:13:39 INFO - Downloaded 1149 bytes. 18:13:39 INFO - Reading from file /builds/slave/test/build/test_packages.json 18:13:39 INFO - Using the following test package requirements: 18:13:39 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 18:13:39 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:13:39 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 18:13:39 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:13:39 INFO - u'jsshell-mac.zip'], 18:13:39 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:13:39 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 18:13:39 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 18:13:39 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:13:39 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 18:13:39 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:13:39 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 18:13:39 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:13:39 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 18:13:39 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 18:13:39 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 18:13:39 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 18:13:39 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:13:39 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:39 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:13:39 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:13:39 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:13:39 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:13:39 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:13:40 INFO - Downloaded 17527182 bytes. 18:13:40 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:13:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 18:13:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 18:13:43 INFO - Return code: 0 18:13:43 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:13:43 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:43 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:13:43 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:13:43 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:13:43 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:13:43 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:13:44 INFO - Downloaded 17527182 bytes. 18:13:44 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:13:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 18:13:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 18:13:47 INFO - Return code: 0 18:13:47 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:47 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:13:47 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:13:47 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:13:47 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:13:47 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:13:48 INFO - Downloaded 11130997 bytes. 18:13:48 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:13:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 18:13:48 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 18:13:48 INFO - Return code: 0 18:13: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:13: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:13: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:13: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:13: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:13: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:13:51 INFO - Downloaded 109896796 bytes. 18:13: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:13:51 INFO - mkdir: /builds/slave/test/properties 18:13:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:13:51 INFO - Writing to file /builds/slave/test/properties/build_url 18:13:51 INFO - Contents: 18:13:51 INFO - build_url:https://queue.taskcluster.net/v1/task/zt1s86WZSEW9A17esriu8g/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 18:13:51 INFO - Running post-action listener: _resource_record_post_action 18:13:51 INFO - Running post-action listener: set_extra_try_arguments 18:13:51 INFO - ##### 18:13:51 INFO - ##### Running populate-webroot step. 18:13:51 INFO - ##### 18:13:51 INFO - Running pre-action listener: _resource_record_pre_action 18:13:51 INFO - Running main action method: populate_webroot 18:13:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:13:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:13:51 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:13:51 INFO - '../fennec_ids.txt'], 18:13:51 INFO - 'tests': ['tcheck2']}, 18:13:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:13:51 INFO - '--tppagecycles', 18:13:51 INFO - '7'], 18:13:51 INFO - 'tests': ['tsvgm']}}, 18:13:51 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:13:51 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:13:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:13:51 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:13:51 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:13:51 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:13:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:13:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:51 INFO - 'talos_options': ['--e10s'], 18:13:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:13:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:51 INFO - 'tests': ['damp', 'tps']}, 18:13:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:51 INFO - 'talos_options': ['--e10s'], 18:13:51 INFO - 'tests': ['damp', 'tps']}, 18:13:51 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:13:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:13:51 INFO - 'other': {'tests': ['a11yr', 18:13:51 INFO - 'ts_paint', 18:13:51 INFO - 'tpaint', 18:13:51 INFO - 'sessionrestore', 18:13:51 INFO - 'sessionrestore_no_auto_restore']}, 18:13:51 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:13:51 INFO - 'tests': ['a11yr', 18:13:51 INFO - 'ts_paint', 18:13:51 INFO - 'tpaint', 18:13:51 INFO - 'sessionrestore', 18:13:51 INFO - 'sessionrestore_no_auto_restore']}, 18:13:51 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:13:51 INFO - 'tests': ['a11yr', 18:13:51 INFO - 'ts_paint', 18:13:51 INFO - 'tpaint', 18:13:51 INFO - 'sessionrestore', 18:13:51 INFO - 'sessionrestore_no_auto_restore']}, 18:13:51 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:13:51 INFO - 'tests': ['a11yr', 18:13:51 INFO - 'ts_paint', 18:13:51 INFO - 'tpaint', 18:13:51 INFO - 'sessionrestore', 18:13:51 INFO - 'sessionrestore_no_auto_restore']}, 18:13:51 INFO - 'other_l64': {'tests': ['a11yr', 18:13:51 INFO - 'ts_paint', 18:13:51 INFO - 'tpaint', 18:13:51 INFO - 'sessionrestore', 18:13:51 INFO - 'sessionrestore_no_auto_restore']}, 18:13:51 INFO - 'other_nol64': {'tests': ['a11yr', 18:13:51 INFO - 'ts_paint', 18:13:51 INFO - 'tpaint', 18:13:51 INFO - 'sessionrestore', 18:13:51 INFO - 'sessionrestore_no_auto_restore']}, 18:13:51 INFO - 'svgr': {'tests': ['tsvgx', 18:13:51 INFO - 'tsvgr_opacity', 18:13:51 INFO - 'tart', 18:13:51 INFO - 'tscrollx', 18:13:51 INFO - 'cart']}, 18:13:51 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:13:51 INFO - 'tests': ['tsvgx', 18:13:51 INFO - 'tsvgr_opacity', 18:13:51 INFO - 'tart', 18:13:51 INFO - 'tscrollx', 18:13:51 INFO - 'cart']}, 18:13:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:51 INFO - 'tests': ['tp5o']}, 18:13:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:51 INFO - 'talos_options': ['--e10s'], 18:13:51 INFO - 'tests': ['tp5o']}, 18:13:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:51 INFO - 'talos_options': ['--xperf_path', 18:13:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:13:51 INFO - 'tests': ['tp5n']}, 18:13:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:51 INFO - 'talos_options': ['--e10s', 18:13:51 INFO - '--xperf_path', 18:13:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:13:51 INFO - 'tests': ['tp5n']}}, 18:13:51 INFO - 'talos.zip': {'path': '', 18:13:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:13:51 INFO - Running post-action listener: _resource_record_post_action 18:13:51 INFO - ##### 18:13:51 INFO - ##### Running create-virtualenv step. 18:13:51 INFO - ##### 18:13:51 INFO - Running pre-action listener: _resource_record_pre_action 18:13:51 INFO - Running main action method: create_virtualenv 18:13:51 INFO - Creating virtualenv /builds/slave/test/build/venv 18:13: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:13:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:13:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:13:52 INFO - Using real prefix '/tools/python27' 18:13:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:13:52 INFO - Installing distribute.............................................................................................................................................................................................done. 18:13:55 INFO - Installing pip.................done. 18:13:55 INFO - Return code: 0 18:13:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:13: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:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13: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:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b16d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14a228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d857b8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0919f0>, '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 0x10adf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0848c8>, '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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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: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', 'pip>=1.5'] in /builds/slave/test/build 18:13: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 pip>=1.5 18:13:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ym4bkGDOC2/Render', 18:13:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:55 INFO - 'HOME': '/Users/cltbld', 18:13:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:55 INFO - 'LOGNAME': 'cltbld', 18:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:55 INFO - 'MOZ_NO_REMOTE': '1', 18:13:55 INFO - 'NO_EM_RESTART': '1', 18:13:55 INFO - 'PAGER': '/bin/cat', 18:13:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:55 INFO - 'PWD': '/builds/slave/test', 18:13:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:55 INFO - 'SHELL': '/bin/bash', 18:13:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amaOLzAC6b/Listeners', 18:13:55 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 18:13:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:55 INFO - 'USER': 'cltbld', 18:13:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:55 INFO - 'XPC_FLAGS': '0x0', 18:13:55 INFO - 'XPC_SERVICE_NAME': '0', 18:13:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:56 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:13:56 INFO - Cleaning up... 18:13:56 INFO - Return code: 0 18:13:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:13:56 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:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:56 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:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:56 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 0x10b16d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14a228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d857b8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0919f0>, '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 0x10adf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0848c8>, '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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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:56 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:13:56 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:13:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ym4bkGDOC2/Render', 18:13:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:56 INFO - 'HOME': '/Users/cltbld', 18:13:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:56 INFO - 'LOGNAME': 'cltbld', 18:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:56 INFO - 'MOZ_NO_REMOTE': '1', 18:13:56 INFO - 'NO_EM_RESTART': '1', 18:13:56 INFO - 'PAGER': '/bin/cat', 18:13:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:56 INFO - 'PWD': '/builds/slave/test', 18:13:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:56 INFO - 'SHELL': '/bin/bash', 18:13:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amaOLzAC6b/Listeners', 18:13:56 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 18:13:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:56 INFO - 'USER': 'cltbld', 18:13:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:56 INFO - 'XPC_FLAGS': '0x0', 18:13:56 INFO - 'XPC_SERVICE_NAME': '0', 18:13:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:56 INFO - Downloading/unpacking psutil>=0.7.1 18:13:56 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:56 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:56 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:56 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:56 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:56 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:58 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 18:13:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:13:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:13:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:13:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:13:58 INFO - Installing collected packages: psutil 18:13:58 INFO - Running setup.py install for psutil 18:13:59 INFO - building 'psutil._psutil_osx' extension 18:13:59 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:13:59 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:13:59 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:13:59 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:13:59 INFO - building 'psutil._psutil_posix' extension 18:13:59 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:13:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:13:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:13:59 INFO - ^ 18:14:00 INFO - 1 warning generated. 18:14:00 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:14:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:14:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:14:00 INFO - Successfully installed psutil 18:14:00 INFO - Cleaning up... 18:14:00 INFO - Return code: 0 18:14:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:14:00 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:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:00 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:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:00 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 0x10b16d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14a228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d857b8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0919f0>, '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 0x10adf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0848c8>, '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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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:14:00 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:14:00 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:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ym4bkGDOC2/Render', 18:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:00 INFO - 'HOME': '/Users/cltbld', 18:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:00 INFO - 'LOGNAME': 'cltbld', 18:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:00 INFO - 'MOZ_NO_REMOTE': '1', 18:14:00 INFO - 'NO_EM_RESTART': '1', 18:14:00 INFO - 'PAGER': '/bin/cat', 18:14:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:00 INFO - 'PWD': '/builds/slave/test', 18:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:00 INFO - 'SHELL': '/bin/bash', 18:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amaOLzAC6b/Listeners', 18:14:00 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 18:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:00 INFO - 'USER': 'cltbld', 18:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:00 INFO - 'XPC_FLAGS': '0x0', 18:14:00 INFO - 'XPC_SERVICE_NAME': '0', 18:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:14:00 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:14:00 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:14:00 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:14:00 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:14:00 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:14:00 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:14:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:14:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:14:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:14:02 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:14:02 INFO - Installing collected packages: mozsystemmonitor 18:14:02 INFO - Running setup.py install for mozsystemmonitor 18:14:02 INFO - Successfully installed mozsystemmonitor 18:14:02 INFO - Cleaning up... 18:14:02 INFO - Return code: 0 18:14:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b16d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14a228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d857b8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0919f0>, '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 0x10adf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0848c8>, '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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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:14:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:14:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:14:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ym4bkGDOC2/Render', 18:14:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:02 INFO - 'HOME': '/Users/cltbld', 18:14:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:02 INFO - 'LOGNAME': 'cltbld', 18:14:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:02 INFO - 'MOZ_NO_REMOTE': '1', 18:14:02 INFO - 'NO_EM_RESTART': '1', 18:14:02 INFO - 'PAGER': '/bin/cat', 18:14:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:02 INFO - 'PWD': '/builds/slave/test', 18:14:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:02 INFO - 'SHELL': '/bin/bash', 18:14:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amaOLzAC6b/Listeners', 18:14:02 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 18:14:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:02 INFO - 'USER': 'cltbld', 18:14:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:02 INFO - 'XPC_FLAGS': '0x0', 18:14:02 INFO - 'XPC_SERVICE_NAME': '0', 18:14:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:03 INFO - Downloading/unpacking blobuploader==1.2.4 18:14: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:14: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:14: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:14: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:14: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:14: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:14:05 INFO - Downloading blobuploader-1.2.4.tar.gz 18:14:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:14:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:14:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:14:05 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:14:05 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:14:05 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:14:05 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:14:05 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:14:05 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:14:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:14:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:14:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:14:05 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:14:05 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:14:05 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:14:05 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:14:05 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:14:05 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:14:06 INFO - Downloading docopt-0.6.1.tar.gz 18:14:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:14:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:14:06 INFO - Installing collected packages: blobuploader, requests, docopt 18:14:06 INFO - Running setup.py install for blobuploader 18:14:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:14:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:14:06 INFO - Running setup.py install for requests 18:14:07 INFO - Running setup.py install for docopt 18:14:07 INFO - Successfully installed blobuploader requests docopt 18:14:07 INFO - Cleaning up... 18:14:07 INFO - Return code: 0 18:14:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:07 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:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:07 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:14:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:07 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 0x10b16d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14a228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d857b8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0919f0>, '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 0x10adf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0848c8>, '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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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:14:07 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:14:07 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:14:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ym4bkGDOC2/Render', 18:14:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:07 INFO - 'HOME': '/Users/cltbld', 18:14:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:07 INFO - 'LOGNAME': 'cltbld', 18:14:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:07 INFO - 'MOZ_NO_REMOTE': '1', 18:14:07 INFO - 'NO_EM_RESTART': '1', 18:14:07 INFO - 'PAGER': '/bin/cat', 18:14:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:07 INFO - 'PWD': '/builds/slave/test', 18:14:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:07 INFO - 'SHELL': '/bin/bash', 18:14:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amaOLzAC6b/Listeners', 18:14:07 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 18:14:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:07 INFO - 'USER': 'cltbld', 18:14:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:07 INFO - 'XPC_FLAGS': '0x0', 18:14:07 INFO - 'XPC_SERVICE_NAME': '0', 18:14:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:14:07 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-Zc7f9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:14:07 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-9_3hVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:14:07 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-kyecg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:07 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-8mOZCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:07 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-_UUi5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:08 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-19kOWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:08 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-cc7IBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:08 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-tDYojG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:08 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-icfq5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:08 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-uyE4wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:08 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-620E5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:08 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-CAZmTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:08 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-Kb8TPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:08 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-qE6vhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:09 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-6Wc1nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:09 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-_aJyiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:09 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-EeyEg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:14:09 INFO - Running setup.py install for manifestparser 18:14:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:14:09 INFO - Running setup.py install for mozcrash 18:14:09 INFO - Running setup.py install for mozdebug 18:14:09 INFO - Running setup.py install for mozdevice 18:14:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:14:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:14:09 INFO - Running setup.py install for mozfile 18:14:10 INFO - Running setup.py install for mozhttpd 18:14:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:14:10 INFO - Running setup.py install for mozinfo 18:14:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:14:10 INFO - Running setup.py install for mozInstall 18:14:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:14:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:14:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:14:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:14:10 INFO - Running setup.py install for mozleak 18:14:10 INFO - Running setup.py install for mozlog 18:14:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:14:10 INFO - Running setup.py install for moznetwork 18:14:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:14:10 INFO - Running setup.py install for mozprocess 18:14:11 INFO - Running setup.py install for mozprofile 18:14:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:14:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:14:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:14:11 INFO - Running setup.py install for mozrunner 18:14:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:14:11 INFO - Running setup.py install for mozscreenshot 18:14:11 INFO - Running setup.py install for moztest 18:14:11 INFO - Running setup.py install for mozversion 18:14:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:14:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:14:11 INFO - Cleaning up... 18:14:11 INFO - Return code: 0 18:14:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:11 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:14:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:11 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:14:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:11 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 0x10b16d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14a228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d857b8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0919f0>, '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 0x10adf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0848c8>, '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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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:14:11 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:14:11 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:14:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ym4bkGDOC2/Render', 18:14:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:11 INFO - 'HOME': '/Users/cltbld', 18:14:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:11 INFO - 'LOGNAME': 'cltbld', 18:14:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:11 INFO - 'MOZ_NO_REMOTE': '1', 18:14:11 INFO - 'NO_EM_RESTART': '1', 18:14:11 INFO - 'PAGER': '/bin/cat', 18:14:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:11 INFO - 'PWD': '/builds/slave/test', 18:14:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:11 INFO - 'SHELL': '/bin/bash', 18:14:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amaOLzAC6b/Listeners', 18:14:11 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 18:14:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:11 INFO - 'USER': 'cltbld', 18:14:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:11 INFO - 'XPC_FLAGS': '0x0', 18:14:11 INFO - 'XPC_SERVICE_NAME': '0', 18:14:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:14:12 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-pWDevU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:14:12 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:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:14:12 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-klBDu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:14:12 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:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:14:12 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-mEfPOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:12 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:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:12 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-gKsLUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:12 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:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:12 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-3hLtKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:12 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:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:12 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-J7grTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:12 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:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:12 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-GxtJuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:12 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:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:12 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-NPPeAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:13 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:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:13 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-W14Y4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:13 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:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:13 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-W2teuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:13 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:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:13 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-Y3DIxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:13 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:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:13 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-txSDAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:13 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:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:13 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-c_Wc71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:13 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:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:13 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-zLqAzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:13 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:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:13 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-_a47A7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:13 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:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:13 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-MdD2Hf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:13 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:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:13 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-zJCwVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:14 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:14:14 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:14:14 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:14:14 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:14:14 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:14:14 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:14:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:14:14 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:14:14 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:14:14 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:14:14 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:14:14 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:14:14 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:14:16 INFO - Downloading blessings-1.5.1.tar.gz 18:14:16 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:14:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:14:16 INFO - Installing collected packages: blessings 18:14:16 INFO - Running setup.py install for blessings 18:14:16 INFO - Successfully installed blessings 18:14:16 INFO - Cleaning up... 18:14:16 INFO - Return code: 0 18:14:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:14:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:16 INFO - Reading from file tmpfile_stdout 18:14:16 INFO - Current package versions: 18:14:16 INFO - blessings == 1.5.1 18:14:16 INFO - blobuploader == 1.2.4 18:14:16 INFO - docopt == 0.6.1 18:14:16 INFO - manifestparser == 1.1 18:14:16 INFO - mozInstall == 1.12 18:14:16 INFO - mozcrash == 0.16 18:14:16 INFO - mozdebug == 0.1 18:14:16 INFO - mozdevice == 0.47 18:14:16 INFO - mozfile == 1.2 18:14:16 INFO - mozhttpd == 0.7 18:14:16 INFO - mozinfo == 0.9 18:14:16 INFO - mozleak == 0.1 18:14:16 INFO - mozlog == 3.1 18:14:16 INFO - moznetwork == 0.27 18:14:16 INFO - mozprocess == 0.22 18:14:16 INFO - mozprofile == 0.27 18:14:16 INFO - mozrunner == 6.11 18:14:16 INFO - mozscreenshot == 0.1 18:14:16 INFO - mozsystemmonitor == 0.0 18:14:16 INFO - moztest == 0.7 18:14:16 INFO - mozversion == 1.4 18:14:16 INFO - psutil == 3.1.1 18:14:16 INFO - requests == 1.2.3 18:14:16 INFO - wsgiref == 0.1.2 18:14:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:16 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 0x10b16d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b14a228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d857b8c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0919f0>, '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 0x10adf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0848c8>, '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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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:14:16 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:14:16 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:14:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ym4bkGDOC2/Render', 18:14:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:16 INFO - 'HOME': '/Users/cltbld', 18:14:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:16 INFO - 'LOGNAME': 'cltbld', 18:14:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:16 INFO - 'MOZ_NO_REMOTE': '1', 18:14:16 INFO - 'NO_EM_RESTART': '1', 18:14:16 INFO - 'PAGER': '/bin/cat', 18:14:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:16 INFO - 'PWD': '/builds/slave/test', 18:14:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:16 INFO - 'SHELL': '/bin/bash', 18:14:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amaOLzAC6b/Listeners', 18:14:16 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 18:14:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:16 INFO - 'USER': 'cltbld', 18:14:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:16 INFO - 'XPC_FLAGS': '0x0', 18:14:16 INFO - 'XPC_SERVICE_NAME': '0', 18:14:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:17 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:14:17 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:14:17 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:14:17 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:14:17 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:14:17 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:14:17 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:14:17 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:14:17 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:14:17 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:14:17 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:14:17 INFO - Cleaning up... 18:14:17 INFO - Return code: 0 18:14:17 INFO - Running post-action listener: _resource_record_post_action 18:14:17 INFO - Running post-action listener: _start_resource_monitoring 18:14:17 INFO - Starting resource monitoring. 18:14:17 INFO - ##### 18:14:17 INFO - ##### Running install step. 18:14:17 INFO - ##### 18:14:17 INFO - Running pre-action listener: _resource_record_pre_action 18:14:17 INFO - Running main action method: install 18:14:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:17 INFO - Reading from file tmpfile_stdout 18:14:17 INFO - Detecting whether we're running mozinstall >=1.0... 18:14:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:14:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:14:17 INFO - Reading from file tmpfile_stdout 18:14:17 INFO - Output received: 18:14:17 INFO - Usage: mozinstall [options] installer 18:14:17 INFO - Options: 18:14:17 INFO - -h, --help show this help message and exit 18:14:17 INFO - -d DEST, --destination=DEST 18:14:17 INFO - Directory to install application into. [default: 18:14:17 INFO - "/builds/slave/test"] 18:14:17 INFO - --app=APP Application being installed. [default: firefox] 18:14:17 INFO - mkdir: /builds/slave/test/build/application 18:14:17 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:14:17 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:14:48 INFO - Reading from file tmpfile_stdout 18:14:48 INFO - Output received: 18:14:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:14:48 INFO - Running post-action listener: _resource_record_post_action 18:14:48 INFO - ##### 18:14:48 INFO - ##### Running run-tests step. 18:14:48 INFO - ##### 18:14:48 INFO - Running pre-action listener: _resource_record_pre_action 18:14:48 INFO - Running main action method: run_tests 18:14:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:14:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:14:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:14:48 INFO - 2015-11-23 18:14:48.732 screenresolution[1642:7348] starting screenresolution argv=screenresolution get 18:14:48 INFO - 2015-11-23 18:14:48.735 screenresolution[1642:7348] Display 0: 1600x1200x32@60 18:14:48 INFO - 2015-11-23 18:14:48.740 screenresolution[1643:7350] starting screenresolution argv=screenresolution list 18:14:48 INFO - Available Modes on Display 0 18:14:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:14:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:14:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:14:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:14:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:14:48 INFO - Intel Iris: 18:14:48 INFO - Chipset Model: Intel Iris 18:14:48 INFO - Type: GPU 18:14:48 INFO - Bus: Built-In 18:14:48 INFO - VRAM (Dynamic, Max): 1536 MB 18:14:48 INFO - Vendor: Intel (0x8086) 18:14:48 INFO - Device ID: 0x0a2e 18:14:48 INFO - Revision ID: 0x0009 18:14:48 INFO - Displays: 18:14:48 INFO - Display: 18:14:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:14:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:14:48 INFO - Display Serial Number: 1600x1200 60 18:14:48 INFO - Main Display: Yes 18:14:48 INFO - Mirror: Off 18:14:48 INFO - Online: Yes 18:14:48 INFO - Rotation: Supported 18:14:48 INFO - Return code: 0 18:14:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:14:48 INFO - Python 2.7.3 18:14:48 INFO - Return code: 0 18:14:48 INFO - grabbing minidump binary from tooltool 18:14: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:14:48 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 0x10b0919f0>, '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 0x10adf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0848c8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:14:48 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:14:48 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:14:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:14:49 INFO - Return code: 0 18:14:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:14:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:14:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:14:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:14:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:14:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/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-0004', '--webServer', 'localhost'] in /builds/slave/test/build 18:14:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/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-0004 --webServer localhost 18:14:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ym4bkGDOC2/Render', 18:14:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:49 INFO - 'HOME': '/Users/cltbld', 18:14:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:49 INFO - 'LOGNAME': 'cltbld', 18:14:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:14:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:14:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:49 INFO - 'MOZ_NO_REMOTE': '1', 18:14:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:14:49 INFO - 'NO_EM_RESTART': '1', 18:14:49 INFO - 'PAGER': '/bin/cat', 18:14:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:49 INFO - 'PWD': '/builds/slave/test', 18:14:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:14:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:49 INFO - 'SHELL': '/bin/bash', 18:14:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amaOLzAC6b/Listeners', 18:14:49 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 18:14:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:49 INFO - 'USER': 'cltbld', 18:14:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:49 INFO - 'XPC_FLAGS': '0x0', 18:14:49 INFO - 'XPC_SERVICE_NAME': '0', 18:14:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/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-0004', '--webServer', 'localhost'] with output_timeout 3600 18:14:49 INFO - mozversion INFO | application_buildid: 20151123162931 18:14:49 INFO - mozversion INFO | application_changeset: 19d89caa664dd9309c796929225ee409df5d1ee4 18:14:49 INFO - mozversion INFO | application_display_name: Nightly 18:14:49 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:14:49 INFO - mozversion INFO | application_name: Firefox 18:14:49 INFO - mozversion INFO | application_remotingname: firefox 18:14:49 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 18:14:49 INFO - mozversion INFO | application_vendor: Mozilla 18:14:49 INFO - mozversion INFO | application_version: 45.0a1 18:14:49 INFO - mozversion INFO | platform_buildid: 20151123162931 18:14:49 INFO - mozversion INFO | platform_changeset: 19d89caa664dd9309c796929225ee409df5d1ee4 18:14:49 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 18:14:49 INFO - mozversion INFO | platform_version: 45.0a1 18:14:49 INFO - 2015-11-23 18:14:49,289 DEBUG : using testdate: 1448331289 18:14:49 INFO - 2015-11-23 18:14:49,289 DEBUG : actual date: 1448331289 18:14:49 INFO - 2015-11-23 18:14:49,298 INFO : starting webserver on 'localhost:49269' 18:14:49 INFO - 2015-11-23 18:14:49,299 INFO : Starting test suite t-yosemite-r7-0004.e 18:14:49 INFO - 2015-11-23 18:14:49,299 INFO : Starting test dromaeo_css 18:14:49 INFO - 2015-11-23 18:14:49,299 DEBUG : operating with platform_type : mac_ 18:14:49 INFO - 2015-11-23 18:14:49,300 INFO : Initialising browser for dromaeo_css test... 18:14:49 INFO - 2015-11-23 18:14:49,313 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmpRpBD6q/profile http://localhost:49269/getInfo.html 18:14:52 INFO - 2015-11-23 18:14:52,193 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:14:52 INFO - 2015-11-23 18:14:52,193 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:14:52 INFO - 2015-11-23 18:14:52,194 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 18:14:52 INFO - 2015-11-23 18:14:52,194 DEBUG : BROWSER_OUTPUT: __metrics 18:14:52 INFO - 2015-11-23 18:14:52,802 INFO : Browser initialized. 18:14:52 INFO - 2015-11-23 18:14:52,802 INFO : Running cycle 1/1 for dromaeo_css test... 18:14:52 INFO - 2015-11-23 18:14:52,802 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmpRpBD6q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:15:04 INFO - 2015-11-23 18:15:04,627 DEBUG : BROWSER_OUTPUT: RSS: Main: 236253184 18:15:04 INFO - 2015-11-23 18:15:04,628 DEBUG : BROWSER_OUTPUT: 18:15:54 INFO - 2015-11-23 18:15:54,336 DEBUG : BROWSER_OUTPUT: 1448331354332 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:18:05 INFO - 2015-11-23 18:18:05,048 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 18:18:06 INFO - 2015-11-23 18:18:06,452 DEBUG : BROWSER_OUTPUT: RSS: Main: 253005824 18:18:06 INFO - 2015-11-23 18:18:06,452 DEBUG : BROWSER_OUTPUT: 18:21:08 INFO - 2015-11-23 18:21:08,495 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 18:21:09 INFO - 2015-11-23 18:21:09,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 263249920 18:21:09 INFO - 2015-11-23 18:21:09,094 DEBUG : BROWSER_OUTPUT: 18:24:10 INFO - 2015-11-23 18:24:10,651 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 18:24:11 INFO - 2015-11-23 18:24:11,249 DEBUG : BROWSER_OUTPUT: RSS: Main: 264024064 18:24:11 INFO - 2015-11-23 18:24:11,249 DEBUG : BROWSER_OUTPUT: 18:27:12 INFO - 2015-11-23 18:27:12,840 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 18:27:13 INFO - 2015-11-23 18:27:13,445 DEBUG : BROWSER_OUTPUT: RSS: Main: 266575872 18:27:13 INFO - 2015-11-23 18:27:13,445 DEBUG : BROWSER_OUTPUT: 18:30:15 INFO - 2015-11-23 18:30:15,843 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 18:30:16 INFO - 2015-11-23 18:30:16,447 DEBUG : BROWSER_OUTPUT: RSS: Main: 270270464 18:30:16 INFO - 2015-11-23 18:30:16,447 DEBUG : BROWSER_OUTPUT: 18:33:18 INFO - 2015-11-23 18:33:18,932 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 18:33:19 INFO - 2015-11-23 18:33:19,190 DEBUG : BROWSER_OUTPUT: RSS: Main: 272048128 18:33:19 INFO - 2015-11-23 18:33:19,190 DEBUG : BROWSER_OUTPUT: 18:33:19 INFO - 2015-11-23 18:33:19,190 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:33:19 INFO - 2015-11-23 18:33:19,190 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:33:19 INFO - 2015-11-23 18:33:19,190 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:33:19 INFO - 2015-11-23 18:33:19,190 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:33:19 INFO - 2015-11-23 18:33:19,190 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3460.0138960396284;3550.804705741183;3561.2699396623693;3596.352656521826;3598.9280214395712;5944.167816505687;5950.137230101633;5993.490553302969;5993.790217342394;6006.579539432241;5418.322709661293;5872.677002764846;5895.9705201473935;5972.432618901197;6095.7256923438445;5276.15581506958;5337.946620533803;5354.625176237645;5370.3287089113865;5423.647462914918;4866.902661946759;5391.514763671269;5462.262594549745;5486.506214440699;5487.890242195173;5192.92210616841;5211.921821172686;5296.3379577552805;5371.248025276464;5396.676199428047;5345.412004679482;5346.4386239445075;5346.91979620306;5361;5376.059189641836;5329.120695085307;5330.92003619946;5339.519443250126;5393.325834270716;5424.376196737347;4396.032872767986;4778.569928706398;5479.890402191933;5687.061634830248;5950.940490595082;6225.003999360126;6327.873442531123;6334;6361.936380636227;6376.585521941059;6450.451711604473;6451.258105317902;6473.44975677068;6479.514036447238;6500.797352489804;5941.910871336933;5942.10868369748;6041.667708276043;6103.367974696828;6143.38566143391;6449.193850768654;6574.178227721535;6600;6604.966975165094;6622.1722284714415;6066.636001839904;6306.590071645328;6314.400131987454;6316.8420789479715;6328.050792381087;6340.112384266206;6354.52341074412;6436.098946147543;6479.125318082093;6482.513811464159;5415.810446634349;6323.683815809283;6331.335209802996;6338.461230795434;6383.904241436382;6128.846778830565;6245.344238854946;6326.905096423558;6349.619022858551;6353.714082866282;4992.076465853828;5043.0922433961505;5043.344365232496;5046.369203849519;5107.770150343169;4520.706154099973;4581.289900065495;4585.472670642852;4594.1271158479785;4637.768111594474;5746.626469279484;5931.169636250842;5937.228160339215;6013.609115407485;6098.664573448503;6409.070684750778;6423.518236132215;6427.967860160669;6445.6454894981225;6463.903041454382;5983.042713165873;5993.0710739835395;6005.459508644243;6023.066424704178;6041.426064523863;5409.107497262908;5525;5532.778688852401;5545.140503222007;5629.605927585029;6411.839704007344;6425.871482570323;6433.453156481658;6474.902876456857;6541.378569035934;6411.5832470889245;6425.935740642533;6451.935480645134;6461.224653041665;6474.773382931575;6548.508861835477;6554.705038273194;6623.470122390101;6636.203655561363;6642.103316052271;6159.891219580519;6214.937850621436;6305.527085468517;6310.463610593151;6320.936790632035;6348.603307272393;6390.5526613136635;6432.549721519449;6436.0989461476365;6443.742250309935;5448.591355648421;6117.827404801005;6252.562320637512;6298.937010630018;6303.936960630335;6147.432404736764;6271.122042914087;6280.937190628035;6293.276273228637;6297.086922396318;6191.907121393273;6219.937800622117;6252.687365631609;6258.155149054819;6267;6038.879222415615;6132.325444201224;6173.567850250619;6293.748250069946;6313.936860631518;6092.147099405998;6164.4760195382105;6167.629942203482;6191.504679625529;6213.130161777264 18:33:19 INFO - 2015-11-23 18:33:19,192 DEBUG : BROWSER_OUTPUT: |1;ext.html;555.0896529691307;555.1450765820639;570.57207094679;580.2167074449491;587.6797145555672;31930.840345798268;40022.19955600886;42093;42131;42179.57820421795;44880.3267950981;45128.0974380513;45456.545434545646;45477;45612.087758244816;9756.951215243931;9949.701508954742;9952.701418957442;9961.601535938555;9975.600975960953;8941.64233430662;9222.86165707515;9733.270004749636;9773.56018979144;9847.753806154868;9411.388259763127;9795.363301385387;9799.804003919917;9828.655997040105;9836.213102951746;9740.269479789009;9782.755431114208;9794.657186998493;9855.507224638775;9885.802283954352;9650.372725772873;9718.222542196607;9780.853287200698;9799.755006124833;9823.214142868554;33872.153196170046;41654.37518437226;41871.58128418707;42114.36828447576;42532.78733606312;3165.4618714818534;3428.434308339121;3469.9826500867584;3496.30073985204;3787.431885217212;28874.84500619973;29049;29394.0431698952;29394.70605293941;29881.551776723365;2473.4434752180796;2746.5468197747346;2780.666320041588;2837.0779496663667;2997.8201307921377;443643;447731;449094.7545262285;449310;449514.50485495053;61601.075983860275;62708;66608;67440.6627966857;68402;66397.33602663911;66536.66731666311;66637;66786;67319;15600.843991560167;15790.605234869221;15800.841991579937;15942.760858587131;15995.440159594358;11999.520019199133;12001.699957501132;12034.217775844541;12118.272903625635;12138.635840924786;17399;21153.25963591267;21681.56636867254;21686.89156554207;21759.67360489594;6890.655467226618;8274.338052955749;8314.251717345467;8480.575971201415;8527.275181609508;997.8004399120205;998.4823068935178;1001.839705647093;1030.6083688198587;1088.8094583448024;29977.700222997926;29984.85007574948;30626.54060189099;30633.38733225323;30875;1035.803197392493;1046.706922061824;1141.9836345652377;1142.1776321048997;1182.9171957963033;14738.11571305725;14950.925245373703;15095.849041509664;15106.697866042838;15329.770053449207;488508;494245;499731;500445;501473;57826.4217357821;58258.70870645619;58409;58799;58904.70547647234;1418.3971811979868;1420.183394548139;1448.920309382994;1453.8328092269523;1455.4760286296926;29511.55732663969;29639.703602963695;29870.850645746632;30244.697553025067;30393.848030759706;52093.739531302104;52896.73551632217;53007.734961324946;53203.733981329846;53355.93288134293;457219.4278057177;458214;459942;460790.69604651764;461701;32045;32760.83619581887;33790.66209337974;34013.82993085118;34165.829170853984;42969.3554596675;43240.78379608082;43442;43551;43814.34278485889;31381.74473021145;32281.838590806896;36148;36634.26731465409;36933.891983240545;36102.09744756402;37102.25795484129;37922.45036145983;38123;39558;36475.27049459049;36932.63067369292;37609.81195094007;37721;37836;32795;33681.663183367855;33691.74556713781;33791.831040845624;33905.32189356248;21148.47128821792;24302.878485608166;25324;25695.614565781154;25964.740352596233 18:33:19 INFO - 2015-11-23 18:33:19,193 DEBUG : BROWSER_OUTPUT: |2;jquery.html;326.1731510620577;354.75521889895987;359.8164935882701;360.5547149270652;361.3639993611245;12703.682407939807;18494.44516664499;19659.803401965975;19917.800821991776;20911.790882091176;20287.797122028776;20479.18083276671;20596.89701551494;20639.277625283117;20931.790682093175;5943.702814859262;6075.544334174932;6087.969560152215;6092.055731361646;6100.7864724734645;5905.763769449217;5925.644461332334;5996.310424301196;5998.190244317027;6052.394760523935;5865.032269675498;5903.409659034105;5919.467247947706;5972.25346831646;6004.429579189992;5790.942090579082;5896.174535565003;5908.645481271137;5925.911111333334;5927.199828023205;5003.699778013331;5084.542391184811;5096.821611243626;5125.1799712045895;5138.897222055575;25298.241052768448;25361.11236106746;25562.488750224893;25609.286653435334;25643.487130257476;2072.927447539344;2086.812186903186;2089.8746075235535;2108.852380333362;2136.8931553422267;14698;14798.778018329735;15041.849581504155;15151.242437878172;15609.297581608742;1727.904965226912;1760.991254805867;1762.541739147818;1785.4643606917873;1788.8121747216614;97968;98670.50664746702;100146;100342.49828750883;101074;10263.538140783683;10288.382697038202;10557.102646275;10601.151907847354;10698.197635177392;11885.821712674317;12249.571265005681;12547.24716517012;12566.434510447052;12632.684182895317;7559.470837041355;7649.847003059907;8233.46482478637;8240.917590824134;8271.2142346477;7948.443608947318;8085.514869107872;8148.26665600087;8292.834143317099;8408.075111737704;6155.753769849245;6158.414950579688;6170.568060235741;6241;6267.2165979252595;1818.4635532517675;1820.6085691576375;1834.8256915592997;1839.3194518028147;1842.4380563927862;810.2990912860452;908.1962463220098;941.6205259295166;1014.6905193915926;1033.256055639938;21162.47093822636;21217;21754;21792.89103554504;21892.562148756937;934.303943562037;997.518684753141;999.0169513515666;1021.0861279155115;1023.4319952426475;9279.489628070522;10202.64290749835;11168.218224724355;11890;11933.940330298294;438847;542303.6157107422;543378;608940;672560;96895.51552242193;116249;118491.40754296345;128629;129218;1164.7670465906683;1181.8699943006263;1191.5472120594118;1191.5769901684778;1197.0722689915385;16815.327386904388;18397.74997266934;18662.78691885023;18666.533336666693;18759.437216883514;40382.39426408665;48934;49298.75350623224;50246.49753502418;52161.47838521718;62952.5571164329;63522;69984.65007674928;71510.64244678743;71539;9567.138957493858;9688.273379496706;9873.456959867277;9981.401115933068;9987.450690211817;11834;12453.688657783629;15447.0731756095;19401.514962125497;19863.702044469057;15661.765073524135;15790.76313855316;15868.2065896707;16073.839261607702;16228.675426491169;15335.923320383774;15629.452969146025;15646.139462329682;15912.204389780696;16004.519864404087;15852.44516441919;16215.18924053769;16249.675006499567;16383.508494745176;16518.83481165173;9139.908600913906;9508.239340852853;9725.610975560898;9854.965228650888;9876.40741555509;10215.080642742189;13999.110120133651;15692;16096.356145754038;16355.509334719978 18:33:19 INFO - 2015-11-23 18:33:19,196 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1514.7273490771656;1618.603442156671;1924.5284905198228;1935.3710044235622;1976.337926794524;7748.457607967431;7885.960570197155;8439.873401898976;8790.472571645705;8838.116188381158;8301.91698083019;8491.91508084919;8522.531260780655;8576.27101696355;8604.05355410906;2352.0826877517784;2363.2555744940373;2367.910761049919;2378.2389635316704;2394.1141777542334;2345.1791872844537;2378.797802186816;2420.6127019676856;2438.122275980654;2446.2538925627678;2339.1462116327502;2373.893174807129;2377.904883804654;2392.9162479313227;2408.7350391456926;2296.196331284054;2339.0643742502966;2341.718993720747;2369.5853225685523;2384.141708984764;2098.223657246813;2364.716234051925;2373.41851246444;2405.9639105413435;2455.0793452455327;7033.929660703378;7453.888191677129;8253;8620.310375169971;8633.65465381384;4600.217962946307;4901.779419926076;5188.092083885305;5193.221016847466;5235.188545775411;3474.062003259106;3479.8260086995547;3494.9650503494895;3539.9115022124397;3566.197605538759;1029.27950434696;1031.5203430404897;1050.0549505445083;1063.4895250279908;1067.0609863320228;11329.150313726503;11341.659750207507;11366.090712743044;11412.258203216765;11544.884551154464;4490.259107247268;4541.931871021938;4560.749158796298;4569.702969306984;4585.426821647294;9109.908900910907;9116.544172791333;9133.543322833832;9171.037041110721;9172.03693612161;4421.38100665907;5127.974360128175;5167.069927413027;5181.06740786662;5185.170372740344;4263.488381394191;4335.52309245989;4650.302454631765;4754.95245047552;4757.191277482767;3749.7000239980734;3755.605661405568;3781.773093614392;3791.867284645024;3817.8854634361014;1171.3674615707594;1181.2203945395997;1185.3895243949373;1187.441902305915;1195.7190060335813;1361.0812701426498;1362.3937347880296;1376.0711519724146;1417.0930604413184;1423.4021710881455;7913.406494512935;8047.9537660103815;8051.879221811678;8052.396070294752;8064.677412903536;499.8200647766801;508.84225889974226;509.17259453388243;512.1140427061131;512.9307543481582;6031.4270144336215;6049.969750151221;6201.96899015502;6232.470240029648;6240.500759939194;9504.239660827117;9731.978142295098;9793.804123917482;9829.508524573886;9857.852132218022;9519.333646644669;9637.951810240904;9660.275479338938;9702;9729.97835227305;7539.396848252017;7544.283293087148;7582.924170758332;7648.38812894967;7676.731314403969;7241.493095483265;7271.636418179176;7311.232320606472;7332.633368331669;7363.8159046024275;8509.829803403773;8671.262942649946;8677.349198810241;8723.258523025614;8724.738257852274;8862.822743544964;8938.284937205139;8958.865617015881;9007.009228984676;9058;6527.640979746159;6647.667616619053;6702.798916032527;6936.2023367311385;7012.789616311519;8131.674733010614;8138.267555919995;8189.099199087977;8203.83592328162;8229.382796290183;7550.886736698844;7729.111152217567;7767.766966990999;7820.765377038698;7840.607969601383;7562.0925488941675;7594.670932586886;7606.429517786078;7699;7724.188960159102;6566.9014964774615;7700.1144868338615;7729;7739.883901741591;7754;6949.965250173716;7025.437964962893;7038.788836334918;7048.118985126859;7064.293570642894;7119.216886142417;7128;7140.285971402818;7145.714171433292;7163.8567228656175 18:33:19 INFO - 2015-11-23 18:33:19,197 DEBUG : BROWSER_OUTPUT: |4;prototype.html;324.2251020062052;324.654243230959;329.7082082357113;331.8208167589502;336.8652538984406;6934.410575101122;7002.229754726976;7038.401735852446;7050.118735158106;7067.975143604174;6881.41507971823;6934.514583979124;6952.4438044956405;6972.860542789142;7059.7882063538045;4979.651424400285;5016.398032236137;5025.974870125662;5033.018561380532;5069.721165335905;4404.515503294635;4862.5380588844;5035.295058691786;5040.042391945537;5062.6456148069465;4958.851234462972;4969.577585905203;4975.12935236334;4981.850544483671;4983.651144419892;4009.278329900617;4965.42897566779;4968.801247950114;4990;5017;4023.577524359929;4103.363978583325;4120.299549076664;4213.452251207324;4223.282042052828;5349.170878513836;5363.517283444508;6664.566803157779;6686.3982241598005;6723.226828914689;4827.927581086286;5871.236739223917;5950.077737950624;5953.940460595425;5959.897418977503;7195.964020179866;7414.59219742914;7420.443466739962;7428.851422971565;7442.8139296517475;4736.265878788793;4745.95254047462;4747.477777444478;4750.168720473937;4767.594754445876;7806.375489960789;7901.20987901211;7901.249381308767;7901.486403383819;7908.367330613537;4669.159551280802;4793.1372352976105;4795.184818580849;4796.8081276749235;4798.928016079762;4873.585745211626;4883.804647814048;4891.779869905863;4895.241237608177;4902.852914412573;5912.970435147797;5923.940760592425;5925.111233314951;5927.525797936155;5980.581359304807;7323.670434830446;7502.587357695379;7507.474476786681;7528.774136775905;7537.698492060256;1677.1027500287532;1711.6234428425726;1714.8456638902558;1716.1076240355196;1716.476474675236;893.8167675626481;1070.539667942792;1081.3728037738092;1086.538221255963;1093.316677076827;6825.624590647462;6827.0783444235385;6848.589084654936;6871.553349032297;6905.861882762417;7755.3407960324;7755.844883102306;7773.844523109507;7773.883391749128;7791.805204869924;6739.196281822792;6808.455323574102;6850.623215723183;6890.345417185359;6935.4104901082965;3989.860354887623;4092;4097.242010228058;4124.175164967019;4139.089400331922;7939.0473143222025;8155.918440815634;8169.918300816916;8189.303909167668;8266.462679925786;8104.675812967415;8134.877976830352;8139.552324622085;8153.987570689203;8157.67369305233;7858.567778772108;8109.675612975533;8128.512289262781;8137.877931831027;8153.22544358285;7848.646810893638;7855.253750893656;7888.960555197187;7901.841963160819;7917.168697286932;7075.610841403773;7520.323170914644;7696.230376962483;7727.343175829893;7767.72812951544;6048.334683184758;7418.48070635072;8133.820596013663;8166;8169.591520424074;4834.8791280218275;4839.467658557626;4840.370751802243;4842.370491836039;4843.782029808596;6479.963205887224;7479.364254038468;7841.843163136819;7845.450818442654;7864.174261702439;7530.209328020479;7532.510386824839;7539.660715267936;7546.962265188639;7582.241775822595;6414.550981431255;7040.612766297902;7394.667239974106;7497.737579184703;7517.6241187939295;7277.345038946521;7315.829467285209;7325.194228634952;7327.816804579928;7351.044364232829;7463.402927765657;7479.6634151464405;7489.438292128003;7530.058742657168;7545.811354716176;5741.885162296814;7331.560106393634;7335.8532829344185;7344.449166312441;7360.116785985716 18:33:19 INFO - 2015-11-23 18:33:19,200 DEBUG : BROWSER_OUTPUT: |5;yui.html;676.2257215488263;696.3106524540705;734.6804140199014;779.0573406178522;815.5595978171791;15285.235738213087;15429.768553471707;15488.3030263638;15552;15574.922125389372;11959.342236177006;14003.238898774414;14481;14484.348204330805;14507.911906606994;750.1373420566338;757.7650928212245;919.0258326174243;929.3355250995537;943.4527973775229;794.9125596184415;934.9439033657968;942.5381563034133;942.5569982108398;948.6205517792869;982.1013772398283;982.7592239901235;990.3958585262984;999.1307562420704;999.3054826895311;963.325672029582;972.2950860626061;979.7795496013412;984.3552473130111;993.1508560181086;691.3604915453236;704.3801454719865;708.5429897715995;715.427657873699;717.7093277222733;15087.773683394758;15133.243337833064;15192.620184495365;15267;15375.846241537552;12492.875071249353;12674.746505069847;12739.681507962376;12932.612021639367;13139.27733974631;10840.403777792219;11113.499892504777;11153.944230278878;11699.23954942926;11802.291862488193;2935.7211064948797;3487.1805125795418;3552.236269202108;3600.6219346968455;3654.086478380405;82185.58907205425;83218;83456;83966.58016709938;84872;9591.520423978773;9624.133827955378;9641.228701703847;9653.517324133765;9854.359466634645;20485.795142048388;20671.379858604265;20730.58538829215;20837.37487875336;20870;7043.788686339316;7289.890651640229;7560.697572097055;7581.279778421042;7672.578008209491;4063.4108054331955;4082.2447847148374;4163.652041190516;4168.791560421967;4204.411382406465;2758.0209025795953;3196.568463257477;3375.835336808797;3434.261633748756;3436.60479044913;601.5578549765919;610.3438803286484;619.0219453263833;626.5238418801653;628.0798630007062;434.6547435686545;446.7475876129976;456.6369387378672;489.56428778387266;513.9152039913372;7641;8592.828143437097;8952.23905967987;9480.525973701288;9641.662541811003;514.5677630790154;555.9153175591532;562.8564715997476;606.1937622961451;648.6951132967498;4096.795160241976;4292.806823692941;4344.913101737948;4430.556944305544;4482.8655140345845;98166;98689.50655246677;98762.01237987672;99100;99289;82299;84508;85197;86432;86901;75540;75899;75973;76182;76287.23712762912;9013.324000700104;9023.323250756088;9172.482552342972;9175.03662115495;9210.171084602418;44870.551294486635;51668.70828229324;52980.73509632427;53856.73071634774;54521;68738.9689154653;70723.64638176776;71694;72081.6395918038;73319.63340183265;11601.477933493183;11683.88316116828;11737.237079589799;11946.641600751991;12381.504739810307;12031.699207519881;12059.698507537381;12506.249625022529;12946.546870859474;13201;11480.712982175512;11672.182947193955;12307.692307692021;12323.445444955178;12615.81076283838;11480.770384592095;11958.521659133885;12112.515499379926;12183.75632487363;12304.26174429537;11537.942310288396;11953.880461195624;12027.037836973197;12033.819492707791;12424.813627795409;9823.950880245553;10043.648472303434;10193.337433066827;10219.233557483367;10284.537195826057;9272.675456358995;9477.834177674622;9829;10056.899431005597;10199.184065274612 18:33:19 INFO - 2015-11-23 18:33:19,200 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:33:19 INFO - 2015-11-23 18:33:19,200 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:33:19 INFO - 2015-11-23 18:33:19,200 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,341 18:33:19 INFO - 2015-11-23 18:33:19,200 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:33:19 INFO - 2015-11-23 18:33:19,200 DEBUG : BROWSER_OUTPUT: __startTimestamp1448332399186__endTimestamp 18:33:19 INFO - 2015-11-23 18:33:19,200 DEBUG : BROWSER_OUTPUT: 18:33:19 INFO - 2015-11-23 18:33:19,201 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:33:19 INFO - 2015-11-23 18:33:19,201 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:33:19 INFO - 2015-11-23 18:33:19,201 DEBUG : BROWSER_OUTPUT: 18:33:19 INFO - 2015-11-23 18:33:19,201 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5894.85 Median:6143.39 stddev:669.20 (10.9%) stddev-sans-first:643.42 18:33:19 INFO - 2015-11-23 18:33:19,201 DEBUG : BROWSER_OUTPUT: Values: 3460.0 3550.8 3561.3 3596.4 3598.9 5944.2 5950.1 5993.5 5993.8 6006.6 5418.3 5872.7 5896.0 5972.4 6095.7 5276.2 5337.9 5354.6 5370.3 5423.6 4866.9 5391.5 5462.3 5486.5 5487.9 5192.9 5211.9 5296.3 5371.2 5396.7 5345.4 5346.4 5346.9 5361.0 5376.1 5329.1 5330.9 5339.5 5393.3 5424.4 4396.0 4778.6 5479.9 5687.1 5950.9 6225.0 6327.9 6334.0 6361.9 6376.6 6450.5 6451.3 6473.4 6479.5 6500.8 5941.9 5942.1 6041.7 6103.4 6143.4 6449.2 6574.2 6600.0 6605.0 6622.2 6066.6 6306.6 6314.4 6316.8 6328.1 6340.1 6354.5 6436.1 6479.1 6482.5 5415.8 6323.7 6331.3 6338.5 6383.9 6128.8 6245.3 6326.9 6349.6 6353.7 4992.1 5043.1 5043.3 5046.4 5107.8 4520.7 4581.3 4585.5 4594.1 4637.8 5746.6 5931.2 5937.2 6013.6 6098.7 6409.1 6423.5 6428.0 6445.6 6463.9 5983.0 5993.1 6005.5 6023.1 6041.4 5409.1 5525.0 5532.8 5545.1 5629.6 6411.8 6425.9 6433.5 6474.9 6541.4 6411.6 6425.9 6451.9 6461.2 6474.8 6548.5 6554.7 6623.5 6636.2 6642.1 6159.9 6214.9 6305.5 6310.5 6320.9 6348.6 6390.6 6432.5 6436.1 6443.7 5448.6 6117.8 6252.6 6298.9 6303.9 6147.4 6271.1 6280.9 6293.3 6297.1 6191.9 6219.9 6252.7 6258.2 6267.0 6038.9 6132.3 6173.6 6293.7 6313.9 6092.1 6164.5 6167.6 6191.5 6213.1 18:33:19 INFO - 2015-11-23 18:33:19,201 DEBUG : BROWSER_OUTPUT: 18:33:19 INFO - 2015-11-23 18:33:19,201 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:62173.58 Median:40838.29 stddev:124267.32 (304.3%) stddev-sans-first:124529.80 18:33:19 INFO - 2015-11-23 18:33:19,203 DEBUG : BROWSER_OUTPUT: Values: 555.1 555.1 570.6 580.2 587.7 31930.8 40022.2 42093.0 42131.0 42179.6 44880.3 45128.1 45456.5 45477.0 45612.1 9757.0 9949.7 9952.7 9961.6 9975.6 8941.6 9222.9 9733.3 9773.6 9847.8 9411.4 9795.4 9799.8 9828.7 9836.2 9740.3 9782.8 9794.7 9855.5 9885.8 9650.4 9718.2 9780.9 9799.8 9823.2 33872.2 41654.4 41871.6 42114.4 42532.8 3165.5 3428.4 3470.0 3496.3 3787.4 28874.8 29049.0 29394.0 29394.7 29881.6 2473.4 2746.5 2780.7 2837.1 2997.8 443643.0 447731.0 449094.8 449310.0 449514.5 61601.1 62708.0 66608.0 67440.7 68402.0 66397.3 66536.7 66637.0 66786.0 67319.0 15600.8 15790.6 15800.8 15942.8 15995.4 11999.5 12001.7 12034.2 12118.3 12138.6 17399.0 21153.3 21681.6 21686.9 21759.7 6890.7 8274.3 8314.3 8480.6 8527.3 997.8 998.5 1001.8 1030.6 1088.8 29977.7 29984.9 30626.5 30633.4 30875.0 1035.8 1046.7 1142.0 1142.2 1182.9 14738.1 14950.9 15095.8 15106.7 15329.8 488508.0 494245.0 499731.0 500445.0 501473.0 57826.4 58258.7 58409.0 58799.0 58904.7 1418.4 1420.2 1448.9 1453.8 1455.5 29511.6 29639.7 29870.9 30244.7 30393.8 52093.7 52896.7 53007.7 53203.7 53355.9 457219.4 458214.0 459942.0 460790.7 461701.0 32045.0 32760.8 33790.7 34013.8 34165.8 42969.4 43240.8 43442.0 43551.0 43814.3 31381.7 32281.8 36148.0 36634.3 36933.9 36102.1 37102.3 37922.5 38123.0 39558.0 36475.3 36932.6 37609.8 37721.0 37836.0 32795.0 33681.7 33691.7 33791.8 33905.3 21148.5 24302.9 25324.0 25695.6 25964.7 18:33:19 INFO - 2015-11-23 18:33:19,203 DEBUG : BROWSER_OUTPUT: 18:33:19 INFO - 2015-11-23 18:33:19,203 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:33499.33 Median:11676.95 stddev:93974.63 (804.8%) stddev-sans-first:94205.25 18:33:19 INFO - 2015-11-23 18:33:19,203 DEBUG : BROWSER_OUTPUT: Values: 326.2 354.8 359.8 360.6 361.4 12703.7 18494.4 19659.8 19917.8 20911.8 20287.8 20479.2 20596.9 20639.3 20931.8 5943.7 6075.5 6088.0 6092.1 6100.8 5905.8 5925.6 5996.3 5998.2 6052.4 5865.0 5903.4 5919.5 5972.3 6004.4 5790.9 5896.2 5908.6 5925.9 5927.2 5003.7 5084.5 5096.8 5125.2 5138.9 25298.2 25361.1 25562.5 25609.3 25643.5 2072.9 2086.8 2089.9 2108.9 2136.9 14698.0 14798.8 15041.8 15151.2 15609.3 1727.9 1761.0 1762.5 1785.5 1788.8 97968.0 98670.5 100146.0 100342.5 101074.0 10263.5 10288.4 10557.1 10601.2 10698.2 11885.8 12249.6 12547.2 12566.4 12632.7 7559.5 7649.8 8233.5 8240.9 8271.2 7948.4 8085.5 8148.3 8292.8 8408.1 6155.8 6158.4 6170.6 6241.0 6267.2 1818.5 1820.6 1834.8 1839.3 1842.4 810.3 908.2 941.6 1014.7 1033.3 21162.5 21217.0 21754.0 21792.9 21892.6 934.3 997.5 999.0 1021.1 1023.4 9279.5 10202.6 11168.2 11890.0 11933.9 438847.0 542303.6 543378.0 608940.0 672560.0 96895.5 116249.0 118491.4 128629.0 129218.0 1164.8 1181.9 1191.5 1191.6 1197.1 16815.3 18397.7 18662.8 18666.5 18759.4 40382.4 48934.0 49298.8 50246.5 52161.5 62952.6 63522.0 69984.7 71510.6 71539.0 9567.1 9688.3 9873.5 9981.4 9987.5 11834.0 12453.7 15447.1 19401.5 19863.7 15661.8 15790.8 15868.2 16073.8 16228.7 15335.9 15629.5 15646.1 15912.2 16004.5 15852.4 16215.2 16249.7 16383.5 16518.8 9139.9 9508.2 9725.6 9855.0 9876.4 10215.1 13999.1 15692.0 16096.4 16355.5 18:33:19 INFO - 2015-11-23 18:33:19,203 DEBUG : BROWSER_OUTPUT: 18:33:19 INFO - 2015-11-23 18:33:19,203 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5768.33 Median:6547.27 stddev:2995.64 (45.8%) stddev-sans-first:2986.98 18:33:19 INFO - 2015-11-23 18:33:19,205 DEBUG : BROWSER_OUTPUT: Values: 1514.7 1618.6 1924.5 1935.4 1976.3 7748.5 7886.0 8439.9 8790.5 8838.1 8301.9 8491.9 8522.5 8576.3 8604.1 2352.1 2363.3 2367.9 2378.2 2394.1 2345.2 2378.8 2420.6 2438.1 2446.3 2339.1 2373.9 2377.9 2392.9 2408.7 2296.2 2339.1 2341.7 2369.6 2384.1 2098.2 2364.7 2373.4 2406.0 2455.1 7033.9 7453.9 8253.0 8620.3 8633.7 4600.2 4901.8 5188.1 5193.2 5235.2 3474.1 3479.8 3495.0 3539.9 3566.2 1029.3 1031.5 1050.1 1063.5 1067.1 11329.2 11341.7 11366.1 11412.3 11544.9 4490.3 4541.9 4560.7 4569.7 4585.4 9109.9 9116.5 9133.5 9171.0 9172.0 4421.4 5128.0 5167.1 5181.1 5185.2 4263.5 4335.5 4650.3 4755.0 4757.2 3749.7 3755.6 3781.8 3791.9 3817.9 1171.4 1181.2 1185.4 1187.4 1195.7 1361.1 1362.4 1376.1 1417.1 1423.4 7913.4 8048.0 8051.9 8052.4 8064.7 499.8 508.8 509.2 512.1 512.9 6031.4 6050.0 6202.0 6232.5 6240.5 9504.2 9732.0 9793.8 9829.5 9857.9 9519.3 9638.0 9660.3 9702.0 9730.0 7539.4 7544.3 7582.9 7648.4 7676.7 7241.5 7271.6 7311.2 7332.6 7363.8 8509.8 8671.3 8677.3 8723.3 8724.7 8862.8 8938.3 8958.9 9007.0 9058.0 6527.6 6647.7 6702.8 6936.2 7012.8 8131.7 8138.3 8189.1 8203.8 8229.4 7550.9 7729.1 7767.8 7820.8 7840.6 7562.1 7594.7 7606.4 7699.0 7724.2 6566.9 7700.1 7729.0 7739.9 7754.0 6950.0 7025.4 7038.8 7048.1 7064.3 7119.2 7128.0 7140.3 7145.7 7163.9 18:33:19 INFO - 2015-11-23 18:33:19,205 DEBUG : BROWSER_OUTPUT: 18:33:19 INFO - 2015-11-23 18:33:19,205 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:6046.58 Median:6885.88 stddev:1994.45 (29.0%) stddev-sans-first:1953.25 18:33:19 INFO - 2015-11-23 18:33:19,206 DEBUG : BROWSER_OUTPUT: Values: 324.2 324.7 329.7 331.8 336.9 6934.4 7002.2 7038.4 7050.1 7068.0 6881.4 6934.5 6952.4 6972.9 7059.8 4979.7 5016.4 5026.0 5033.0 5069.7 4404.5 4862.5 5035.3 5040.0 5062.6 4958.9 4969.6 4975.1 4981.9 4983.7 4009.3 4965.4 4968.8 4990.0 5017.0 4023.6 4103.4 4120.3 4213.5 4223.3 5349.2 5363.5 6664.6 6686.4 6723.2 4827.9 5871.2 5950.1 5953.9 5959.9 7196.0 7414.6 7420.4 7428.9 7442.8 4736.3 4746.0 4747.5 4750.2 4767.6 7806.4 7901.2 7901.2 7901.5 7908.4 4669.2 4793.1 4795.2 4796.8 4798.9 4873.6 4883.8 4891.8 4895.2 4902.9 5913.0 5923.9 5925.1 5927.5 5980.6 7323.7 7502.6 7507.5 7528.8 7537.7 1677.1 1711.6 1714.8 1716.1 1716.5 893.8 1070.5 1081.4 1086.5 1093.3 6825.6 6827.1 6848.6 6871.6 6905.9 7755.3 7755.8 7773.8 7773.9 7791.8 6739.2 6808.5 6850.6 6890.3 6935.4 3989.9 4092.0 4097.2 4124.2 4139.1 7939.0 8155.9 8169.9 8189.3 8266.5 8104.7 8134.9 8139.6 8154.0 8157.7 7858.6 8109.7 8128.5 8137.9 8153.2 7848.6 7855.3 7889.0 7901.8 7917.2 7075.6 7520.3 7696.2 7727.3 7767.7 6048.3 7418.5 8133.8 8166.0 8169.6 4834.9 4839.5 4840.4 4842.4 4843.8 6480.0 7479.4 7841.8 7845.5 7864.2 7530.2 7532.5 7539.7 7547.0 7582.2 6414.6 7040.6 7394.7 7497.7 7517.6 7277.3 7315.8 7325.2 7327.8 7351.0 7463.4 7479.7 7489.4 7530.1 7545.8 5741.9 7331.6 7335.9 7344.4 7360.1 18:33:19 INFO - 2015-11-23 18:33:19,206 DEBUG : BROWSER_OUTPUT: 18:33:19 INFO - 2015-11-23 18:33:19,206 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:19254.11 Median:54188.87 stddev:27390.72 (50.5%) stddev-sans-first:27432.04 18:33:19 INFO - 2015-11-23 18:33:19,208 DEBUG : BROWSER_OUTPUT: Values: 676.2 696.3 734.7 779.1 815.6 15285.2 15429.8 15488.3 15552.0 15574.9 11959.3 14003.2 14481.0 14484.3 14507.9 750.1 757.8 919.0 929.3 943.5 794.9 934.9 942.5 942.6 948.6 982.1 982.8 990.4 999.1 999.3 963.3 972.3 979.8 984.4 993.2 691.4 704.4 708.5 715.4 717.7 15087.8 15133.2 15192.6 15267.0 15375.8 12492.9 12674.7 12739.7 12932.6 13139.3 10840.4 11113.5 11153.9 11699.2 11802.3 2935.7 3487.2 3552.2 3600.6 3654.1 82185.6 83218.0 83456.0 83966.6 84872.0 9591.5 9624.1 9641.2 9653.5 9854.4 20485.8 20671.4 20730.6 20837.4 20870.0 7043.8 7289.9 7560.7 7581.3 7672.6 4063.4 4082.2 4163.7 4168.8 4204.4 2758.0 3196.6 3375.8 3434.3 3436.6 601.6 610.3 619.0 626.5 628.1 434.7 446.7 456.6 489.6 513.9 7641.0 8592.8 8952.2 9480.5 9641.7 514.6 555.9 562.9 606.2 648.7 4096.8 4292.8 4344.9 4430.6 4482.9 98166.0 98689.5 98762.0 99100.0 99289.0 82299.0 84508.0 85197.0 86432.0 86901.0 75540.0 75899.0 75973.0 76182.0 76287.2 9013.3 9023.3 9172.5 9175.0 9210.2 44870.6 51668.7 52980.7 53856.7 54521.0 68739.0 70723.6 71694.0 72081.6 73319.6 11601.5 11683.9 11737.2 11946.6 12381.5 12031.7 12059.7 12506.2 12946.5 13201.0 11480.7 11672.2 12307.7 12323.4 12615.8 11480.8 11958.5 12112.5 12183.8 12304.3 11537.9 11953.9 12027.0 12033.8 12424.8 9824.0 10043.6 10193.3 10219.2 10284.5 9272.7 9477.8 9829.0 10056.9 10199.2 18:33:19 INFO - 2015-11-23 18:33:19,208 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:33:19 INFO - 2015-11-23 18:33:19,208 DEBUG : BROWSER_OUTPUT: 18:33:19 INFO - 2015-11-23 18:33:19,682 INFO : Browser exited with error code: 0 18:33:19 INFO - 2015-11-23 18:33:19,707 INFO : Completed test dromaeo_css (00:18:30) 18:33:19 INFO - 2015-11-23 18:33:19,707 INFO : Starting test kraken 18:33:19 INFO - 2015-11-23 18:33:19,707 DEBUG : operating with platform_type : mac_ 18:33:19 INFO - 2015-11-23 18:33:19,707 INFO : Initialising browser for kraken test... 18:33:19 INFO - 2015-11-23 18:33:19,719 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmplmQnYY/profile http://localhost:49269/getInfo.html 18:33:22 INFO - 2015-11-23 18:33:22,124 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:33:22 INFO - 2015-11-23 18:33:22,124 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:33:22 INFO - 2015-11-23 18:33:22,124 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 18:33:22 INFO - 2015-11-23 18:33:22,124 DEBUG : BROWSER_OUTPUT: __metrics 18:33:22 INFO - 2015-11-23 18:33:22,718 INFO : Browser initialized. 18:33:22 INFO - 2015-11-23 18:33:22,718 INFO : Running cycle 1/1 for kraken test... 18:33:22 INFO - 2015-11-23 18:33:22,718 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmplmQnYY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:33:34 INFO - 2015-11-23 18:33:34,712 DEBUG : BROWSER_OUTPUT: RSS: Main: 236355584 18:33:34 INFO - 2015-11-23 18:33:34,712 DEBUG : BROWSER_OUTPUT: 18:33:37 INFO - 2015-11-23 18:33:37,844 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 18:33:38 INFO - 2015-11-23 18:33:38,464 DEBUG : BROWSER_OUTPUT: RSS: Main: 249761792 18:33:38 INFO - 2015-11-23 18:33:38,464 DEBUG : BROWSER_OUTPUT: 18:33:41 INFO - 2015-11-23 18:33:41,079 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 18:33:41 INFO - 2015-11-23 18:33:41,682 DEBUG : BROWSER_OUTPUT: RSS: Main: 259641344 18:33:41 INFO - 2015-11-23 18:33:41,682 DEBUG : BROWSER_OUTPUT: 18:33:45 INFO - 2015-11-23 18:33:45,233 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 18:33:45 INFO - 2015-11-23 18:33:45,815 DEBUG : BROWSER_OUTPUT: RSS: Main: 262144000 18:33:45 INFO - 2015-11-23 18:33:45,815 DEBUG : BROWSER_OUTPUT: 18:33:47 INFO - 2015-11-23 18:33:47,615 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 18:33:48 INFO - 2015-11-23 18:33:48,208 DEBUG : BROWSER_OUTPUT: RSS: Main: 262983680 18:33:48 INFO - 2015-11-23 18:33:48,208 DEBUG : BROWSER_OUTPUT: 18:33:50 INFO - 2015-11-23 18:33:50,305 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:33:50 INFO - 2015-11-23 18:33:50,897 DEBUG : BROWSER_OUTPUT: RSS: Main: 264970240 18:33:50 INFO - 2015-11-23 18:33:50,897 DEBUG : BROWSER_OUTPUT: 18:33:53 INFO - 2015-11-23 18:33:53,617 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 18:33:54 INFO - 2015-11-23 18:33:54,207 DEBUG : BROWSER_OUTPUT: RSS: Main: 266346496 18:33:54 INFO - 2015-11-23 18:33:54,208 DEBUG : BROWSER_OUTPUT: 18:33:57 INFO - 2015-11-23 18:33:57,275 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 18:33:57 INFO - 2015-11-23 18:33:57,868 DEBUG : BROWSER_OUTPUT: RSS: Main: 266231808 18:33:57 INFO - 2015-11-23 18:33:57,868 DEBUG : BROWSER_OUTPUT: 18:34:00 INFO - 2015-11-23 18:34:00,935 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 18:34:01 INFO - 2015-11-23 18:34:01,520 DEBUG : BROWSER_OUTPUT: RSS: Main: 264638464 18:34:01 INFO - 2015-11-23 18:34:01,520 DEBUG : BROWSER_OUTPUT: 18:34:03 INFO - 2015-11-23 18:34:03,174 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:34:03 INFO - 2015-11-23 18:34:03,765 DEBUG : BROWSER_OUTPUT: RSS: Main: 265191424 18:34:03 INFO - 2015-11-23 18:34:03,766 DEBUG : BROWSER_OUTPUT: 18:34:05 INFO - 2015-11-23 18:34:05,529 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:34:06 INFO - 2015-11-23 18:34:06,153 DEBUG : BROWSER_OUTPUT: RSS: Main: 264892416 18:34:06 INFO - 2015-11-23 18:34:06,153 DEBUG : BROWSER_OUTPUT: 18:34:08 INFO - 2015-11-23 18:34:08,155 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:34:08 INFO - 2015-11-23 18:34:08,748 DEBUG : BROWSER_OUTPUT: RSS: Main: 265416704 18:34:08 INFO - 2015-11-23 18:34:08,748 DEBUG : BROWSER_OUTPUT: 18:34:11 INFO - 2015-11-23 18:34:11,326 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:34:11 INFO - 2015-11-23 18:34:11,938 DEBUG : BROWSER_OUTPUT: RSS: Main: 265048064 18:34:11 INFO - 2015-11-23 18:34:11,938 DEBUG : BROWSER_OUTPUT: 18:34:14 INFO - 2015-11-23 18:34:14,367 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:34:14 INFO - 2015-11-23 18:34:14,958 DEBUG : BROWSER_OUTPUT: RSS: Main: 265736192 18:34:14 INFO - 2015-11-23 18:34:14,958 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,567 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 18:34:16 INFO - 2015-11-23 18:34:16,825 DEBUG : BROWSER_OUTPUT: RSS: Main: 264470528 18:34:16 INFO - 2015-11-23 18:34:16,826 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,826 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:34:16 INFO - 2015-11-23 18:34:16,826 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:34:16 INFO - 2015-11-23 18:34:16,826 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:34:16 INFO - 2015-11-23 18:34:16,826 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:34:16 INFO - 2015-11-23 18:34:16,826 DEBUG : BROWSER_OUTPUT: |0;ai-astar;114;149;169;95;161;103;159;169;145;169 18:34:16 INFO - 2015-11-23 18:34:16,826 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;123;145;155;122;141;157;149;168;154 18:34:16 INFO - 2015-11-23 18:34:16,826 DEBUG : BROWSER_OUTPUT: |2;audio-dft;230;270;220;263;196;274;245;194;196;191 18:34:16 INFO - 2015-11-23 18:34:16,827 DEBUG : BROWSER_OUTPUT: |3;audio-fft;62;65;69;116;55;58;59;59;88;55 18:34:16 INFO - 2015-11-23 18:34:16,827 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;77;80;123;64;111;113;118;102;69;113 18:34:16 INFO - 2015-11-23 18:34:16,827 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;117;96;90;90;91;90;91;92;89;94 18:34:16 INFO - 2015-11-23 18:34:16,827 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;131;99;152;129;152;115;97;97;97;97 18:34:16 INFO - 2015-11-23 18:34:16,827 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;91;92;116;147;150;147;94;118;117;90 18:34:16 INFO - 2015-11-23 18:34:16,827 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;59;59;57;59;80;89;88;95;72;57 18:34:16 INFO - 2015-11-23 18:34:16,827 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;65;65;64;66;67;66;66;66;59;95 18:34:16 INFO - 2015-11-23 18:34:16,827 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;84;86;84;85;87;84;84;87;84 18:34:16 INFO - 2015-11-23 18:34:16,827 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;124;145;126;133;127;127;126;121;144;131 18:34:16 INFO - 2015-11-23 18:34:16,827 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;138;144;156;136;142;137;138;138;139;140 18:34:16 INFO - 2015-11-23 18:34:16,827 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;65;67;67;66;64;67;67;65;67 18:34:16 INFO - 2015-11-23 18:34:16,827 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:34:16 INFO - 2015-11-23 18:34:16,828 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:34:16 INFO - 2015-11-23 18:34:16,828 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,822 18:34:16 INFO - 2015-11-23 18:34:16,828 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:34:16 INFO - 2015-11-23 18:34:16,828 DEBUG : BROWSER_OUTPUT: __startTimestamp1448332456820__endTimestamp 18:34:16 INFO - 2015-11-23 18:34:16,828 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,828 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:34:16 INFO - 2015-11-23 18:34:16,828 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:34:16 INFO - 2015-11-23 18:34:16,828 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,828 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:143.30 Median:165.00 stddev:28.66 (17.4%) stddev-sans-first:28.37 18:34:16 INFO - 2015-11-23 18:34:16,828 DEBUG : BROWSER_OUTPUT: Values: 114.0 149.0 169.0 95.0 161.0 103.0 159.0 169.0 145.0 169.0 18:34:16 INFO - 2015-11-23 18:34:16,828 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,829 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:146.00 Median:151.50 stddev:14.49 (9.6%) stddev-sans-first:15.37 18:34:16 INFO - 2015-11-23 18:34:16,829 DEBUG : BROWSER_OUTPUT: Values: 146.0 123.0 145.0 155.0 122.0 141.0 157.0 149.0 168.0 154.0 18:34:16 INFO - 2015-11-23 18:34:16,829 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,829 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:227.90 Median:237.50 stddev:33.39 (14.1%) stddev-sans-first:35.41 18:34:16 INFO - 2015-11-23 18:34:16,829 DEBUG : BROWSER_OUTPUT: Values: 230.0 270.0 220.0 263.0 196.0 274.0 245.0 194.0 196.0 191.0 18:34:16 INFO - 2015-11-23 18:34:16,829 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,829 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:68.60 Median:60.50 stddev:19.28 (31.9%) stddev-sans-first:20.30 18:34:16 INFO - 2015-11-23 18:34:16,829 DEBUG : BROWSER_OUTPUT: Values: 62.0 65.0 69.0 116.0 55.0 58.0 59.0 59.0 88.0 55.0 18:34:16 INFO - 2015-11-23 18:34:16,829 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,829 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:97.00 Median:93.50 stddev:22.14 (23.7%) stddev-sans-first:22.27 18:34:16 INFO - 2015-11-23 18:34:16,829 DEBUG : BROWSER_OUTPUT: Values: 77.0 80.0 123.0 64.0 111.0 113.0 118.0 102.0 69.0 113.0 18:34:16 INFO - 2015-11-23 18:34:16,829 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,829 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:94.00 Median:91.00 stddev:8.35 (9.2%) stddev-sans-first:2.24 18:34:16 INFO - 2015-11-23 18:34:16,830 DEBUG : BROWSER_OUTPUT: Values: 117.0 96.0 90.0 90.0 91.0 90.0 91.0 92.0 89.0 94.0 18:34:16 INFO - 2015-11-23 18:34:16,830 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,830 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:116.60 Median:97.00 stddev:22.89 (23.6%) stddev-sans-first:23.68 18:34:16 INFO - 2015-11-23 18:34:16,830 DEBUG : BROWSER_OUTPUT: Values: 131.0 99.0 152.0 129.0 152.0 115.0 97.0 97.0 97.0 97.0 18:34:16 INFO - 2015-11-23 18:34:16,830 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,830 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:116.20 Median:120.00 stddev:24.59 (20.5%) stddev-sans-first:24.34 18:34:16 INFO - 2015-11-23 18:34:16,830 DEBUG : BROWSER_OUTPUT: Values: 91.0 92.0 116.0 147.0 150.0 147.0 94.0 118.0 117.0 90.0 18:34:16 INFO - 2015-11-23 18:34:16,830 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,830 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:71.50 Median:76.00 stddev:15.25 (20.1%) stddev-sans-first:15.49 18:34:16 INFO - 2015-11-23 18:34:16,830 DEBUG : BROWSER_OUTPUT: Values: 59.0 59.0 57.0 59.0 80.0 89.0 88.0 95.0 72.0 57.0 18:34:16 INFO - 2015-11-23 18:34:16,830 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,830 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:66.00 stddev:9.78 (14.8%) stddev-sans-first:10.32 18:34:16 INFO - 2015-11-23 18:34:16,830 DEBUG : BROWSER_OUTPUT: Values: 65.0 65.0 64.0 66.0 67.0 66.0 66.0 66.0 59.0 95.0 18:34:16 INFO - 2015-11-23 18:34:16,831 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,831 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:85.00 stddev:1.25 (1.5%) stddev-sans-first:1.32 18:34:16 INFO - 2015-11-23 18:34:16,831 DEBUG : BROWSER_OUTPUT: Values: 85.0 84.0 86.0 84.0 85.0 87.0 84.0 84.0 87.0 84.0 18:34:16 INFO - 2015-11-23 18:34:16,831 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,831 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:130.40 Median:129.00 stddev:8.14 (6.3%) stddev-sans-first:8.30 18:34:16 INFO - 2015-11-23 18:34:16,831 DEBUG : BROWSER_OUTPUT: Values: 124.0 145.0 126.0 133.0 127.0 127.0 126.0 121.0 144.0 131.0 18:34:16 INFO - 2015-11-23 18:34:16,831 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,831 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.80 Median:139.50 stddev:5.85 (4.2%) stddev-sans-first:6.11 18:34:16 INFO - 2015-11-23 18:34:16,831 DEBUG : BROWSER_OUTPUT: Values: 138.0 144.0 156.0 136.0 142.0 137.0 138.0 138.0 139.0 140.0 18:34:16 INFO - 2015-11-23 18:34:16,831 DEBUG : BROWSER_OUTPUT: 18:34:16 INFO - 2015-11-23 18:34:16,831 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:67.00 stddev:1.14 (1.7%) stddev-sans-first:1.17 18:34:16 INFO - 2015-11-23 18:34:16,831 DEBUG : BROWSER_OUTPUT: Values: 67.0 65.0 67.0 67.0 66.0 64.0 67.0 67.0 65.0 67.0 18:34:16 INFO - 2015-11-23 18:34:16,831 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:34:16 INFO - 2015-11-23 18:34:16,832 DEBUG : BROWSER_OUTPUT: 18:34:17 INFO - 2015-11-23 18:34:17,457 INFO : Browser exited with error code: 0 18:34:17 INFO - 2015-11-23 18:34:17,501 INFO : Completed test kraken (00:00:57) 18:34:17 INFO - 2015-11-23 18:34:17,501 INFO : Starting test v8_7 18:34:17 INFO - 2015-11-23 18:34:17,501 DEBUG : operating with platform_type : mac_ 18:34:17 INFO - 2015-11-23 18:34:17,501 INFO : Initialising browser for v8_7 test... 18:34:17 INFO - 2015-11-23 18:34:17,513 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmp84l2yM/profile http://localhost:49269/getInfo.html 18:34:20 INFO - 2015-11-23 18:34:20,088 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:34:20 INFO - 2015-11-23 18:34:20,088 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:34:20 INFO - 2015-11-23 18:34:20,088 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 18:34:20 INFO - 2015-11-23 18:34:20,088 DEBUG : BROWSER_OUTPUT: __metrics 18:34:20 INFO - 2015-11-23 18:34:20,863 INFO : Browser initialized. 18:34:20 INFO - 2015-11-23 18:34:20,863 INFO : Running cycle 1/1 for v8_7 test... 18:34:20 INFO - 2015-11-23 18:34:20,863 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmp84l2yM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:34:32 INFO - 2015-11-23 18:34:32,962 DEBUG : BROWSER_OUTPUT: RSS: Main: 236748800 18:34:32 INFO - 2015-11-23 18:34:32,962 DEBUG : BROWSER_OUTPUT: 18:34:54 INFO - 2015-11-23 18:34:54,334 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49269/tests/v8_7/run.html (next: http://localhost:49269/tests/v8_7/run.html) 18:34:54 INFO - 2015-11-23 18:34:54,591 DEBUG : BROWSER_OUTPUT: RSS: Main: 248053760 18:34:54 INFO - 2015-11-23 18:34:54,591 DEBUG : BROWSER_OUTPUT: 18:34:54 INFO - 2015-11-23 18:34:54,591 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:34:54 INFO - 2015-11-23 18:34:54,591 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:34:54 INFO - 2015-11-23 18:34:54,591 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:34:54 INFO - 2015-11-23 18:34:54,592 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:34:54 INFO - 2015-11-23 18:34:54,592 DEBUG : BROWSER_OUTPUT: |0;Richards;670.2412868632708 18:34:54 INFO - 2015-11-23 18:34:54,592 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;158.78056525881232 18:34:54 INFO - 2015-11-23 18:34:54,592 DEBUG : BROWSER_OUTPUT: |2;Encrypt;294.3773918163085 18:34:54 INFO - 2015-11-23 18:34:54,592 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4995.024875621891 18:34:54 INFO - 2015-11-23 18:34:54,592 DEBUG : BROWSER_OUTPUT: |4;RayTrace;990.108803165183 18:34:54 INFO - 2015-11-23 18:34:54,592 DEBUG : BROWSER_OUTPUT: |5;Earley;840.3361344537815 18:34:54 INFO - 2015-11-23 18:34:54,592 DEBUG : BROWSER_OUTPUT: |6;Boyer;15089.55223880597 18:34:54 INFO - 2015-11-23 18:34:54,592 DEBUG : BROWSER_OUTPUT: |7;RegExp;27805.555555555555 18:34:54 INFO - 2015-11-23 18:34:54,592 DEBUG : BROWSER_OUTPUT: |8;Splay;658.5686145764938 18:34:54 INFO - 2015-11-23 18:34:54,592 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4570.776255707762 18:34:54 INFO - 2015-11-23 18:34:54,592 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:34:54 INFO - 2015-11-23 18:34:54,592 DEBUG : BROWSER_OUTPUT: __startTimestamp1448332494585__endTimestamp 18:34:54 INFO - 2015-11-23 18:34:54,593 DEBUG : BROWSER_OUTPUT: 18:34:54 INFO - 2015-11-23 18:34:54,593 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:34:54 INFO - 2015-11-23 18:34:54,593 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:34:54 INFO - 2015-11-23 18:34:54,593 DEBUG : BROWSER_OUTPUT: 18:34:54 INFO - 2015-11-23 18:34:54,593 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:670.24 Median:670.24 stddev:0.00 (0.0%) 18:34:54 INFO - 2015-11-23 18:34:54,593 DEBUG : BROWSER_OUTPUT: Values: 670.2 18:34:54 INFO - 2015-11-23 18:34:54,593 DEBUG : BROWSER_OUTPUT: 18:34:54 INFO - 2015-11-23 18:34:54,593 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:158.78 Median:158.78 stddev:0.00 (0.0%) 18:34:54 INFO - 2015-11-23 18:34:54,593 DEBUG : BROWSER_OUTPUT: Values: 158.8 18:34:54 INFO - 2015-11-23 18:34:54,593 DEBUG : BROWSER_OUTPUT: 18:34:54 INFO - 2015-11-23 18:34:54,593 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:294.38 Median:294.38 stddev:0.00 (0.0%) 18:34:54 INFO - 2015-11-23 18:34:54,593 DEBUG : BROWSER_OUTPUT: Values: 294.4 18:34:54 INFO - 2015-11-23 18:34:54,593 DEBUG : BROWSER_OUTPUT: 18:34:54 INFO - 2015-11-23 18:34:54,594 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4995.02 Median:4995.02 stddev:0.00 (0.0%) 18:34:54 INFO - 2015-11-23 18:34:54,594 DEBUG : BROWSER_OUTPUT: Values: 4995.0 18:34:54 INFO - 2015-11-23 18:34:54,594 DEBUG : BROWSER_OUTPUT: 18:34:54 INFO - 2015-11-23 18:34:54,594 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:990.11 Median:990.11 stddev:0.00 (0.0%) 18:34:54 INFO - 2015-11-23 18:34:54,594 DEBUG : BROWSER_OUTPUT: Values: 990.1 18:34:54 INFO - 2015-11-23 18:34:54,594 DEBUG : BROWSER_OUTPUT: 18:34:54 INFO - 2015-11-23 18:34:54,594 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:840.34 Median:840.34 stddev:0.00 (0.0%) 18:34:54 INFO - 2015-11-23 18:34:54,594 DEBUG : BROWSER_OUTPUT: Values: 840.3 18:34:54 INFO - 2015-11-23 18:34:54,594 DEBUG : BROWSER_OUTPUT: 18:34:54 INFO - 2015-11-23 18:34:54,594 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:15089.55 Median:15089.55 stddev:0.00 (0.0%) 18:34:54 INFO - 2015-11-23 18:34:54,594 DEBUG : BROWSER_OUTPUT: Values: 15089.6 18:34:54 INFO - 2015-11-23 18:34:54,594 DEBUG : BROWSER_OUTPUT: 18:34:54 INFO - 2015-11-23 18:34:54,594 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:27805.56 Median:27805.56 stddev:0.00 (0.0%) 18:34:54 INFO - 2015-11-23 18:34:54,595 DEBUG : BROWSER_OUTPUT: Values: 27805.6 18:34:54 INFO - 2015-11-23 18:34:54,595 DEBUG : BROWSER_OUTPUT: 18:34:54 INFO - 2015-11-23 18:34:54,595 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:658.57 Median:658.57 stddev:0.00 (0.0%) 18:34:54 INFO - 2015-11-23 18:34:54,595 DEBUG : BROWSER_OUTPUT: Values: 658.6 18:34:54 INFO - 2015-11-23 18:34:54,595 DEBUG : BROWSER_OUTPUT: 18:34:54 INFO - 2015-11-23 18:34:54,595 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4570.78 Median:4570.78 stddev:0.00 (0.0%) 18:34:54 INFO - 2015-11-23 18:34:54,595 DEBUG : BROWSER_OUTPUT: Values: 4570.8 18:34:54 INFO - 2015-11-23 18:34:54,595 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:34:54 INFO - 2015-11-23 18:34:54,595 DEBUG : BROWSER_OUTPUT: 18:34:55 INFO - 2015-11-23 18:34:55,366 INFO : Browser exited with error code: 0 18:34:55 INFO - 2015-11-23 18:34:55,384 INFO : Completed test v8_7 (00:00:37) 18:34:55 INFO - 2015-11-23 18:34:55,682 INFO : Completed test suite (00:20:06) 18:34:55 INFO - 2015-11-23 18:34:55,683 DEBUG : Working with test: dromaeo_css 18:34:55 INFO - 2015-11-23 18:34:55,683 DEBUG : Generating results file: dromaeo_css 18:34:55 INFO - 2015-11-23 18:34:55,683 DEBUG : Working with test: kraken 18:34:55 INFO - 2015-11-23 18:34:55,683 DEBUG : Generating results file: kraken 18:34:55 INFO - 2015-11-23 18:34:55,684 INFO : javascript benchmark 18:34:55 INFO - 2015-11-23 18:34:55,684 DEBUG : Working with test: v8_7 18:34:55 INFO - 2015-11-23 18:34:55,684 DEBUG : Generating results file: v8_7 18:34:55 INFO - 2015-11-23 18:34:55,684 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:34:56 INFO - 2015-11-23 18:34:56,074 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,61]] 18:34:56 INFO - 2015-11-23 18:34:56,074 DEBUG : process_Request line: dromaeo_css 7690.56 graph.html#tests=[[72,1,61]] 18:34:56 INFO - 2015-11-23 18:34:56,074 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:34:56 INFO - 2015-11-23 18:34:56,102 DEBUG : process_Request line: kraken 1571.40 graph.html#tests=[[232,1,61]] 18:34:56 INFO - 2015-11-23 18:34:56,102 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:34:56 INFO - 2015-11-23 18:34:56,140 DEBUG : process_Request line: v8_7 18129.74 graph.html#tests=[[230,1,61]] 18:34:56 INFO - 2015-11-23 18:34:56,141 INFO : javascript benchmark 18:34:56 INFO - 2015-11-23 18:34:56,142 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3460.0138960396284, 3550.804705741183, 3561.2699396623693, 3596.352656521826, 3598.9280214395712, 5944.167816505687, 5950.137230101633, 5993.490553302969, 5993.790217342394, 6006.579539432241, 5418.322709661293, 5872.677002764846, 5895.9705201473935, 5972.432618901197, 6095.7256923438445, 5276.15581506958, 5337.946620533803, 5354.625176237645, 5370.3287089113865, 5423.647462914918, 4866.902661946759, 5391.514763671269, 5462.262594549745, 5486.506214440699, 5487.890242195173, 5192.92210616841, 5211.921821172686, 5296.3379577552805, 5371.248025276464, 5396.676199428047, 5345.412004679482, 5346.4386239445075, 5346.91979620306, 5361.0, 5376.059189641836, 5329.120695085307, 5330.92003619946, 5339.519443250126, 5393.325834270716, 5424.376196737347, 4396.032872767986, 4778.569928706398, 5479.890402191933, 5687.061634830248, 5950.940490595082, 6225.003999360126, 6327.873442531123, 6334.0, 6361.936380636227, 6376.585521941059, 6450.451711604473, 6451.258105317902, 6473.44975677068, 6479.514036447238, 6500.797352489804, 5941.910871336933, 5942.10868369748, 6041.667708276043, 6103.367974696828, 6143.38566143391, 6449.193850768654, 6574.178227721535, 6600.0, 6604.966975165094, 6622.1722284714415, 6066.636001839904, 6306.590071645328, 6314.400131987454, 6316.8420789479715, 6328.050792381087, 6340.112384266206, 6354.52341074412, 6436.098946147543, 6479.125318082093, 6482.513811464159, 5415.810446634349, 6323.683815809283, 6331.335209802996, 6338.461230795434, 6383.904241436382, 6128.846778830565, 6245.344238854946, 6326.905096423558, 6349.619022858551, 6353.714082866282, 4992.076465853828, 5043.0922433961505, 5043.344365232496, 5046.369203849519, 5107.770150343169, 4520.706154099973, 4581.289900065495, 4585.472670642852, 4594.1271158479785, 4637.768111594474, 5746.626469279484, 5931.169636250842, 5937.228160339215, 6013.609115407485, 6098.664573448503, 6409.070684750778, 6423.518236132215, 6427.967860160669, 6445.6454894981225, 6463.903041454382, 5983.042713165873, 5993.0710739835395, 6005.459508644243, 6023.066424704178, 6041.426064523863, 5409.107497262908, 5525.0, 5532.778688852401, 5545.140503222007, 5629.605927585029, 6411.839704007344, 6425.871482570323, 6433.453156481658, 6474.902876456857, 6541.378569035934, 6411.5832470889245, 6425.935740642533, 6451.935480645134, 6461.224653041665, 6474.773382931575, 6548.508861835477, 6554.705038273194, 6623.470122390101, 6636.203655561363, 6642.103316052271, 6159.891219580519, 6214.937850621436, 6305.527085468517, 6310.463610593151, 6320.936790632035, 6348.603307272393, 6390.5526613136635, 6432.549721519449, 6436.0989461476365, 6443.742250309935, 5448.591355648421, 6117.827404801005, 6252.562320637512, 6298.937010630018, 6303.936960630335, 6147.432404736764, 6271.122042914087, 6280.937190628035, 6293.276273228637, 6297.086922396318, 6191.907121393273, 6219.937800622117, 6252.687365631609, 6258.155149054819, 6267.0, 6038.879222415615, 6132.325444201224, 6173.567850250619, 6293.748250069946, 6313.936860631518, 6092.147099405998, 6164.4760195382105, 6167.629942203482, 6191.504679625529, 6213.130161777264], "unit": "ms", "name": "dojo.html", "value": 5853.1058917751225}, {"lowerIsBetter": false, "replicates": [555.0896529691307, 555.1450765820639, 570.57207094679, 580.2167074449491, 587.6797145555672, 31930.840345798268, 40022.19955600886, 42093.0, 42131.0, 42179.57820421795, 44880.3267950981, 45128.0974380513, 45456.545434545646, 45477.0, 45612.087758244816, 9756.951215243931, 9949.701508954742, 9952.701418957442, 9961.601535938555, 9975.600975960953, 8941.64233430662, 9222.86165707515, 9733.270004749636, 9773.56018979144, 9847.753806154868, 9411.388259763127, 9795.363301385387, 9799.804003919917, 9828.655997040105, 9836.213102951746, 9740.269479789009, 9782.755431114208, 9794.657186998493, 9855.507224638775, 9885.802283954352, 9650.372725772873, 9718.222542196607, 9780.853287200698, 9799.755006124833, 9823.214142868554, 33872.153196170046, 41654.37518437226, 41871.58128418707, 42114.36828447576, 42532.78733606312, 3165.4618714818534, 3428.434308339121, 3469.9826500867584, 3496.30073985204, 3787.431885217212, 28874.84500619973, 29049.0, 29394.0431698952, 29394.70605293941, 29881.551776723365, 2473.4434752180796, 2746.5468197747346, 2780.666320041588, 2837.0779496663667, 2997.8201307921377, 443643.0, 447731.0, 449094.7545262285, 449310.0, 449514.50485495053, 61601.075983860275, 62708.0, 66608.0, 67440.6627966857, 68402.0, 66397.33602663911, 66536.66731666311, 66637.0, 66786.0, 67319.0, 15600.843991560167, 15790.605234869221, 15800.841991579937, 15942.760858587131, 15995.440159594358, 11999.520019199133, 12001.699957501132, 12034.217775844541, 12118.272903625635, 12138.635840924786, 17399.0, 21153.25963591267, 21681.56636867254, 21686.89156554207, 21759.67360489594, 6890.655467226618, 8274.338052955749, 8314.251717345467, 8480.575971201415, 8527.275181609508, 997.8004399120205, 998.4823068935178, 1001.839705647093, 1030.6083688198587, 1088.8094583448024, 29977.700222997926, 29984.85007574948, 30626.54060189099, 30633.38733225323, 30875.0, 1035.803197392493, 1046.706922061824, 1141.9836345652377, 1142.1776321048997, 1182.9171957963033, 14738.11571305725, 14950.925245373703, 15095.849041509664, 15106.697866042838, 15329.770053449207, 488508.0, 494245.0, 499731.0, 500445.0, 501473.0, 57826.4217357821, 58258.70870645619, 58409.0, 58799.0, 58904.70547647234, 1418.3971811979868, 1420.183394548139, 1448.920309382994, 1453.8328092269523, 1455.4760286296926, 29511.55732663969, 29639.703602963695, 29870.850645746632, 30244.697553025067, 30393.848030759706, 52093.739531302104, 52896.73551632217, 53007.734961324946, 53203.733981329846, 53355.93288134293, 457219.4278057177, 458214.0, 459942.0, 460790.69604651764, 461701.0, 32045.0, 32760.83619581887, 33790.66209337974, 34013.82993085118, 34165.829170853984, 42969.3554596675, 43240.78379608082, 43442.0, 43551.0, 43814.34278485889, 31381.74473021145, 32281.838590806896, 36148.0, 36634.26731465409, 36933.891983240545, 36102.09744756402, 37102.25795484129, 37922.45036145983, 38123.0, 39558.0, 36475.27049459049, 36932.63067369292, 37609.81195094007, 37721.0, 37836.0, 32795.0, 33681.663183367855, 33691.74556713781, 33791.831040845624, 33905.32189356248, 21148.47128821792, 24302.878485608166, 25324.0, 25695.614565781154, 25964.740352596233], "unit": "ms", "name": "ext.html", "value": 19916.954486457376}, {"lowerIsBetter": false, "replicates": [326.1731510620577, 354.75521889895987, 359.8164935882701, 360.5547149270652, 361.3639993611245, 12703.682407939807, 18494.44516664499, 19659.803401965975, 19917.800821991776, 20911.790882091176, 20287.797122028776, 20479.18083276671, 20596.89701551494, 20639.277625283117, 20931.790682093175, 5943.702814859262, 6075.544334174932, 6087.969560152215, 6092.055731361646, 6100.7864724734645, 5905.763769449217, 5925.644461332334, 5996.310424301196, 5998.190244317027, 6052.394760523935, 5865.032269675498, 5903.409659034105, 5919.467247947706, 5972.25346831646, 6004.429579189992, 5790.942090579082, 5896.174535565003, 5908.645481271137, 5925.911111333334, 5927.199828023205, 5003.699778013331, 5084.542391184811, 5096.821611243626, 5125.1799712045895, 5138.897222055575, 25298.241052768448, 25361.11236106746, 25562.488750224893, 25609.286653435334, 25643.487130257476, 2072.927447539344, 2086.812186903186, 2089.8746075235535, 2108.852380333362, 2136.8931553422267, 14698.0, 14798.778018329735, 15041.849581504155, 15151.242437878172, 15609.297581608742, 1727.904965226912, 1760.991254805867, 1762.541739147818, 1785.4643606917873, 1788.8121747216614, 97968.0, 98670.50664746702, 100146.0, 100342.49828750883, 101074.0, 10263.538140783683, 10288.382697038202, 10557.102646275, 10601.151907847354, 10698.197635177392, 11885.821712674317, 12249.571265005681, 12547.24716517012, 12566.434510447052, 12632.684182895317, 7559.470837041355, 7649.847003059907, 8233.46482478637, 8240.917590824134, 8271.2142346477, 7948.443608947318, 8085.514869107872, 8148.26665600087, 8292.834143317099, 8408.075111737704, 6155.753769849245, 6158.414950579688, 6170.568060235741, 6241.0, 6267.2165979252595, 1818.4635532517675, 1820.6085691576375, 1834.8256915592997, 1839.3194518028147, 1842.4380563927862, 810.2990912860452, 908.1962463220098, 941.6205259295166, 1014.6905193915926, 1033.256055639938, 21162.47093822636, 21217.0, 21754.0, 21792.89103554504, 21892.562148756937, 934.303943562037, 997.518684753141, 999.0169513515666, 1021.0861279155115, 1023.4319952426475, 9279.489628070522, 10202.64290749835, 11168.218224724355, 11890.0, 11933.940330298294, 438847.0, 542303.6157107422, 543378.0, 608940.0, 672560.0, 96895.51552242193, 116249.0, 118491.40754296345, 128629.0, 129218.0, 1164.7670465906683, 1181.8699943006263, 1191.5472120594118, 1191.5769901684778, 1197.0722689915385, 16815.327386904388, 18397.74997266934, 18662.78691885023, 18666.533336666693, 18759.437216883514, 40382.39426408665, 48934.0, 49298.75350623224, 50246.49753502418, 52161.47838521718, 62952.5571164329, 63522.0, 69984.65007674928, 71510.64244678743, 71539.0, 9567.138957493858, 9688.273379496706, 9873.456959867277, 9981.401115933068, 9987.450690211817, 11834.0, 12453.688657783629, 15447.0731756095, 19401.514962125497, 19863.702044469057, 15661.765073524135, 15790.76313855316, 15868.2065896707, 16073.839261607702, 16228.675426491169, 15335.923320383774, 15629.452969146025, 15646.139462329682, 15912.204389780696, 16004.519864404087, 15852.44516441919, 16215.18924053769, 16249.675006499567, 16383.508494745176, 16518.83481165173, 9139.908600913906, 9508.239340852853, 9725.610975560898, 9854.965228650888, 9876.40741555509, 10215.080642742189, 13999.110120133651, 15692.0, 16096.356145754038, 16355.509334719978], "unit": "ms", "name": "jquery.html", "value": 10017.540847170838}, {"lowerIsBetter": false, "replicates": [1514.7273490771656, 1618.603442156671, 1924.5284905198228, 1935.3710044235622, 1976.337926794524, 7748.457607967431, 7885.960570197155, 8439.873401898976, 8790.472571645705, 8838.116188381158, 8301.91698083019, 8491.91508084919, 8522.531260780655, 8576.27101696355, 8604.05355410906, 2352.0826877517784, 2363.2555744940373, 2367.910761049919, 2378.2389635316704, 2394.1141777542334, 2345.1791872844537, 2378.797802186816, 2420.6127019676856, 2438.122275980654, 2446.2538925627678, 2339.1462116327502, 2373.893174807129, 2377.904883804654, 2392.9162479313227, 2408.7350391456926, 2296.196331284054, 2339.0643742502966, 2341.718993720747, 2369.5853225685523, 2384.141708984764, 2098.223657246813, 2364.716234051925, 2373.41851246444, 2405.9639105413435, 2455.0793452455327, 7033.929660703378, 7453.888191677129, 8253.0, 8620.310375169971, 8633.65465381384, 4600.217962946307, 4901.779419926076, 5188.092083885305, 5193.221016847466, 5235.188545775411, 3474.062003259106, 3479.8260086995547, 3494.9650503494895, 3539.9115022124397, 3566.197605538759, 1029.27950434696, 1031.5203430404897, 1050.0549505445083, 1063.4895250279908, 1067.0609863320228, 11329.150313726503, 11341.659750207507, 11366.090712743044, 11412.258203216765, 11544.884551154464, 4490.259107247268, 4541.931871021938, 4560.749158796298, 4569.702969306984, 4585.426821647294, 9109.908900910907, 9116.544172791333, 9133.543322833832, 9171.037041110721, 9172.03693612161, 4421.38100665907, 5127.974360128175, 5167.069927413027, 5181.06740786662, 5185.170372740344, 4263.488381394191, 4335.52309245989, 4650.302454631765, 4754.95245047552, 4757.191277482767, 3749.7000239980734, 3755.605661405568, 3781.773093614392, 3791.867284645024, 3817.8854634361014, 1171.3674615707594, 1181.2203945395997, 1185.3895243949373, 1187.441902305915, 1195.7190060335813, 1361.0812701426498, 1362.3937347880296, 1376.0711519724146, 1417.0930604413184, 1423.4021710881455, 7913.406494512935, 8047.9537660103815, 8051.879221811678, 8052.396070294752, 8064.677412903536, 499.8200647766801, 508.84225889974226, 509.17259453388243, 512.1140427061131, 512.9307543481582, 6031.4270144336215, 6049.969750151221, 6201.96899015502, 6232.470240029648, 6240.500759939194, 9504.239660827117, 9731.978142295098, 9793.804123917482, 9829.508524573886, 9857.852132218022, 9519.333646644669, 9637.951810240904, 9660.275479338938, 9702.0, 9729.97835227305, 7539.396848252017, 7544.283293087148, 7582.924170758332, 7648.38812894967, 7676.731314403969, 7241.493095483265, 7271.636418179176, 7311.232320606472, 7332.633368331669, 7363.8159046024275, 8509.829803403773, 8671.262942649946, 8677.349198810241, 8723.258523025614, 8724.738257852274, 8862.822743544964, 8938.284937205139, 8958.865617015881, 9007.009228984676, 9058.0, 6527.640979746159, 6647.667616619053, 6702.798916032527, 6936.2023367311385, 7012.789616311519, 8131.674733010614, 8138.267555919995, 8189.099199087977, 8203.83592328162, 8229.382796290183, 7550.886736698844, 7729.111152217567, 7767.766966990999, 7820.765377038698, 7840.607969601383, 7562.0925488941675, 7594.670932586886, 7606.429517786078, 7699.0, 7724.188960159102, 6566.9014964774615, 7700.1144868338615, 7729.0, 7739.883901741591, 7754.0, 6949.965250173716, 7025.437964962893, 7038.788836334918, 7048.118985126859, 7064.293570642894, 7119.216886142417, 7128.0, 7140.285971402818, 7145.714171433292, 7163.8567228656175], "unit": "ms", "name": "mootools.html", "value": 4663.302917925158}, {"lowerIsBetter": false, "replicates": [324.2251020062052, 324.654243230959, 329.7082082357113, 331.8208167589502, 336.8652538984406, 6934.410575101122, 7002.229754726976, 7038.401735852446, 7050.118735158106, 7067.975143604174, 6881.41507971823, 6934.514583979124, 6952.4438044956405, 6972.860542789142, 7059.7882063538045, 4979.651424400285, 5016.398032236137, 5025.974870125662, 5033.018561380532, 5069.721165335905, 4404.515503294635, 4862.5380588844, 5035.295058691786, 5040.042391945537, 5062.6456148069465, 4958.851234462972, 4969.577585905203, 4975.12935236334, 4981.850544483671, 4983.651144419892, 4009.278329900617, 4965.42897566779, 4968.801247950114, 4990.0, 5017.0, 4023.577524359929, 4103.363978583325, 4120.299549076664, 4213.452251207324, 4223.282042052828, 5349.170878513836, 5363.517283444508, 6664.566803157779, 6686.3982241598005, 6723.226828914689, 4827.927581086286, 5871.236739223917, 5950.077737950624, 5953.940460595425, 5959.897418977503, 7195.964020179866, 7414.59219742914, 7420.443466739962, 7428.851422971565, 7442.8139296517475, 4736.265878788793, 4745.95254047462, 4747.477777444478, 4750.168720473937, 4767.594754445876, 7806.375489960789, 7901.20987901211, 7901.249381308767, 7901.486403383819, 7908.367330613537, 4669.159551280802, 4793.1372352976105, 4795.184818580849, 4796.8081276749235, 4798.928016079762, 4873.585745211626, 4883.804647814048, 4891.779869905863, 4895.241237608177, 4902.852914412573, 5912.970435147797, 5923.940760592425, 5925.111233314951, 5927.525797936155, 5980.581359304807, 7323.670434830446, 7502.587357695379, 7507.474476786681, 7528.774136775905, 7537.698492060256, 1677.1027500287532, 1711.6234428425726, 1714.8456638902558, 1716.1076240355196, 1716.476474675236, 893.8167675626481, 1070.539667942792, 1081.3728037738092, 1086.538221255963, 1093.316677076827, 6825.624590647462, 6827.0783444235385, 6848.589084654936, 6871.553349032297, 6905.861882762417, 7755.3407960324, 7755.844883102306, 7773.844523109507, 7773.883391749128, 7791.805204869924, 6739.196281822792, 6808.455323574102, 6850.623215723183, 6890.345417185359, 6935.4104901082965, 3989.860354887623, 4092.0, 4097.242010228058, 4124.175164967019, 4139.089400331922, 7939.0473143222025, 8155.918440815634, 8169.918300816916, 8189.303909167668, 8266.462679925786, 8104.675812967415, 8134.877976830352, 8139.552324622085, 8153.987570689203, 8157.67369305233, 7858.567778772108, 8109.675612975533, 8128.512289262781, 8137.877931831027, 8153.22544358285, 7848.646810893638, 7855.253750893656, 7888.960555197187, 7901.841963160819, 7917.168697286932, 7075.610841403773, 7520.323170914644, 7696.230376962483, 7727.343175829893, 7767.72812951544, 6048.334683184758, 7418.48070635072, 8133.820596013663, 8166.0, 8169.591520424074, 4834.8791280218275, 4839.467658557626, 4840.370751802243, 4842.370491836039, 4843.782029808596, 6479.963205887224, 7479.364254038468, 7841.843163136819, 7845.450818442654, 7864.174261702439, 7530.209328020479, 7532.510386824839, 7539.660715267936, 7546.962265188639, 7582.241775822595, 6414.550981431255, 7040.612766297902, 7394.667239974106, 7497.737579184703, 7517.6241187939295, 7277.345038946521, 7315.829467285209, 7325.194228634952, 7327.816804579928, 7351.044364232829, 7463.402927765657, 7479.6634151464405, 7489.438292128003, 7530.058742657168, 7545.811354716176, 5741.885162296814, 7331.560106393634, 7335.8532829344185, 7344.449166312441, 7360.116785985716], "unit": "ms", "name": "prototype.html", "value": 5371.157464386111}, {"lowerIsBetter": false, "replicates": [676.2257215488263, 696.3106524540705, 734.6804140199014, 779.0573406178522, 815.5595978171791, 15285.235738213087, 15429.768553471707, 15488.3030263638, 15552.0, 15574.922125389372, 11959.342236177006, 14003.238898774414, 14481.0, 14484.348204330805, 14507.911906606994, 750.1373420566338, 757.7650928212245, 919.0258326174243, 929.3355250995537, 943.4527973775229, 794.9125596184415, 934.9439033657968, 942.5381563034133, 942.5569982108398, 948.6205517792869, 982.1013772398283, 982.7592239901235, 990.3958585262984, 999.1307562420704, 999.3054826895311, 963.325672029582, 972.2950860626061, 979.7795496013412, 984.3552473130111, 993.1508560181086, 691.3604915453236, 704.3801454719865, 708.5429897715995, 715.427657873699, 717.7093277222733, 15087.773683394758, 15133.243337833064, 15192.620184495365, 15267.0, 15375.846241537552, 12492.875071249353, 12674.746505069847, 12739.681507962376, 12932.612021639367, 13139.27733974631, 10840.403777792219, 11113.499892504777, 11153.944230278878, 11699.23954942926, 11802.291862488193, 2935.7211064948797, 3487.1805125795418, 3552.236269202108, 3600.6219346968455, 3654.086478380405, 82185.58907205425, 83218.0, 83456.0, 83966.58016709938, 84872.0, 9591.520423978773, 9624.133827955378, 9641.228701703847, 9653.517324133765, 9854.359466634645, 20485.795142048388, 20671.379858604265, 20730.58538829215, 20837.37487875336, 20870.0, 7043.788686339316, 7289.890651640229, 7560.697572097055, 7581.279778421042, 7672.578008209491, 4063.4108054331955, 4082.2447847148374, 4163.652041190516, 4168.791560421967, 4204.411382406465, 2758.0209025795953, 3196.568463257477, 3375.835336808797, 3434.261633748756, 3436.60479044913, 601.5578549765919, 610.3438803286484, 619.0219453263833, 626.5238418801653, 628.0798630007062, 434.6547435686545, 446.7475876129976, 456.6369387378672, 489.56428778387266, 513.9152039913372, 7641.0, 8592.828143437097, 8952.23905967987, 9480.525973701288, 9641.662541811003, 514.5677630790154, 555.9153175591532, 562.8564715997476, 606.1937622961451, 648.6951132967498, 4096.795160241976, 4292.806823692941, 4344.913101737948, 4430.556944305544, 4482.8655140345845, 98166.0, 98689.50655246677, 98762.01237987672, 99100.0, 99289.0, 82299.0, 84508.0, 85197.0, 86432.0, 86901.0, 75540.0, 75899.0, 75973.0, 76182.0, 76287.23712762912, 9013.324000700104, 9023.323250756088, 9172.482552342972, 9175.03662115495, 9210.171084602418, 44870.551294486635, 51668.70828229324, 52980.73509632427, 53856.73071634774, 54521.0, 68738.9689154653, 70723.64638176776, 71694.0, 72081.6395918038, 73319.63340183265, 11601.477933493183, 11683.88316116828, 11737.237079589799, 11946.641600751991, 12381.504739810307, 12031.699207519881, 12059.698507537381, 12506.249625022529, 12946.546870859474, 13201.0, 11480.712982175512, 11672.182947193955, 12307.692307692021, 12323.445444955178, 12615.81076283838, 11480.770384592095, 11958.521659133885, 12112.515499379926, 12183.75632487363, 12304.26174429537, 11537.942310288396, 11953.880461195624, 12027.037836973197, 12033.819492707791, 12424.813627795409, 9823.950880245553, 10043.648472303434, 10193.337433066827, 10219.233557483367, 10284.537195826057, 9272.675456358995, 9477.834177674622, 9829.0, 10056.899431005597, 10199.184065274612], "unit": "ms", "name": "yui.html", "value": 7072.632954606552}], "name": "dromaeo_css", "value": 7690.563517330699}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 149.0, 169.0, 95.0, 161.0, 103.0, 159.0, 169.0, 145.0, 169.0], "unit": "ms", "name": "ai-astar", "value": 143.3}, {"lowerIsBetter": true, "replicates": [146.0, 123.0, 145.0, 155.0, 122.0, 141.0, 157.0, 149.0, 168.0, 154.0], "unit": "ms", "name": "audio-beat-detection", "value": 146.0}, {"lowerIsBetter": true, "replicates": [230.0, 270.0, 220.0, 263.0, 196.0, 274.0, 245.0, 194.0, 196.0, 191.0], "unit": "ms", "name": "audio-dft", "value": 227.9}, {"lowerIsBetter": true, "replicates": [62.0, 65.0, 69.0, 116.0, 55.0, 58.0, 59.0, 59.0, 88.0, 55.0], "unit": "ms", "name": "audio-fft", "value": 68.6}, {"lowerIsBetter": true, "replicates": [77.0, 80.0, 123.0, 64.0, 111.0, 113.0, 118.0, 102.0, 69.0, 113.0], "unit": "ms", "name": "audio-oscillator", "value": 97.0}, {"lowerIsBetter": true, "replicates": [117.0, 96.0, 90.0, 90.0, 91.0, 90.0, 91.0, 92.0, 89.0, 94.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 94.0}, {"lowerIsBetter": true, "replicates": [131.0, 99.0, 152.0, 129.0, 152.0, 115.0, 97.0, 97.0, 97.0, 97.0], "unit": "ms", "name": "imaging-darkroom", "value": 116.6}, {"lowerIsBetter": true, "replicates": [91.0, 92.0, 116.0, 147.0, 150.0, 147.0, 94.0, 118.0, 117.0, 90.0], "unit": "ms", "name": "imaging-desaturate", "value": 116.2}, {"lowerIsBetter": true, "replicates": [59.0, 59.0, 57.0, 59.0, 80.0, 89.0, 88.0, 95.0, 72.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 71.5}, {"lowerIsBetter": true, "replicates": [65.0, 65.0, 64.0, 66.0, 67.0, 66.0, 66.0, 66.0, 59.0, 95.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 67.9}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 86.0, 84.0, 85.0, 87.0, 84.0, 84.0, 87.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.0}, {"lowerIsBetter": true, "replicates": [124.0, 145.0, 126.0, 133.0, 127.0, 127.0, 126.0, 121.0, 144.0, 131.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 130.4}, {"lowerIsBetter": true, "replicates": [138.0, 144.0, 156.0, 136.0, 142.0, 137.0, 138.0, 138.0, 139.0, 140.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.8}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 67.0, 67.0, 66.0, 64.0, 67.0, 67.0, 65.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.2}], "name": "kraken", "value": 1571.4000000000003}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [670.2412868632708], "unit": "score", "name": "Richards", "value": 52.670584000000005}, {"lowerIsBetter": false, "replicates": [158.78056525881232], "unit": "score", "name": "DeltaBlue", "value": 416.41116400000016}, {"lowerIsBetter": false, "replicates": [294.3773918163085], "unit": "score", "name": "Encrypt", "value": 904.2168570000002}, {"lowerIsBetter": false, "replicates": [4995.024875621891], "unit": "score", "name": "Decrypt", "value": 53.289224103585696}, {"lowerIsBetter": false, "replicates": [990.108803165183], "unit": "score", "name": "RayTrace", "value": 747.3814975024974}, {"lowerIsBetter": false, "replicates": [840.3361344537815], "unit": "score", "name": "Earley", "value": 793.09097}, {"lowerIsBetter": false, "replicates": [15089.55223880597], "unit": "score", "name": "Boyer", "value": 44.16718199802176}, {"lowerIsBetter": false, "replicates": [27805.555555555555], "unit": "score", "name": "RegExp", "value": 32.762697302697305}, {"lowerIsBetter": false, "replicates": [658.5686145764938], "unit": "score", "name": "Splay", "value": 123.7395742771685}, {"lowerIsBetter": false, "replicates": [4570.776255707762], "unit": "score", "name": "NavierStokes", "value": 324.6713286713285}], "name": "v8_7", "value": 18129.73629067929}]} 18:34:56 INFO - RETURN: dromaeo_css: 7690.56 18:34:56 INFO - RETURN: kraken: 1571.40 18:34:56 INFO - RETURN: v8_7: 18129.74 18:34:56 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,61]]", "result": "18129.74"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,61]]", "result": "1571.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,61]]", "result": "7690.56"}}} 18:34:56 INFO - Return code: 0 18:34:56 INFO - # TBPL SUCCESS # 18:34:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:34:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:34:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:34:56 INFO - 2015-11-23 18:34:56.233 screenresolution[1682:10763] starting screenresolution argv=screenresolution get 18:34:56 INFO - 2015-11-23 18:34:56.235 screenresolution[1682:10763] Display 0: 1600x1200x32@60 18:34:56 INFO - 2015-11-23 18:34:56.241 screenresolution[1683:10766] starting screenresolution argv=screenresolution list 18:34:56 INFO - Available Modes on Display 0 18:34:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:34:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:34:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:34:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:34:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:34:56 INFO - Intel Iris: 18:34:56 INFO - Chipset Model: Intel Iris 18:34:56 INFO - Type: GPU 18:34:56 INFO - Bus: Built-In 18:34:56 INFO - VRAM (Dynamic, Max): 1536 MB 18:34:56 INFO - Vendor: Intel (0x8086) 18:34:56 INFO - Device ID: 0x0a2e 18:34:56 INFO - Revision ID: 0x0009 18:34:56 INFO - Displays: 18:34:56 INFO - Display: 18:34:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:34:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:34:56 INFO - Display Serial Number: 1600x1200 60 18:34:56 INFO - Main Display: Yes 18:34:56 INFO - Mirror: Off 18:34:56 INFO - Online: Yes 18:34:56 INFO - Rotation: Supported 18:34:56 INFO - Return code: 0 18:34:56 INFO - Running post-action listener: _resource_record_post_action 18:34:56 INFO - Running post-run listener: _resource_record_post_run 18:34:57 INFO - Total resource usage - Wall time: 1238s; CPU: 53.0%; Read bytes: 39110144; Write bytes: 696749568; Read time: 426; Write time: 2065 18:34:57 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 339231232; Write bytes: 339851264; Read time: 27049; Write time: 647 18:34:57 INFO - run-tests - Wall time: 1208s; CPU: 53.0%; Read bytes: 37144064; Write bytes: 345622016; Read time: 405; Write time: 1388 18:34:57 INFO - Running post-run listener: _upload_blobber_files 18:34:57 INFO - Blob upload gear active. 18:34:57 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:34:57 INFO - Copying logs to upload dir... 18:34:57 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1281.596042 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2015-11-23 18:34:57.590583) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 18:34:57.593785) ========= 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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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.009251 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:34:57.617399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:34:57.617711) ========= 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.Ym4bkGDOC2/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.amaOLzAC6b/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005068 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 18:34:57.666571) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 18:34:57.666873) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 18:34:57.667245) ========= ========= Total master_lag: 0.22 =========