builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0048 starttime: 1448295332.13 results: success (0) buildid: 20151123062128 builduid: 563983de6ca948699f612fe25d78c50b revision: bfd81e42d1ddc7071c7fab2598f3099aab30ca92 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.135321) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.135787) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.136101) ========= 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.jgcNMiRvsa/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.dtCiUIUD13/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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.005328 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.157782) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.158140) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.173876) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.174267) ========= 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.jgcNMiRvsa/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.dtCiUIUD13/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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.005414 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.222576) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.222899) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.223273) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.223581) ========= 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.jgcNMiRvsa/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.dtCiUIUD13/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 08:15:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 1.41G=0s 2015-11-23 08:15:32 (1.41 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119954 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.358246) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.358611) ========= 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.jgcNMiRvsa/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.dtCiUIUD13/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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.025835 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:32.407288) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 08:15:32.407654) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev bfd81e42d1ddc7071c7fab2598f3099aab30ca92 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev bfd81e42d1ddc7071c7fab2598f3099aab30ca92 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jgcNMiRvsa/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.dtCiUIUD13/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 08:15:32,567 truncating revision to first 12 chars 2015-11-23 08:15:32,567 Setting DEBUG logging. 2015-11-23 08:15:32,567 attempt 1/10 2015-11-23 08:15:32,567 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/bfd81e42d1dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-23 08:15:34,262 unpacking tar archive at: fx-team-bfd81e42d1dd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.931099 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 08:15:34.353028) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:34.353382) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:34.364837) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:34.365160) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 08:15:34.365600) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2015-11-23 08:15:34.365900) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jgcNMiRvsa/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.dtCiUIUD13/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 08:15:34 INFO - MultiFileLogger online at 20151123 08:15:34 in /builds/slave/test 08:15:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:15:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:15:34 INFO - {'append_to_log': False, 08:15:34 INFO - 'base_work_dir': '/builds/slave/test', 08:15:34 INFO - 'blob_upload_branch': 'Fx-Team', 08:15:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:15:34 INFO - 'branch': 'Fx-Team', 08:15:34 INFO - 'buildbot_json_path': 'buildprops.json', 08:15:34 INFO - 'config_files': ('talos/mac_config.py',), 08:15:34 INFO - 'default_actions': ('clobber', 08:15:34 INFO - 'read-buildbot-config', 08:15:34 INFO - 'download-and-extract', 08:15:34 INFO - 'populate-webroot', 08:15:34 INFO - 'create-virtualenv', 08:15:34 INFO - 'install', 08:15:34 INFO - 'run-tests'), 08:15:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:15:34 INFO - 'download_minidump_stackwalk': True, 08:15:34 INFO - 'download_symbols': 'ondemand', 08:15:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:15:34 INFO - 'tooltool.py': '/tools/tooltool.py', 08:15:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:15:34 INFO - '/tools/misc-python/virtualenv.py')}, 08:15:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:15:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:15:34 INFO - 'installer_path': 'installer.exe', 08:15:34 INFO - 'log_level': 'info', 08:15:34 INFO - 'log_name': 'talos', 08:15:34 INFO - 'log_to_console': True, 08:15:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:15:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:15:34 INFO - 'opt_config_files': (), 08:15:34 INFO - 'pip_index': False, 08:15:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:15:34 INFO - 'cmd': ('bash', 08:15:34 INFO - '-c', 08:15:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:15:34 INFO - 'enabled': True, 08:15:34 INFO - 'halt_on_failure': False, 08:15:34 INFO - 'name': 'check_screen_resolution'},), 08:15:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:15:34 INFO - 'cmd': ('bash', 08:15:34 INFO - '-c', 08:15:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:15:34 INFO - 'enabled': True, 08:15:34 INFO - 'halt_on_failure': False, 08:15:34 INFO - 'name': 'check_screen_resolution'},), 08:15:34 INFO - 'run_cmd_checks_enabled': True, 08:15:34 INFO - 'sps_profile': False, 08:15:34 INFO - 'sps_profile_interval': 0, 08:15:34 INFO - 'suite': 'dromaeojs', 08:15:34 INFO - 'system_bits': '64', 08:15:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:15:34 INFO - 'title': 't-yosemite-r7-0048', 08:15:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:15:34 INFO - 'use_talos_json': True, 08:15:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:15:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:15:34 INFO - 'work_dir': 'build'} 08:15:34 INFO - ##### 08:15:34 INFO - ##### Running clobber step. 08:15:34 INFO - ##### 08:15:34 INFO - Running pre-action listener: _resource_record_pre_action 08:15:34 INFO - Running main action method: clobber 08:15:34 INFO - rmtree: /builds/slave/test/build 08:15:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:15:36 INFO - Running post-action listener: _resource_record_post_action 08:15:36 INFO - ##### 08:15:36 INFO - ##### Running read-buildbot-config step. 08:15:36 INFO - ##### 08:15:36 INFO - Running pre-action listener: _resource_record_pre_action 08:15:36 INFO - Running main action method: read_buildbot_config 08:15:36 INFO - Using buildbot properties: 08:15:36 INFO - { 08:15:36 INFO - "properties": { 08:15:36 INFO - "buildnumber": 13, 08:15:36 INFO - "product": "firefox", 08:15:36 INFO - "script_repo_revision": "production", 08:15:36 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:15:36 INFO - "repository": "", 08:15:36 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:15:36 INFO - "buildid": "20151123062128", 08:15:36 INFO - "slavename": "t-yosemite-r7-0048", 08:15:36 INFO - "pgo_build": "False", 08:15:36 INFO - "basedir": "/builds/slave/test", 08:15:36 INFO - "project": "", 08:15:36 INFO - "platform": "yosemite_r7", 08:15:36 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 08:15:36 INFO - "slavebuilddir": "test", 08:15:36 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:15:36 INFO - "branch": "fx-team", 08:15:36 INFO - "repo_path": "integration/fx-team", 08:15:36 INFO - "stage_platform": "macosx64", 08:15:36 INFO - "builduid": "563983de6ca948699f612fe25d78c50b", 08:15:36 INFO - "revision": "bfd81e42d1ddc7071c7fab2598f3099aab30ca92" 08:15:36 INFO - }, 08:15:36 INFO - "sourcestamp": { 08:15:36 INFO - "repository": "", 08:15:36 INFO - "hasPatch": false, 08:15:36 INFO - "project": "", 08:15:36 INFO - "branch": "fx-team-macosx64-talos", 08:15:36 INFO - "changes": [ 08:15:36 INFO - { 08:15:36 INFO - "category": null, 08:15:36 INFO - "files": [ 08:15:36 INFO - { 08:15:36 INFO - "url": null, 08:15:36 INFO - "name": "https://queue.taskcluster.net/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 08:15:36 INFO - } 08:15:36 INFO - ], 08:15:36 INFO - "repository": "", 08:15:36 INFO - "rev": "bfd81e42d1ddc7071c7fab2598f3099aab30ca92", 08:15:36 INFO - "who": "felipc@gmail.com", 08:15:36 INFO - "when": 1448294764, 08:15:36 INFO - "number": 6695434, 08:15:36 INFO - "comments": "Bug 1210617 - [e10s] Implement PrivateBrowsingChannel for ExternalHelperAppParent. r=jduell", 08:15:36 INFO - "project": "", 08:15:36 INFO - "at": "Mon 23 Nov 2015 08:06:04", 08:15:36 INFO - "branch": "fx-team-macosx64-talos", 08:15:36 INFO - "revlink": "", 08:15:36 INFO - "properties": [ 08:15:36 INFO - [ 08:15:36 INFO - "buildid", 08:15:36 INFO - "20151123062128", 08:15:36 INFO - "Change" 08:15:36 INFO - ], 08:15:36 INFO - [ 08:15:36 INFO - "builduid", 08:15:36 INFO - "563983de6ca948699f612fe25d78c50b", 08:15:36 INFO - "Change" 08:15:36 INFO - ], 08:15:36 INFO - [ 08:15:36 INFO - "pgo_build", 08:15:36 INFO - "False", 08:15:36 INFO - "Change" 08:15:36 INFO - ] 08:15:36 INFO - ], 08:15:36 INFO - "revision": "bfd81e42d1ddc7071c7fab2598f3099aab30ca92" 08:15:36 INFO - } 08:15:36 INFO - ], 08:15:36 INFO - "revision": "bfd81e42d1ddc7071c7fab2598f3099aab30ca92" 08:15:36 INFO - } 08:15:36 INFO - } 08:15:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 08:15:36 INFO - Running post-action listener: _resource_record_post_action 08:15:36 INFO - ##### 08:15:36 INFO - ##### Running download-and-extract step. 08:15:36 INFO - ##### 08:15:36 INFO - Running pre-action listener: _resource_record_pre_action 08:15:36 INFO - Running main action method: download_and_extract 08:15:36 INFO - mkdir: /builds/slave/test/build/tests 08:15:36 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')]} 08:15:36 INFO - https://queue.taskcluster.net/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 08:15:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/test_packages.json 08:15:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/test_packages.json 08:15:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 08:15:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 08:15:37 INFO - Downloaded 1149 bytes. 08:15:37 INFO - Reading from file /builds/slave/test/build/test_packages.json 08:15:37 INFO - Using the following test package requirements: 08:15:37 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 08:15:37 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 08:15:37 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 08:15:37 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 08:15:37 INFO - u'jsshell-mac.zip'], 08:15:37 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 08:15:37 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 08:15:37 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 08:15:37 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 08:15:37 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 08:15:37 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 08:15:37 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 08:15:37 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 08:15:37 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 08:15:37 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 08:15:37 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 08:15:37 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 08:15:37 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:15: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')]} 08:15:37 INFO - https://queue.taskcluster.net/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:15:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 08:15:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 08:15:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/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 08:15:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/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 08:15:40 INFO - Downloaded 17527246 bytes. 08:15: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 08:15:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 08:15:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 08:15:43 INFO - Return code: 0 08:15: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 08:15: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')]} 08:15:43 INFO - https://queue.taskcluster.net/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:15:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 08:15:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 08:15:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/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 08:15:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/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 08:15:44 INFO - Downloaded 17527246 bytes. 08:15: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 08:15:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 08:15:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 08:15:48 INFO - Return code: 0 08:15: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')]} 08:15:48 INFO - https://queue.taskcluster.net/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:15:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 08:15:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 08:15:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/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 08:15:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/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 08:15:48 INFO - Downloaded 11130997 bytes. 08:15: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 08:15:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 08:15:48 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 08:15:49 INFO - Return code: 0 08:15:49 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')]} 08:15:49 INFO - https://queue.taskcluster.net/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:15:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 08:15:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 08:15:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg 08:15:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/sRPG6_P4QoGtb_JChtqtXg/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 08:15:52 INFO - Downloaded 109887073 bytes. 08:15:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 08:15:52 INFO - mkdir: /builds/slave/test/properties 08:15:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:15:52 INFO - Writing to file /builds/slave/test/properties/build_url 08:15:52 INFO - Contents: 08:15:52 INFO - build_url:https://queue.taskcluster.net/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 08:15:52 INFO - Running post-action listener: _resource_record_post_action 08:15:52 INFO - Running post-action listener: set_extra_try_arguments 08:15:52 INFO - ##### 08:15:52 INFO - ##### Running populate-webroot step. 08:15:52 INFO - ##### 08:15:52 INFO - Running pre-action listener: _resource_record_pre_action 08:15:52 INFO - Running main action method: populate_webroot 08:15:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:15:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:15:52 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 08:15:52 INFO - '../fennec_ids.txt'], 08:15:52 INFO - 'tests': ['tcheck2']}, 08:15:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:15:52 INFO - '--tppagecycles', 08:15:52 INFO - '7'], 08:15:52 INFO - 'tests': ['tsvgm']}}, 08:15:52 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:15:52 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:15:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:15:52 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:15:52 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:15:52 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:15:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:15:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:15:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:15:52 INFO - 'talos_options': ['--e10s'], 08:15:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:15:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:15:52 INFO - 'tests': ['damp', 'tps']}, 08:15:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:15:52 INFO - 'talos_options': ['--e10s'], 08:15:52 INFO - 'tests': ['damp', 'tps']}, 08:15:52 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:15:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:15:52 INFO - 'other': {'tests': ['a11yr', 08:15:52 INFO - 'ts_paint', 08:15:52 INFO - 'tpaint', 08:15:52 INFO - 'sessionrestore', 08:15:52 INFO - 'sessionrestore_no_auto_restore']}, 08:15:52 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:15:52 INFO - 'tests': ['a11yr', 08:15:52 INFO - 'ts_paint', 08:15:52 INFO - 'tpaint', 08:15:52 INFO - 'sessionrestore', 08:15:52 INFO - 'sessionrestore_no_auto_restore']}, 08:15:52 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:15:52 INFO - 'tests': ['a11yr', 08:15:52 INFO - 'ts_paint', 08:15:52 INFO - 'tpaint', 08:15:52 INFO - 'sessionrestore', 08:15:52 INFO - 'sessionrestore_no_auto_restore']}, 08:15:52 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:15:52 INFO - 'tests': ['a11yr', 08:15:52 INFO - 'ts_paint', 08:15:52 INFO - 'tpaint', 08:15:52 INFO - 'sessionrestore', 08:15:52 INFO - 'sessionrestore_no_auto_restore']}, 08:15:52 INFO - 'other_l64': {'tests': ['a11yr', 08:15:52 INFO - 'ts_paint', 08:15:52 INFO - 'tpaint', 08:15:52 INFO - 'sessionrestore', 08:15:52 INFO - 'sessionrestore_no_auto_restore']}, 08:15:52 INFO - 'other_nol64': {'tests': ['a11yr', 08:15:52 INFO - 'ts_paint', 08:15:52 INFO - 'tpaint', 08:15:52 INFO - 'sessionrestore', 08:15:52 INFO - 'sessionrestore_no_auto_restore']}, 08:15:52 INFO - 'svgr': {'tests': ['tsvgx', 08:15:52 INFO - 'tsvgr_opacity', 08:15:52 INFO - 'tart', 08:15:52 INFO - 'tscrollx', 08:15:52 INFO - 'cart']}, 08:15:52 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:15:52 INFO - 'tests': ['tsvgx', 08:15:52 INFO - 'tsvgr_opacity', 08:15:52 INFO - 'tart', 08:15:52 INFO - 'tscrollx', 08:15:52 INFO - 'cart']}, 08:15:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:15:52 INFO - 'tests': ['tp5o']}, 08:15:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:15:52 INFO - 'talos_options': ['--e10s'], 08:15:52 INFO - 'tests': ['tp5o']}, 08:15:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:15:52 INFO - 'talos_options': ['--xperf_path', 08:15:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:15:52 INFO - 'tests': ['tp5n']}, 08:15:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:15:52 INFO - 'talos_options': ['--e10s', 08:15:52 INFO - '--xperf_path', 08:15:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:15:52 INFO - 'tests': ['tp5n']}}, 08:15:52 INFO - 'talos.zip': {'path': '', 08:15:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:15:52 INFO - Running post-action listener: _resource_record_post_action 08:15:52 INFO - ##### 08:15:52 INFO - ##### Running create-virtualenv step. 08:15:52 INFO - ##### 08:15:52 INFO - Running pre-action listener: _resource_record_pre_action 08:15:52 INFO - Running main action method: create_virtualenv 08:15:52 INFO - Creating virtualenv /builds/slave/test/build/venv 08:15:52 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 08:15:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:15:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:15:52 INFO - Using real prefix '/tools/python27' 08:15:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:15:53 INFO - Installing distribute.............................................................................................................................................................................................done. 08:15:56 INFO - Installing pip.................done. 08:15:56 INFO - Return code: 0 08:15:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:15: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')]} 08:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:15: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')]} 08:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:15: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101e10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ded228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e158a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d349f0>, '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 0x101a93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d278c8>, '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.jgcNMiRvsa/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.dtCiUIUD13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 08:15: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', 'pip>=1.5'] in /builds/slave/test/build 08:15: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 pip>=1.5 08:15:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jgcNMiRvsa/Render', 08:15:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:15:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:15:56 INFO - 'HOME': '/Users/cltbld', 08:15:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:15:56 INFO - 'LOGNAME': 'cltbld', 08:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:56 INFO - 'MOZ_NO_REMOTE': '1', 08:15:56 INFO - 'NO_EM_RESTART': '1', 08:15:56 INFO - 'PAGER': '/bin/cat', 08:15:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:56 INFO - 'PWD': '/builds/slave/test', 08:15:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:15:56 INFO - 'SHELL': '/bin/bash', 08:15:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dtCiUIUD13/Listeners', 08:15:56 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 08:15:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:15:56 INFO - 'USER': 'cltbld', 08:15:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:15:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:56 INFO - 'XPC_FLAGS': '0x0', 08:15:56 INFO - 'XPC_SERVICE_NAME': '0', 08:15:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:15: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 08:15:56 INFO - Cleaning up... 08:15:56 INFO - Return code: 0 08:15:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:15: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')]} 08:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:15: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')]} 08:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:15: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 0x101e10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ded228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e158a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d349f0>, '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 0x101a93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d278c8>, '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.jgcNMiRvsa/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.dtCiUIUD13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 08:15: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 08:15: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 08:15:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jgcNMiRvsa/Render', 08:15:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:15:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:15:56 INFO - 'HOME': '/Users/cltbld', 08:15:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:15:56 INFO - 'LOGNAME': 'cltbld', 08:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:15:56 INFO - 'MOZ_NO_REMOTE': '1', 08:15:56 INFO - 'NO_EM_RESTART': '1', 08:15:56 INFO - 'PAGER': '/bin/cat', 08:15:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:15:56 INFO - 'PWD': '/builds/slave/test', 08:15:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:15:56 INFO - 'SHELL': '/bin/bash', 08:15:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dtCiUIUD13/Listeners', 08:15:56 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 08:15:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:15:56 INFO - 'USER': 'cltbld', 08:15:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:15:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:15:56 INFO - 'XPC_FLAGS': '0x0', 08:15:56 INFO - 'XPC_SERVICE_NAME': '0', 08:15:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:15:56 INFO - Downloading/unpacking psutil>=0.7.1 08:15: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 08:15: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 08:15: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 08:15: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 08:15: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 08:15: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 08:15:58 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 08:15:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 08:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:15:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:15:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:15:59 INFO - Installing collected packages: psutil 08:15:59 INFO - Running setup.py install for psutil 08:15:59 INFO - building 'psutil._psutil_osx' extension 08:15: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 08:15: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 08:15: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 08:15: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 08:15:59 INFO - building 'psutil._psutil_posix' extension 08:15: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 08:16:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:16:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:16:00 INFO - ^ 08:16:00 INFO - 1 warning generated. 08:16: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 08:16:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:16:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:16:00 INFO - Successfully installed psutil 08:16:00 INFO - Cleaning up... 08:16:00 INFO - Return code: 0 08:16:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:16: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')]} 08:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16: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')]} 08:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16: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 0x101e10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ded228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e158a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d349f0>, '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 0x101a93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d278c8>, '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.jgcNMiRvsa/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.dtCiUIUD13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 08:16: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 08:16: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 08:16:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jgcNMiRvsa/Render', 08:16:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:00 INFO - 'HOME': '/Users/cltbld', 08:16:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:00 INFO - 'LOGNAME': 'cltbld', 08:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:00 INFO - 'MOZ_NO_REMOTE': '1', 08:16:00 INFO - 'NO_EM_RESTART': '1', 08:16:00 INFO - 'PAGER': '/bin/cat', 08:16:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:00 INFO - 'PWD': '/builds/slave/test', 08:16:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:00 INFO - 'SHELL': '/bin/bash', 08:16:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dtCiUIUD13/Listeners', 08:16:00 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 08:16:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:00 INFO - 'USER': 'cltbld', 08:16:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:00 INFO - 'XPC_FLAGS': '0x0', 08:16:00 INFO - 'XPC_SERVICE_NAME': '0', 08:16:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:16:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 08:16:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:16: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) 08:16:02 INFO - Installing collected packages: mozsystemmonitor 08:16:02 INFO - Running setup.py install for mozsystemmonitor 08:16:02 INFO - Successfully installed mozsystemmonitor 08:16:02 INFO - Cleaning up... 08:16:03 INFO - Return code: 0 08:16:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101e10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ded228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e158a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d349f0>, '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 0x101a93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d278c8>, '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.jgcNMiRvsa/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.dtCiUIUD13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 08:16:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:16:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:16:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jgcNMiRvsa/Render', 08:16:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:03 INFO - 'HOME': '/Users/cltbld', 08:16:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:03 INFO - 'LOGNAME': 'cltbld', 08:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:03 INFO - 'MOZ_NO_REMOTE': '1', 08:16:03 INFO - 'NO_EM_RESTART': '1', 08:16:03 INFO - 'PAGER': '/bin/cat', 08:16:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:03 INFO - 'PWD': '/builds/slave/test', 08:16:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:03 INFO - 'SHELL': '/bin/bash', 08:16:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dtCiUIUD13/Listeners', 08:16:03 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 08:16:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:03 INFO - 'USER': 'cltbld', 08:16:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:03 INFO - 'XPC_FLAGS': '0x0', 08:16:03 INFO - 'XPC_SERVICE_NAME': '0', 08:16:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:03 INFO - Downloading/unpacking blobuploader==1.2.4 08:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:05 INFO - Downloading blobuploader-1.2.4.tar.gz 08:16:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 08:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:16:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 08:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:16:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:16:06 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 08:16:06 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 08:16:06 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 08:16:06 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 08:16:06 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 08:16:06 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 08:16:06 INFO - Downloading docopt-0.6.1.tar.gz 08:16:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 08:16:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:16:06 INFO - Installing collected packages: blobuploader, requests, docopt 08:16:06 INFO - Running setup.py install for blobuploader 08:16:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:16:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:16:06 INFO - Running setup.py install for requests 08:16:07 INFO - Running setup.py install for docopt 08:16:07 INFO - Successfully installed blobuploader requests docopt 08:16:07 INFO - Cleaning up... 08:16:07 INFO - Return code: 0 08:16:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16: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')]} 08:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16: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')]} 08:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16: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 0x101e10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ded228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e158a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d349f0>, '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 0x101a93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d278c8>, '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.jgcNMiRvsa/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.dtCiUIUD13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 08:16: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 08:16: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 08:16:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jgcNMiRvsa/Render', 08:16:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:07 INFO - 'HOME': '/Users/cltbld', 08:16:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:07 INFO - 'LOGNAME': 'cltbld', 08:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:07 INFO - 'MOZ_NO_REMOTE': '1', 08:16:07 INFO - 'NO_EM_RESTART': '1', 08:16:07 INFO - 'PAGER': '/bin/cat', 08:16:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:07 INFO - 'PWD': '/builds/slave/test', 08:16:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:07 INFO - 'SHELL': '/bin/bash', 08:16:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dtCiUIUD13/Listeners', 08:16:07 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 08:16:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:07 INFO - 'USER': 'cltbld', 08:16:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:07 INFO - 'XPC_FLAGS': '0x0', 08:16:07 INFO - 'XPC_SERVICE_NAME': '0', 08:16:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:16:07 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-ENqPzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:16:07 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-UlrVoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:16:07 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-cnhe3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:16:08 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-zaGq7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:16:08 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-ighvR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:16:08 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-a1UzTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:16:08 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-Z1cZIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:16:08 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-TlJuBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:16:08 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-yClXIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:16:08 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-CgqQbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:16:08 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-_Cy4VK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:16:08 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-DJAAfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:16:08 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-cgkuBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:16:09 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-wLlSOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:09 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-ipqCqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:16:09 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-PdhTdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:16:09 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-Kerjlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:16:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:16:09 INFO - Running setup.py install for manifestparser 08:16:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:16:09 INFO - Running setup.py install for mozcrash 08:16:09 INFO - Running setup.py install for mozdebug 08:16:09 INFO - Running setup.py install for mozdevice 08:16:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:16:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:16:10 INFO - Running setup.py install for mozfile 08:16:10 INFO - Running setup.py install for mozhttpd 08:16:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:16:10 INFO - Running setup.py install for mozinfo 08:16:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:16:10 INFO - Running setup.py install for mozInstall 08:16:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:16:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:16:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:16:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:16:10 INFO - Running setup.py install for mozleak 08:16:10 INFO - Running setup.py install for mozlog 08:16:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:16:10 INFO - Running setup.py install for moznetwork 08:16:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:16:11 INFO - Running setup.py install for mozprocess 08:16:11 INFO - Running setup.py install for mozprofile 08:16:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:16:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:16:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:16:11 INFO - Running setup.py install for mozrunner 08:16:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:16:11 INFO - Running setup.py install for mozscreenshot 08:16:11 INFO - Running setup.py install for moztest 08:16:11 INFO - Running setup.py install for mozversion 08:16:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:16:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:16:11 INFO - Cleaning up... 08:16:12 INFO - Return code: 0 08:16:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-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 0x101e10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ded228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e158a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d349f0>, '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 0x101a93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d278c8>, '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.jgcNMiRvsa/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.dtCiUIUD13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 08:16:12 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 08:16:12 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 08:16:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jgcNMiRvsa/Render', 08:16:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:12 INFO - 'HOME': '/Users/cltbld', 08:16:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:12 INFO - 'LOGNAME': 'cltbld', 08:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:12 INFO - 'MOZ_NO_REMOTE': '1', 08:16:12 INFO - 'NO_EM_RESTART': '1', 08:16:12 INFO - 'PAGER': '/bin/cat', 08:16:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:12 INFO - 'PWD': '/builds/slave/test', 08:16:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:12 INFO - 'SHELL': '/bin/bash', 08:16:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dtCiUIUD13/Listeners', 08:16:12 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 08:16:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:12 INFO - 'USER': 'cltbld', 08:16:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:12 INFO - 'XPC_FLAGS': '0x0', 08:16:12 INFO - 'XPC_SERVICE_NAME': '0', 08:16:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:16:12 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-M3DA3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:16: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)) 08:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:16:12 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-c2t1ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:16: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)) 08:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:16:12 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-WzhCvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:16: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)) 08:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:16:12 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-i8KDsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:16: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)) 08:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:16:12 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-0SZszT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:16: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)) 08:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:16:12 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-nToZrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:16: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)) 08:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:16:12 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-FeMcwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:16:13 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)) 08:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:16:13 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-vhqJGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:16: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)) 08:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:16:13 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-4aTfTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:16: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)) 08:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:16:13 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-uFJ6Z2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:16: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)) 08:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:16:13 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-jzHjbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:16: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)) 08:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:16:13 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-P5Mv2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:16: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)) 08:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:16:13 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-IsgOrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:16: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)) 08:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:16:13 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-GsBNWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:16: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)) 08:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:13 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-wgmRke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:16: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)) 08:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:16:13 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-l1xQ0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:16: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)) 08:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:16:13 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-a94o3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:16: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)) 08:16: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)) 08:16: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)) 08:16: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)) 08:16: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)) 08:16: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)) 08:16:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16: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 08:16:17 INFO - Downloading blessings-1.5.1.tar.gz 08:16:17 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 08:16:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:16:17 INFO - Installing collected packages: blessings 08:16:17 INFO - Running setup.py install for blessings 08:16:17 INFO - Successfully installed blessings 08:16:17 INFO - Cleaning up... 08:16:17 INFO - Return code: 0 08:16:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:16:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:16:17 INFO - Reading from file tmpfile_stdout 08:16:17 INFO - Current package versions: 08:16:17 INFO - blessings == 1.5.1 08:16:17 INFO - blobuploader == 1.2.4 08:16:17 INFO - docopt == 0.6.1 08:16:17 INFO - manifestparser == 1.1 08:16:17 INFO - mozInstall == 1.12 08:16:17 INFO - mozcrash == 0.16 08:16:17 INFO - mozdebug == 0.1 08:16:17 INFO - mozdevice == 0.47 08:16:17 INFO - mozfile == 1.2 08:16:17 INFO - mozhttpd == 0.7 08:16:17 INFO - mozinfo == 0.9 08:16:17 INFO - mozleak == 0.1 08:16:17 INFO - mozlog == 3.1 08:16:17 INFO - moznetwork == 0.27 08:16:17 INFO - mozprocess == 0.22 08:16:17 INFO - mozprofile == 0.27 08:16:17 INFO - mozrunner == 6.11 08:16:17 INFO - mozscreenshot == 0.1 08:16:17 INFO - mozsystemmonitor == 0.0 08:16:17 INFO - moztest == 0.7 08:16:17 INFO - mozversion == 1.4 08:16:17 INFO - psutil == 3.1.1 08:16:17 INFO - requests == 1.2.3 08:16:17 INFO - wsgiref == 0.1.2 08:16:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:17 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')]} 08:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:17 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')]} 08:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:17 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 0x101e10490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ded228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e158a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d349f0>, '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 0x101a93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d278c8>, '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.jgcNMiRvsa/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.dtCiUIUD13/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 08:16:17 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 08:16:17 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 08:16:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jgcNMiRvsa/Render', 08:16:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:17 INFO - 'HOME': '/Users/cltbld', 08:16:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:17 INFO - 'LOGNAME': 'cltbld', 08:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:17 INFO - 'MOZ_NO_REMOTE': '1', 08:16:17 INFO - 'NO_EM_RESTART': '1', 08:16:17 INFO - 'PAGER': '/bin/cat', 08:16:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:17 INFO - 'PWD': '/builds/slave/test', 08:16:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:17 INFO - 'SHELL': '/bin/bash', 08:16:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dtCiUIUD13/Listeners', 08:16:17 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 08:16:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:17 INFO - 'USER': 'cltbld', 08:16:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:17 INFO - 'XPC_FLAGS': '0x0', 08:16:17 INFO - 'XPC_SERVICE_NAME': '0', 08:16:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:18 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)) 08:16:18 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)) 08:16:18 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)) 08:16:18 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)) 08:16:18 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)) 08:16:18 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)) 08:16:18 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)) 08:16:18 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)) 08:16:18 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)) 08:16:18 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)) 08:16:18 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)) 08:16:18 INFO - Cleaning up... 08:16:18 INFO - Return code: 0 08:16:18 INFO - Running post-action listener: _resource_record_post_action 08:16:18 INFO - Running post-action listener: _start_resource_monitoring 08:16:18 INFO - Starting resource monitoring. 08:16:18 INFO - ##### 08:16:18 INFO - ##### Running install step. 08:16:18 INFO - ##### 08:16:18 INFO - Running pre-action listener: _resource_record_pre_action 08:16:18 INFO - Running main action method: install 08:16:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:16:18 INFO - Reading from file tmpfile_stdout 08:16:18 INFO - Detecting whether we're running mozinstall >=1.0... 08:16:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:16:18 INFO - Reading from file tmpfile_stdout 08:16:18 INFO - Output received: 08:16:18 INFO - Usage: mozinstall [options] installer 08:16:18 INFO - Options: 08:16:18 INFO - -h, --help show this help message and exit 08:16:18 INFO - -d DEST, --destination=DEST 08:16:18 INFO - Directory to install application into. [default: 08:16:18 INFO - "/builds/slave/test"] 08:16:18 INFO - --app=APP Application being installed. [default: firefox] 08:16:18 INFO - mkdir: /builds/slave/test/build/application 08:16:18 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'] 08:16:18 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 08:16:49 INFO - Reading from file tmpfile_stdout 08:16:49 INFO - Output received: 08:16:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:16:49 INFO - Running post-action listener: _resource_record_post_action 08:16:49 INFO - ##### 08:16:49 INFO - ##### Running run-tests step. 08:16:49 INFO - ##### 08:16:49 INFO - Running pre-action listener: _resource_record_pre_action 08:16:49 INFO - Running main action method: run_tests 08:16:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:16:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:16:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:16:49 INFO - 2015-11-23 08:16:49.235 screenresolution[1637:7323] starting screenresolution argv=screenresolution get 08:16:49 INFO - 2015-11-23 08:16:49.237 screenresolution[1637:7323] Display 0: 1600x1200x32@60 08:16:49 INFO - 2015-11-23 08:16:49.243 screenresolution[1638:7326] starting screenresolution argv=screenresolution list 08:16:49 INFO - Available Modes on Display 0 08:16:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:16:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:16:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:16:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:16:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:16:49 INFO - Intel Iris: 08:16:49 INFO - Chipset Model: Intel Iris 08:16:49 INFO - Type: GPU 08:16:49 INFO - Bus: Built-In 08:16:49 INFO - VRAM (Dynamic, Max): 1536 MB 08:16:49 INFO - Vendor: Intel (0x8086) 08:16:49 INFO - Device ID: 0x0a2e 08:16:49 INFO - Revision ID: 0x0009 08:16:49 INFO - Displays: 08:16:49 INFO - Display: 08:16:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:16:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:16:49 INFO - Display Serial Number: 1600x1200 60 08:16:49 INFO - Main Display: Yes 08:16:49 INFO - Mirror: Off 08:16:49 INFO - Online: Yes 08:16:49 INFO - Rotation: Supported 08:16:49 INFO - Return code: 0 08:16:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:16:49 INFO - Python 2.7.3 08:16:49 INFO - Return code: 0 08:16:49 INFO - grabbing minidump binary from tooltool 08:16:49 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')]} 08:16:49 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 0x101d349f0>, '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 0x101a93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d278c8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:16:49 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 08:16:49 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 08:16:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:16:49 INFO - Return code: 0 08:16:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:16:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:16:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:16:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:16:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:16:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0048', '--webServer', 'localhost'] in /builds/slave/test/build 08:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0048 --webServer localhost 08:16:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jgcNMiRvsa/Render', 08:16:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:49 INFO - 'HOME': '/Users/cltbld', 08:16:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:49 INFO - 'LOGNAME': 'cltbld', 08:16:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:16:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:16:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:49 INFO - 'MOZ_NO_REMOTE': '1', 08:16:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:16:49 INFO - 'NO_EM_RESTART': '1', 08:16:49 INFO - 'PAGER': '/bin/cat', 08:16:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:49 INFO - 'PWD': '/builds/slave/test', 08:16:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:16:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:49 INFO - 'SHELL': '/bin/bash', 08:16:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dtCiUIUD13/Listeners', 08:16:49 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 08:16:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:49 INFO - 'USER': 'cltbld', 08:16:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:49 INFO - 'XPC_FLAGS': '0x0', 08:16:49 INFO - 'XPC_SERVICE_NAME': '0', 08:16:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0048', '--webServer', 'localhost'] with output_timeout 3600 08:16:49 INFO - mozversion INFO | application_buildid: 20151123062128 08:16:49 INFO - mozversion INFO | application_changeset: bfd81e42d1ddc7071c7fab2598f3099aab30ca92 08:16:49 INFO - mozversion INFO | application_display_name: Nightly 08:16:49 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:16:49 INFO - mozversion INFO | application_name: Firefox 08:16:49 INFO - mozversion INFO | application_remotingname: firefox 08:16:49 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 08:16:49 INFO - mozversion INFO | application_vendor: Mozilla 08:16:49 INFO - mozversion INFO | application_version: 45.0a1 08:16:49 INFO - mozversion INFO | platform_buildid: 20151123062128 08:16:49 INFO - mozversion INFO | platform_changeset: bfd81e42d1ddc7071c7fab2598f3099aab30ca92 08:16:49 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 08:16:49 INFO - mozversion INFO | platform_version: 45.0a1 08:16:49 INFO - 2015-11-23 08:16:49,789 DEBUG : using testdate: 1448295409 08:16:49 INFO - 2015-11-23 08:16:49,789 DEBUG : actual date: 1448295409 08:16:50 INFO - 2015-11-23 08:16:50,206 INFO : starting webserver on 'localhost:49264' 08:16:50 INFO - 2015-11-23 08:16:50,207 INFO : Starting test suite t-yosemite-r7-0048 08:16:50 INFO - 2015-11-23 08:16:50,207 INFO : Starting test dromaeo_css 08:16:50 INFO - 2015-11-23 08:16:50,207 DEBUG : operating with platform_type : mac_ 08:16:50 INFO - 2015-11-23 08:16:50,208 INFO : Initialising browser for dromaeo_css test... 08:16:50 INFO - 2015-11-23 08:16:50,220 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmp5Oxtfy/profile http://localhost:49264/getInfo.html 08:16:52 INFO - 2015-11-23 08:16:52,446 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:16:52 INFO - 2015-11-23 08:16:52,446 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:16:52 INFO - 2015-11-23 08:16:52,446 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 08:16:52 INFO - 2015-11-23 08:16:52,446 DEBUG : BROWSER_OUTPUT: __metrics 08:16:52 INFO - 2015-11-23 08:16:52,462 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:16:53 INFO - 2015-11-23 08:16:53,106 INFO : Browser initialized. 08:16:53 INFO - 2015-11-23 08:16:53,107 INFO : Running cycle 1/1 for dromaeo_css test... 08:16:53 INFO - 2015-11-23 08:16:53,107 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmp5Oxtfy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:17:04 INFO - 2015-11-23 08:17:04,882 DEBUG : BROWSER_OUTPUT: RSS: Main: 226406400 08:17:04 INFO - 2015-11-23 08:17:04,882 DEBUG : BROWSER_OUTPUT: 08:17:56 INFO - 2015-11-23 08:17:56,587 DEBUG : BROWSER_OUTPUT: 1448295476583 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] 08:20:07 INFO - 2015-11-23 08:20:07,043 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49264/tests/dromaeo/cssquery-ext.html) 08:20:08 INFO - 2015-11-23 08:20:08,481 DEBUG : BROWSER_OUTPUT: RSS: Main: 332025856 08:20:08 INFO - 2015-11-23 08:20:08,481 DEBUG : BROWSER_OUTPUT: 08:23:11 INFO - 2015-11-23 08:23:11,015 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-ext.html (next: http://localhost:49264/tests/dromaeo/cssquery-jquery.html) 08:23:11 INFO - 2015-11-23 08:23:11,602 DEBUG : BROWSER_OUTPUT: RSS: Main: 364625920 08:23:11 INFO - 2015-11-23 08:23:11,602 DEBUG : BROWSER_OUTPUT: 08:26:13 INFO - 2015-11-23 08:26:13,692 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49264/tests/dromaeo/cssquery-mootools.html) 08:26:14 INFO - 2015-11-23 08:26:14,275 DEBUG : BROWSER_OUTPUT: RSS: Main: 348340224 08:26:14 INFO - 2015-11-23 08:26:14,275 DEBUG : BROWSER_OUTPUT: 08:29:16 INFO - 2015-11-23 08:29:16,208 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49264/tests/dromaeo/cssquery-prototype.html) 08:29:16 INFO - 2015-11-23 08:29:16,797 DEBUG : BROWSER_OUTPUT: RSS: Main: 321712128 08:29:16 INFO - 2015-11-23 08:29:16,797 DEBUG : BROWSER_OUTPUT: 08:32:19 INFO - 2015-11-23 08:32:19,774 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49264/tests/dromaeo/cssquery-yui.html) 08:32:20 INFO - 2015-11-23 08:32:20,380 DEBUG : BROWSER_OUTPUT: RSS: Main: 319967232 08:32:20 INFO - 2015-11-23 08:32:20,380 DEBUG : BROWSER_OUTPUT: 08:35:23 INFO - 2015-11-23 08:35:23,096 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-yui.html (next: http://localhost:49264/tests/dromaeo/cssquery-dojo.html) 08:35:23 INFO - 2015-11-23 08:35:23,356 DEBUG : BROWSER_OUTPUT: RSS: Main: 385069056 08:35:23 INFO - 2015-11-23 08:35:23,356 DEBUG : BROWSER_OUTPUT: 08:35:23 INFO - 2015-11-23 08:35:23,356 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:35:23 INFO - 2015-11-23 08:35:23,356 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:35:23 INFO - 2015-11-23 08:35:23,356 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:35:23 INFO - 2015-11-23 08:35:23,356 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:35:23 INFO - 2015-11-23 08:35:23,357 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3325.767196296259;3458.948115778262;3498.807565583892;3506.070891213829;3526.8236588170594;4773.952260477394;5131.435542090367;5233.267342572039;5557.666540007603;5684.914726279109;5625.987322281988;5629.577781666371;5639.774409023634;5758.3090029196555;5796.362400135981;5103.132467480528;5125.051865404912;5149.201873709562;5149.871253218662;5210.895782084356;5196.428392876781;5255.894882102356;5273.525382715555;5275.208718692188;5314.840554783363;4271.145770845843;5047.192449208109;5177.870553236162;5188.221766735001;5225.190095535198;5189.896202075937;5192.818251361222;5203.19350500673;5210.635255532115;5216.947830521722;5104.055749686283;5145.819896303648;5154.098032844237;5160.767765450526;5167.276581278624;5631.380548139701;5637.577181711347;5639.8590035249035;5648.322201335866;5648.406917273709;6126.111713801519;6174.320824709278;6180.505559555225;6186.9381306186815;6196.659183744892;6294.653794041325;6298;6310.274318453391;6313.589616674902;6313.810585682437;5776.913346299767;5862.384449632727;5872.265966754156;5873.500752436048;5882.647041177543;5493.642913210629;6132.50939924805;6187.102870083812;6259.780907668301;6279.309275979638;6151.446369826737;6169.198004259418;6170.012797952306;6174.320824709278;6191.938080619227;6286.622802631857;6295.559310848288;6307.400796924285;6309.3690630936535;6309.432151106423;6014.1580178775;6107.439248620928;6124.319907527303;6202.224721909762;6231.22109736283;6127.417895299939;6140.9030504681;6149.569530132848;6160.260768707783;6209.379062093845;4923.852284431473;4942.061008408391;4949.82675606352;4950.381202349707;4976.601871850244;4384.934225986613;4426.955730442654;4431.578999994996;4460.464744230713;4462.41988541494;5475.178723191553;5620.971895140498;5625.296837895263;5626.634268772516;5644.689542075143;6211.9689401552705;6212.72042758077;6227.688615569204;6228.875422491525;6230.59501132425;5646.8023619173955;5664.405237450091;5680.11958146488;5684.431556844283;5698.572607054404;5316.255724198615;5378.273933019071;5406.270153529302;5458.208559758813;5471.452854714496;6309;6322.620642761449;6341.619502829845;6346.397092276226;6347.492200623939;6085.786997455068;6234.719437625408;6266.780662676784;6274.996000639876;6295.244570651551;4956.479569645136;6167.044108163242;6291.811245662638;6332.366763323631;6451.000094985097;6177.444030037318;6188.752449901954;6192.690365481798;6194.039923811816;6207.689615519296;5413.648112872808;6061.636301821904;6277.748890044347;6278.686065696788;6283.528735344776;5991.970040149771;6004.909926351021;6018.939810601838;6142.109394137736;6156.199694039925;5928.673922934107;6055.545834062551;6084.11780291864;6095.969520152371;6103.572749907464;5333.813316533902;5930.911036334544;5939.554533410098;5944.702764861826;5956.404359564009;5982.88034239304;5991.490723288569;6029.276486821609;6146.723397447036;6149.7847575334645;5970.731317090655;5992.101184822312;5999.250093738283;6019.51843852482;6041.456268935817 08:35:23 INFO - 2015-11-23 08:35:23,358 DEBUG : BROWSER_OUTPUT: |1;ext.html;541.762444798413;568.0853825341203;578.3349148479248;617.7312868902028;633.6008314761699;32341.353172936557;33027.66972330276;39950.8014759557;40281;40423.393649095284;43900.68297951059;44437;44448.77775611117;44473.33290000653;44680.55319446805;8702.695405660803;8855.95572022139;9746.610135594568;9914.702558923243;9924.900750992507;8720.651173953034;11888.940555297211;11991.16061875669;11995.16033877629;12008.879911200864;11651.766964660701;11698.707532311675;11731.882681173207;11741.354225517593;11745.354005529693;8766.824663506757;8854.070322616097;11707.121965852553;11721.413929303544;11724.648260552198;8744.956275218647;11776.705582360424;11901.880981190165;11904.98807601355;11960.34218118003;41479;41829;41913.58086419127;42088.57911420877;42130.78934605338;3174.8553048278027;3355.832208389595;3377.4596064629786;3612.6026137124895;3783.7135373972706;28761.13716588506;29349.559756603667;29373.70626293731;29603.40793184146;29879.701202987908;2793.189974907295;2836.4327134572964;2866.5413533834485;2888.959974409211;2970.4059188162237;444757;446262.7686861545;449173;449483;449825;55628;57005;57140.42859571351;67428.32571674319;68239.29401764995;69569;70583.64708176529;70648.2935170652;70750.64624676947;70754;15585.298661560257;15616;15834.366625334858;15834.60413489672;15852.286647100678;12070.336131512851;12170.81743773844;12171.14801963854;12177.452014659184;12206.450709718085;20540.383788486368;20945.89527052355;20989.265375711777;21033.36899892975;21034.47413814636;7194.82700033979;8427.957860210783;8619.094995025433;8634.309255259564;8728.432651877607;943.5233859010626;992.4541502173776;1010.3682552677374;1033.813913495578;1103.8509801176738;29645.703542964293;29726.702732972826;29800.552991705143;29910;30072;1134.9546018159347;1149.2587281203578;1177.31127290536;1207.8309036734863;1291.0833308350989;12782.488700452064;15057.623559410884;15231.543053708407;15251;15464.84535154634;522227;526462;526519.3674031605;527134;527367;58935.41064589385;59247.703761480916;59262;59307;59447;1448.6740483391152;1457.8177727784027;1468.1851572377648;1477.0694462488564;1484.7624380099132;29043.56434653524;29085.563716544693;29187.70812291935;29750;30286.84856575703;51649;51929.48070519398;51942.96114077773;51949.480505194464;52091.95816083581;487527;638653;645265;665045;665817;31568.210794730316;31753.8412307937;31789.36421271608;31839.522407164375;31965.520517191795;44232;44664;45263.77368113138;45278;46406;36897.81551092227;37385;37916;37920.620793791706;37949.810250949675;36065.27869442649;36924.630753692116;37213.25573488461;37349.066273343386;37515.87452376433;36726.816365918;37024.88925332244;37232.62767372291;37310.880673579835;37335.6266437343;30972.690273097884;31903.780204967516;32152.51771223387;32347.19132021718;32394.028179154662;21749.130034798432;24790.76046197719;25054.74945250597;25100.874495627406;25150.371240718396 08:35:23 INFO - 2015-11-23 08:35:23,361 DEBUG : BROWSER_OUTPUT: |2;jquery.html;358.62702789099336;359.7176216669915;363.709032773781;364.5115545169472;364.81212175729485;13060.412281447334;15681.29434175462;19495.317663881768;20187;20907.163713451482;16956.4065257716;19076.90461547694;19397.93311367874;19650.508737281576;19677.704834427495;5887.4406931341455;5923.9407605923925;5981.983062879321;5988.041913293874;6001.219841420631;4242.490901091874;4388.49532303786;5989.520838332945;6028.4272994065495;6045.969770151143;4337.284348082579;6336.46140078094;6507.869842603145;6520.608763474207;6522.673866306665;6468.935310646904;6517.185351831021;6543.836404089936;6565.507586930976;6569.671516424208;5625.831225063254;5631.211630371751;5639.069553523664;5652.547796176296;5655.462731040545;24885.5022899541;25070.373240668945;25268.873655631603;25436.491270174676;25475.745242547524;2142.582196471689;2150.8817015064164;2171.3811563704426;2217.6119179143748;2400.447896983686;14660.266986650624;14730.89518286122;15046.022008569407;15157.848421515753;15575.37698492059;1712.1781544858538;1736.748171515136;1752.9912350438294;1768.6551122531112;1768.938087166956;95872.041279587;97981.5100924498;98471;99358.0064199356;100143.49928250312;9979.850302245472;10136.54385552652;10344.22418318629;10345.327553708985;10436.112930400848;12350.62948111558;12383.876161238453;12384.690382740504;12390.876091238972;12394.194377365622;6663.900041499382;8026.197380261927;8072.112067672551;8075.636596353178;8136;7326.743563975236;7681.231876812274;8220.424570280142;8288.70989515364;8288.751337459886;4665.2768820832825;5044.520770526868;6033.306169790442;6047.941610218282;6070.848228794227;1533.7545992641124;1626.8779841511935;1701.8893771904786;1724.1379310344828;1747.816479269684;825.2119226139023;831.5883637599427;999.4652860719481;1029.2743615750878;1052.3580615824342;17405.390811321544;18734.063296835102;20513.79486205119;21443.892780535996;21587.892060539598;979.6571200080058;987.7036888933292;991.5290237137447;1021.5249908792447;1070.7751372211767;11342.206045576895;11470.713232169262;11481.531470733666;11595.710107247387;11599.594014209631;594721;601263;607325;692963;698485;115892.42053789846;118769.81230187588;118919;121797.78202218041;123517.38241308916;902.7652810269246;1173.7945859474728;1183.0712890381183;1186.8160435132568;1195.6413076077142;14775;17756.200970956597;17921.37275195362;17939.641207176046;18215.45353639393;48535.51464485451;48845.755771220916;50293.49706502888;52804.47195527996;53751.19373209327;70580.94128587973;71466.64266678633;71622;77845.61077194578;79758.6012069936;15998.600034999217;16376.918115409348;16638.584035399213;16872;16943.23755430984;19989.508655359678;20487.692684609443;20679.172833086508;21503.8172900492;22122.33632991013;13247.997761689863;13702.93148534251;15632.140232287333;16546.255418506436;16785;16400.589985250463;16570.420035298706;16590;16746.832531675016;17507.387241447;13686.589402317946;14012.159270443806;15304.617384565476;17009.489715308562;17531.298748049936;16771.91614041922;16879.66240675155;17077.40229091976;17102.48692539226;17126.91436542859;16983.320667173175;17019.14904254807;17033.97796132236;17470;17499 08:35:23 INFO - 2015-11-23 08:35:23,362 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1522.7791970164326;1526.473366688492;1664.217817625717;1835.3301045118533;1845.5570663040867;6852.7258909643615;7694.076710794706;7991.840163196733;8374.748757537269;8504.447210931285;8184.140665230155;8369.581520923944;8375.874361884577;8379.539125348103;8384.706535271267;2337.9883100584516;2345.7067866516686;2350.6591544226076;2352.4236562042324;2356.163561935514;2379.274321331993;2386.677798497207;2435.768601982809;2442.3649851038767;2445.4131008557906;2356.2106694257445;2384.6899903012672;2391.1272385579314;2397.244867866625;2405.0980882169188;2014.9496262593407;2324.1749179041535;2332.790048895599;2336.6728657987896;2353.7175538935353;2367.0531787285054;2387.6418537219383;2392.306231192952;2394.030417680842;2395.916142934989;8331.916680833236;8392.496450212946;8393.24460798531;8396.286315663176;8403.453775504573;4620.260758278693;4835.734034628094;4864.927026094611;5005.049040682259;5140.897182056376;3520.366334059868;3577.141486043357;3639.1811842335264;3650.0874781304674;3651.3427583035045;915.7893684452547;1037.061459379263;1081.7620123572801;1627.1620115640455;1655.6440365321514;11112.999830015257;11216.495257713423;11244.718882027933;11249.381284029376;11264.549418023269;3926.6662333701383;4532.456105267389;4546.40896683429;4636.976815115903;4641.419822522184;9150.08499150093;9178.082191780768;9179.357444978921;9183.219426348835;9185.494797786187;5134.332536770196;5160.432352441228;5168.715720635326;5169.71566563835;5190.714510701872;4725.054989002213;4748.928766068511;4765.666403351802;4770.833020844254;4773.307870358779;3581.8567257309414;3591.2637909228547;3620.511230983836;3649.2701459708164;3657.122290650225;1114.676743744305;1147.1969621265148;1150.9194356394971;1152.5793085523892;1154.1343992005995;1189.8096304591224;1197.2756482328066;1227.643983244849;1231.9630411087683;1234.3334599316447;7905.407094467938;7952.960235198787;7972.681092756341;8026.237507436784;8034.276915077554;497.5820310938829;502.0461123864686;502.20149961561293;505.68142070495355;512.4593553800702;5974.910376344358;6132.908006379907;6185.690715464209;6246.750129994749;6266.279377871603;9790.461524616214;10065.194784417228;10122.544485498023;10173.389596624227;10176.796464070678;9206.401583897024;9402.15380615748;9902.405855648683;10168.644097456554;10250.897491024994;7884.21157884207;7966.044074710956;7984.640691168911;7986.321162701235;8049.2755651992775;7768.84462310762;7821.804454888445;7887.40844436681;7888.368930485432;7918.802029949297;8972.667106552213;9059.275257979214;9086.36395452312;9141.314401420053;9206.401583897024;9218.907810922074;9431.481268530297;9463.905360946577;9469.857952130862;9478.668246611336;6911.5853048817235;6931.029655848286;6947.548409353376;6969.686364113729;7014.333638304353;7994.960025199837;8071.959640201761;8071.959640201761;8072.950516433061;8081.757547273592;7426.4430167736555;7545.094588649397;7549.0563679540055;7625.275598818103;7677.347425468898;7402.888956665546;7405.518641288299;7438.776836694908;7528.397728181842;7544.585047822423;7490.662920168715;7575.242475752601;7578.507397019176;7582.924170758442;7598.620068996417;6803.659817009031;6814.250432452525;6832.453403727789;6841.555298905555;6887.483438742014;6922.965385173042;6969.477289203229;6992.0910281663055;7000.824979375557;7002.719891204295 08:35:23 INFO - 2015-11-23 08:35:23,364 DEBUG : BROWSER_OUTPUT: |4;prototype.html;317.28294055434714;321.7972677213357;325.1335191714082;328.2172019732937;329.43337459569534;6722.663866806664;6739.494537909658;6765.898511522325;6856.58860468371;6886.449084073274;5289.603279754024;6756.2568117507035;6761.459083273338;6772.796816095512;6780.660966951645;4967.875803104916;4970.353853998975;4973.328600638913;4981;4984.252362145671;4712.434507859061;4835.468098509161;4858.708477491344;4878.365812444377;4912.606991440676;4856.198727210024;4858.975705121487;4860.392450943632;4865.902681946359;4870.853874383756;4597.632189424838;4697.906041879178;4733.573978341931;4846.4911184325665;4869.001854619794;4002.3196056670436;4091.0386059276343;4098.057446787256;4106.630403263721;4113.9920719423935;6438.581492202992;6459.67701614922;6498.935010649928;6509.381608747161;6546.70539825709;5470.234167216593;5632;5636.971815140898;5648.858778530528;5794.188813566104;7191.496595238335;7212.74755383559;7218.4225261979445;7225.349718525305;7234;4652.255639097728;4655.51117132703;4660.464046634613;4662.976685116587;4670.206064968964;5998.340182579913;7575.166731659513;7619.199984001711;7637.579933103677;7656.516285090271;4270.701050926467;4344.652427805768;4692.131955588227;4714.4106986626675;4720.268358404384;4788.138135135709;4796.856094317176;4800.279958006257;4800.615950723934;4807.7836497357;4982.950170498249;5766.106253530709;5769.278840144982;5800.9129863052085;5830.679312637845;7008.3206316684145;7295.489315747953;7315.085614298213;7329.780106596811;7333.266673332624;1646.8517833395051;1669.5575672446812;1669.8998060116432;1672.2809192047293;1672.6821903838231;950.6720181537497;1031.5873650539695;1053.9789204216027;1073.9194560408;1075.3977772447454;6733.966330168318;6759.662016899136;6771.424428923597;6776.186857577122;6778.661066946633;7557.168711441737;7561.7731468056045;7571.734989275349;7573.091229052549;7586.734464293723;6590;6729.6971636276485;6732.427743641747;6737.764178253738;6814.2163651180745;3663.560372755233;3984.4023396490757;4021.3163762160502;4024.2756303865585;4025.1345960618023;6170.444659980623;7849.529028258323;7880.763577092696;7934.761957141295;7946.284834364935;7424.406047516186;7712.344450721639;7896.447248692536;7923.801904952307;7945.841083178304;6281.748730050838;7883.566403847729;7892.368610511146;7894.763157105066;7896.526208427513;6490.8052758417325;7298.6715597797165;7617.42869284817;7638.847223055619;7656.387489000978;7164.856702866018;7434.107907051189;7442.218567050381;7444.702211911463;7447.962760186165;7781.766547003599;7792.181820908723;7795.766127016199;7821.569813660303;7843.490173138728;4581.93127103087;4662.930056049094;4713.151632706145;4718.646101542467;4725.527447255247;7495.213002634645;7496.587687677012;7497.287757663232;7508.43686723487;7512.661930213263;7206.423486121203;7209.963950180216;7211.8004008777825;7219.530730502711;7231.63841807918;6974.895376569254;7157.355837974607;7172;7189.065421495172;7210.134783825974;5960.701964901824;6994.755183568755;7044.542104763174;7097.751578694721;7101.893471598033;7233.0597022386755;7243.0946131733535;7247.2390399007345;7249.238829922782;7269.563826170447;6303.9684801575695;6684.598924064571;6913.930860691329;7206.603636800026;7222.855542889218 08:35:23 INFO - 2015-11-23 08:35:23,367 DEBUG : BROWSER_OUTPUT: |5;yui.html;732.2494443195724;748.1209578744973;792.0851416613809;794.2295972906279;821.6138414944974;14763.852361476382;15354.769678454832;15366.84633153668;15370.923145384286;15399.615009624751;14334.856651433483;14469.131852088882;14490.637734056654;14503.564893053199;14513.782293265584;759.8062494064034;770.1682183242085;928.9210417114554;966.8598053282306;970.9563069661847;754.9207333230014;951.4719330771454;969.0019280141465;969.9854502182437;972.9880923839198;986.6793292180034;1028.9897101029005;1029.27950434696;1033.5090831854845;1034.6482196053341;791.7149826062641;794.0590400375573;805.7864665863551;1000.3397757480064;1027.3476342522515;594.8929192745304;596.9194158788551;746.8319628083692;760.2321655128305;762.1349768013345;15241.695166096615;15400.922995385063;15408;15409.537713868602;15417.15205663688;12199.20705154171;12606.243625382414;12975.1566148201;13152.868471315356;13380.130291531019;10059.849102263472;10181.949090254502;10231.641892533726;10329.122024627915;10889.074428673572;3521.8943431696835;3538.982305088484;3544.149404142987;3553.289342131558;3556.5020897074432;54862;70959.29040709579;71763.64118179429;72295.55408891792;72586.63706681432;9507;9563.760905977268;9722.326210548943;9736.026397360349;9785.559649815776;22123.570846518815;22490.887545562167;22498.88750556237;22515.77484225137;22531.211407600655;6837.726490940307;6936.271691472423;7026.9648651756415;7755.922440775633;7788.688452461838;3861.2663593917064;3937.3306537888056;3952.2490726761825;3983.5817239190046;4101.938470922939;3625.6736893679695;3685.207680348738;3688.0964163780045;3692;3693.9815300923324;645.8869697802866;658.2923357390824;658.2989116590816;675.6115233740619;685.2975699907635;475.35589276530493;481.14762618987896;506.74915916621507;516.3932379454127;522.6733291692692;8176.223258790405;8656.22094011542;8764.079771624016;8922.152395522415;9464;494.78229578985133;495.57628227865376;517.9093658609728;519.2056154084257;541.8753686652092;3279.2785587170783;3861.9613803861603;4300.720453170534;4367.563243675607;4466.3747075409465;94473.52763236278;94966;96308;96452;96558;82107;82637.58681206555;83387.58306208551;83433;84009.73985390224;54908.17637735437;75110.62444687741;75686;75829.6208518954;76906.61546692342;8580.699675511327;8608.91391086081;8882;8955.776105597412;9190.080991900757;51476.48523514717;52027.47972520226;52110;52379.476205238985;52954;66556.66721666361;73776;74586.6270668665;74670.62664686641;74699;10902.945485272523;10937.234393592373;10974.231803773659;11133.721656958642;11493.080553555528;12020.218685785396;12130.878691212974;12162.574309899112;12331.938340308241;12940.352982351033;11528.769424611628;11674.533018679158;11862.940685296518;12040.75918481608;12319.137660363689;11459.369734664673;12116.636500904988;12125.575604853788;12132.75734485323;12192;11356.772864542829;11454.82817757751;11477.540898364306;11493.712657183638;12319.322437266037;9676.516174191403;9827.803443930938;10147.34042287249;10150.644727434505;10546.57813687444;9000.68497602607;9461.810763784548;9574.856377154209;9739.707808765748;9778.41329520231 08:35:23 INFO - 2015-11-23 08:35:23,367 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:35:23 INFO - 2015-11-23 08:35:23,367 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:35:23 INFO - 2015-11-23 08:35:23,367 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1237 08:35:23 INFO - 2015-11-23 08:35:23,367 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:35:23 INFO - 2015-11-23 08:35:23,368 DEBUG : BROWSER_OUTPUT: __startTimestamp1448296523353__endTimestamp 08:35:23 INFO - 2015-11-23 08:35:23,368 DEBUG : BROWSER_OUTPUT: 08:35:23 INFO - 2015-11-23 08:35:23,368 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:35:23 INFO - 2015-11-23 08:35:23,368 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:35:23 INFO - 2015-11-23 08:35:23,368 DEBUG : BROWSER_OUTPUT: 08:35:23 INFO - 2015-11-23 08:35:23,368 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5700.84 Median:5944.70 stddev:642.48 (10.8%) stddev-sans-first:616.85 08:35:23 INFO - 2015-11-23 08:35:23,368 DEBUG : BROWSER_OUTPUT: Values: 3325.8 3458.9 3498.8 3506.1 3526.8 4774.0 5131.4 5233.3 5557.7 5684.9 5626.0 5629.6 5639.8 5758.3 5796.4 5103.1 5125.1 5149.2 5149.9 5210.9 5196.4 5255.9 5273.5 5275.2 5314.8 4271.1 5047.2 5177.9 5188.2 5225.2 5189.9 5192.8 5203.2 5210.6 5216.9 5104.1 5145.8 5154.1 5160.8 5167.3 5631.4 5637.6 5639.9 5648.3 5648.4 6126.1 6174.3 6180.5 6186.9 6196.7 6294.7 6298.0 6310.3 6313.6 6313.8 5776.9 5862.4 5872.3 5873.5 5882.6 5493.6 6132.5 6187.1 6259.8 6279.3 6151.4 6169.2 6170.0 6174.3 6191.9 6286.6 6295.6 6307.4 6309.4 6309.4 6014.2 6107.4 6124.3 6202.2 6231.2 6127.4 6140.9 6149.6 6160.3 6209.4 4923.9 4942.1 4949.8 4950.4 4976.6 4384.9 4427.0 4431.6 4460.5 4462.4 5475.2 5621.0 5625.3 5626.6 5644.7 6212.0 6212.7 6227.7 6228.9 6230.6 5646.8 5664.4 5680.1 5684.4 5698.6 5316.3 5378.3 5406.3 5458.2 5471.5 6309.0 6322.6 6341.6 6346.4 6347.5 6085.8 6234.7 6266.8 6275.0 6295.2 4956.5 6167.0 6291.8 6332.4 6451.0 6177.4 6188.8 6192.7 6194.0 6207.7 5413.6 6061.6 6277.7 6278.7 6283.5 5992.0 6004.9 6018.9 6142.1 6156.2 5928.7 6055.5 6084.1 6096.0 6103.6 5333.8 5930.9 5939.6 5944.7 5956.4 5982.9 5991.5 6029.3 6146.7 6149.8 5970.7 5992.1 5999.3 6019.5 6041.5 08:35:23 INFO - 2015-11-23 08:35:23,368 DEBUG : BROWSER_OUTPUT: 08:35:23 INFO - 2015-11-23 08:35:23,368 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:67527.05 Median:20169.71 stddev:143367.22 (710.8%) stddev-sans-first:143681.19 08:35:23 INFO - 2015-11-23 08:35:23,368 DEBUG : BROWSER_OUTPUT: Values: 541.8 568.1 578.3 617.7 633.6 32341.4 33027.7 39950.8 40281.0 40423.4 43900.7 44437.0 44448.8 44473.3 44680.6 8702.7 8856.0 9746.6 9914.7 9924.9 8720.7 11888.9 11991.2 11995.2 12008.9 11651.8 11698.7 11731.9 11741.4 11745.4 8766.8 8854.1 11707.1 11721.4 11724.6 8745.0 11776.7 11901.9 11905.0 11960.3 41479.0 41829.0 41913.6 42088.6 42130.8 3174.9 3355.8 3377.5 3612.6 3783.7 28761.1 29349.6 29373.7 29603.4 29879.7 2793.2 2836.4 2866.5 2889.0 2970.4 444757.0 446262.8 449173.0 449483.0 449825.0 55628.0 57005.0 57140.4 67428.3 68239.3 69569.0 70583.6 70648.3 70750.6 70754.0 15585.3 15616.0 15834.4 15834.6 15852.3 12070.3 12170.8 12171.1 12177.5 12206.5 20540.4 20945.9 20989.3 21033.4 21034.5 7194.8 8428.0 8619.1 8634.3 8728.4 943.5 992.5 1010.4 1033.8 1103.9 29645.7 29726.7 29800.6 29910.0 30072.0 1135.0 1149.3 1177.3 1207.8 1291.1 12782.5 15057.6 15231.5 15251.0 15464.8 522227.0 526462.0 526519.4 527134.0 527367.0 58935.4 59247.7 59262.0 59307.0 59447.0 1448.7 1457.8 1468.2 1477.1 1484.8 29043.6 29085.6 29187.7 29750.0 30286.8 51649.0 51929.5 51943.0 51949.5 52092.0 487527.0 638653.0 645265.0 665045.0 665817.0 31568.2 31753.8 31789.4 31839.5 31965.5 44232.0 44664.0 45263.8 45278.0 46406.0 36897.8 37385.0 37916.0 37920.6 37949.8 36065.3 36924.6 37213.3 37349.1 37515.9 36726.8 37024.9 37232.6 37310.9 37335.6 30972.7 31903.8 32152.5 32347.2 32394.0 21749.1 24790.8 25054.7 25100.9 25150.4 08:35:23 INFO - 2015-11-23 08:35:23,368 DEBUG : BROWSER_OUTPUT: 08:35:23 INFO - 2015-11-23 08:35:23,370 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:36382.07 Median:20500.74 stddev:105699.36 (515.6%) stddev-sans-first:105961.27 08:35:23 INFO - 2015-11-23 08:35:23,370 DEBUG : BROWSER_OUTPUT: Values: 358.6 359.7 363.7 364.5 364.8 13060.4 15681.3 19495.3 20187.0 20907.2 16956.4 19076.9 19397.9 19650.5 19677.7 5887.4 5923.9 5982.0 5988.0 6001.2 4242.5 4388.5 5989.5 6028.4 6046.0 4337.3 6336.5 6507.9 6520.6 6522.7 6468.9 6517.2 6543.8 6565.5 6569.7 5625.8 5631.2 5639.1 5652.5 5655.5 24885.5 25070.4 25268.9 25436.5 25475.7 2142.6 2150.9 2171.4 2217.6 2400.4 14660.3 14730.9 15046.0 15157.8 15575.4 1712.2 1736.7 1753.0 1768.7 1768.9 95872.0 97981.5 98471.0 99358.0 100143.5 9979.9 10136.5 10344.2 10345.3 10436.1 12350.6 12383.9 12384.7 12390.9 12394.2 6663.9 8026.2 8072.1 8075.6 8136.0 7326.7 7681.2 8220.4 8288.7 8288.8 4665.3 5044.5 6033.3 6047.9 6070.8 1533.8 1626.9 1701.9 1724.1 1747.8 825.2 831.6 999.5 1029.3 1052.4 17405.4 18734.1 20513.8 21443.9 21587.9 979.7 987.7 991.5 1021.5 1070.8 11342.2 11470.7 11481.5 11595.7 11599.6 594721.0 601263.0 607325.0 692963.0 698485.0 115892.4 118769.8 118919.0 121797.8 123517.4 902.8 1173.8 1183.1 1186.8 1195.6 14775.0 17756.2 17921.4 17939.6 18215.5 48535.5 48845.8 50293.5 52804.5 53751.2 70580.9 71466.6 71622.0 77845.6 79758.6 15998.6 16376.9 16638.6 16872.0 16943.2 19989.5 20487.7 20679.2 21503.8 22122.3 13248.0 13702.9 15632.1 16546.3 16785.0 16400.6 16570.4 16590.0 16746.8 17507.4 13686.6 14012.2 15304.6 17009.5 17531.3 16771.9 16879.7 17077.4 17102.5 17126.9 16983.3 17019.1 17034.0 17470.0 17499.0 08:35:23 INFO - 2015-11-23 08:35:23,370 DEBUG : BROWSER_OUTPUT: 08:35:23 INFO - 2015-11-23 08:35:23,371 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5801.86 Median:6053.91 stddev:3035.29 (50.1%) stddev-sans-first:3026.77 08:35:23 INFO - 2015-11-23 08:35:23,372 DEBUG : BROWSER_OUTPUT: Values: 1522.8 1526.5 1664.2 1835.3 1845.6 6852.7 7694.1 7991.8 8374.7 8504.4 8184.1 8369.6 8375.9 8379.5 8384.7 2338.0 2345.7 2350.7 2352.4 2356.2 2379.3 2386.7 2435.8 2442.4 2445.4 2356.2 2384.7 2391.1 2397.2 2405.1 2014.9 2324.2 2332.8 2336.7 2353.7 2367.1 2387.6 2392.3 2394.0 2395.9 8331.9 8392.5 8393.2 8396.3 8403.5 4620.3 4835.7 4864.9 5005.0 5140.9 3520.4 3577.1 3639.2 3650.1 3651.3 915.8 1037.1 1081.8 1627.2 1655.6 11113.0 11216.5 11244.7 11249.4 11264.5 3926.7 4532.5 4546.4 4637.0 4641.4 9150.1 9178.1 9179.4 9183.2 9185.5 5134.3 5160.4 5168.7 5169.7 5190.7 4725.1 4748.9 4765.7 4770.8 4773.3 3581.9 3591.3 3620.5 3649.3 3657.1 1114.7 1147.2 1150.9 1152.6 1154.1 1189.8 1197.3 1227.6 1232.0 1234.3 7905.4 7953.0 7972.7 8026.2 8034.3 497.6 502.0 502.2 505.7 512.5 5974.9 6132.9 6185.7 6246.8 6266.3 9790.5 10065.2 10122.5 10173.4 10176.8 9206.4 9402.2 9902.4 10168.6 10250.9 7884.2 7966.0 7984.6 7986.3 8049.3 7768.8 7821.8 7887.4 7888.4 7918.8 8972.7 9059.3 9086.4 9141.3 9206.4 9218.9 9431.5 9463.9 9469.9 9478.7 6911.6 6931.0 6947.5 6969.7 7014.3 7995.0 8072.0 8072.0 8073.0 8081.8 7426.4 7545.1 7549.1 7625.3 7677.3 7402.9 7405.5 7438.8 7528.4 7544.6 7490.7 7575.2 7578.5 7582.9 7598.6 6803.7 6814.3 6832.5 6841.6 6887.5 6923.0 6969.5 6992.1 7000.8 7002.7 08:35:23 INFO - 2015-11-23 08:35:23,372 DEBUG : BROWSER_OUTPUT: 08:35:23 INFO - 2015-11-23 08:35:23,372 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5868.63 Median:6733.20 stddev:1909.10 (28.4%) stddev-sans-first:1868.43 08:35:23 INFO - 2015-11-23 08:35:23,373 DEBUG : BROWSER_OUTPUT: Values: 317.3 321.8 325.1 328.2 329.4 6722.7 6739.5 6765.9 6856.6 6886.4 5289.6 6756.3 6761.5 6772.8 6780.7 4967.9 4970.4 4973.3 4981.0 4984.3 4712.4 4835.5 4858.7 4878.4 4912.6 4856.2 4859.0 4860.4 4865.9 4870.9 4597.6 4697.9 4733.6 4846.5 4869.0 4002.3 4091.0 4098.1 4106.6 4114.0 6438.6 6459.7 6498.9 6509.4 6546.7 5470.2 5632.0 5637.0 5648.9 5794.2 7191.5 7212.7 7218.4 7225.3 7234.0 4652.3 4655.5 4660.5 4663.0 4670.2 5998.3 7575.2 7619.2 7637.6 7656.5 4270.7 4344.7 4692.1 4714.4 4720.3 4788.1 4796.9 4800.3 4800.6 4807.8 4983.0 5766.1 5769.3 5800.9 5830.7 7008.3 7295.5 7315.1 7329.8 7333.3 1646.9 1669.6 1669.9 1672.3 1672.7 950.7 1031.6 1054.0 1073.9 1075.4 6734.0 6759.7 6771.4 6776.2 6778.7 7557.2 7561.8 7571.7 7573.1 7586.7 6590.0 6729.7 6732.4 6737.8 6814.2 3663.6 3984.4 4021.3 4024.3 4025.1 6170.4 7849.5 7880.8 7934.8 7946.3 7424.4 7712.3 7896.4 7923.8 7945.8 6281.7 7883.6 7892.4 7894.8 7896.5 6490.8 7298.7 7617.4 7638.8 7656.4 7164.9 7434.1 7442.2 7444.7 7448.0 7781.8 7792.2 7795.8 7821.6 7843.5 4581.9 4662.9 4713.2 4718.6 4725.5 7495.2 7496.6 7497.3 7508.4 7512.7 7206.4 7210.0 7211.8 7219.5 7231.6 6974.9 7157.4 7172.0 7189.1 7210.1 5960.7 6994.8 7044.5 7097.8 7101.9 7233.1 7243.1 7247.2 7249.2 7269.6 6304.0 6684.6 6913.9 7206.6 7222.9 08:35:23 INFO - 2015-11-23 08:35:23,374 DEBUG : BROWSER_OUTPUT: 08:35:23 INFO - 2015-11-23 08:35:23,374 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:18610.77 Median:517.15 stddev:26056.85 (5038.5%) stddev-sans-first:26095.36 08:35:23 INFO - 2015-11-23 08:35:23,375 DEBUG : BROWSER_OUTPUT: Values: 732.2 748.1 792.1 794.2 821.6 14763.9 15354.8 15366.8 15370.9 15399.6 14334.9 14469.1 14490.6 14503.6 14513.8 759.8 770.2 928.9 966.9 971.0 754.9 951.5 969.0 970.0 973.0 986.7 1029.0 1029.3 1033.5 1034.6 791.7 794.1 805.8 1000.3 1027.3 594.9 596.9 746.8 760.2 762.1 15241.7 15400.9 15408.0 15409.5 15417.2 12199.2 12606.2 12975.2 13152.9 13380.1 10059.8 10181.9 10231.6 10329.1 10889.1 3521.9 3539.0 3544.1 3553.3 3556.5 54862.0 70959.3 71763.6 72295.6 72586.6 9507.0 9563.8 9722.3 9736.0 9785.6 22123.6 22490.9 22498.9 22515.8 22531.2 6837.7 6936.3 7027.0 7755.9 7788.7 3861.3 3937.3 3952.2 3983.6 4101.9 3625.7 3685.2 3688.1 3692.0 3694.0 645.9 658.3 658.3 675.6 685.3 475.4 481.1 506.7 516.4 522.7 8176.2 8656.2 8764.1 8922.2 9464.0 494.8 495.6 517.9 519.2 541.9 3279.3 3862.0 4300.7 4367.6 4466.4 94473.5 94966.0 96308.0 96452.0 96558.0 82107.0 82637.6 83387.6 83433.0 84009.7 54908.2 75110.6 75686.0 75829.6 76906.6 8580.7 8608.9 8882.0 8955.8 9190.1 51476.5 52027.5 52110.0 52379.5 52954.0 66556.7 73776.0 74586.6 74670.6 74699.0 10902.9 10937.2 10974.2 11133.7 11493.1 12020.2 12130.9 12162.6 12331.9 12940.4 11528.8 11674.5 11862.9 12040.8 12319.1 11459.4 12116.6 12125.6 12132.8 12192.0 11356.8 11454.8 11477.5 11493.7 12319.3 9676.5 9827.8 10147.3 10150.6 10546.6 9000.7 9461.8 9574.9 9739.7 9778.4 08:35:23 INFO - 2015-11-23 08:35:23,375 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:35:23 INFO - 2015-11-23 08:35:23,375 DEBUG : BROWSER_OUTPUT: 08:35:23 INFO - 2015-11-23 08:35:23,863 INFO : Browser exited with error code: 0 08:35:23 INFO - 2015-11-23 08:35:23,887 INFO : Completed test dromaeo_css (00:18:33) 08:35:23 INFO - 2015-11-23 08:35:23,887 INFO : Starting test kraken 08:35:23 INFO - 2015-11-23 08:35:23,887 DEBUG : operating with platform_type : mac_ 08:35:23 INFO - 2015-11-23 08:35:23,888 INFO : Initialising browser for kraken test... 08:35:23 INFO - 2015-11-23 08:35:23,900 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmprxXAu1/profile http://localhost:49264/getInfo.html 08:35:25 INFO - 2015-11-23 08:35:25,888 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:35:25 INFO - 2015-11-23 08:35:25,888 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:35:25 INFO - 2015-11-23 08:35:25,888 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 08:35:25 INFO - 2015-11-23 08:35:25,888 DEBUG : BROWSER_OUTPUT: __metrics 08:35:25 INFO - 2015-11-23 08:35:25,904 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:35:26 INFO - 2015-11-23 08:35:26,623 INFO : Browser initialized. 08:35:26 INFO - 2015-11-23 08:35:26,623 INFO : Running cycle 1/1 for kraken test... 08:35:26 INFO - 2015-11-23 08:35:26,623 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmprxXAu1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:35:38 INFO - 2015-11-23 08:35:38,591 DEBUG : BROWSER_OUTPUT: RSS: Main: 226590720 08:35:38 INFO - 2015-11-23 08:35:38,591 DEBUG : BROWSER_OUTPUT: 08:35:41 INFO - 2015-11-23 08:35:41,082 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-beat-detection) 08:35:41 INFO - 2015-11-23 08:35:41,675 DEBUG : BROWSER_OUTPUT: RSS: Main: 275623936 08:35:41 INFO - 2015-11-23 08:35:41,676 DEBUG : BROWSER_OUTPUT: 08:35:44 INFO - 2015-11-23 08:35:44,246 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-dft) 08:35:44 INFO - 2015-11-23 08:35:44,838 DEBUG : BROWSER_OUTPUT: RSS: Main: 289271808 08:35:44 INFO - 2015-11-23 08:35:44,838 DEBUG : BROWSER_OUTPUT: 08:35:48 INFO - 2015-11-23 08:35:48,181 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-fft) 08:35:48 INFO - 2015-11-23 08:35:48,761 DEBUG : BROWSER_OUTPUT: RSS: Main: 279506944 08:35:48 INFO - 2015-11-23 08:35:48,761 DEBUG : BROWSER_OUTPUT: 08:35:50 INFO - 2015-11-23 08:35:50,647 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-oscillator) 08:35:51 INFO - 2015-11-23 08:35:51,234 DEBUG : BROWSER_OUTPUT: RSS: Main: 282177536 08:35:51 INFO - 2015-11-23 08:35:51,234 DEBUG : BROWSER_OUTPUT: 08:35:53 INFO - 2015-11-23 08:35:53,214 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:35:53 INFO - 2015-11-23 08:35:53,794 DEBUG : BROWSER_OUTPUT: RSS: Main: 282673152 08:35:53 INFO - 2015-11-23 08:35:53,794 DEBUG : BROWSER_OUTPUT: 08:35:56 INFO - 2015-11-23 08:35:56,544 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-darkroom) 08:35:57 INFO - 2015-11-23 08:35:57,134 DEBUG : BROWSER_OUTPUT: RSS: Main: 283389952 08:35:57 INFO - 2015-11-23 08:35:57,134 DEBUG : BROWSER_OUTPUT: 08:35:59 INFO - 2015-11-23 08:35:59,900 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-desaturate) 08:36:00 INFO - 2015-11-23 08:36:00,487 DEBUG : BROWSER_OUTPUT: RSS: Main: 283959296 08:36:00 INFO - 2015-11-23 08:36:00,487 DEBUG : BROWSER_OUTPUT: 08:36:03 INFO - 2015-11-23 08:36:03,150 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49264/tests/kraken/driver.html?testName=json-parse-financial) 08:36:03 INFO - 2015-11-23 08:36:03,742 DEBUG : BROWSER_OUTPUT: RSS: Main: 283684864 08:36:03 INFO - 2015-11-23 08:36:03,742 DEBUG : BROWSER_OUTPUT: 08:36:05 INFO - 2015-11-23 08:36:05,375 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49264/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:36:05 INFO - 2015-11-23 08:36:05,948 DEBUG : BROWSER_OUTPUT: RSS: Main: 316153856 08:36:05 INFO - 2015-11-23 08:36:05,948 DEBUG : BROWSER_OUTPUT: 08:36:07 INFO - 2015-11-23 08:36:07,713 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:36:08 INFO - 2015-11-23 08:36:08,334 DEBUG : BROWSER_OUTPUT: RSS: Main: 318349312 08:36:08 INFO - 2015-11-23 08:36:08,334 DEBUG : BROWSER_OUTPUT: 08:36:10 INFO - 2015-11-23 08:36:10,356 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:36:10 INFO - 2015-11-23 08:36:10,944 DEBUG : BROWSER_OUTPUT: RSS: Main: 324362240 08:36:10 INFO - 2015-11-23 08:36:10,945 DEBUG : BROWSER_OUTPUT: 08:36:13 INFO - 2015-11-23 08:36:13,419 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:36:14 INFO - 2015-11-23 08:36:14,017 DEBUG : BROWSER_OUTPUT: RSS: Main: 325603328 08:36:14 INFO - 2015-11-23 08:36:14,018 DEBUG : BROWSER_OUTPUT: 08:36:16 INFO - 2015-11-23 08:36:16,590 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:36:17 INFO - 2015-11-23 08:36:17,178 DEBUG : BROWSER_OUTPUT: RSS: Main: 324263936 08:36:17 INFO - 2015-11-23 08:36:17,178 DEBUG : BROWSER_OUTPUT: 08:36:18 INFO - 2015-11-23 08:36:18,815 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49264/tests/kraken/driver.html?testName=ai-astar) 08:36:19 INFO - 2015-11-23 08:36:19,074 DEBUG : BROWSER_OUTPUT: RSS: Main: 384266240 08:36:19 INFO - 2015-11-23 08:36:19,074 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,074 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:36:19 INFO - 2015-11-23 08:36:19,074 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:36:19 INFO - 2015-11-23 08:36:19,074 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:36:19 INFO - 2015-11-23 08:36:19,074 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:36:19 INFO - 2015-11-23 08:36:19,074 DEBUG : BROWSER_OUTPUT: |0;ai-astar;102;111;109;110;109;109;111;110;109;116 08:36:19 INFO - 2015-11-23 08:36:19,074 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;164;148;142;153;117;140;138;116;152;117 08:36:19 INFO - 2015-11-23 08:36:19,074 DEBUG : BROWSER_OUTPUT: |2;audio-dft;194;257;203;184;185;184;184;185;183;188 08:36:19 INFO - 2015-11-23 08:36:19,074 DEBUG : BROWSER_OUTPUT: |3;audio-fft;63;70;77;71;55;77;57;73;73;55 08:36:19 INFO - 2015-11-23 08:36:19,074 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;83;84;116;73;75;76;103;75;72;100 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;87;88;125;87;88;88;87;87;88;88 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;94;94;94;94;93;94;94;93;94;95 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;86;83;84;85;91;83;84;86;85;84 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;57;55;56;54;52;58;83;82;81 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;63;65;66;67;66;66;67;65 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;81;83;81;82;80;81;81;81;82;81 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;121;120;111;117;120;125;118;123;122 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;133;134;132;132;131;138;170;166;159;151 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;63;64;63;64;64;64;64;63;65 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1027 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:36:19 INFO - 2015-11-23 08:36:19,075 DEBUG : BROWSER_OUTPUT: __startTimestamp1448296579068__endTimestamp 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.60 Median:110.00 stddev:3.41 (3.1%) stddev-sans-first:2.24 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: Values: 102.0 111.0 109.0 110.0 109.0 109.0 111.0 110.0 109.0 116.0 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:138.70 Median:145.00 stddev:16.92 (11.7%) stddev-sans-first:15.28 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: Values: 164.0 148.0 142.0 153.0 117.0 140.0 138.0 116.0 152.0 117.0 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:194.70 Median:186.50 stddev:22.76 (12.2%) stddev-sans-first:24.14 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: Values: 194.0 257.0 203.0 184.0 185.0 184.0 184.0 185.0 183.0 188.0 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,076 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:67.10 Median:72.00 stddev:8.82 (12.3%) stddev-sans-first:9.23 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: Values: 63.0 70.0 77.0 71.0 55.0 77.0 57.0 73.0 73.0 55.0 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:85.70 Median:75.00 stddev:15.29 (20.4%) stddev-sans-first:16.19 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: Values: 83.0 84.0 116.0 73.0 75.0 76.0 103.0 75.0 72.0 100.0 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:88.00 stddev:11.85 (13.5%) stddev-sans-first:12.47 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: Values: 87.0 88.0 125.0 87.0 88.0 88.0 87.0 87.0 88.0 88.0 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: Values: 94.0 94.0 94.0 94.0 93.0 94.0 94.0 93.0 94.0 95.0 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.00 stddev:2.33 (2.7%) stddev-sans-first:2.45 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: Values: 86.0 83.0 84.0 85.0 91.0 83.0 84.0 86.0 85.0 84.0 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,077 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:63.20 Median:57.50 stddev:13.09 (22.8%) stddev-sans-first:13.45 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: Values: 54.0 57.0 55.0 56.0 54.0 52.0 58.0 83.0 82.0 81.0 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:66.00 stddev:1.60 (2.4%) stddev-sans-first:1.50 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 63.0 65.0 66.0 67.0 66.0 66.0 67.0 65.0 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: Values: 81.0 83.0 81.0 82.0 80.0 81.0 81.0 81.0 82.0 81.0 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.60 Median:120.50 stddev:3.84 (3.2%) stddev-sans-first:4.06 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: Values: 119.0 121.0 120.0 111.0 117.0 120.0 125.0 118.0 123.0 122.0 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.60 Median:144.50 stddev:15.44 (10.7%) stddev-sans-first:15.79 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: Values: 133.0 134.0 132.0 132.0 131.0 138.0 170.0 166.0 159.0 151.0 08:36:19 INFO - 2015-11-23 08:36:19,078 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,079 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:64.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 08:36:19 INFO - 2015-11-23 08:36:19,079 DEBUG : BROWSER_OUTPUT: Values: 64.0 63.0 64.0 63.0 64.0 64.0 64.0 64.0 63.0 65.0 08:36:19 INFO - 2015-11-23 08:36:19,079 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:36:19 INFO - 2015-11-23 08:36:19,079 DEBUG : BROWSER_OUTPUT: 08:36:19 INFO - 2015-11-23 08:36:19,697 INFO : Browser exited with error code: 0 08:36:19 INFO - 2015-11-23 08:36:19,742 INFO : Completed test kraken (00:00:55) 08:36:19 INFO - 2015-11-23 08:36:19,742 INFO : Starting test v8_7 08:36:19 INFO - 2015-11-23 08:36:19,742 DEBUG : operating with platform_type : mac_ 08:36:19 INFO - 2015-11-23 08:36:19,742 INFO : Initialising browser for v8_7 test... 08:36:19 INFO - 2015-11-23 08:36:19,755 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmp0O7SFK/profile http://localhost:49264/getInfo.html 08:36:21 INFO - 2015-11-23 08:36:21,925 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:36:21 INFO - 2015-11-23 08:36:21,925 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:36:21 INFO - 2015-11-23 08:36:21,925 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 08:36:21 INFO - 2015-11-23 08:36:21,925 DEBUG : BROWSER_OUTPUT: __metrics 08:36:21 INFO - 2015-11-23 08:36:21,942 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:36:22 INFO - 2015-11-23 08:36:22,204 DEBUG : BROWSER_OUTPUT: console.error: 08:36:22 INFO - 2015-11-23 08:36:22,204 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction. 08:36:22 INFO - 2015-11-23 08:36:22,204 DEBUG : BROWSER_OUTPUT: Stack: 08:36:22 INFO - 2015-11-23 08:36:22,204 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 08:36:22 INFO - 2015-11-23 08:36:22,205 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 08:36:22 INFO - 2015-11-23 08:36:22,205 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 08:36:22 INFO - 2015-11-23 08:36:22,205 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 08:36:22 INFO - 2015-11-23 08:36:22,205 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 08:36:22 INFO - 2015-11-23 08:36:22,205 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 08:36:22 INFO - 2015-11-23 08:36:22,205 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 08:36:22 INFO - 2015-11-23 08:36:22,205 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7 08:36:22 INFO - 2015-11-23 08:36:22,205 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 08:36:22 INFO - 2015-11-23 08:36:22,205 DEBUG : BROWSER_OUTPUT: 08:36:22 INFO - 2015-11-23 08:36:22,604 INFO : Browser initialized. 08:36:22 INFO - 2015-11-23 08:36:22,605 INFO : Running cycle 1/1 for v8_7 test... 08:36:22 INFO - 2015-11-23 08:36:22,605 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmp0O7SFK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:36:34 INFO - 2015-11-23 08:36:34,527 DEBUG : BROWSER_OUTPUT: RSS: Main: 226127872 08:36:34 INFO - 2015-11-23 08:36:34,527 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,016 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49264/tests/v8_7/run.html (next: http://localhost:49264/tests/v8_7/run.html) 08:36:56 INFO - 2015-11-23 08:36:56,273 DEBUG : BROWSER_OUTPUT: RSS: Main: 661942272 08:36:56 INFO - 2015-11-23 08:36:56,273 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,274 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:36:56 INFO - 2015-11-23 08:36:56,274 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:36:56 INFO - 2015-11-23 08:36:56,274 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:36:56 INFO - 2015-11-23 08:36:56,274 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:36:56 INFO - 2015-11-23 08:36:56,274 DEBUG : BROWSER_OUTPUT: |0;Richards;175.43859649122808 08:36:56 INFO - 2015-11-23 08:36:56,274 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;160.46213093709883 08:36:56 INFO - 2015-11-23 08:36:56,274 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.6901408450704 08:36:56 INFO - 2015-11-23 08:36:56,274 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4744.075829383886 08:36:56 INFO - 2015-11-23 08:36:56,274 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1712.3287671232877 08:36:56 INFO - 2015-11-23 08:36:56,274 DEBUG : BROWSER_OUTPUT: |5;Earley;789.2659826361484 08:36:56 INFO - 2015-11-23 08:36:56,274 DEBUG : BROWSER_OUTPUT: |6;Boyer;12530.864197530864 08:36:56 INFO - 2015-11-23 08:36:56,274 DEBUG : BROWSER_OUTPUT: |7;RegExp;28083.333333333332 08:36:56 INFO - 2015-11-23 08:36:56,274 DEBUG : BROWSER_OUTPUT: |8;Splay;563.063063063063 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4542.986425339366 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: __startTimestamp1448296616269__endTimestamp 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: Number of tests: 10 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:175.44 Median:175.44 stddev:0.00 (0.0%) 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: Values: 175.4 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:160.46 Median:160.46 stddev:0.00 (0.0%) 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: Values: 160.5 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,275 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.69 Median:281.69 stddev:0.00 (0.0%) 08:36:56 INFO - 2015-11-23 08:36:56,276 DEBUG : BROWSER_OUTPUT: Values: 281.7 08:36:56 INFO - 2015-11-23 08:36:56,276 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,276 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4744.08 Median:4744.08 stddev:0.00 (0.0%) 08:36:56 INFO - 2015-11-23 08:36:56,276 DEBUG : BROWSER_OUTPUT: Values: 4744.1 08:36:56 INFO - 2015-11-23 08:36:56,276 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,276 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1712.33 Median:1712.33 stddev:0.00 (0.0%) 08:36:56 INFO - 2015-11-23 08:36:56,276 DEBUG : BROWSER_OUTPUT: Values: 1712.3 08:36:56 INFO - 2015-11-23 08:36:56,276 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,276 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:789.27 Median:789.27 stddev:0.00 (0.0%) 08:36:56 INFO - 2015-11-23 08:36:56,276 DEBUG : BROWSER_OUTPUT: Values: 789.3 08:36:56 INFO - 2015-11-23 08:36:56,276 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,276 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12530.86 Median:12530.86 stddev:0.00 (0.0%) 08:36:56 INFO - 2015-11-23 08:36:56,276 DEBUG : BROWSER_OUTPUT: Values: 12530.9 08:36:56 INFO - 2015-11-23 08:36:56,277 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,277 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:28083.33 Median:28083.33 stddev:0.00 (0.0%) 08:36:56 INFO - 2015-11-23 08:36:56,277 DEBUG : BROWSER_OUTPUT: Values: 28083.3 08:36:56 INFO - 2015-11-23 08:36:56,277 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,277 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:563.06 Median:563.06 stddev:0.00 (0.0%) 08:36:56 INFO - 2015-11-23 08:36:56,277 DEBUG : BROWSER_OUTPUT: Values: 563.1 08:36:56 INFO - 2015-11-23 08:36:56,277 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,277 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4542.99 Median:4542.99 stddev:0.00 (0.0%) 08:36:56 INFO - 2015-11-23 08:36:56,277 DEBUG : BROWSER_OUTPUT: Values: 4543.0 08:36:56 INFO - 2015-11-23 08:36:56,277 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:36:56 INFO - 2015-11-23 08:36:56,277 DEBUG : BROWSER_OUTPUT: 08:36:56 INFO - 2015-11-23 08:36:56,950 INFO : Browser exited with error code: 0 08:36:56 INFO - 2015-11-23 08:36:56,968 INFO : Completed test v8_7 (00:00:37) 08:36:57 INFO - 2015-11-23 08:36:57,254 INFO : Completed test suite (00:20:07) 08:36:57 INFO - 2015-11-23 08:36:57,254 DEBUG : Working with test: dromaeo_css 08:36:57 INFO - 2015-11-23 08:36:57,254 DEBUG : Generating results file: dromaeo_css 08:36:57 INFO - 2015-11-23 08:36:57,255 DEBUG : Working with test: kraken 08:36:57 INFO - 2015-11-23 08:36:57,255 DEBUG : Generating results file: kraken 08:36:57 INFO - 2015-11-23 08:36:57,255 INFO : javascript benchmark 08:36:57 INFO - 2015-11-23 08:36:57,255 DEBUG : Working with test: v8_7 08:36:57 INFO - 2015-11-23 08:36:57,255 DEBUG : Generating results file: v8_7 08:36:57 INFO - 2015-11-23 08:36:57,255 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:36:57 INFO - 2015-11-23 08:36:57,747 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 08:36:57 INFO - 2015-11-23 08:36:57,747 DEBUG : process_Request line: dromaeo_css 7686.58 graph.html#tests=[[72,64,59]] 08:36:57 INFO - 2015-11-23 08:36:57,747 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:36:57 INFO - 2015-11-23 08:36:57,776 DEBUG : process_Request line: kraken 1403.70 graph.html#tests=[[232,64,59]] 08:36:57 INFO - 2015-11-23 08:36:57,776 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:36:57 INFO - 2015-11-23 08:36:57,882 DEBUG : process_Request line: v8_7 20578.52 graph.html#tests=[[230,64,59]] 08:36:57 INFO - 2015-11-23 08:36:57,883 INFO : javascript benchmark 08:36:57 INFO - 2015-11-23 08:36:57,885 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3325.767196296259, 3458.948115778262, 3498.807565583892, 3506.070891213829, 3526.8236588170594, 4773.952260477394, 5131.435542090367, 5233.267342572039, 5557.666540007603, 5684.914726279109, 5625.987322281988, 5629.577781666371, 5639.774409023634, 5758.3090029196555, 5796.362400135981, 5103.132467480528, 5125.051865404912, 5149.201873709562, 5149.871253218662, 5210.895782084356, 5196.428392876781, 5255.894882102356, 5273.525382715555, 5275.208718692188, 5314.840554783363, 4271.145770845843, 5047.192449208109, 5177.870553236162, 5188.221766735001, 5225.190095535198, 5189.896202075937, 5192.818251361222, 5203.19350500673, 5210.635255532115, 5216.947830521722, 5104.055749686283, 5145.819896303648, 5154.098032844237, 5160.767765450526, 5167.276581278624, 5631.380548139701, 5637.577181711347, 5639.8590035249035, 5648.322201335866, 5648.406917273709, 6126.111713801519, 6174.320824709278, 6180.505559555225, 6186.9381306186815, 6196.659183744892, 6294.653794041325, 6298.0, 6310.274318453391, 6313.589616674902, 6313.810585682437, 5776.913346299767, 5862.384449632727, 5872.265966754156, 5873.500752436048, 5882.647041177543, 5493.642913210629, 6132.50939924805, 6187.102870083812, 6259.780907668301, 6279.309275979638, 6151.446369826737, 6169.198004259418, 6170.012797952306, 6174.320824709278, 6191.938080619227, 6286.622802631857, 6295.559310848288, 6307.400796924285, 6309.3690630936535, 6309.432151106423, 6014.1580178775, 6107.439248620928, 6124.319907527303, 6202.224721909762, 6231.22109736283, 6127.417895299939, 6140.9030504681, 6149.569530132848, 6160.260768707783, 6209.379062093845, 4923.852284431473, 4942.061008408391, 4949.82675606352, 4950.381202349707, 4976.601871850244, 4384.934225986613, 4426.955730442654, 4431.578999994996, 4460.464744230713, 4462.41988541494, 5475.178723191553, 5620.971895140498, 5625.296837895263, 5626.634268772516, 5644.689542075143, 6211.9689401552705, 6212.72042758077, 6227.688615569204, 6228.875422491525, 6230.59501132425, 5646.8023619173955, 5664.405237450091, 5680.11958146488, 5684.431556844283, 5698.572607054404, 5316.255724198615, 5378.273933019071, 5406.270153529302, 5458.208559758813, 5471.452854714496, 6309.0, 6322.620642761449, 6341.619502829845, 6346.397092276226, 6347.492200623939, 6085.786997455068, 6234.719437625408, 6266.780662676784, 6274.996000639876, 6295.244570651551, 4956.479569645136, 6167.044108163242, 6291.811245662638, 6332.366763323631, 6451.000094985097, 6177.444030037318, 6188.752449901954, 6192.690365481798, 6194.039923811816, 6207.689615519296, 5413.648112872808, 6061.636301821904, 6277.748890044347, 6278.686065696788, 6283.528735344776, 5991.970040149771, 6004.909926351021, 6018.939810601838, 6142.109394137736, 6156.199694039925, 5928.673922934107, 6055.545834062551, 6084.11780291864, 6095.969520152371, 6103.572749907464, 5333.813316533902, 5930.911036334544, 5939.554533410098, 5944.702764861826, 5956.404359564009, 5982.88034239304, 5991.490723288569, 6029.276486821609, 6146.723397447036, 6149.7847575334645, 5970.731317090655, 5992.101184822312, 5999.250093738283, 6019.51843852482, 6041.456268935817], "unit": "ms", "name": "dojo.html", "value": 5661.527177638737}, {"lowerIsBetter": false, "replicates": [541.762444798413, 568.0853825341203, 578.3349148479248, 617.7312868902028, 633.6008314761699, 32341.353172936557, 33027.66972330276, 39950.8014759557, 40281.0, 40423.393649095284, 43900.68297951059, 44437.0, 44448.77775611117, 44473.33290000653, 44680.55319446805, 8702.695405660803, 8855.95572022139, 9746.610135594568, 9914.702558923243, 9924.900750992507, 8720.651173953034, 11888.940555297211, 11991.16061875669, 11995.16033877629, 12008.879911200864, 11651.766964660701, 11698.707532311675, 11731.882681173207, 11741.354225517593, 11745.354005529693, 8766.824663506757, 8854.070322616097, 11707.121965852553, 11721.413929303544, 11724.648260552198, 8744.956275218647, 11776.705582360424, 11901.880981190165, 11904.98807601355, 11960.34218118003, 41479.0, 41829.0, 41913.58086419127, 42088.57911420877, 42130.78934605338, 3174.8553048278027, 3355.832208389595, 3377.4596064629786, 3612.6026137124895, 3783.7135373972706, 28761.13716588506, 29349.559756603667, 29373.70626293731, 29603.40793184146, 29879.701202987908, 2793.189974907295, 2836.4327134572964, 2866.5413533834485, 2888.959974409211, 2970.4059188162237, 444757.0, 446262.7686861545, 449173.0, 449483.0, 449825.0, 55628.0, 57005.0, 57140.42859571351, 67428.32571674319, 68239.29401764995, 69569.0, 70583.64708176529, 70648.2935170652, 70750.64624676947, 70754.0, 15585.298661560257, 15616.0, 15834.366625334858, 15834.60413489672, 15852.286647100678, 12070.336131512851, 12170.81743773844, 12171.14801963854, 12177.452014659184, 12206.450709718085, 20540.383788486368, 20945.89527052355, 20989.265375711777, 21033.36899892975, 21034.47413814636, 7194.82700033979, 8427.957860210783, 8619.094995025433, 8634.309255259564, 8728.432651877607, 943.5233859010626, 992.4541502173776, 1010.3682552677374, 1033.813913495578, 1103.8509801176738, 29645.703542964293, 29726.702732972826, 29800.552991705143, 29910.0, 30072.0, 1134.9546018159347, 1149.2587281203578, 1177.31127290536, 1207.8309036734863, 1291.0833308350989, 12782.488700452064, 15057.623559410884, 15231.543053708407, 15251.0, 15464.84535154634, 522227.0, 526462.0, 526519.3674031605, 527134.0, 527367.0, 58935.41064589385, 59247.703761480916, 59262.0, 59307.0, 59447.0, 1448.6740483391152, 1457.8177727784027, 1468.1851572377648, 1477.0694462488564, 1484.7624380099132, 29043.56434653524, 29085.563716544693, 29187.70812291935, 29750.0, 30286.84856575703, 51649.0, 51929.48070519398, 51942.96114077773, 51949.480505194464, 52091.95816083581, 487527.0, 638653.0, 645265.0, 665045.0, 665817.0, 31568.210794730316, 31753.8412307937, 31789.36421271608, 31839.522407164375, 31965.520517191795, 44232.0, 44664.0, 45263.77368113138, 45278.0, 46406.0, 36897.81551092227, 37385.0, 37916.0, 37920.620793791706, 37949.810250949675, 36065.27869442649, 36924.630753692116, 37213.25573488461, 37349.066273343386, 37515.87452376433, 36726.816365918, 37024.88925332244, 37232.62767372291, 37310.880673579835, 37335.6266437343, 30972.690273097884, 31903.780204967516, 32152.51771223387, 32347.19132021718, 32394.028179154662, 21749.130034798432, 24790.76046197719, 25054.74945250597, 25100.874495627406, 25150.371240718396], "unit": "ms", "name": "ext.html", "value": 20447.03379353189}, {"lowerIsBetter": false, "replicates": [358.62702789099336, 359.7176216669915, 363.709032773781, 364.5115545169472, 364.81212175729485, 13060.412281447334, 15681.29434175462, 19495.317663881768, 20187.0, 20907.163713451482, 16956.4065257716, 19076.90461547694, 19397.93311367874, 19650.508737281576, 19677.704834427495, 5887.4406931341455, 5923.9407605923925, 5981.983062879321, 5988.041913293874, 6001.219841420631, 4242.490901091874, 4388.49532303786, 5989.520838332945, 6028.4272994065495, 6045.969770151143, 4337.284348082579, 6336.46140078094, 6507.869842603145, 6520.608763474207, 6522.673866306665, 6468.935310646904, 6517.185351831021, 6543.836404089936, 6565.507586930976, 6569.671516424208, 5625.831225063254, 5631.211630371751, 5639.069553523664, 5652.547796176296, 5655.462731040545, 24885.5022899541, 25070.373240668945, 25268.873655631603, 25436.491270174676, 25475.745242547524, 2142.582196471689, 2150.8817015064164, 2171.3811563704426, 2217.6119179143748, 2400.447896983686, 14660.266986650624, 14730.89518286122, 15046.022008569407, 15157.848421515753, 15575.37698492059, 1712.1781544858538, 1736.748171515136, 1752.9912350438294, 1768.6551122531112, 1768.938087166956, 95872.041279587, 97981.5100924498, 98471.0, 99358.0064199356, 100143.49928250312, 9979.850302245472, 10136.54385552652, 10344.22418318629, 10345.327553708985, 10436.112930400848, 12350.62948111558, 12383.876161238453, 12384.690382740504, 12390.876091238972, 12394.194377365622, 6663.900041499382, 8026.197380261927, 8072.112067672551, 8075.636596353178, 8136.0, 7326.743563975236, 7681.231876812274, 8220.424570280142, 8288.70989515364, 8288.751337459886, 4665.2768820832825, 5044.520770526868, 6033.306169790442, 6047.941610218282, 6070.848228794227, 1533.7545992641124, 1626.8779841511935, 1701.8893771904786, 1724.1379310344828, 1747.816479269684, 825.2119226139023, 831.5883637599427, 999.4652860719481, 1029.2743615750878, 1052.3580615824342, 17405.390811321544, 18734.063296835102, 20513.79486205119, 21443.892780535996, 21587.892060539598, 979.6571200080058, 987.7036888933292, 991.5290237137447, 1021.5249908792447, 1070.7751372211767, 11342.206045576895, 11470.713232169262, 11481.531470733666, 11595.710107247387, 11599.594014209631, 594721.0, 601263.0, 607325.0, 692963.0, 698485.0, 115892.42053789846, 118769.81230187588, 118919.0, 121797.78202218041, 123517.38241308916, 902.7652810269246, 1173.7945859474728, 1183.0712890381183, 1186.8160435132568, 1195.6413076077142, 14775.0, 17756.200970956597, 17921.37275195362, 17939.641207176046, 18215.45353639393, 48535.51464485451, 48845.755771220916, 50293.49706502888, 52804.47195527996, 53751.19373209327, 70580.94128587973, 71466.64266678633, 71622.0, 77845.61077194578, 79758.6012069936, 15998.600034999217, 16376.918115409348, 16638.584035399213, 16872.0, 16943.23755430984, 19989.508655359678, 20487.692684609443, 20679.172833086508, 21503.8172900492, 22122.33632991013, 13247.997761689863, 13702.93148534251, 15632.140232287333, 16546.255418506436, 16785.0, 16400.589985250463, 16570.420035298706, 16590.0, 16746.832531675016, 17507.387241447, 13686.589402317946, 14012.159270443806, 15304.617384565476, 17009.489715308562, 17531.298748049936, 16771.91614041922, 16879.66240675155, 17077.40229091976, 17102.48692539226, 17126.91436542859, 16983.320667173175, 17019.14904254807, 17033.97796132236, 17470.0, 17499.0], "unit": "ms", "name": "jquery.html", "value": 10432.289321525359}, {"lowerIsBetter": false, "replicates": [1522.7791970164326, 1526.473366688492, 1664.217817625717, 1835.3301045118533, 1845.5570663040867, 6852.7258909643615, 7694.076710794706, 7991.840163196733, 8374.748757537269, 8504.447210931285, 8184.140665230155, 8369.581520923944, 8375.874361884577, 8379.539125348103, 8384.706535271267, 2337.9883100584516, 2345.7067866516686, 2350.6591544226076, 2352.4236562042324, 2356.163561935514, 2379.274321331993, 2386.677798497207, 2435.768601982809, 2442.3649851038767, 2445.4131008557906, 2356.2106694257445, 2384.6899903012672, 2391.1272385579314, 2397.244867866625, 2405.0980882169188, 2014.9496262593407, 2324.1749179041535, 2332.790048895599, 2336.6728657987896, 2353.7175538935353, 2367.0531787285054, 2387.6418537219383, 2392.306231192952, 2394.030417680842, 2395.916142934989, 8331.916680833236, 8392.496450212946, 8393.24460798531, 8396.286315663176, 8403.453775504573, 4620.260758278693, 4835.734034628094, 4864.927026094611, 5005.049040682259, 5140.897182056376, 3520.366334059868, 3577.141486043357, 3639.1811842335264, 3650.0874781304674, 3651.3427583035045, 915.7893684452547, 1037.061459379263, 1081.7620123572801, 1627.1620115640455, 1655.6440365321514, 11112.999830015257, 11216.495257713423, 11244.718882027933, 11249.381284029376, 11264.549418023269, 3926.6662333701383, 4532.456105267389, 4546.40896683429, 4636.976815115903, 4641.419822522184, 9150.08499150093, 9178.082191780768, 9179.357444978921, 9183.219426348835, 9185.494797786187, 5134.332536770196, 5160.432352441228, 5168.715720635326, 5169.71566563835, 5190.714510701872, 4725.054989002213, 4748.928766068511, 4765.666403351802, 4770.833020844254, 4773.307870358779, 3581.8567257309414, 3591.2637909228547, 3620.511230983836, 3649.2701459708164, 3657.122290650225, 1114.676743744305, 1147.1969621265148, 1150.9194356394971, 1152.5793085523892, 1154.1343992005995, 1189.8096304591224, 1197.2756482328066, 1227.643983244849, 1231.9630411087683, 1234.3334599316447, 7905.407094467938, 7952.960235198787, 7972.681092756341, 8026.237507436784, 8034.276915077554, 497.5820310938829, 502.0461123864686, 502.20149961561293, 505.68142070495355, 512.4593553800702, 5974.910376344358, 6132.908006379907, 6185.690715464209, 6246.750129994749, 6266.279377871603, 9790.461524616214, 10065.194784417228, 10122.544485498023, 10173.389596624227, 10176.796464070678, 9206.401583897024, 9402.15380615748, 9902.405855648683, 10168.644097456554, 10250.897491024994, 7884.21157884207, 7966.044074710956, 7984.640691168911, 7986.321162701235, 8049.2755651992775, 7768.84462310762, 7821.804454888445, 7887.40844436681, 7888.368930485432, 7918.802029949297, 8972.667106552213, 9059.275257979214, 9086.36395452312, 9141.314401420053, 9206.401583897024, 9218.907810922074, 9431.481268530297, 9463.905360946577, 9469.857952130862, 9478.668246611336, 6911.5853048817235, 6931.029655848286, 6947.548409353376, 6969.686364113729, 7014.333638304353, 7994.960025199837, 8071.959640201761, 8071.959640201761, 8072.950516433061, 8081.757547273592, 7426.4430167736555, 7545.094588649397, 7549.0563679540055, 7625.275598818103, 7677.347425468898, 7402.888956665546, 7405.518641288299, 7438.776836694908, 7528.397728181842, 7544.585047822423, 7490.662920168715, 7575.242475752601, 7578.507397019176, 7582.924170758442, 7598.620068996417, 6803.659817009031, 6814.250432452525, 6832.453403727789, 6841.555298905555, 6887.483438742014, 6922.965385173042, 6969.477289203229, 6992.0910281663055, 7000.824979375557, 7002.719891204295], "unit": "ms", "name": "mootools.html", "value": 4674.685198891413}, {"lowerIsBetter": false, "replicates": [317.28294055434714, 321.7972677213357, 325.1335191714082, 328.2172019732937, 329.43337459569534, 6722.663866806664, 6739.494537909658, 6765.898511522325, 6856.58860468371, 6886.449084073274, 5289.603279754024, 6756.2568117507035, 6761.459083273338, 6772.796816095512, 6780.660966951645, 4967.875803104916, 4970.353853998975, 4973.328600638913, 4981.0, 4984.252362145671, 4712.434507859061, 4835.468098509161, 4858.708477491344, 4878.365812444377, 4912.606991440676, 4856.198727210024, 4858.975705121487, 4860.392450943632, 4865.902681946359, 4870.853874383756, 4597.632189424838, 4697.906041879178, 4733.573978341931, 4846.4911184325665, 4869.001854619794, 4002.3196056670436, 4091.0386059276343, 4098.057446787256, 4106.630403263721, 4113.9920719423935, 6438.581492202992, 6459.67701614922, 6498.935010649928, 6509.381608747161, 6546.70539825709, 5470.234167216593, 5632.0, 5636.971815140898, 5648.858778530528, 5794.188813566104, 7191.496595238335, 7212.74755383559, 7218.4225261979445, 7225.349718525305, 7234.0, 4652.255639097728, 4655.51117132703, 4660.464046634613, 4662.976685116587, 4670.206064968964, 5998.340182579913, 7575.166731659513, 7619.199984001711, 7637.579933103677, 7656.516285090271, 4270.701050926467, 4344.652427805768, 4692.131955588227, 4714.4106986626675, 4720.268358404384, 4788.138135135709, 4796.856094317176, 4800.279958006257, 4800.615950723934, 4807.7836497357, 4982.950170498249, 5766.106253530709, 5769.278840144982, 5800.9129863052085, 5830.679312637845, 7008.3206316684145, 7295.489315747953, 7315.085614298213, 7329.780106596811, 7333.266673332624, 1646.8517833395051, 1669.5575672446812, 1669.8998060116432, 1672.2809192047293, 1672.6821903838231, 950.6720181537497, 1031.5873650539695, 1053.9789204216027, 1073.9194560408, 1075.3977772447454, 6733.966330168318, 6759.662016899136, 6771.424428923597, 6776.186857577122, 6778.661066946633, 7557.168711441737, 7561.7731468056045, 7571.734989275349, 7573.091229052549, 7586.734464293723, 6590.0, 6729.6971636276485, 6732.427743641747, 6737.764178253738, 6814.2163651180745, 3663.560372755233, 3984.4023396490757, 4021.3163762160502, 4024.2756303865585, 4025.1345960618023, 6170.444659980623, 7849.529028258323, 7880.763577092696, 7934.761957141295, 7946.284834364935, 7424.406047516186, 7712.344450721639, 7896.447248692536, 7923.801904952307, 7945.841083178304, 6281.748730050838, 7883.566403847729, 7892.368610511146, 7894.763157105066, 7896.526208427513, 6490.8052758417325, 7298.6715597797165, 7617.42869284817, 7638.847223055619, 7656.387489000978, 7164.856702866018, 7434.107907051189, 7442.218567050381, 7444.702211911463, 7447.962760186165, 7781.766547003599, 7792.181820908723, 7795.766127016199, 7821.569813660303, 7843.490173138728, 4581.93127103087, 4662.930056049094, 4713.151632706145, 4718.646101542467, 4725.527447255247, 7495.213002634645, 7496.587687677012, 7497.287757663232, 7508.43686723487, 7512.661930213263, 7206.423486121203, 7209.963950180216, 7211.8004008777825, 7219.530730502711, 7231.63841807918, 6974.895376569254, 7157.355837974607, 7172.0, 7189.065421495172, 7210.134783825974, 5960.701964901824, 6994.755183568755, 7044.542104763174, 7097.751578694721, 7101.893471598033, 7233.0597022386755, 7243.0946131733535, 7247.2390399007345, 7249.238829922782, 7269.563826170447, 6303.9684801575695, 6684.598924064571, 6913.930860691329, 7206.603636800026, 7222.855542889218], "unit": "ms", "name": "prototype.html", "value": 5224.570079796823}, {"lowerIsBetter": false, "replicates": [732.2494443195724, 748.1209578744973, 792.0851416613809, 794.2295972906279, 821.6138414944974, 14763.852361476382, 15354.769678454832, 15366.84633153668, 15370.923145384286, 15399.615009624751, 14334.856651433483, 14469.131852088882, 14490.637734056654, 14503.564893053199, 14513.782293265584, 759.8062494064034, 770.1682183242085, 928.9210417114554, 966.8598053282306, 970.9563069661847, 754.9207333230014, 951.4719330771454, 969.0019280141465, 969.9854502182437, 972.9880923839198, 986.6793292180034, 1028.9897101029005, 1029.27950434696, 1033.5090831854845, 1034.6482196053341, 791.7149826062641, 794.0590400375573, 805.7864665863551, 1000.3397757480064, 1027.3476342522515, 594.8929192745304, 596.9194158788551, 746.8319628083692, 760.2321655128305, 762.1349768013345, 15241.695166096615, 15400.922995385063, 15408.0, 15409.537713868602, 15417.15205663688, 12199.20705154171, 12606.243625382414, 12975.1566148201, 13152.868471315356, 13380.130291531019, 10059.849102263472, 10181.949090254502, 10231.641892533726, 10329.122024627915, 10889.074428673572, 3521.8943431696835, 3538.982305088484, 3544.149404142987, 3553.289342131558, 3556.5020897074432, 54862.0, 70959.29040709579, 71763.64118179429, 72295.55408891792, 72586.63706681432, 9507.0, 9563.760905977268, 9722.326210548943, 9736.026397360349, 9785.559649815776, 22123.570846518815, 22490.887545562167, 22498.88750556237, 22515.77484225137, 22531.211407600655, 6837.726490940307, 6936.271691472423, 7026.9648651756415, 7755.922440775633, 7788.688452461838, 3861.2663593917064, 3937.3306537888056, 3952.2490726761825, 3983.5817239190046, 4101.938470922939, 3625.6736893679695, 3685.207680348738, 3688.0964163780045, 3692.0, 3693.9815300923324, 645.8869697802866, 658.2923357390824, 658.2989116590816, 675.6115233740619, 685.2975699907635, 475.35589276530493, 481.14762618987896, 506.74915916621507, 516.3932379454127, 522.6733291692692, 8176.223258790405, 8656.22094011542, 8764.079771624016, 8922.152395522415, 9464.0, 494.78229578985133, 495.57628227865376, 517.9093658609728, 519.2056154084257, 541.8753686652092, 3279.2785587170783, 3861.9613803861603, 4300.720453170534, 4367.563243675607, 4466.3747075409465, 94473.52763236278, 94966.0, 96308.0, 96452.0, 96558.0, 82107.0, 82637.58681206555, 83387.58306208551, 83433.0, 84009.73985390224, 54908.17637735437, 75110.62444687741, 75686.0, 75829.6208518954, 76906.61546692342, 8580.699675511327, 8608.91391086081, 8882.0, 8955.776105597412, 9190.080991900757, 51476.48523514717, 52027.47972520226, 52110.0, 52379.476205238985, 52954.0, 66556.66721666361, 73776.0, 74586.6270668665, 74670.62664686641, 74699.0, 10902.945485272523, 10937.234393592373, 10974.231803773659, 11133.721656958642, 11493.080553555528, 12020.218685785396, 12130.878691212974, 12162.574309899112, 12331.938340308241, 12940.352982351033, 11528.769424611628, 11674.533018679158, 11862.940685296518, 12040.75918481608, 12319.137660363689, 11459.369734664673, 12116.636500904988, 12125.575604853788, 12132.75734485323, 12192.0, 11356.772864542829, 11454.82817757751, 11477.540898364306, 11493.712657183638, 12319.322437266037, 9676.516174191403, 9827.803443930938, 10147.34042287249, 10150.644727434505, 10546.57813687444, 9000.68497602607, 9461.810763784548, 9574.856377154209, 9739.707808765748, 9778.41329520231], "unit": "ms", "name": "yui.html", "value": 6992.197675655881}], "name": "dromaeo_css", "value": 7686.5798806879775}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [102.0, 111.0, 109.0, 110.0, 109.0, 109.0, 111.0, 110.0, 109.0, 116.0], "unit": "ms", "name": "ai-astar", "value": 109.6}, {"lowerIsBetter": true, "replicates": [164.0, 148.0, 142.0, 153.0, 117.0, 140.0, 138.0, 116.0, 152.0, 117.0], "unit": "ms", "name": "audio-beat-detection", "value": 138.7}, {"lowerIsBetter": true, "replicates": [194.0, 257.0, 203.0, 184.0, 185.0, 184.0, 184.0, 185.0, 183.0, 188.0], "unit": "ms", "name": "audio-dft", "value": 194.7}, {"lowerIsBetter": true, "replicates": [63.0, 70.0, 77.0, 71.0, 55.0, 77.0, 57.0, 73.0, 73.0, 55.0], "unit": "ms", "name": "audio-fft", "value": 67.1}, {"lowerIsBetter": true, "replicates": [83.0, 84.0, 116.0, 73.0, 75.0, 76.0, 103.0, 75.0, 72.0, 100.0], "unit": "ms", "name": "audio-oscillator", "value": 85.7}, {"lowerIsBetter": true, "replicates": [87.0, 88.0, 125.0, 87.0, 88.0, 88.0, 87.0, 87.0, 88.0, 88.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 91.3}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 95.0], "unit": "ms", "name": "imaging-darkroom", "value": 93.9}, {"lowerIsBetter": true, "replicates": [86.0, 83.0, 84.0, 85.0, 91.0, 83.0, 84.0, 86.0, 85.0, 84.0], "unit": "ms", "name": "imaging-desaturate", "value": 85.1}, {"lowerIsBetter": true, "replicates": [54.0, 57.0, 55.0, 56.0, 54.0, 52.0, 58.0, 83.0, 82.0, 81.0], "unit": "ms", "name": "json-parse-financial", "value": 63.2}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 63.0, 65.0, 66.0, 67.0, 66.0, 66.0, 67.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 65.1}, {"lowerIsBetter": true, "replicates": [81.0, 83.0, 81.0, 82.0, 80.0, 81.0, 81.0, 81.0, 82.0, 81.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 81.3}, {"lowerIsBetter": true, "replicates": [119.0, 121.0, 120.0, 111.0, 117.0, 120.0, 125.0, 118.0, 123.0, 122.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 119.6}, {"lowerIsBetter": true, "replicates": [133.0, 134.0, 132.0, 132.0, 131.0, 138.0, 170.0, 166.0, 159.0, 151.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 144.6}, {"lowerIsBetter": true, "replicates": [64.0, 63.0, 64.0, 63.0, 64.0, 64.0, 64.0, 64.0, 63.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.8}], "name": "kraken", "value": 1403.6999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [175.43859649122808], "unit": "score", "name": "Richards", "value": 201.22140000000002}, {"lowerIsBetter": false, "replicates": [160.46213093709883], "unit": "score", "name": "DeltaBlue", "value": 412.04737600000004}, {"lowerIsBetter": false, "replicates": [281.6901408450704], "unit": "score", "name": "Encrypt", "value": 944.9425500000004}, {"lowerIsBetter": false, "replicates": [4744.075829383886], "unit": "score", "name": "Decrypt", "value": 56.10808291708288}, {"lowerIsBetter": false, "replicates": [1712.3287671232877], "unit": "score", "name": "RayTrace", "value": 432.1535759999999}, {"lowerIsBetter": false, "replicates": [789.2659826361484], "unit": "score", "name": "Earley", "value": 844.4086210000003}, {"lowerIsBetter": false, "replicates": [12530.864197530864], "unit": "score", "name": "Boyer", "value": 53.185717241379315}, {"lowerIsBetter": false, "replicates": [28083.333333333332], "unit": "score", "name": "RegExp", "value": 32.43863501483677}, {"lowerIsBetter": false, "replicates": [563.063063063063], "unit": "score", "name": "Splay", "value": 144.728016}, {"lowerIsBetter": false, "replicates": [4542.986425339366], "unit": "score", "name": "NavierStokes", "value": 326.65737051792814}], "name": "v8_7", "value": 20578.51875147262}]} 08:36:57 INFO - RETURN: dromaeo_css: 7686.58 08:36:57 INFO - RETURN: kraken: 1403.70 08:36:57 INFO - RETURN: v8_7: 20578.52 08:36:57 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,59]]", "result": "20578.52"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1403.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7686.58"}}} 08:36:57 INFO - Return code: 0 08:36:57 INFO - # TBPL SUCCESS # 08:36:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:36:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:36:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:36:57 INFO - 2015-11-23 08:36:57.989 screenresolution[1669:9930] starting screenresolution argv=screenresolution get 08:36:57 INFO - 2015-11-23 08:36:57.992 screenresolution[1669:9930] Display 0: 1600x1200x32@60 08:36:57 INFO - 2015-11-23 08:36:57.998 screenresolution[1670:9933] starting screenresolution argv=screenresolution list 08:36:58 INFO - Available Modes on Display 0 08:36:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:36:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:36:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:36:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:36:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:36:58 INFO - Intel Iris: 08:36:58 INFO - Chipset Model: Intel Iris 08:36:58 INFO - Type: GPU 08:36:58 INFO - Bus: Built-In 08:36:58 INFO - VRAM (Dynamic, Max): 1536 MB 08:36:58 INFO - Vendor: Intel (0x8086) 08:36:58 INFO - Device ID: 0x0a2e 08:36:58 INFO - Revision ID: 0x0009 08:36:58 INFO - Displays: 08:36:58 INFO - Display: 08:36:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:36:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:36:58 INFO - Display Serial Number: 1600x1200 60 08:36:58 INFO - Main Display: Yes 08:36:58 INFO - Mirror: Off 08:36:58 INFO - Online: Yes 08:36:58 INFO - Rotation: Supported 08:36:58 INFO - Return code: 0 08:36:58 INFO - Running post-action listener: _resource_record_post_action 08:36:58 INFO - Running post-run listener: _resource_record_post_run 08:36:59 INFO - Total resource usage - Wall time: 1239s; CPU: 52.0%; Read bytes: 39015936; Write bytes: 693936640; Read time: 419; Write time: 2503 08:36:59 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345606656; Write bytes: 350936576; Read time: 27085; Write time: 665 08:36:59 INFO - run-tests - Wall time: 1209s; CPU: 52.0%; Read bytes: 36984320; Write bytes: 341500928; Read time: 397; Write time: 1835 08:36:59 INFO - Running post-run listener: _upload_blobber_files 08:36:59 INFO - Blob upload gear active. 08:36:59 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:36:59 INFO - Copying logs to upload dir... 08:36:59 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1284.870940 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2015-11-23 08:36:59.280081) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 08:36:59.283836) ========= 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.jgcNMiRvsa/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.dtCiUIUD13/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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/sRPG6_P4QoGtb_JChtqtXg/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009464 build_url: 'https://queue.taskcluster.net/v1/task/sRPG6_P4QoGtb_JChtqtXg/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 08:36:59.307362) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 08:36:59.307706) ========= 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.jgcNMiRvsa/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.dtCiUIUD13/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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.004840 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 08:36:59.356511) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 08:36:59.356830) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 08:36:59.357269) ========= ========= Total master_lag: 0.21 =========