builder: fx-team_yosemite_test-dromaeojs slave: t-yosemite-r5-0097 starttime: 1447694119.88 results: success (0) buildid: 20151116070823 builduid: 4949b86c10dd4439bd5a5fc70e179da5 revision: 2443d724b1a233074f2ffdee1f2ed3e1f6792650 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:19.876243) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:19.878497) ========= ========= Started set props: basedir (results: 0, elapsed: 4 secs) (at 2015-11-16 09:15:19.878840) ========= 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.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners TMPDIR=/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/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:0x0:0x0 using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006116 basedir: '/builds/slave/test' ========= master_lag: 4.07 ========= ========= Finished set props: basedir (results: 0, elapsed: 4 secs) (at 2015-11-16 09:15:23.958421) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2015-11-16 09:15:23.958724) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2015-11-16 09:15:27.762284) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:27.762893) ========= 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.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners TMPDIR=/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/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:0x0:0x0 using PTY: False program finished with exit code 0 elapsedTime=0.017760 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:27.804994) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:27.805301) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:27.805671) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:27.805976) ========= 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.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners TMPDIR=/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/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:0x0:0x0 using PTY: False --2015-11-16 09:15:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 8.78M=0.001s 2015-11-16 09:15:27 (8.78 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.181562 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:28.007539) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:28.007900) ========= 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.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners TMPDIR=/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/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:0x0:0x0 using PTY: False program finished with exit code 0 elapsedTime=0.076860 ========= master_lag: 0.34 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:28.420496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:28.420890) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2443d724b1a233074f2ffdee1f2ed3e1f6792650 --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 2443d724b1a233074f2ffdee1f2ed3e1f6792650 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners TMPDIR=/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/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:0x0:0x0 using PTY: False 2015-11-16 09:15:28,505 truncating revision to first 12 chars 2015-11-16 09:15:28,505 Setting DEBUG logging. 2015-11-16 09:15:28,505 attempt 1/10 2015-11-16 09:15:28,506 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2443d724b1a2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-16 09:15:28,779 unpacking tar archive at: fx-team-2443d724b1a2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.527918 ========= master_lag: 0.18 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:29.131190) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:29.131455) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:29.353645) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:29.353925) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-16 09:15:29.354270) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 47 secs) (at 2015-11-16 09:15:29.359299) ========= /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.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners TMPDIR=/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/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:0x0:0x0 using PTY: False 09:15:29 INFO - MultiFileLogger online at 20151116 09:15:29 in /builds/slave/test 09:15:29 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 09:15:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:29 INFO - {'append_to_log': False, 09:15:29 INFO - 'base_work_dir': '/builds/slave/test', 09:15:29 INFO - 'blob_upload_branch': 'Fx-Team', 09:15:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:29 INFO - 'branch': 'Fx-Team', 09:15:29 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:29 INFO - 'config_files': ('talos/mac_config.py',), 09:15:29 INFO - 'default_actions': ('clobber', 09:15:29 INFO - 'read-buildbot-config', 09:15:29 INFO - 'download-and-extract', 09:15:29 INFO - 'populate-webroot', 09:15:29 INFO - 'create-virtualenv', 09:15:29 INFO - 'install', 09:15:29 INFO - 'run-tests'), 09:15:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:29 INFO - 'download_minidump_stackwalk': True, 09:15:29 INFO - 'download_symbols': 'ondemand', 09:15:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:29 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:29 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:29 INFO - 'installer_path': 'installer.exe', 09:15:29 INFO - 'log_level': 'info', 09:15:29 INFO - 'log_name': 'talos', 09:15:29 INFO - 'log_to_console': True, 09:15:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:15:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:15:29 INFO - 'opt_config_files': (), 09:15:29 INFO - 'pip_index': False, 09:15:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:29 INFO - 'cmd': ('bash', 09:15:29 INFO - '-c', 09:15:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:29 INFO - 'enabled': True, 09:15:29 INFO - 'halt_on_failure': False, 09:15:29 INFO - 'name': 'check_screen_resolution'},), 09:15:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:29 INFO - 'cmd': ('bash', 09:15:29 INFO - '-c', 09:15:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:29 INFO - 'enabled': True, 09:15:29 INFO - 'halt_on_failure': False, 09:15:29 INFO - 'name': 'check_screen_resolution'},), 09:15:29 INFO - 'run_cmd_checks_enabled': True, 09:15:29 INFO - 'sps_profile': False, 09:15:29 INFO - 'sps_profile_interval': 0, 09:15:29 INFO - 'suite': 'dromaeojs', 09:15:29 INFO - 'system_bits': '64', 09:15:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:29 INFO - 'title': 't-yosemite-r5-0097', 09:15:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:29 INFO - 'use_talos_json': True, 09:15:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:29 INFO - 'work_dir': 'build'} 09:15:29 INFO - ##### 09:15:29 INFO - ##### Running clobber step. 09:15:29 INFO - ##### 09:15:29 INFO - Running pre-action listener: _resource_record_pre_action 09:15:29 INFO - Running main action method: clobber 09:15:29 INFO - rmtree: /builds/slave/test/build 09:15:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:33 INFO - Running post-action listener: _resource_record_post_action 09:15:33 INFO - ##### 09:15:33 INFO - ##### Running read-buildbot-config step. 09:15:33 INFO - ##### 09:15:33 INFO - Running pre-action listener: _resource_record_pre_action 09:15:33 INFO - Running main action method: read_buildbot_config 09:15:33 INFO - Using buildbot properties: 09:15:33 INFO - { 09:15:33 INFO - "properties": { 09:15:33 INFO - "buildnumber": 1236, 09:15:33 INFO - "product": "firefox", 09:15:33 INFO - "script_repo_revision": "production", 09:15:33 INFO - "builddir": "fx-team_yosemite_test-dromaeojs", 09:15:33 INFO - "repository": "", 09:15:33 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10 fx-team talos dromaeojs", 09:15:33 INFO - "buildid": "20151116070823", 09:15:33 INFO - "slavename": "t-yosemite-r5-0097", 09:15:33 INFO - "pgo_build": "False", 09:15:33 INFO - "basedir": "/builds/slave/test", 09:15:33 INFO - "project": "", 09:15:33 INFO - "platform": "yosemite", 09:15:33 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:15:33 INFO - "slavebuilddir": "test", 09:15:33 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:15:33 INFO - "branch": "fx-team", 09:15:33 INFO - "repo_path": "integration/fx-team", 09:15:33 INFO - "stage_platform": "macosx64", 09:15:33 INFO - "builduid": "4949b86c10dd4439bd5a5fc70e179da5", 09:15:33 INFO - "revision": "2443d724b1a233074f2ffdee1f2ed3e1f6792650" 09:15:33 INFO - }, 09:15:33 INFO - "sourcestamp": { 09:15:33 INFO - "repository": "", 09:15:33 INFO - "hasPatch": false, 09:15:33 INFO - "project": "", 09:15:33 INFO - "branch": "fx-team-macosx64-talos", 09:15:33 INFO - "changes": [ 09:15:33 INFO - { 09:15:33 INFO - "category": null, 09:15:33 INFO - "files": [ 09:15:33 INFO - { 09:15:33 INFO - "url": null, 09:15:33 INFO - "name": "https://queue.taskcluster.net/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 09:15:33 INFO - } 09:15:33 INFO - ], 09:15:33 INFO - "repository": "", 09:15:33 INFO - "rev": "2443d724b1a233074f2ffdee1f2ed3e1f6792650", 09:15:33 INFO - "who": "cbook@mozilla.com", 09:15:33 INFO - "when": 1447693080, 09:15:33 INFO - "number": 6666629, 09:15:33 INFO - "comments": "Bug 1224135 - Updating the comment for assemblePing. r=dexter", 09:15:33 INFO - "project": "", 09:15:33 INFO - "at": "Mon 16 Nov 2015 08:58:00", 09:15:33 INFO - "branch": "fx-team-macosx64-talos", 09:15:33 INFO - "revlink": "", 09:15:33 INFO - "properties": [ 09:15:33 INFO - [ 09:15:33 INFO - "buildid", 09:15:33 INFO - "20151116070823", 09:15:33 INFO - "Change" 09:15:33 INFO - ], 09:15:33 INFO - [ 09:15:33 INFO - "builduid", 09:15:33 INFO - "4949b86c10dd4439bd5a5fc70e179da5", 09:15:33 INFO - "Change" 09:15:33 INFO - ], 09:15:33 INFO - [ 09:15:33 INFO - "pgo_build", 09:15:33 INFO - "False", 09:15:33 INFO - "Change" 09:15:33 INFO - ] 09:15:33 INFO - ], 09:15:33 INFO - "revision": "2443d724b1a233074f2ffdee1f2ed3e1f6792650" 09:15:33 INFO - } 09:15:33 INFO - ], 09:15:33 INFO - "revision": "2443d724b1a233074f2ffdee1f2ed3e1f6792650" 09:15:33 INFO - } 09:15:33 INFO - } 09:15:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 09:15:33 INFO - Running post-action listener: _resource_record_post_action 09:15:33 INFO - ##### 09:15:33 INFO - ##### Running download-and-extract step. 09:15:33 INFO - ##### 09:15:33 INFO - Running pre-action listener: _resource_record_pre_action 09:15:33 INFO - Running main action method: download_and_extract 09:15:33 INFO - mkdir: /builds/slave/test/build/tests 09:15:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:33 INFO - https://queue.taskcluster.net/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:15:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/test_packages.json 09:15:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/test_packages.json 09:15:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 09:15:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 09:15:33 INFO - Downloaded 1149 bytes. 09:15:33 INFO - Reading from file /builds/slave/test/build/test_packages.json 09:15:33 INFO - Using the following test package requirements: 09:15:33 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 09:15:33 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:33 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 09:15:33 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:33 INFO - u'jsshell-mac.zip'], 09:15:33 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:33 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 09:15:33 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 09:15:33 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:33 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 09:15:33 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:33 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 09:15:33 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:33 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 09:15:33 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 09:15:33 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 09:15:33 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 09:15:33 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:15:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:33 INFO - https://queue.taskcluster.net/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:15:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 09:15:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 09:15:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 09:15:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:15:34 INFO - Downloaded 17464168 bytes. 09:15:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] in /builds/slave/test/build/tests 09:15:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 09:15:34 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 09:15:39 INFO - Return code: 0 09:15:39 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip', u'firefox-45.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:15:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:39 INFO - https://queue.taskcluster.net/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:15:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 09:15:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 09:15:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 09:15:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:15:40 INFO - Downloaded 17464168 bytes. 09: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 09:15:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 09: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 09:15:45 INFO - Return code: 0 09:15:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:45 INFO - https://queue.taskcluster.net/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:15:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 09:15:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 09:15:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 09:15:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:15:46 INFO - Downloaded 11131693 bytes. 09:15:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] in /builds/slave/test/build/tests 09:15:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 09:15:46 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 09:15:47 INFO - Return code: 0 09:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:47 INFO - https://queue.taskcluster.net/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:15:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 09:15:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 09:15:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg 09:15:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg'}, attempt #1 09:15:50 INFO - Downloaded 108683554 bytes. 09:15:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 09:15:50 INFO - mkdir: /builds/slave/test/properties 09:15:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:15:50 INFO - Writing to file /builds/slave/test/properties/build_url 09:15:50 INFO - Contents: 09:15:50 INFO - build_url:https://queue.taskcluster.net/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 09:15:50 INFO - Running post-action listener: _resource_record_post_action 09:15:50 INFO - Running post-action listener: set_extra_try_arguments 09:15:50 INFO - ##### 09:15:50 INFO - ##### Running populate-webroot step. 09:15:50 INFO - ##### 09:15:50 INFO - Running pre-action listener: _resource_record_pre_action 09:15:50 INFO - Running main action method: populate_webroot 09:15:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:15:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:15:50 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 09:15:50 INFO - '../fennec_ids.txt'], 09:15:50 INFO - 'tests': ['tcheck2']}, 09:15:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:15:50 INFO - '--tppagecycles', 09:15:50 INFO - '7'], 09:15:50 INFO - 'tests': ['tsvgm']}}, 09:15:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:15:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:15:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:15:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:15:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:15:50 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:15:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:50 INFO - 'talos_options': ['--e10s'], 09:15:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:50 INFO - 'tests': ['damp', 'tps']}, 09:15:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:50 INFO - 'talos_options': ['--e10s'], 09:15:50 INFO - 'tests': ['damp', 'tps']}, 09:15:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:15:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:15:50 INFO - 'other': {'tests': ['a11yr', 09:15:50 INFO - 'ts_paint', 09:15:50 INFO - 'tpaint', 09:15:50 INFO - 'sessionrestore', 09:15:50 INFO - 'sessionrestore_no_auto_restore']}, 09:15:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:15:50 INFO - 'tests': ['a11yr', 09:15:50 INFO - 'ts_paint', 09:15:50 INFO - 'tpaint', 09:15:50 INFO - 'sessionrestore', 09:15:50 INFO - 'sessionrestore_no_auto_restore']}, 09:15:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:15:50 INFO - 'tests': ['a11yr', 09:15:50 INFO - 'ts_paint', 09:15:50 INFO - 'tpaint', 09:15:50 INFO - 'sessionrestore', 09:15:50 INFO - 'sessionrestore_no_auto_restore']}, 09:15:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:15:50 INFO - 'tests': ['a11yr', 09:15:50 INFO - 'ts_paint', 09:15:50 INFO - 'tpaint', 09:15:50 INFO - 'sessionrestore', 09:15:50 INFO - 'sessionrestore_no_auto_restore']}, 09:15:50 INFO - 'other_l64': {'tests': ['a11yr', 09:15:50 INFO - 'ts_paint', 09:15:50 INFO - 'tpaint', 09:15:50 INFO - 'sessionrestore', 09:15:50 INFO - 'sessionrestore_no_auto_restore']}, 09:15:50 INFO - 'other_nol64': {'tests': ['a11yr', 09:15:50 INFO - 'ts_paint', 09:15:50 INFO - 'tpaint', 09:15:50 INFO - 'sessionrestore', 09:15:50 INFO - 'sessionrestore_no_auto_restore']}, 09:15:50 INFO - 'svgr': {'tests': ['tsvgx', 09:15:50 INFO - 'tsvgr_opacity', 09:15:50 INFO - 'tart', 09:15:50 INFO - 'tscrollx', 09:15:50 INFO - 'cart']}, 09:15:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:15:50 INFO - 'tests': ['tsvgx', 09:15:50 INFO - 'tsvgr_opacity', 09:15:50 INFO - 'tart', 09:15:50 INFO - 'tscrollx', 09:15:50 INFO - 'cart']}, 09:15:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:50 INFO - 'tests': ['tp5o']}, 09:15:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:50 INFO - 'talos_options': ['--e10s'], 09:15:50 INFO - 'tests': ['tp5o']}, 09:15:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:50 INFO - 'talos_options': ['--xperf_path', 09:15:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:50 INFO - 'tests': ['tp5n']}, 09:15:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:50 INFO - 'talos_options': ['--e10s', 09:15:50 INFO - '--xperf_path', 09:15:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:50 INFO - 'tests': ['tp5n']}}, 09:15:50 INFO - 'talos.zip': {'path': '', 09:15:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:15:50 INFO - Running post-action listener: _resource_record_post_action 09:15:50 INFO - ##### 09:15:50 INFO - ##### Running create-virtualenv step. 09:15:50 INFO - ##### 09:15:50 INFO - Running pre-action listener: _resource_record_pre_action 09:15:50 INFO - Running main action method: create_virtualenv 09:15:50 INFO - Creating virtualenv /builds/slave/test/build/venv 09:15:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:15:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:15:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:15:50 INFO - Using real prefix '/tools/python27' 09:15:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:15:51 INFO - Installing distribute.............................................................................................................................................................................................done. 09:15:54 INFO - Installing pip.................done. 09:15:54 INFO - Return code: 0 09:15:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108ec1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e9e228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8de9d2d550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108de49f0>, '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 0x108b44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dd88c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:15:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:15:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/Render', 09:15:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:54 INFO - 'HOME': '/Users/cltbld', 09:15:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:54 INFO - 'LOGNAME': 'cltbld', 09:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:54 INFO - 'MOZ_NO_REMOTE': '1', 09:15:54 INFO - 'NO_EM_RESTART': '1', 09:15:54 INFO - 'PAGER': '/bin/cat', 09:15:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:54 INFO - 'PWD': '/builds/slave/test', 09:15:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:54 INFO - 'SHELL': '/bin/bash', 09:15:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.24ZLbGbaiQ/Listeners', 09:15:54 INFO - 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', 09:15:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:54 INFO - 'USER': 'cltbld', 09:15:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:54 INFO - 'XPC_FLAGS': '0x0', 09:15:54 INFO - 'XPC_SERVICE_NAME': '0', 09:15:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 09:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09: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 09:15:56 INFO - Cleaning up... 09:15:56 INFO - Return code: 0 09:15:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09: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')]} 09:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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')]} 09:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09: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 0x108ec1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e9e228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8de9d2d550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108de49f0>, '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 0x108b44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dd88c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09: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 09: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 09:15:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/Render', 09:15:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:56 INFO - 'HOME': '/Users/cltbld', 09:15:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:56 INFO - 'LOGNAME': 'cltbld', 09:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:56 INFO - 'MOZ_NO_REMOTE': '1', 09:15:56 INFO - 'NO_EM_RESTART': '1', 09:15:56 INFO - 'PAGER': '/bin/cat', 09:15:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:56 INFO - 'PWD': '/builds/slave/test', 09:15:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:56 INFO - 'SHELL': '/bin/bash', 09:15:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.24ZLbGbaiQ/Listeners', 09:15:56 INFO - 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', 09:15:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:56 INFO - 'USER': 'cltbld', 09:15:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:56 INFO - 'XPC_FLAGS': '0x0', 09:15:56 INFO - 'XPC_SERVICE_NAME': '0', 09:15:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 09:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:56 INFO - Downloading/unpacking psutil>=0.7.1 09: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 09: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 09: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 09: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 09: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 09: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 09:15:58 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 09: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 09:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:15:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:58 INFO - Installing collected packages: psutil 09:15:58 INFO - Running setup.py install for psutil 09:15:59 INFO - building 'psutil._psutil_osx' extension 09: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 09:16:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:16:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:16:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:16:01 INFO - building 'psutil._psutil_posix' extension 09:16:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:16:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:16:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:16:01 INFO - ^ 09:16:01 INFO - 1 warning generated. 09:16:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:16:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:01 INFO - Successfully installed psutil 09:16:01 INFO - Cleaning up... 09:16:01 INFO - Return code: 0 09:16:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108ec1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e9e228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8de9d2d550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108de49f0>, '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 0x108b44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dd88c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:16:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/Render', 09:16:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:01 INFO - 'HOME': '/Users/cltbld', 09:16:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:01 INFO - 'LOGNAME': 'cltbld', 09:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:01 INFO - 'MOZ_NO_REMOTE': '1', 09:16:01 INFO - 'NO_EM_RESTART': '1', 09:16:01 INFO - 'PAGER': '/bin/cat', 09:16:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:01 INFO - 'PWD': '/builds/slave/test', 09:16:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:01 INFO - 'SHELL': '/bin/bash', 09:16:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.24ZLbGbaiQ/Listeners', 09:16:01 INFO - 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', 09:16:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:01 INFO - 'USER': 'cltbld', 09:16:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:01 INFO - 'XPC_FLAGS': '0x0', 09:16:01 INFO - 'XPC_SERVICE_NAME': '0', 09:16:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 09:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:16:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 09:16:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:16:04 INFO - Installing collected packages: mozsystemmonitor 09:16:04 INFO - Running setup.py install for mozsystemmonitor 09:16:04 INFO - Successfully installed mozsystemmonitor 09:16:04 INFO - Cleaning up... 09:16:04 INFO - Return code: 0 09:16:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:04 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 0x108ec1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e9e228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8de9d2d550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108de49f0>, '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 0x108b44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dd88c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:16:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:16:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/Render', 09:16:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:04 INFO - 'HOME': '/Users/cltbld', 09:16:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:04 INFO - 'LOGNAME': 'cltbld', 09:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:04 INFO - 'MOZ_NO_REMOTE': '1', 09:16:04 INFO - 'NO_EM_RESTART': '1', 09:16:04 INFO - 'PAGER': '/bin/cat', 09:16:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:04 INFO - 'PWD': '/builds/slave/test', 09:16:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:04 INFO - 'SHELL': '/bin/bash', 09:16:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.24ZLbGbaiQ/Listeners', 09:16:04 INFO - 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', 09:16:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:04 INFO - 'USER': 'cltbld', 09:16:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:04 INFO - 'XPC_FLAGS': '0x0', 09:16:04 INFO - 'XPC_SERVICE_NAME': '0', 09:16:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 09:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:04 INFO - Downloading/unpacking blobuploader==1.2.4 09:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:07 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 09:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 09:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:08 INFO - Downloading docopt-0.6.1.tar.gz 09:16:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 09:16:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:08 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:08 INFO - Running setup.py install for blobuploader 09:16:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:16:08 INFO - Running setup.py install for requests 09:16:09 INFO - Running setup.py install for docopt 09:16:09 INFO - Successfully installed blobuploader requests docopt 09:16:09 INFO - Cleaning up... 09:16:09 INFO - Return code: 0 09:16:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x108ec1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e9e228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8de9d2d550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108de49f0>, '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 0x108b44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dd88c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/Render', 09:16:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:09 INFO - 'HOME': '/Users/cltbld', 09:16:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:09 INFO - 'LOGNAME': 'cltbld', 09:16:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:09 INFO - 'MOZ_NO_REMOTE': '1', 09:16:09 INFO - 'NO_EM_RESTART': '1', 09:16:09 INFO - 'PAGER': '/bin/cat', 09:16:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:09 INFO - 'PWD': '/builds/slave/test', 09:16:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:09 INFO - 'SHELL': '/bin/bash', 09:16:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.24ZLbGbaiQ/Listeners', 09:16:09 INFO - 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', 09:16:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:09 INFO - 'USER': 'cltbld', 09:16:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:09 INFO - 'XPC_FLAGS': '0x0', 09:16:09 INFO - 'XPC_SERVICE_NAME': '0', 09:16:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 09:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:09 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-AN70tM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:10 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-JVsP6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:10 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-uNhf2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:10 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-ZH3I7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:10 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-09BgyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:10 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-hXemTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:10 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-MAcCcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:10 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-W_gP4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:10 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-YWIprK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:11 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-VZ1deA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:11 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-mAP8Tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:11 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-7dUII9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:11 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-U29RvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:11 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-Ywu9Pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:11 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-D4wVej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:11 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-u0ISrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:11 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-3iRG6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:11 INFO - Running setup.py install for manifestparser 09:16:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:12 INFO - Running setup.py install for mozcrash 09:16:12 INFO - Running setup.py install for mozdebug 09:16:12 INFO - Running setup.py install for mozdevice 09:16:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:16:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:12 INFO - Running setup.py install for mozfile 09:16:12 INFO - Running setup.py install for mozhttpd 09:16:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:13 INFO - Running setup.py install for mozinfo 09:16:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:13 INFO - Running setup.py install for mozInstall 09:16:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:13 INFO - Running setup.py install for mozleak 09:16:13 INFO - Running setup.py install for mozlog 09:16:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:13 INFO - Running setup.py install for moznetwork 09:16:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:13 INFO - Running setup.py install for mozprocess 09:16:14 INFO - Running setup.py install for mozprofile 09:16:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:16:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:16:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:16:14 INFO - Running setup.py install for mozrunner 09:16:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:16:14 INFO - Running setup.py install for mozscreenshot 09:16:14 INFO - Running setup.py install for moztest 09:16:14 INFO - Running setup.py install for mozversion 09:16:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:16:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:14 INFO - Cleaning up... 09:16:14 INFO - Return code: 0 09:16:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x108ec1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e9e228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8de9d2d550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108de49f0>, '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 0x108b44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dd88c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:16:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/Render', 09:16:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:14 INFO - 'HOME': '/Users/cltbld', 09:16:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:14 INFO - 'LOGNAME': 'cltbld', 09:16:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:14 INFO - 'MOZ_NO_REMOTE': '1', 09:16:14 INFO - 'NO_EM_RESTART': '1', 09:16:14 INFO - 'PAGER': '/bin/cat', 09:16:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:14 INFO - 'PWD': '/builds/slave/test', 09:16:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:14 INFO - 'SHELL': '/bin/bash', 09:16:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.24ZLbGbaiQ/Listeners', 09:16:14 INFO - 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', 09:16:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:14 INFO - 'USER': 'cltbld', 09:16:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:14 INFO - 'XPC_FLAGS': '0x0', 09:16:14 INFO - 'XPC_SERVICE_NAME': '0', 09:16:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 09:16:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:15 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-Qtw3tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:15 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:15 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-1twIW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:15 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-i_FZVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:15 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-mmTdRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:15 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-3UFfZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:15 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-LjoEnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:16 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-jYxCaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:16 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-5pwl5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:16 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-JmZdyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:16 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-Xrtk83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:16 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-Im7GYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:16 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-L1rc5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:16 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-QHpnw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:16 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-IWh6gZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:17 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-0XYgWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:17 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-i3yPHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:17 INFO - Running setup.py (path:/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/pip-avvYoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:19 INFO - Downloading blessings-1.5.1.tar.gz 09:16:19 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 09:16:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:19 INFO - Installing collected packages: blessings 09:16:19 INFO - Running setup.py install for blessings 09:16:20 INFO - Successfully installed blessings 09:16:20 INFO - Cleaning up... 09:16:20 INFO - Return code: 0 09:16:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:20 INFO - Reading from file tmpfile_stdout 09:16:20 INFO - Current package versions: 09:16:20 INFO - blessings == 1.5.1 09:16:20 INFO - blobuploader == 1.2.4 09:16:20 INFO - docopt == 0.6.1 09:16:20 INFO - manifestparser == 1.1 09:16:20 INFO - mozInstall == 1.12 09:16:20 INFO - mozcrash == 0.16 09:16:20 INFO - mozdebug == 0.1 09:16:20 INFO - mozdevice == 0.47 09:16:20 INFO - mozfile == 1.2 09:16:20 INFO - mozhttpd == 0.7 09:16:20 INFO - mozinfo == 0.9 09:16:20 INFO - mozleak == 0.1 09:16:20 INFO - mozlog == 3.0 09:16:20 INFO - moznetwork == 0.27 09:16:20 INFO - mozprocess == 0.22 09:16:20 INFO - mozprofile == 0.27 09:16:20 INFO - mozrunner == 6.11 09:16:20 INFO - mozscreenshot == 0.1 09:16:20 INFO - mozsystemmonitor == 0.0 09:16:20 INFO - moztest == 0.7 09:16:20 INFO - mozversion == 1.4 09:16:20 INFO - psutil == 3.1.1 09:16:20 INFO - requests == 1.2.3 09:16:20 INFO - wsgiref == 0.1.2 09:16:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:20 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 0x108ec1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e9e228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8de9d2d550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108de49f0>, '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 0x108b44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dd88c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'HOME': '/Users/cltbld', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/Render', 09:16:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:20 INFO - 'HOME': '/Users/cltbld', 09:16:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:20 INFO - 'LOGNAME': 'cltbld', 09:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:20 INFO - 'MOZ_NO_REMOTE': '1', 09:16:20 INFO - 'NO_EM_RESTART': '1', 09:16:20 INFO - 'PAGER': '/bin/cat', 09:16:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:20 INFO - 'PWD': '/builds/slave/test', 09:16:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:20 INFO - 'SHELL': '/bin/bash', 09:16:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.24ZLbGbaiQ/Listeners', 09:16:20 INFO - 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', 09:16:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:20 INFO - 'USER': 'cltbld', 09:16:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:20 INFO - 'XPC_FLAGS': '0x0', 09:16:20 INFO - 'XPC_SERVICE_NAME': '0', 09:16:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 09:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:16:20 INFO - Cleaning up... 09:16:20 INFO - Return code: 0 09:16:20 INFO - Running post-action listener: _resource_record_post_action 09:16:20 INFO - Running post-action listener: _start_resource_monitoring 09:16:20 INFO - Starting resource monitoring. 09:16:20 INFO - ##### 09:16:20 INFO - ##### Running install step. 09:16:20 INFO - ##### 09:16:20 INFO - Running pre-action listener: _resource_record_pre_action 09:16:20 INFO - Running main action method: install 09:16:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:21 INFO - Reading from file tmpfile_stdout 09:16:21 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:21 INFO - Reading from file tmpfile_stdout 09:16:21 INFO - Output received: 09:16:21 INFO - Usage: mozinstall [options] installer 09:16:21 INFO - Options: 09:16:21 INFO - -h, --help show this help message and exit 09:16:21 INFO - -d DEST, --destination=DEST 09:16:21 INFO - Directory to install application into. [default: 09:16:21 INFO - "/builds/slave/test"] 09:16:21 INFO - --app=APP Application being installed. [default: firefox] 09:16:21 INFO - mkdir: /builds/slave/test/build/application 09:16:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:16:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:16:59 INFO - Reading from file tmpfile_stdout 09:16:59 INFO - Output received: 09:16:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:16:59 INFO - Running post-action listener: _resource_record_post_action 09:16:59 INFO - ##### 09:16:59 INFO - ##### Running run-tests step. 09:16:59 INFO - ##### 09:16:59 INFO - Running pre-action listener: _resource_record_pre_action 09:16:59 INFO - Running main action method: run_tests 09:16:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:16:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:16:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:16:59 INFO - 2015-11-16 09:16:59.459 screenresolution[1629:7604] starting screenresolution argv=screenresolution get 09:16:59 INFO - 2015-11-16 09:16:59.463 screenresolution[1629:7604] Display 0: 1600x1200x32@60 09:16:59 INFO - 2015-11-16 09:16:59.469 screenresolution[1630:7606] starting screenresolution argv=screenresolution list 09:16:59 INFO - Available Modes on Display 0 09:16:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:16:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:16:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:16:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:16:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:16:59 INFO - Intel HD Graphics 3000: 09:16:59 INFO - Chipset Model: Intel HD Graphics 3000 09:16:59 INFO - Type: GPU 09:16:59 INFO - Bus: Built-In 09:16:59 INFO - VRAM (Total): 512 MB 09:16:59 INFO - VRAM (Dynamic, Max): 10 09:16:59 INFO - Vendor: Intel (0x8086) 09:16:59 INFO - Device ID: 0x0116 09:16:59 INFO - Revision ID: 0x0009 09:16:59 INFO - Displays: 09:16:59 INFO - Display: 09:16:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:16:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:16:59 INFO - Display Serial Number: 1600x1200 60 09:16:59 INFO - Main Display: Yes 09:16:59 INFO - Mirror: Off 09:16:59 INFO - Online: Yes 09:16:59 INFO - Rotation: Supported 09:16:59 INFO - Return code: 0 09:16:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:16:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:16:59 INFO - Python 2.7.3 09:16:59 INFO - Return code: 0 09:16:59 INFO - grabbing minidump binary from tooltool 09:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:16:59 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 0x108de49f0>, '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 0x108b44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108dd88c8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:16:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:16:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:16:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:16:59 INFO - Return code: 0 09:16:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:16:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:16:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:16:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:16:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:16:59 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/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r5-0097', '--webServer', 'localhost'] in /builds/slave/test/build 09:16:59 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/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r5-0097 --webServer localhost 09:16:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QBrq3jWLRq/Render', 09:16:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:59 INFO - 'HOME': '/Users/cltbld', 09:16:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:59 INFO - 'LOGNAME': 'cltbld', 09:16:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:16:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:59 INFO - 'MOZ_NO_REMOTE': '1', 09:16:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:16:59 INFO - 'NO_EM_RESTART': '1', 09:16:59 INFO - 'PAGER': '/bin/cat', 09:16:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:59 INFO - 'PWD': '/builds/slave/test', 09:16:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:16:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:59 INFO - 'SHELL': '/bin/bash', 09:16:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.24ZLbGbaiQ/Listeners', 09:16:59 INFO - 'TMPDIR': '/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/', 09:16:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:59 INFO - 'USER': 'cltbld', 09:16:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:59 INFO - 'XPC_FLAGS': '0x0', 09:16:59 INFO - 'XPC_SERVICE_NAME': '0', 09:16:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0x0:0x0'} 09:16:59 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/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r5-0097', '--webServer', 'localhost'] with output_timeout 3600 09:17:00 INFO - mozversion INFO | application_buildid: 20151116070823 09:17:00 INFO - mozversion INFO | application_changeset: 2443d724b1a233074f2ffdee1f2ed3e1f6792650 09:17:00 INFO - mozversion INFO | application_display_name: Nightly 09:17:00 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:17:00 INFO - mozversion INFO | application_name: Firefox 09:17:00 INFO - mozversion INFO | application_remotingname: firefox 09:17:00 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 09:17:00 INFO - mozversion INFO | application_vendor: Mozilla 09:17:00 INFO - mozversion INFO | application_version: 45.0a1 09:17:00 INFO - mozversion INFO | platform_buildid: 20151116070823 09:17:00 INFO - mozversion INFO | platform_changeset: 2443d724b1a233074f2ffdee1f2ed3e1f6792650 09:17:00 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 09:17:00 INFO - mozversion INFO | platform_version: 45.0a1 09:17:00 INFO - 2015-11-16 09:17:00,154 DEBUG : using testdate: 1447694220 09:17:00 INFO - 2015-11-16 09:17:00,154 DEBUG : actual date: 1447694220 09:17:00 INFO - 2015-11-16 09:17:00,163 INFO : starting webserver on 'localhost:49268' 09:17:00 INFO - 2015-11-16 09:17:00,164 INFO : Starting test suite t-yosemite-r5-0097 09:17:00 INFO - 2015-11-16 09:17:00,165 INFO : Starting test dromaeo_css 09:17:00 INFO - 2015-11-16 09:17:00,165 DEBUG : operating with platform_type : mac_ 09:17:00 INFO - 2015-11-16 09:17:00,165 INFO : Initialising browser for dromaeo_css test... 09:17:00 INFO - 2015-11-16 09:17:00,188 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/tmpjCciK9/profile http://localhost:49268/getInfo.html 09:17:03 INFO - 2015-11-16 09:17:03,626 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:17:03 INFO - 2015-11-16 09:17:03,626 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:17:03 INFO - 2015-11-16 09:17:03,626 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 09:17:03 INFO - 2015-11-16 09:17:03,626 DEBUG : BROWSER_OUTPUT: __metrics 09:17:03 INFO - 2015-11-16 09:17:03,642 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:17:04 INFO - 2015-11-16 09:17:04,368 INFO : Browser initialized. 09:17:04 INFO - 2015-11-16 09:17:04,369 INFO : Running cycle 1/1 for dromaeo_css test... 09:17:04 INFO - 2015-11-16 09:17:04,369 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/tmpjCciK9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:17:16 INFO - 2015-11-16 09:17:16,412 DEBUG : BROWSER_OUTPUT: RSS: Main: 229097472 09:17:16 INFO - 2015-11-16 09:17:16,412 DEBUG : BROWSER_OUTPUT: 09:18:08 INFO - 2015-11-16 09:18:08,344 DEBUG : BROWSER_OUTPUT: 1447694288334 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:20:18 INFO - 2015-11-16 09:20:18,533 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 09:20:19 INFO - 2015-11-16 09:20:19,784 DEBUG : BROWSER_OUTPUT: RSS: Main: 337092608 09:20:19 INFO - 2015-11-16 09:20:19,784 DEBUG : BROWSER_OUTPUT: 09:23:22 INFO - 2015-11-16 09:23:22,492 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 09:23:23 INFO - 2015-11-16 09:23:23,077 DEBUG : BROWSER_OUTPUT: RSS: Main: 365076480 09:23:23 INFO - 2015-11-16 09:23:23,077 DEBUG : BROWSER_OUTPUT: 09:26:25 INFO - 2015-11-16 09:26:25,333 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 09:26:25 INFO - 2015-11-16 09:26:25,914 DEBUG : BROWSER_OUTPUT: RSS: Main: 320811008 09:26:25 INFO - 2015-11-16 09:26:25,915 DEBUG : BROWSER_OUTPUT: 09:29:27 INFO - 2015-11-16 09:29:27,994 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 09:29:28 INFO - 2015-11-16 09:29:28,586 DEBUG : BROWSER_OUTPUT: RSS: Main: 319324160 09:29:28 INFO - 2015-11-16 09:29:28,586 DEBUG : BROWSER_OUTPUT: 09:32:31 INFO - 2015-11-16 09:32:31,721 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 09:32:32 INFO - 2015-11-16 09:32:32,305 DEBUG : BROWSER_OUTPUT: RSS: Main: 319754240 09:32:32 INFO - 2015-11-16 09:32:32,306 DEBUG : BROWSER_OUTPUT: 09:35:34 INFO - 2015-11-16 09:35:34,954 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 09:35:35 INFO - 2015-11-16 09:35:35,212 DEBUG : BROWSER_OUTPUT: RSS: Main: 420253696 09:35:35 INFO - 2015-11-16 09:35:35,212 DEBUG : BROWSER_OUTPUT: 09:35:35 INFO - 2015-11-16 09:35:35,212 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:35:35 INFO - 2015-11-16 09:35:35,212 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:35:35 INFO - 2015-11-16 09:35:35,213 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:35:35 INFO - 2015-11-16 09:35:35,213 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:35:35 INFO - 2015-11-16 09:35:35,217 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2957.5563665450168;2967.1098670398874;3033.105233955983;3117.4700300948844;3227.35452909418;4843.685160464567;4991.326170966928;5008.599312055036;5020.548150666439;5024.271480635311;4916.606671466283;4969.975150124254;4995.375578052744;5027.49725027498;5085.745712714368;4526.298423744325;4602.838900638479;4605.700629459091;4682.250839865622;4745.928811067837;4696.29555566666;4718.882027949295;4723.574878260956;4767.499412561683;4770.212914869043;4439.200943830142;4564.589186973155;4604.838830640945;4634;4700.036492519025;4461.888452788674;4573.702709323917;4604.493505714368;4605.125026245003;4642.628589712815;4456.22016147173;4576.8626941191815;4600.815967361302;4620.537946205386;4681.157391669498;4816.229403295456;4861.611071114337;4955.157623204064;4999.450060493343;5000.449950505442;5231.764570594332;5245.6852588844795;5263.315768950012;5303.602229832778;5328;5298.867528311785;5330.28041214435;5338.412774594792;5382.515573598395;5390.137577987503;4751.548602882721;4951.108800415924;4957.3059771632;5012.724300163526;5076.593872490229;5289;5365.731713414314;5465.863353416117;5490.56075513958;5490.835274941758;5207.869803254949;5217.973910130501;5221.6344855859725;5235.267062611238;5299.761510732026;5243.816466423733;5355.812546560852;5411.648242864201;5426.728663566885;5505;5194.818181363634;5225.190095535198;5247.1866860636665;5257.553107985864;5292.708901010405;5202.401723801736;5212.348456442945;5281.069130834374;5288.788448462095;5293.523582877559;3972.6424621784176;4075.1645912587846;4077.89805254871;4163.875083747493;4180.728252663567;3679.981600092036;3683.7053035757076;3762.172322089136;3768.6419790119894;3775;5039.143345631252;5050.419201791768;5067.87330316745;5114.105031619451;5179.352580927384;5214.061468935628;5377.865553361197;5382.085045542267;5444.428335024765;5445.754941027663;4965.4041515018425;4968.552830245295;5013.874653133701;5065.037642847848;5098.133317336062;4666.440027196691;4670.5329467053025;4724.220503616864;4724.480307166209;4764.285357196379;5351.678899266057;5489.505944465017;5497.39528651848;5527.281453411031;5541.141123125922;5243.082460569462;5263.973680131575;5304.893902122013;5354.919676204938;5415.35015798091;5517.917231241454;5553.139263414178;5609.803656872153;5623.268975033219;5625.971870140623;5230.7384630769075;5270.367555893317;5305;5339.546138578265;5391.7034563099405;5203.765830537559;5303.840884773463;5359.035373632628;5377.973110134424;5395.13677811564;5227.947720522746;5249.947500525099;5265.842024739264;5271.499207575274;5273.077211488051;5060.8987820242655;5266.8419947401635;5267.051930652519;5278.313819203479;5321.760520776497;5021.372328458942;5022.773975171053;5115.360579927509;5143.254228136974;5151.562117220078;5232.659877107976;5264.499872512105;5276.314079169683;5277.947220527899;5329.253904453457;4931.309616653743;5167.948320516747;5173.22401639757;5174.767135478988;5182 09:35:35 INFO - 2015-11-16 09:35:35,217 DEBUG : BROWSER_OUTPUT: |1;ext.html;507.63450315772644;518.4996478398347;521.1583292981835;531.1740243920704;539.0458887768647;28326.29184270392;34864;35615.46576801343;35654.821725891365;36203.63796362036;36876.446853297224;36913.815430922805;36996.81501592495;37395;37417.438738418874;7625.65684544194;7664.310212080912;8482.194191551807;8649.69726059588;8660.740177794676;10082.495875206247;10212.693619191436;10225.539850706698;10303.793924121514;10339.534720937538;9946.65186718465;10043.447610381427;10088.646897358594;10090.949545252264;10099.29304948654;7788.026496687914;7919.762407127795;9778.755531111708;10172.643957461527;10368.688939331832;9857.457839818808;9902.9504852476;10011.14905233056;10014;10030.49847507629;35119;35243.1189220269;35616.28767424663;35657.108572285644;35775.105622359646;2695.420484595821;2795.0636536760207;2845.5447128459555;2866.226118947893;3085.3674996625864;24441.511169776506;24793.62809557858;25986.480270394488;26082.217533474028;26598.6010209847;2234.597772400967;2280.737715162761;2283.977160228393;2286.965695514569;2383.690120284352;254576;256133;259380;260912;261550;50839.23741143886;51736;54176;54593;54621;62921.370786292464;63516.36483635197;63940;63950.680246598466;64052;13196.340182990813;13330.800037999437;13473.124246923919;13486.258255796025;13486.527971521145;9762.463064531377;9932.106110450095;10066.345687530433;10071.597136114473;10075.697729068139;18140.274389024555;18308.725369119475;18535.424488918594;18553.721694174597;18567;8032.558209298428;8309.709160179485;8334.66661333552;8364.916350836535;8394.286485648667;884.5930871798915;926.3515539122641;943.7499062748377;951.2152474208806;1027.5632856036154;25568.74431255701;28377.29056773556;29391.26521836971;29700.851495742383;30160.84919575432;874.1258741258741;911.8632205169278;938.502593625366;942.9057094290654;1068.460427484131;13010.349482526026;13182.934085329513;13193.802092968614;13313.66715832112;13317.134386264861;374731;379964;391416;392041;394167;49217.50782492201;49234;49278;49643;49704.75147624239;1264.7533730922567;1272.3320256865036;1287.9742405152033;1291.2962435472878;1296.358302640199;24455.75544244535;25059.624105638064;25114.24657260285;25387.238382848544;25405.872970635028;41032.79483602563;41576.376354354106;41614;42144.578554214066;42147;276975;278867;280568;282923;285616;24532.141375051786;24744.752552474245;27468.450630987667;27967.720322797326;28478.57282140811;32709;33365.16587085342;33448.498272525445;33584.66415335816;33774.83112584421;29179.7082029177;29225.269368265963;29561.113166605035;29708.55437168401;29956.850215748782;24028;27557.31106722348;28825.279368015967;29130.56304155482;29180.124596262147;28888.711112889443;29352.706472935;29415.705842941297;29474.263143421587;29527.409451811272;24637.50724985526;24933.252002439956;25811.354716132246;25886.482270354863;26524.204273871816;19963;20366.185352586323;21048.158073677492;21174.788252117283;21232.787672123082 09:35:35 INFO - 2015-11-16 09:35:35,220 DEBUG : BROWSER_OUTPUT: |2;jquery.html;304.7866493454581;307.6446704056815;311.81135413075106;314.16275625458155;317.12157324211927;13427.597172084854;13898.51355202568;16497.340106395746;16556.83443165568;16992.660146797072;13390.866091339083;15569.455069072557;15638.218089095528;15817.683646327067;15836.683266334667;4858.222684370501;4932.038252540756;4942.555170034696;4992.101421744084;4997.250412438127;3622.1125824173114;3683.576388715292;3723.3484140275477;4778.092162489133;4949.232868905326;5213.504717051874;5412.07994640912;5459.535939445152;5473.644213126134;5499.862503437406;5312.495312945264;5441.319835020622;5453.672779633234;5472.7263636818;5491.28613280275;4664.906701865943;4737.95262047382;4740.644451666139;4746.264329028972;4776.80892764289;19822.603547929106;19891.403257902286;20042.098105585283;20215;20215.898920505304;1973.5263536751077;1988.3537850198743;2018.1221168791476;2102.3797979596056;2375.3586531636533;12425.75148497025;12482.625521234377;12755.426005829668;12784.872151278461;12855.807162892565;1491.433255362966;1548.0866288889497;1587.6110352963483;1600.0879498685754;1624.61821471954;52194.47805521934;52773.736131319485;53094;53915.73042134803;53926;8755.693550725695;8920.375573709907;9111.772205694779;9145.496997665192;9178.35751497402;10979.560817567368;11018.44907754609;11104.278221915549;11106.278091924161;11109.888901111048;6866.073080134118;6866.729655013838;6876.243613202544;6892.689828957709;6946.583205007716;6708.698108585126;6733.259341472434;6738.393544581011;6774.729010839511;6852.417544508772;5390.245365648812;5392.325959255093;5471.480209380103;5471.6169868108855;5517.531009864127;1432.5129455985016;1496.768000959853;1515.4620109861062;1557.2758667219798;1566.130797407427;837.660747397305;907.3331101640289;919.3656377099779;950.9144177024101;1032.5198782565992;16411.753823692656;17601.911990439967;17667.29330826778;17746.03111128886;17762.28950841952;762.7787941497015;802.8554860125233;831.4553967151526;850.128618166372;884.0496466298755;9360.765980850398;9600.711978640651;9688.273379496566;9716.465594392235;9747.951260243653;530249;534806;535403;553810;562213;62486;64903.675481623235;65589;65790.34209657938;65935.67032164808;1010.7068950004564;1026.8357062869904;1090.6128324444708;1104.8397982292522;1108.3183841937312;14761.92619036898;14770.261486925825;14900.850991489946;15218.086914785148;15267.465638702592;30725.23186920345;30975.535366969965;30984.22539436442;31341.686583133876;31453;39959.2008159841;40676.59323406728;41045.794771025954;41663;43345.78327108344;5631.155326701028;5754.856128596819;5831.35855055952;5832.358440571618;5835.649861008353;10857.457127143452;11090.390028548507;11179;11204.103671706407;11355.886441135483;7776.84446311082;8220;8251.711190108317;8952.373333866566;9045.457272563668;8828.911710882809;8859.291256699578;9011.188992990663;9175.082491751038;9420.340576159602;9384.343095983215;9618.51907404613;9623.615055397706;9665.178459830711;9713.417194968324;5658.519025882846;5765.740541675551;5857.179994800646;5860.706964651665;5872.911906321323;9340.159385655325;9463.526823658927;9540.475273860004;9628.422294662343;9643.662471813454 09:35:35 INFO - 2015-11-16 09:35:35,220 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1463.9926800365997;1542;1669.8998060116394;1687.468447439057;1698.5159229619553;6791.11715476988;7121.323474269942;7368.668409921546;7544.321011108999;7571.053618297713;7356.96321518393;7363.889541656866;7389.556626602408;7472.804351303793;7498.662560184791;2088.5822835432937;2118.226847200776;2119.0993827623197;2146.914123435061;2150.698902153692;2157.255746767363;2164.9242276520326;2206.2719302630094;2226.9665955010687;2235.7093577834858;2122.066290832029;2127.7978592033733;2131.2966720982117;2143.2070134050423;2159.276642324823;1744.8865823721417;1746.6594014167242;1747.1177055586975;1754.956126096845;1759.7976232733208;2099.4961209309654;2129.2973318804757;2130.4141361125658;2131.328631481094;2154.2137119951103;6611.537192396534;7403.222661620506;7444.181140074587;7461.477696561243;7465;4185.246655601991;4419.248727716296;4554.95445045552;4557.74932378719;4607.838725644619;2698.892044318225;2721.687005994316;2786.0945192812214;2798.4123334099863;2860.413615208877;852.1180578101697;882.8101958079044;883.0198479687542;884.1600479544394;894.7449976756658;9845.360051596552;9896.752581185456;9937.155341795884;9948.452835094067;9984.750381240527;4165.66674666026;4258.787060646955;4297.290946993773;4308.784560771949;4323.005708687019;8674.6096425661;8720.084391138838;8740.737777866674;8815.515146666994;8832.205101540892;4700.459447163552;4714.269288260257;4722.645801564897;4777;4781.880452988634;4259.297215959332;4273.380359847804;4306.181825453154;4310.80601372939;4316.287812510901;3444.9483257751153;3446.1212390840174;3457.7060949819042;3482.7039701625645;3510.859565617398;1095.6822521468684;1120.9719757005978;1126.2510430563748;1126.7802778458122;1136.0457215938652;953.5661274120258;981.9165370943407;986.2455221755363;1064.414571985405;1232.8397308349856;6867.690953906987;6922.2731613180895;6930.029795828588;7012.018317435563;7035.894461583081;431.2840684463775;1112.4159816096453;1149.3333866357493;1178.4166837415535;1179.9233049851732;5136.049830781318;5320.547753440924;5383.273258110184;5434.565234781208;5453;7700.229977002255;7778.766637000899;7789.065312162576;7802.453828231969;8147.307478864363;7741.8451630967065;7761.185075567097;7761.534307941542;7801.765947021599;7806.180351063056;6409.455196308273;6416;6505.772297969548;6630.071789949411;6638.506336074344;6442.613443193423;6464.062710906798;6500.219973603198;6511.2512061113575;6552.934470655233;7008.684609192701;7302.488825782144;7373.741919032808;7415.184329723834;7427.702891884264;7373;7580.810479738051;7625.389968802371;7701.4608977371045;7706.922930770845;6207.844803879939;6226.782062627786;6250.749970001149;6250.781222657185;6358.90461643085;7103.644817759195;7104.11198600175;7123.572585644878;7180.246074162346;7183.640817959185;6607.438367738604;6676.332366763285;6680.265170831108;6709.161354830646;6802.14973128359;6280.874382512416;6417.486601071996;6494.870102598016;6602.438792702674;6659.101021362054;6622.63575503352;6654.633995130314;6679.0649309097735;6680.3987641112535;6687;6082.60463069899;6120.051392034242;6134.723937422737;6147.969260153671;6198.349173336735;6191.566590338633;6251.562390632794;6261.092141639528;6292.3078461368295;6295.401936815995 09:35:35 INFO - 2015-11-16 09:35:35,224 DEBUG : BROWSER_OUTPUT: |4;prototype.html;290.29748009816245;299.1862134992819;304.5340628837877;305.1562429881378;307.6554259229663;5666.433356664332;5679.204911312419;5698.430156984305;5810.215620891178;5839.416058394169;5641.52047075998;5656.971715141419;5726.427357264271;5770.567207459437;5770.826875193751;4375.4530683664625;4402;4448.221561226788;4450.4881938577155;4461.665375096864;4227.175700738358;4346.652267818583;4384.276594361926;4446.577575130357;4471.6199123074575;4391.385206071152;4399.604035636792;4402.25161722508;4406.140802543506;4429.6899217054815;4367.628751556105;4399.604035636776;4428.202923473774;4429.025614364835;4452.443444569429;3842.769433833979;3843.0392401899526;3845.8461661533784;3849.2493963677093;3862.9034274143373;5415.268938693265;5593.300837395325;5624.409437009096;5653.349864765522;5661.66030038199;4994.400671919357;5006.974965125188;5007.048660754482;5009.849704508871;5020.1214787412055;6043.576949613529;6045.214122164134;6068.484178844804;6100.69496525172;6110.327863934964;4157.8960525987095;4159.896002599929;4161.2301724181125;4165.875023749262;4174.853880114181;5606.383297837235;5984.222051133324;6057.636541807506;6289.496840252768;6461.709223084972;4237.385579091015;4245.957540424618;4246.087091275391;4248.086661367821;4250.638695710899;4252.425922500484;4270.935935960963;4321.092570560218;4337.869863904088;4347.3044312910415;4889.095517329305;4918.262260660858;4955.1576232039915;4966.056449274626;4968.056069346812;5788.421157884262;6026.758929642765;6055.969720151371;6058.242719660043;6070.848228794315;1448.739226939161;1467.2736995187452;1478.135290854862;1481.451862810767;1482.0366761605044;942.7360339105062;943.580106852444;950.235060776068;958.726762872592;971.2133172130597;5863.061910094364;5880.470757631833;5895.587308888423;5896.204012458349;5898.380670029585;6145.539084568675;6198.752049918043;6313.873722525524;6324.873502530016;6370.267419246753;5863.5309175265875;5894.056950887837;5898.852528686817;5922.526197904157;5930.792422265286;3404.4382676858536;3444.7781181138025;3447.8103704296504;3450.1719587298867;3453.32660131272;6494.6103233806125;6635.2037755469655;6655.86688266232;6660.100886380374;6693.631850248185;6564.081028655992;6564.146660934143;6573.737050517926;6574.243961944438;6637.402633762985;6637.933620663925;6662.500312476487;6706.765263215764;6761.5604985676755;6781.6609169541325;6295.622262664255;6304;6364.427201551883;6401.807945761635;6415.903761443488;6184.350643182581;6234.376562343729;6241.968790156202;6253.843653908689;6260.904341740268;6611.404973552211;6661.467082633475;6673.9332606673315;6680.799576012728;6729.327067293231;4023.1953609277675;4118.6499147572795;4130.421740956209;4137.296659567941;4142.2751018571025;6287.685615719104;6346.397092276226;6355.650439225702;6370.140031095742;6448.742050318122;6175.814725558061;6178.443940045418;6192.504599632109;6199.473044791243;6202.534809889186;5995.340512543704;6052.818415447544;6167.290761562478;6169.198004259418;6170.352113028068;5957.821265362046;5959.940400595939;6005.099235114768;6008.87982240344;6019.036954087325;6148.385161483816;6150.7539698411565;6156.322804491592;6166.290876549075;6179.28938172098;5640.830775076754;5703.201551782837;5983.910241346296;6046.455818976313;6054.606450580698 09:35:35 INFO - 2015-11-16 09:35:35,225 DEBUG : BROWSER_OUTPUT: |5;yui.html;677.840707433753;693.829274448574;696.1402667705386;718.1131302840992;733.431590517349;11389.145814063948;11651.184417090806;11687.649370518877;11743.354115523643;11765.882341176597;11876;11982;12006.519739210422;12014.03887688985;12107.394630268496;652.4682383857141;667.2160211751197;820.0733171516179;825.286127499713;840.3991146330372;820.4379999700195;831.7380025292044;835.2607941971369;838.8280402517499;845.7166849105557;865.1391865094208;872.376250980552;876.5135349880813;881.9603117859712;888.7778055486128;676.5500941873648;685.1504134872772;829.5769157729601;838.4047326398264;858.4591707224471;635.6694518850218;638.3999040901584;639.48840927258;640.5996252342286;648.7567162314132;12554.937225313815;12687.365631718378;12687.936560317232;12715.237085774883;12726.23642581448;10575.312604680672;10744.892551074545;10760.892391076146;10764.569417223302;11038.724031899186;8450.154984501562;8693.304535637133;8783.253423459013;8826.338024648112;9181.770455738593;3001.3847161331873;3129.765267604939;3133.8903138390274;3153.952690709641;3172.888948886778;43574.56425435737;43645.12709745787;43756.56243437557;44186.779066104784;44323.11353772906;6028.909566356509;6044.425779550936;6077.513798896078;6262.3111457739615;6263.342349053284;11065.225434219688;11247.606333778442;11306.321620702784;11337.376444295478;11348.546058157746;5477.45225477457;5706.172604972256;5829.242198514166;5865.325487568899;6033.939660603338;3523.735719821024;3581.71346292296;3610.8736194233074;3636.1091532574687;3776.6789822864816;2449.902003919823;2465.506898620283;2497.0760818497274;2501.7373175816642;2502.1617758050893;375.1166004060398;378.8219536817691;382.7512117123892;383.9635234652703;388.2099926649255;473.25462396725294;489.59852920605476;510.45636397236825;553.4576115406843;580.6545105662125;7238.819029524198;7364.742234021891;7389;7633.5419874807685;7684.308412242814;480.0988087174757;483.27026190451966;521.3144714700157;539.4794023767005;540.9964515823092;3331.267121233325;3343.715784158374;3472.6353732857688;3610.4764809102535;3713.9071523211596;53772;54198.45801542013;54357;54410;54601.72699136479;47135.764321178176;48031.27953080706;49030.26454603184;49310;49421.25868111981;39719;40040.799796000836;40497.19005619871;41385.793071035056;43574.34638480425;7273.163586187656;7597.772066838003;7691.5769632670745;7791.766247012599;7929.112363070378;32912.506312404854;32933;33729.83135084309;33764.49353259654;33797.66202337945;39958.2008359837;40532;43105.784471077444;43239;44030.55969440265;6958.721651133898;6967.581945083311;7146.857062858818;7199.316064973819;7347.448941329529;10147.695569132939;10272.948635256776;10381.172754669115;10655;10988;9693.369930954465;9701.272404569545;9704.175145112744;9993.700188994051;10429.895701042893;9347.906520934976;9401.435913845191;9551.522423878918;10010.44942528168;10334.741631459274;9328.860067099135;9511.904880951102;9584.616615335588;9718.9028109718;10186.796264074825;8056.154103819014;8148;8195.262426381654;8509;8908.643654253758;7662.310392064518;7795.623457793425;7932.48025057253;8519.318454523747;8552.786180345542 09:35:35 INFO - 2015-11-16 09:35:35,225 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:35:35 INFO - 2015-11-16 09:35:35,225 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:35:35 INFO - 2015-11-16 09:35:35,225 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,947 09:35:35 INFO - 2015-11-16 09:35:35,225 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:35:35 INFO - 2015-11-16 09:35:35,226 DEBUG : BROWSER_OUTPUT: __startTimestamp1447695335211__endTimestamp 09:35:35 INFO - 2015-11-16 09:35:35,226 DEBUG : BROWSER_OUTPUT: 09:35:35 INFO - 2015-11-16 09:35:35,226 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:35:35 INFO - 2015-11-16 09:35:35,226 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:35:35 INFO - 2015-11-16 09:35:35,226 DEBUG : BROWSER_OUTPUT: 09:35:35 INFO - 2015-11-16 09:35:35,226 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4976.78 Median:5173.22 stddev:526.08 (10.2%) stddev-sans-first:503.28 09:35:35 INFO - 2015-11-16 09:35:35,228 DEBUG : BROWSER_OUTPUT: Values: 2957.6 2967.1 3033.1 3117.5 3227.4 4843.7 4991.3 5008.6 5020.5 5024.3 4916.6 4970.0 4995.4 5027.5 5085.7 4526.3 4602.8 4605.7 4682.3 4745.9 4696.3 4718.9 4723.6 4767.5 4770.2 4439.2 4564.6 4604.8 4634.0 4700.0 4461.9 4573.7 4604.5 4605.1 4642.6 4456.2 4576.9 4600.8 4620.5 4681.2 4816.2 4861.6 4955.2 4999.5 5000.4 5231.8 5245.7 5263.3 5303.6 5328.0 5298.9 5330.3 5338.4 5382.5 5390.1 4751.5 4951.1 4957.3 5012.7 5076.6 5289.0 5365.7 5465.9 5490.6 5490.8 5207.9 5218.0 5221.6 5235.3 5299.8 5243.8 5355.8 5411.6 5426.7 5505.0 5194.8 5225.2 5247.2 5257.6 5292.7 5202.4 5212.3 5281.1 5288.8 5293.5 3972.6 4075.2 4077.9 4163.9 4180.7 3680.0 3683.7 3762.2 3768.6 3775.0 5039.1 5050.4 5067.9 5114.1 5179.4 5214.1 5377.9 5382.1 5444.4 5445.8 4965.4 4968.6 5013.9 5065.0 5098.1 4666.4 4670.5 4724.2 4724.5 4764.3 5351.7 5489.5 5497.4 5527.3 5541.1 5243.1 5264.0 5304.9 5354.9 5415.4 5517.9 5553.1 5609.8 5623.3 5626.0 5230.7 5270.4 5305.0 5339.5 5391.7 5203.8 5303.8 5359.0 5378.0 5395.1 5227.9 5249.9 5265.8 5271.5 5273.1 5060.9 5266.8 5267.1 5278.3 5321.8 5021.4 5022.8 5115.4 5143.3 5151.6 5232.7 5264.5 5276.3 5277.9 5329.3 4931.3 5167.9 5173.2 5174.8 5182.0 09:35:35 INFO - 2015-11-16 09:35:35,228 DEBUG : BROWSER_OUTPUT: 09:35:35 INFO - 2015-11-16 09:35:35,228 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45353.75 Median:29155.14 stddev:82709.46 (283.7%) stddev-sans-first:82872.94 09:35:35 INFO - 2015-11-16 09:35:35,228 DEBUG : BROWSER_OUTPUT: Values: 507.6 518.5 521.2 531.2 539.0 28326.3 34864.0 35615.5 35654.8 36203.6 36876.4 36913.8 36996.8 37395.0 37417.4 7625.7 7664.3 8482.2 8649.7 8660.7 10082.5 10212.7 10225.5 10303.8 10339.5 9946.7 10043.4 10088.6 10090.9 10099.3 7788.0 7919.8 9778.8 10172.6 10368.7 9857.5 9903.0 10011.1 10014.0 10030.5 35119.0 35243.1 35616.3 35657.1 35775.1 2695.4 2795.1 2845.5 2866.2 3085.4 24441.5 24793.6 25986.5 26082.2 26598.6 2234.6 2280.7 2284.0 2287.0 2383.7 254576.0 256133.0 259380.0 260912.0 261550.0 50839.2 51736.0 54176.0 54593.0 54621.0 62921.4 63516.4 63940.0 63950.7 64052.0 13196.3 13330.8 13473.1 13486.3 13486.5 9762.5 9932.1 10066.3 10071.6 10075.7 18140.3 18308.7 18535.4 18553.7 18567.0 8032.6 8309.7 8334.7 8364.9 8394.3 884.6 926.4 943.7 951.2 1027.6 25568.7 28377.3 29391.3 29700.9 30160.8 874.1 911.9 938.5 942.9 1068.5 13010.3 13182.9 13193.8 13313.7 13317.1 374731.0 379964.0 391416.0 392041.0 394167.0 49217.5 49234.0 49278.0 49643.0 49704.8 1264.8 1272.3 1288.0 1291.3 1296.4 24455.8 25059.6 25114.2 25387.2 25405.9 41032.8 41576.4 41614.0 42144.6 42147.0 276975.0 278867.0 280568.0 282923.0 285616.0 24532.1 24744.8 27468.5 27967.7 28478.6 32709.0 33365.2 33448.5 33584.7 33774.8 29179.7 29225.3 29561.1 29708.6 29956.9 24028.0 27557.3 28825.3 29130.6 29180.1 28888.7 29352.7 29415.7 29474.3 29527.4 24637.5 24933.3 25811.4 25886.5 26524.2 19963.0 20366.2 21048.2 21174.8 21232.8 09:35:35 INFO - 2015-11-16 09:35:35,228 DEBUG : BROWSER_OUTPUT: 09:35:35 INFO - 2015-11-16 09:35:35,228 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26967.82 Median:5105.38 stddev:88676.94 (1736.9%) stddev-sans-first:88903.10 09:35:35 INFO - 2015-11-16 09:35:35,230 DEBUG : BROWSER_OUTPUT: Values: 304.8 307.6 311.8 314.2 317.1 13427.6 13898.5 16497.3 16556.8 16992.7 13390.9 15569.5 15638.2 15817.7 15836.7 4858.2 4932.0 4942.6 4992.1 4997.3 3622.1 3683.6 3723.3 4778.1 4949.2 5213.5 5412.1 5459.5 5473.6 5499.9 5312.5 5441.3 5453.7 5472.7 5491.3 4664.9 4738.0 4740.6 4746.3 4776.8 19822.6 19891.4 20042.1 20215.0 20215.9 1973.5 1988.4 2018.1 2102.4 2375.4 12425.8 12482.6 12755.4 12784.9 12855.8 1491.4 1548.1 1587.6 1600.1 1624.6 52194.5 52773.7 53094.0 53915.7 53926.0 8755.7 8920.4 9111.8 9145.5 9178.4 10979.6 11018.4 11104.3 11106.3 11109.9 6866.1 6866.7 6876.2 6892.7 6946.6 6708.7 6733.3 6738.4 6774.7 6852.4 5390.2 5392.3 5471.5 5471.6 5517.5 1432.5 1496.8 1515.5 1557.3 1566.1 837.7 907.3 919.4 950.9 1032.5 16411.8 17601.9 17667.3 17746.0 17762.3 762.8 802.9 831.5 850.1 884.0 9360.8 9600.7 9688.3 9716.5 9748.0 530249.0 534806.0 535403.0 553810.0 562213.0 62486.0 64903.7 65589.0 65790.3 65935.7 1010.7 1026.8 1090.6 1104.8 1108.3 14761.9 14770.3 14900.9 15218.1 15267.5 30725.2 30975.5 30984.2 31341.7 31453.0 39959.2 40676.6 41045.8 41663.0 43345.8 5631.2 5754.9 5831.4 5832.4 5835.6 10857.5 11090.4 11179.0 11204.1 11355.9 7776.8 8220.0 8251.7 8952.4 9045.5 8828.9 8859.3 9011.2 9175.1 9420.3 9384.3 9618.5 9623.6 9665.2 9713.4 5658.5 5765.7 5857.2 5860.7 5872.9 9340.2 9463.5 9540.5 9628.4 9643.7 09:35:35 INFO - 2015-11-16 09:35:35,230 DEBUG : BROWSER_OUTPUT: 09:35:35 INFO - 2015-11-16 09:35:35,230 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5059.62 Median:6293.85 stddev:2562.11 (40.7%) stddev-sans-first:2555.05 09:35:35 INFO - 2015-11-16 09:35:35,231 DEBUG : BROWSER_OUTPUT: Values: 1464.0 1542.0 1669.9 1687.5 1698.5 6791.1 7121.3 7368.7 7544.3 7571.1 7357.0 7363.9 7389.6 7472.8 7498.7 2088.6 2118.2 2119.1 2146.9 2150.7 2157.3 2164.9 2206.3 2227.0 2235.7 2122.1 2127.8 2131.3 2143.2 2159.3 1744.9 1746.7 1747.1 1755.0 1759.8 2099.5 2129.3 2130.4 2131.3 2154.2 6611.5 7403.2 7444.2 7461.5 7465.0 4185.2 4419.2 4555.0 4557.7 4607.8 2698.9 2721.7 2786.1 2798.4 2860.4 852.1 882.8 883.0 884.2 894.7 9845.4 9896.8 9937.2 9948.5 9984.8 4165.7 4258.8 4297.3 4308.8 4323.0 8674.6 8720.1 8740.7 8815.5 8832.2 4700.5 4714.3 4722.6 4777.0 4781.9 4259.3 4273.4 4306.2 4310.8 4316.3 3444.9 3446.1 3457.7 3482.7 3510.9 1095.7 1121.0 1126.3 1126.8 1136.0 953.6 981.9 986.2 1064.4 1232.8 6867.7 6922.3 6930.0 7012.0 7035.9 431.3 1112.4 1149.3 1178.4 1179.9 5136.0 5320.5 5383.3 5434.6 5453.0 7700.2 7778.8 7789.1 7802.5 8147.3 7741.8 7761.2 7761.5 7801.8 7806.2 6409.5 6416.0 6505.8 6630.1 6638.5 6442.6 6464.1 6500.2 6511.3 6552.9 7008.7 7302.5 7373.7 7415.2 7427.7 7373.0 7580.8 7625.4 7701.5 7706.9 6207.8 6226.8 6250.7 6250.8 6358.9 7103.6 7104.1 7123.6 7180.2 7183.6 6607.4 6676.3 6680.3 6709.2 6802.1 6280.9 6417.5 6494.9 6602.4 6659.1 6622.6 6654.6 6679.1 6680.4 6687.0 6082.6 6120.1 6134.7 6148.0 6198.3 6191.6 6251.6 6261.1 6292.3 6295.4 09:35:35 INFO - 2015-11-16 09:35:35,231 DEBUG : BROWSER_OUTPUT: 09:35:35 INFO - 2015-11-16 09:35:35,232 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5098.57 Median:5872.00 stddev:1570.04 (26.7%) stddev-sans-first:1532.40 09:35:35 INFO - 2015-11-16 09:35:35,233 DEBUG : BROWSER_OUTPUT: Values: 290.3 299.2 304.5 305.2 307.7 5666.4 5679.2 5698.4 5810.2 5839.4 5641.5 5657.0 5726.4 5770.6 5770.8 4375.5 4402.0 4448.2 4450.5 4461.7 4227.2 4346.7 4384.3 4446.6 4471.6 4391.4 4399.6 4402.3 4406.1 4429.7 4367.6 4399.6 4428.2 4429.0 4452.4 3842.8 3843.0 3845.8 3849.2 3862.9 5415.3 5593.3 5624.4 5653.3 5661.7 4994.4 5007.0 5007.0 5009.8 5020.1 6043.6 6045.2 6068.5 6100.7 6110.3 4157.9 4159.9 4161.2 4165.9 4174.9 5606.4 5984.2 6057.6 6289.5 6461.7 4237.4 4246.0 4246.1 4248.1 4250.6 4252.4 4270.9 4321.1 4337.9 4347.3 4889.1 4918.3 4955.2 4966.1 4968.1 5788.4 6026.8 6056.0 6058.2 6070.8 1448.7 1467.3 1478.1 1481.5 1482.0 942.7 943.6 950.2 958.7 971.2 5863.1 5880.5 5895.6 5896.2 5898.4 6145.5 6198.8 6313.9 6324.9 6370.3 5863.5 5894.1 5898.9 5922.5 5930.8 3404.4 3444.8 3447.8 3450.2 3453.3 6494.6 6635.2 6655.9 6660.1 6693.6 6564.1 6564.1 6573.7 6574.2 6637.4 6637.9 6662.5 6706.8 6761.6 6781.7 6295.6 6304.0 6364.4 6401.8 6415.9 6184.4 6234.4 6242.0 6253.8 6260.9 6611.4 6661.5 6673.9 6680.8 6729.3 4023.2 4118.6 4130.4 4137.3 4142.3 6287.7 6346.4 6355.7 6370.1 6448.7 6175.8 6178.4 6192.5 6199.5 6202.5 5995.3 6052.8 6167.3 6169.2 6170.4 5957.8 5959.9 6005.1 6008.9 6019.0 6148.4 6150.8 6156.3 6166.3 6179.3 5640.8 5703.2 5983.9 6046.5 6054.6 09:35:35 INFO - 2015-11-16 09:35:35,233 DEBUG : BROWSER_OUTPUT: 09:35:35 INFO - 2015-11-16 09:35:35,233 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12213.24 Median:27155.74 stddev:14970.58 (55.1%) stddev-sans-first:14987.52 09:35:35 INFO - 2015-11-16 09:35:35,234 DEBUG : BROWSER_OUTPUT: Values: 677.8 693.8 696.1 718.1 733.4 11389.1 11651.2 11687.6 11743.4 11765.9 11876.0 11982.0 12006.5 12014.0 12107.4 652.5 667.2 820.1 825.3 840.4 820.4 831.7 835.3 838.8 845.7 865.1 872.4 876.5 882.0 888.8 676.6 685.2 829.6 838.4 858.5 635.7 638.4 639.5 640.6 648.8 12554.9 12687.4 12687.9 12715.2 12726.2 10575.3 10744.9 10760.9 10764.6 11038.7 8450.2 8693.3 8783.3 8826.3 9181.8 3001.4 3129.8 3133.9 3154.0 3172.9 43574.6 43645.1 43756.6 44186.8 44323.1 6028.9 6044.4 6077.5 6262.3 6263.3 11065.2 11247.6 11306.3 11337.4 11348.5 5477.5 5706.2 5829.2 5865.3 6033.9 3523.7 3581.7 3610.9 3636.1 3776.7 2449.9 2465.5 2497.1 2501.7 2502.2 375.1 378.8 382.8 384.0 388.2 473.3 489.6 510.5 553.5 580.7 7238.8 7364.7 7389.0 7633.5 7684.3 480.1 483.3 521.3 539.5 541.0 3331.3 3343.7 3472.6 3610.5 3713.9 53772.0 54198.5 54357.0 54410.0 54601.7 47135.8 48031.3 49030.3 49310.0 49421.3 39719.0 40040.8 40497.2 41385.8 43574.3 7273.2 7597.8 7691.6 7791.8 7929.1 32912.5 32933.0 33729.8 33764.5 33797.7 39958.2 40532.0 43105.8 43239.0 44030.6 6958.7 6967.6 7146.9 7199.3 7347.4 10147.7 10272.9 10381.2 10655.0 10988.0 9693.4 9701.3 9704.2 9993.7 10429.9 9347.9 9401.4 9551.5 10010.4 10334.7 9328.9 9511.9 9584.6 9718.9 10186.8 8056.2 8148.0 8195.3 8509.0 8908.6 7662.3 7795.6 7932.5 8519.3 8552.8 09:35:35 INFO - 2015-11-16 09:35:35,234 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:35:35 INFO - 2015-11-16 09:35:35,234 DEBUG : BROWSER_OUTPUT: 09:35:35 INFO - 2015-11-16 09:35:35,678 INFO : Browser exited with error code: 0 09:35:35 INFO - 2015-11-16 09:35:35,708 INFO : Completed test dromaeo_css (00:18:35) 09:35:35 INFO - 2015-11-16 09:35:35,709 INFO : Starting test kraken 09:35:35 INFO - 2015-11-16 09:35:35,709 DEBUG : operating with platform_type : mac_ 09:35:35 INFO - 2015-11-16 09:35:35,709 INFO : Initialising browser for kraken test... 09:35:35 INFO - 2015-11-16 09:35:35,723 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/tmpDT9vEB/profile http://localhost:49268/getInfo.html 09:35:38 INFO - 2015-11-16 09:35:38,074 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:35:38 INFO - 2015-11-16 09:35:38,074 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:35:38 INFO - 2015-11-16 09:35:38,074 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 09:35:38 INFO - 2015-11-16 09:35:38,075 DEBUG : BROWSER_OUTPUT: __metrics 09:35:38 INFO - 2015-11-16 09:35:38,090 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:35:38 INFO - 2015-11-16 09:35:38,807 INFO : Browser initialized. 09:35:38 INFO - 2015-11-16 09:35:38,808 INFO : Running cycle 1/1 for kraken test... 09:35:38 INFO - 2015-11-16 09:35:38,808 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/tmpDT9vEB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:35:50 INFO - 2015-11-16 09:35:50,831 DEBUG : BROWSER_OUTPUT: RSS: Main: 230019072 09:35:50 INFO - 2015-11-16 09:35:50,831 DEBUG : BROWSER_OUTPUT: 09:35:53 INFO - 2015-11-16 09:35:53,416 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 09:35:54 INFO - 2015-11-16 09:35:54,003 DEBUG : BROWSER_OUTPUT: RSS: Main: 286920704 09:35:54 INFO - 2015-11-16 09:35:54,003 DEBUG : BROWSER_OUTPUT: 09:35:56 INFO - 2015-11-16 09:35:56,786 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 09:35:57 INFO - 2015-11-16 09:35:57,362 DEBUG : BROWSER_OUTPUT: RSS: Main: 299556864 09:35:57 INFO - 2015-11-16 09:35:57,362 DEBUG : BROWSER_OUTPUT: 09:36:00 INFO - 2015-11-16 09:36:00,484 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 09:36:01 INFO - 2015-11-16 09:36:01,060 DEBUG : BROWSER_OUTPUT: RSS: Main: 281960448 09:36:01 INFO - 2015-11-16 09:36:01,060 DEBUG : BROWSER_OUTPUT: 09:36:03 INFO - 2015-11-16 09:36:03,154 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 09:36:03 INFO - 2015-11-16 09:36:03,736 DEBUG : BROWSER_OUTPUT: RSS: Main: 284336128 09:36:03 INFO - 2015-11-16 09:36:03,736 DEBUG : BROWSER_OUTPUT: 09:36:05 INFO - 2015-11-16 09:36:05,997 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:36:06 INFO - 2015-11-16 09:36:06,586 DEBUG : BROWSER_OUTPUT: RSS: Main: 285261824 09:36:06 INFO - 2015-11-16 09:36:06,586 DEBUG : BROWSER_OUTPUT: 09:36:09 INFO - 2015-11-16 09:36:09,818 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:10 INFO - 2015-11-16 09:36:10,408 DEBUG : BROWSER_OUTPUT: RSS: Main: 285446144 09:36:10 INFO - 2015-11-16 09:36:10,408 DEBUG : BROWSER_OUTPUT: 09:36:13 INFO - 2015-11-16 09:36:13,754 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:14 INFO - 2015-11-16 09:36:14,343 DEBUG : BROWSER_OUTPUT: RSS: Main: 284295168 09:36:14 INFO - 2015-11-16 09:36:14,343 DEBUG : BROWSER_OUTPUT: 09:36:17 INFO - 2015-11-16 09:36:17,405 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 09:36:17 INFO - 2015-11-16 09:36:17,987 DEBUG : BROWSER_OUTPUT: RSS: Main: 286183424 09:36:17 INFO - 2015-11-16 09:36:17,987 DEBUG : BROWSER_OUTPUT: 09:36:19 INFO - 2015-11-16 09:36:19,614 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:20 INFO - 2015-11-16 09:36:20,178 DEBUG : BROWSER_OUTPUT: RSS: Main: 320835584 09:36:20 INFO - 2015-11-16 09:36:20,178 DEBUG : BROWSER_OUTPUT: 09:36:22 INFO - 2015-11-16 09:36:22,096 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:22 INFO - 2015-11-16 09:36:22,716 DEBUG : BROWSER_OUTPUT: RSS: Main: 320917504 09:36:22 INFO - 2015-11-16 09:36:22,716 DEBUG : BROWSER_OUTPUT: 09:36:24 INFO - 2015-11-16 09:36:24,931 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:25 INFO - 2015-11-16 09:36:25,510 DEBUG : BROWSER_OUTPUT: RSS: Main: 329494528 09:36:25 INFO - 2015-11-16 09:36:25,510 DEBUG : BROWSER_OUTPUT: 09:36:28 INFO - 2015-11-16 09:36:28,298 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:28 INFO - 2015-11-16 09:36:28,884 DEBUG : BROWSER_OUTPUT: RSS: Main: 329101312 09:36:28 INFO - 2015-11-16 09:36:28,884 DEBUG : BROWSER_OUTPUT: 09:36:31 INFO - 2015-11-16 09:36:31,708 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:32 INFO - 2015-11-16 09:36:32,288 DEBUG : BROWSER_OUTPUT: RSS: Main: 329428992 09:36:32 INFO - 2015-11-16 09:36:32,288 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,117 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 09:36:34 INFO - 2015-11-16 09:36:34,364 DEBUG : BROWSER_OUTPUT: RSS: Main: 388870144 09:36:34 INFO - 2015-11-16 09:36:34,365 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,366 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:36:34 INFO - 2015-11-16 09:36:34,366 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:36:34 INFO - 2015-11-16 09:36:34,366 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:36:34 INFO - 2015-11-16 09:36:34,366 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:36:34 INFO - 2015-11-16 09:36:34,366 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;105;106;112;108;107;107;110;101;112 09:36:34 INFO - 2015-11-16 09:36:34,366 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;164;133;157;170;137;169;166;142;175;142 09:36:34 INFO - 2015-11-16 09:36:34,366 DEBUG : BROWSER_OUTPUT: |2;audio-dft;166;171;175;181;178;174;174;173;173;182 09:36:34 INFO - 2015-11-16 09:36:34,366 DEBUG : BROWSER_OUTPUT: |3;audio-fft;76;84;94;91;76;78;79;86;88;71 09:36:34 INFO - 2015-11-16 09:36:34,367 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;101;101;144;99;101;96;130;96;94;135 09:36:34 INFO - 2015-11-16 09:36:34,367 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;126;128;124;130;135;134;130;125;125;135 09:36:34 INFO - 2015-11-16 09:36:34,367 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;130;141;142;136;141;140;135;136;142;139 09:36:34 INFO - 2015-11-16 09:36:34,367 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;105;112;105;114;112;108;113;114;113;105 09:36:34 INFO - 2015-11-16 09:36:34,367 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;60;62;60;60;63;63;65;76;66;68 09:36:34 INFO - 2015-11-16 09:36:34,367 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;70;74;74;78;79;78;75;76;75 09:36:34 INFO - 2015-11-16 09:36:34,367 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;89;95;98;98;96;96;93;95;98;98 09:36:34 INFO - 2015-11-16 09:36:34,367 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;139;150;153;146;147;141;137;141;147;136 09:36:34 INFO - 2015-11-16 09:36:34,367 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;151;169;175;176;165;168;174;173;171;166 09:36:34 INFO - 2015-11-16 09:36:34,368 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;72;72;74;80;79;80;79;78;77;79 09:36:34 INFO - 2015-11-16 09:36:34,368 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:36:34 INFO - 2015-11-16 09:36:34,368 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:36:34 INFO - 2015-11-16 09:36:34,368 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,934 09:36:34 INFO - 2015-11-16 09:36:34,368 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:36:34 INFO - 2015-11-16 09:36:34,368 DEBUG : BROWSER_OUTPUT: __startTimestamp1447695394365__endTimestamp 09:36:34 INFO - 2015-11-16 09:36:34,368 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,369 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:36:34 INFO - 2015-11-16 09:36:34,370 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:36:34 INFO - 2015-11-16 09:36:34,370 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,370 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.50 Median:107.50 stddev:3.31 (3.1%) stddev-sans-first:3.50 09:36:34 INFO - 2015-11-16 09:36:34,370 DEBUG : BROWSER_OUTPUT: Values: 107.0 105.0 106.0 112.0 108.0 107.0 107.0 110.0 101.0 112.0 09:36:34 INFO - 2015-11-16 09:36:34,370 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,370 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:155.50 Median:165.00 stddev:15.53 (9.4%) stddev-sans-first:16.16 09:36:34 INFO - 2015-11-16 09:36:34,370 DEBUG : BROWSER_OUTPUT: Values: 164.0 133.0 157.0 170.0 137.0 169.0 166.0 142.0 175.0 142.0 09:36:34 INFO - 2015-11-16 09:36:34,371 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,371 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:174.70 Median:174.50 stddev:4.72 (2.7%) stddev-sans-first:3.81 09:36:34 INFO - 2015-11-16 09:36:34,371 DEBUG : BROWSER_OUTPUT: Values: 166.0 171.0 175.0 181.0 178.0 174.0 174.0 173.0 173.0 182.0 09:36:34 INFO - 2015-11-16 09:36:34,371 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,371 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:82.30 Median:85.00 stddev:7.44 (8.8%) stddev-sans-first:7.53 09:36:34 INFO - 2015-11-16 09:36:34,371 DEBUG : BROWSER_OUTPUT: Values: 76.0 84.0 94.0 91.0 76.0 78.0 79.0 86.0 88.0 71.0 09:36:34 INFO - 2015-11-16 09:36:34,372 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,372 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:109.70 Median:119.00 stddev:18.83 (15.8%) stddev-sans-first:19.71 09:36:34 INFO - 2015-11-16 09:36:34,372 DEBUG : BROWSER_OUTPUT: Values: 101.0 101.0 144.0 99.0 101.0 96.0 130.0 96.0 94.0 135.0 09:36:34 INFO - 2015-11-16 09:36:34,372 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,372 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:129.20 Median:130.00 stddev:4.29 (3.3%) stddev-sans-first:4.39 09:36:34 INFO - 2015-11-16 09:36:34,372 DEBUG : BROWSER_OUTPUT: Values: 126.0 128.0 124.0 130.0 135.0 134.0 130.0 125.0 125.0 135.0 09:36:34 INFO - 2015-11-16 09:36:34,373 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,374 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:138.20 Median:140.50 stddev:3.88 (2.8%) stddev-sans-first:2.76 09:36:34 INFO - 2015-11-16 09:36:34,374 DEBUG : BROWSER_OUTPUT: Values: 130.0 141.0 142.0 136.0 141.0 140.0 135.0 136.0 142.0 139.0 09:36:34 INFO - 2015-11-16 09:36:34,375 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,375 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:110.10 Median:112.50 stddev:3.90 (3.5%) stddev-sans-first:3.67 09:36:34 INFO - 2015-11-16 09:36:34,375 DEBUG : BROWSER_OUTPUT: Values: 105.0 112.0 105.0 114.0 112.0 108.0 113.0 114.0 113.0 105.0 09:36:34 INFO - 2015-11-16 09:36:34,375 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,375 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.00 stddev:4.92 (7.7%) stddev-sans-first:4.97 09:36:34 INFO - 2015-11-16 09:36:34,375 DEBUG : BROWSER_OUTPUT: Values: 60.0 62.0 60.0 60.0 63.0 63.0 65.0 76.0 66.0 68.0 09:36:34 INFO - 2015-11-16 09:36:34,375 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,375 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:74.70 Median:75.50 stddev:3.50 (4.6%) stddev-sans-first:2.74 09:36:34 INFO - 2015-11-16 09:36:34,375 DEBUG : BROWSER_OUTPUT: Values: 68.0 70.0 74.0 74.0 78.0 79.0 78.0 75.0 76.0 75.0 09:36:34 INFO - 2015-11-16 09:36:34,375 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,376 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:95.60 Median:97.00 stddev:2.88 (3.0%) stddev-sans-first:1.80 09:36:34 INFO - 2015-11-16 09:36:34,376 DEBUG : BROWSER_OUTPUT: Values: 89.0 95.0 98.0 98.0 96.0 96.0 93.0 95.0 98.0 98.0 09:36:34 INFO - 2015-11-16 09:36:34,376 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,376 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:146.50 stddev:5.72 (3.9%) stddev-sans-first:5.80 09:36:34 INFO - 2015-11-16 09:36:34,376 DEBUG : BROWSER_OUTPUT: Values: 139.0 150.0 153.0 146.0 147.0 141.0 137.0 141.0 147.0 136.0 09:36:34 INFO - 2015-11-16 09:36:34,376 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,376 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.80 Median:172.00 stddev:7.30 (4.2%) stddev-sans-first:3.99 09:36:34 INFO - 2015-11-16 09:36:34,376 DEBUG : BROWSER_OUTPUT: Values: 151.0 169.0 175.0 176.0 165.0 168.0 174.0 173.0 171.0 166.0 09:36:34 INFO - 2015-11-16 09:36:34,376 DEBUG : BROWSER_OUTPUT: 09:36:34 INFO - 2015-11-16 09:36:34,376 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.00 Median:79.00 stddev:3.16 (4.0%) stddev-sans-first:2.79 09:36:34 INFO - 2015-11-16 09:36:34,376 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 74.0 80.0 79.0 80.0 79.0 78.0 77.0 79.0 09:36:34 INFO - 2015-11-16 09:36:34,376 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:36:34 INFO - 2015-11-16 09:36:34,376 DEBUG : BROWSER_OUTPUT: 09:36:35 INFO - 2015-11-16 09:36:35,046 INFO : Browser exited with error code: 0 09:36:35 INFO - 2015-11-16 09:36:35,097 INFO : Completed test kraken (00:00:59) 09:36:35 INFO - 2015-11-16 09:36:35,097 INFO : Starting test v8_7 09:36:35 INFO - 2015-11-16 09:36:35,097 DEBUG : operating with platform_type : mac_ 09:36:35 INFO - 2015-11-16 09:36:35,097 INFO : Initialising browser for v8_7 test... 09:36:35 INFO - 2015-11-16 09:36:35,111 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/tmpzYPas6/profile http://localhost:49268/getInfo.html 09:36:37 INFO - 2015-11-16 09:36:37,431 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:36:37 INFO - 2015-11-16 09:36:37,431 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:36:37 INFO - 2015-11-16 09:36:37,432 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 09:36:37 INFO - 2015-11-16 09:36:37,432 DEBUG : BROWSER_OUTPUT: __metrics 09:36:37 INFO - 2015-11-16 09:36:37,490 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:36:37 INFO - 2015-11-16 09:36:37,694 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 09:36:38 INFO - 2015-11-16 09:36:38,192 INFO : Browser initialized. 09:36:38 INFO - 2015-11-16 09:36:38,192 INFO : Running cycle 1/1 for v8_7 test... 09:36:38 INFO - 2015-11-16 09:36:38,193 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/T/tmpzYPas6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:36:50 INFO - 2015-11-16 09:36:50,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 228139008 09:36:50 INFO - 2015-11-16 09:36:50,234 DEBUG : BROWSER_OUTPUT: 09:37:11 INFO - 2015-11-16 09:37:11,816 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49268/tests/v8_7/run.html (next: http://localhost:49268/tests/v8_7/run.html) 09:37:12 INFO - 2015-11-16 09:37:12,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 695173120 09:37:12 INFO - 2015-11-16 09:37:12,075 DEBUG : BROWSER_OUTPUT: 09:37:12 INFO - 2015-11-16 09:37:12,075 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:37:12 INFO - 2015-11-16 09:37:12,075 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:37:12 INFO - 2015-11-16 09:37:12,075 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:37:12 INFO - 2015-11-16 09:37:12,075 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:37:12 INFO - 2015-11-16 09:37:12,075 DEBUG : BROWSER_OUTPUT: |0;Richards;174.76406850751485 09:37:12 INFO - 2015-11-16 09:37:12,076 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.1598770176787 09:37:12 INFO - 2015-11-16 09:37:12,076 DEBUG : BROWSER_OUTPUT: |2;Encrypt;345.05342985177526 09:37:12 INFO - 2015-11-16 09:37:12,076 DEBUG : BROWSER_OUTPUT: |3;Decrypt;6217.391304347826 09:37:12 INFO - 2015-11-16 09:37:12,076 DEBUG : BROWSER_OUTPUT: |4;RayTrace;2022.2222222222222 09:37:12 INFO - 2015-11-16 09:37:12,076 DEBUG : BROWSER_OUTPUT: |5;Earley;1055.439330543933 09:37:12 INFO - 2015-11-16 09:37:12,076 DEBUG : BROWSER_OUTPUT: |6;Boyer;14705.882352941177 09:37:12 INFO - 2015-11-16 09:37:12,076 DEBUG : BROWSER_OUTPUT: |7;RegExp;31625 09:37:12 INFO - 2015-11-16 09:37:12,077 DEBUG : BROWSER_OUTPUT: |8;Splay;644.7453255963894 09:37:12 INFO - 2015-11-16 09:37:12,077 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;6141.104294478528 09:37:12 INFO - 2015-11-16 09:37:12,077 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:37:12 INFO - 2015-11-16 09:37:12,077 DEBUG : BROWSER_OUTPUT: __startTimestamp1447695432069__endTimestamp 09:37:12 INFO - 2015-11-16 09:37:12,077 DEBUG : BROWSER_OUTPUT: 09:37:12 INFO - 2015-11-16 09:37:12,077 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:37:12 INFO - 2015-11-16 09:37:12,077 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:37:12 INFO - 2015-11-16 09:37:12,077 DEBUG : BROWSER_OUTPUT: 09:37:12 INFO - 2015-11-16 09:37:12,078 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:174.76 Median:174.76 stddev:0.00 (0.0%) 09:37:12 INFO - 2015-11-16 09:37:12,078 DEBUG : BROWSER_OUTPUT: Values: 174.8 09:37:12 INFO - 2015-11-16 09:37:12,078 DEBUG : BROWSER_OUTPUT: 09:37:12 INFO - 2015-11-16 09:37:12,078 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.16 Median:192.16 stddev:0.00 (0.0%) 09:37:12 INFO - 2015-11-16 09:37:12,078 DEBUG : BROWSER_OUTPUT: Values: 192.2 09:37:12 INFO - 2015-11-16 09:37:12,078 DEBUG : BROWSER_OUTPUT: 09:37:12 INFO - 2015-11-16 09:37:12,078 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:345.05 Median:345.05 stddev:0.00 (0.0%) 09:37:12 INFO - 2015-11-16 09:37:12,078 DEBUG : BROWSER_OUTPUT: Values: 345.1 09:37:12 INFO - 2015-11-16 09:37:12,079 DEBUG : BROWSER_OUTPUT: 09:37:12 INFO - 2015-11-16 09:37:12,079 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:6217.39 Median:6217.39 stddev:0.00 (0.0%) 09:37:12 INFO - 2015-11-16 09:37:12,079 DEBUG : BROWSER_OUTPUT: Values: 6217.4 09:37:12 INFO - 2015-11-16 09:37:12,079 DEBUG : BROWSER_OUTPUT: 09:37:12 INFO - 2015-11-16 09:37:12,079 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:2022.22 Median:2022.22 stddev:0.00 (0.0%) 09:37:12 INFO - 2015-11-16 09:37:12,079 DEBUG : BROWSER_OUTPUT: Values: 2022.2 09:37:12 INFO - 2015-11-16 09:37:12,079 DEBUG : BROWSER_OUTPUT: 09:37:12 INFO - 2015-11-16 09:37:12,079 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:1055.44 Median:1055.44 stddev:0.00 (0.0%) 09:37:12 INFO - 2015-11-16 09:37:12,079 DEBUG : BROWSER_OUTPUT: Values: 1055.4 09:37:12 INFO - 2015-11-16 09:37:12,080 DEBUG : BROWSER_OUTPUT: 09:37:12 INFO - 2015-11-16 09:37:12,080 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:14705.88 Median:14705.88 stddev:0.00 (0.0%) 09:37:12 INFO - 2015-11-16 09:37:12,080 DEBUG : BROWSER_OUTPUT: Values: 14705.9 09:37:12 INFO - 2015-11-16 09:37:12,080 DEBUG : BROWSER_OUTPUT: 09:37:12 INFO - 2015-11-16 09:37:12,080 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:31625.00 Median:31625.00 stddev:0.00 (0.0%) 09:37:12 INFO - 2015-11-16 09:37:12,080 DEBUG : BROWSER_OUTPUT: Values: 31625.0 09:37:12 INFO - 2015-11-16 09:37:12,080 DEBUG : BROWSER_OUTPUT: 09:37:12 INFO - 2015-11-16 09:37:12,080 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:644.75 Median:644.75 stddev:0.00 (0.0%) 09:37:12 INFO - 2015-11-16 09:37:12,081 DEBUG : BROWSER_OUTPUT: Values: 644.7 09:37:12 INFO - 2015-11-16 09:37:12,081 DEBUG : BROWSER_OUTPUT: 09:37:12 INFO - 2015-11-16 09:37:12,081 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:6141.10 Median:6141.10 stddev:0.00 (0.0%) 09:37:12 INFO - 2015-11-16 09:37:12,081 DEBUG : BROWSER_OUTPUT: Values: 6141.1 09:37:12 INFO - 2015-11-16 09:37:12,081 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:37:12 INFO - 2015-11-16 09:37:12,081 DEBUG : BROWSER_OUTPUT: 09:37:12 INFO - 2015-11-16 09:37:12,756 INFO : Browser exited with error code: 0 09:37:12 INFO - 2015-11-16 09:37:12,777 INFO : Completed test v8_7 (00:00:37) 09:37:13 INFO - 2015-11-16 09:37:13,063 INFO : Completed test suite (00:20:12) 09:37:13 INFO - 2015-11-16 09:37:13,063 DEBUG : Working with test: dromaeo_css 09:37:13 INFO - 2015-11-16 09:37:13,064 DEBUG : Generating results file: dromaeo_css 09:37:13 INFO - 2015-11-16 09:37:13,064 DEBUG : Working with test: kraken 09:37:13 INFO - 2015-11-16 09:37:13,064 DEBUG : Generating results file: kraken 09:37:13 INFO - 2015-11-16 09:37:13,065 INFO : javascript benchmark 09:37:13 INFO - 2015-11-16 09:37:13,065 DEBUG : Working with test: v8_7 09:37:13 INFO - 2015-11-16 09:37:13,065 DEBUG : Generating results file: v8_7 09:37:13 INFO - 2015-11-16 09:37:13,065 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:37:13 INFO - 2015-11-16 09:37:13,133 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,55]] 09:37:13 INFO - 2015-11-16 09:37:13,133 DEBUG : process_Request line: dromaeo_css 6362.63 graph.html#tests=[[72,64,55]] 09:37:13 INFO - 2015-11-16 09:37:13,133 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:37:13 INFO - 2015-11-16 09:37:13,160 DEBUG : process_Request line: kraken 1631.30 graph.html#tests=[[232,64,55]] 09:37:13 INFO - 2015-11-16 09:37:13,160 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:37:13 INFO - 2015-11-16 09:37:13,189 DEBUG : process_Request line: v8_7 17161.00 graph.html#tests=[[230,64,55]] 09:37:13 INFO - 2015-11-16 09:37:13,190 INFO : javascript benchmark 09:37:13 INFO - 2015-11-16 09:37:13,192 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"jquery.html": [304.7866493454581, 307.6446704056815, 311.81135413075106, 314.16275625458155, 317.12157324211927, 13427.597172084854, 13898.51355202568, 16497.340106395746, 16556.83443165568, 16992.660146797072, 13390.866091339083, 15569.455069072557, 15638.218089095528, 15817.683646327067, 15836.683266334667, 4858.222684370501, 4932.038252540756, 4942.555170034696, 4992.101421744084, 4997.250412438127, 3622.1125824173114, 3683.576388715292, 3723.3484140275477, 4778.092162489133, 4949.232868905326, 5213.504717051874, 5412.07994640912, 5459.535939445152, 5473.644213126134, 5499.862503437406, 5312.495312945264, 5441.319835020622, 5453.672779633234, 5472.7263636818, 5491.28613280275, 4664.906701865943, 4737.95262047382, 4740.644451666139, 4746.264329028972, 4776.80892764289, 19822.603547929106, 19891.403257902286, 20042.098105585283, 20215.0, 20215.898920505304, 1973.5263536751077, 1988.3537850198743, 2018.1221168791476, 2102.3797979596056, 2375.3586531636533, 12425.75148497025, 12482.625521234377, 12755.426005829668, 12784.872151278461, 12855.807162892565, 1491.433255362966, 1548.0866288889497, 1587.6110352963483, 1600.0879498685754, 1624.61821471954, 52194.47805521934, 52773.736131319485, 53094.0, 53915.73042134803, 53926.0, 8755.693550725695, 8920.375573709907, 9111.772205694779, 9145.496997665192, 9178.35751497402, 10979.560817567368, 11018.44907754609, 11104.278221915549, 11106.278091924161, 11109.888901111048, 6866.073080134118, 6866.729655013838, 6876.243613202544, 6892.689828957709, 6946.583205007716, 6708.698108585126, 6733.259341472434, 6738.393544581011, 6774.729010839511, 6852.417544508772, 5390.245365648812, 5392.325959255093, 5471.480209380103, 5471.6169868108855, 5517.531009864127, 1432.5129455985016, 1496.768000959853, 1515.4620109861062, 1557.2758667219798, 1566.130797407427, 837.660747397305, 907.3331101640289, 919.3656377099779, 950.9144177024101, 1032.5198782565992, 16411.753823692656, 17601.911990439967, 17667.29330826778, 17746.03111128886, 17762.28950841952, 762.7787941497015, 802.8554860125233, 831.4553967151526, 850.128618166372, 884.0496466298755, 9360.765980850398, 9600.711978640651, 9688.273379496566, 9716.465594392235, 9747.951260243653, 530249.0, 534806.0, 535403.0, 553810.0, 562213.0, 62486.0, 64903.675481623235, 65589.0, 65790.34209657938, 65935.67032164808, 1010.7068950004564, 1026.8357062869904, 1090.6128324444708, 1104.8397982292522, 1108.3183841937312, 14761.92619036898, 14770.261486925825, 14900.850991489946, 15218.086914785148, 15267.465638702592, 30725.23186920345, 30975.535366969965, 30984.22539436442, 31341.686583133876, 31453.0, 39959.2008159841, 40676.59323406728, 41045.794771025954, 41663.0, 43345.78327108344, 5631.155326701028, 5754.856128596819, 5831.35855055952, 5832.358440571618, 5835.649861008353, 10857.457127143452, 11090.390028548507, 11179.0, 11204.103671706407, 11355.886441135483, 7776.84446311082, 8220.0, 8251.711190108317, 8952.373333866566, 9045.457272563668, 8828.911710882809, 8859.291256699578, 9011.188992990663, 9175.082491751038, 9420.340576159602, 9384.343095983215, 9618.51907404613, 9623.615055397706, 9665.178459830711, 9713.417194968324, 5658.519025882846, 5765.740541675551, 5857.179994800646, 5860.706964651665, 5872.911906321323, 9340.159385655325, 9463.526823658927, 9540.475273860004, 9628.422294662343, 9643.662471813454], "dojo.html": [2957.5563665450168, 2967.1098670398874, 3033.105233955983, 3117.4700300948844, 3227.35452909418, 4843.685160464567, 4991.326170966928, 5008.599312055036, 5020.548150666439, 5024.271480635311, 4916.606671466283, 4969.975150124254, 4995.375578052744, 5027.49725027498, 5085.745712714368, 4526.298423744325, 4602.838900638479, 4605.700629459091, 4682.250839865622, 4745.928811067837, 4696.29555566666, 4718.882027949295, 4723.574878260956, 4767.499412561683, 4770.212914869043, 4439.200943830142, 4564.589186973155, 4604.838830640945, 4634.0, 4700.036492519025, 4461.888452788674, 4573.702709323917, 4604.493505714368, 4605.125026245003, 4642.628589712815, 4456.22016147173, 4576.8626941191815, 4600.815967361302, 4620.537946205386, 4681.157391669498, 4816.229403295456, 4861.611071114337, 4955.157623204064, 4999.450060493343, 5000.449950505442, 5231.764570594332, 5245.6852588844795, 5263.315768950012, 5303.602229832778, 5328.0, 5298.867528311785, 5330.28041214435, 5338.412774594792, 5382.515573598395, 5390.137577987503, 4751.548602882721, 4951.108800415924, 4957.3059771632, 5012.724300163526, 5076.593872490229, 5289.0, 5365.731713414314, 5465.863353416117, 5490.56075513958, 5490.835274941758, 5207.869803254949, 5217.973910130501, 5221.6344855859725, 5235.267062611238, 5299.761510732026, 5243.816466423733, 5355.812546560852, 5411.648242864201, 5426.728663566885, 5505.0, 5194.818181363634, 5225.190095535198, 5247.1866860636665, 5257.553107985864, 5292.708901010405, 5202.401723801736, 5212.348456442945, 5281.069130834374, 5288.788448462095, 5293.523582877559, 3972.6424621784176, 4075.1645912587846, 4077.89805254871, 4163.875083747493, 4180.728252663567, 3679.981600092036, 3683.7053035757076, 3762.172322089136, 3768.6419790119894, 3775.0, 5039.143345631252, 5050.419201791768, 5067.87330316745, 5114.105031619451, 5179.352580927384, 5214.061468935628, 5377.865553361197, 5382.085045542267, 5444.428335024765, 5445.754941027663, 4965.4041515018425, 4968.552830245295, 5013.874653133701, 5065.037642847848, 5098.133317336062, 4666.440027196691, 4670.5329467053025, 4724.220503616864, 4724.480307166209, 4764.285357196379, 5351.678899266057, 5489.505944465017, 5497.39528651848, 5527.281453411031, 5541.141123125922, 5243.082460569462, 5263.973680131575, 5304.893902122013, 5354.919676204938, 5415.35015798091, 5517.917231241454, 5553.139263414178, 5609.803656872153, 5623.268975033219, 5625.971870140623, 5230.7384630769075, 5270.367555893317, 5305.0, 5339.546138578265, 5391.7034563099405, 5203.765830537559, 5303.840884773463, 5359.035373632628, 5377.973110134424, 5395.13677811564, 5227.947720522746, 5249.947500525099, 5265.842024739264, 5271.499207575274, 5273.077211488051, 5060.8987820242655, 5266.8419947401635, 5267.051930652519, 5278.313819203479, 5321.760520776497, 5021.372328458942, 5022.773975171053, 5115.360579927509, 5143.254228136974, 5151.562117220078, 5232.659877107976, 5264.499872512105, 5276.314079169683, 5277.947220527899, 5329.253904453457, 4931.309616653743, 5167.948320516747, 5173.22401639757, 5174.767135478988, 5182.0], "mootools.html": [1463.9926800365997, 1542.0, 1669.8998060116394, 1687.468447439057, 1698.5159229619553, 6791.11715476988, 7121.323474269942, 7368.668409921546, 7544.321011108999, 7571.053618297713, 7356.96321518393, 7363.889541656866, 7389.556626602408, 7472.804351303793, 7498.662560184791, 2088.5822835432937, 2118.226847200776, 2119.0993827623197, 2146.914123435061, 2150.698902153692, 2157.255746767363, 2164.9242276520326, 2206.2719302630094, 2226.9665955010687, 2235.7093577834858, 2122.066290832029, 2127.7978592033733, 2131.2966720982117, 2143.2070134050423, 2159.276642324823, 1744.8865823721417, 1746.6594014167242, 1747.1177055586975, 1754.956126096845, 1759.7976232733208, 2099.4961209309654, 2129.2973318804757, 2130.4141361125658, 2131.328631481094, 2154.2137119951103, 6611.537192396534, 7403.222661620506, 7444.181140074587, 7461.477696561243, 7465.0, 4185.246655601991, 4419.248727716296, 4554.95445045552, 4557.74932378719, 4607.838725644619, 2698.892044318225, 2721.687005994316, 2786.0945192812214, 2798.4123334099863, 2860.413615208877, 852.1180578101697, 882.8101958079044, 883.0198479687542, 884.1600479544394, 894.7449976756658, 9845.360051596552, 9896.752581185456, 9937.155341795884, 9948.452835094067, 9984.750381240527, 4165.66674666026, 4258.787060646955, 4297.290946993773, 4308.784560771949, 4323.005708687019, 8674.6096425661, 8720.084391138838, 8740.737777866674, 8815.515146666994, 8832.205101540892, 4700.459447163552, 4714.269288260257, 4722.645801564897, 4777.0, 4781.880452988634, 4259.297215959332, 4273.380359847804, 4306.181825453154, 4310.80601372939, 4316.287812510901, 3444.9483257751153, 3446.1212390840174, 3457.7060949819042, 3482.7039701625645, 3510.859565617398, 1095.6822521468684, 1120.9719757005978, 1126.2510430563748, 1126.7802778458122, 1136.0457215938652, 953.5661274120258, 981.9165370943407, 986.2455221755363, 1064.414571985405, 1232.8397308349856, 6867.690953906987, 6922.2731613180895, 6930.029795828588, 7012.018317435563, 7035.894461583081, 431.2840684463775, 1112.4159816096453, 1149.3333866357493, 1178.4166837415535, 1179.9233049851732, 5136.049830781318, 5320.547753440924, 5383.273258110184, 5434.565234781208, 5453.0, 7700.229977002255, 7778.766637000899, 7789.065312162576, 7802.453828231969, 8147.307478864363, 7741.8451630967065, 7761.185075567097, 7761.534307941542, 7801.765947021599, 7806.180351063056, 6409.455196308273, 6416.0, 6505.772297969548, 6630.071789949411, 6638.506336074344, 6442.613443193423, 6464.062710906798, 6500.219973603198, 6511.2512061113575, 6552.934470655233, 7008.684609192701, 7302.488825782144, 7373.741919032808, 7415.184329723834, 7427.702891884264, 7373.0, 7580.810479738051, 7625.389968802371, 7701.4608977371045, 7706.922930770845, 6207.844803879939, 6226.782062627786, 6250.749970001149, 6250.781222657185, 6358.90461643085, 7103.644817759195, 7104.11198600175, 7123.572585644878, 7180.246074162346, 7183.640817959185, 6607.438367738604, 6676.332366763285, 6680.265170831108, 6709.161354830646, 6802.14973128359, 6280.874382512416, 6417.486601071996, 6494.870102598016, 6602.438792702674, 6659.101021362054, 6622.63575503352, 6654.633995130314, 6679.0649309097735, 6680.3987641112535, 6687.0, 6082.60463069899, 6120.051392034242, 6134.723937422737, 6147.969260153671, 6198.349173336735, 6191.566590338633, 6251.562390632794, 6261.092141639528, 6292.3078461368295, 6295.401936815995], "ext.html": [507.63450315772644, 518.4996478398347, 521.1583292981835, 531.1740243920704, 539.0458887768647, 28326.29184270392, 34864.0, 35615.46576801343, 35654.821725891365, 36203.63796362036, 36876.446853297224, 36913.815430922805, 36996.81501592495, 37395.0, 37417.438738418874, 7625.65684544194, 7664.310212080912, 8482.194191551807, 8649.69726059588, 8660.740177794676, 10082.495875206247, 10212.693619191436, 10225.539850706698, 10303.793924121514, 10339.534720937538, 9946.65186718465, 10043.447610381427, 10088.646897358594, 10090.949545252264, 10099.29304948654, 7788.026496687914, 7919.762407127795, 9778.755531111708, 10172.643957461527, 10368.688939331832, 9857.457839818808, 9902.9504852476, 10011.14905233056, 10014.0, 10030.49847507629, 35119.0, 35243.1189220269, 35616.28767424663, 35657.108572285644, 35775.105622359646, 2695.420484595821, 2795.0636536760207, 2845.5447128459555, 2866.226118947893, 3085.3674996625864, 24441.511169776506, 24793.62809557858, 25986.480270394488, 26082.217533474028, 26598.6010209847, 2234.597772400967, 2280.737715162761, 2283.977160228393, 2286.965695514569, 2383.690120284352, 254576.0, 256133.0, 259380.0, 260912.0, 261550.0, 50839.23741143886, 51736.0, 54176.0, 54593.0, 54621.0, 62921.370786292464, 63516.36483635197, 63940.0, 63950.680246598466, 64052.0, 13196.340182990813, 13330.800037999437, 13473.124246923919, 13486.258255796025, 13486.527971521145, 9762.463064531377, 9932.106110450095, 10066.345687530433, 10071.597136114473, 10075.697729068139, 18140.274389024555, 18308.725369119475, 18535.424488918594, 18553.721694174597, 18567.0, 8032.558209298428, 8309.709160179485, 8334.66661333552, 8364.916350836535, 8394.286485648667, 884.5930871798915, 926.3515539122641, 943.7499062748377, 951.2152474208806, 1027.5632856036154, 25568.74431255701, 28377.29056773556, 29391.26521836971, 29700.851495742383, 30160.84919575432, 874.1258741258741, 911.8632205169278, 938.502593625366, 942.9057094290654, 1068.460427484131, 13010.349482526026, 13182.934085329513, 13193.802092968614, 13313.66715832112, 13317.134386264861, 374731.0, 379964.0, 391416.0, 392041.0, 394167.0, 49217.50782492201, 49234.0, 49278.0, 49643.0, 49704.75147624239, 1264.7533730922567, 1272.3320256865036, 1287.9742405152033, 1291.2962435472878, 1296.358302640199, 24455.75544244535, 25059.624105638064, 25114.24657260285, 25387.238382848544, 25405.872970635028, 41032.79483602563, 41576.376354354106, 41614.0, 42144.578554214066, 42147.0, 276975.0, 278867.0, 280568.0, 282923.0, 285616.0, 24532.141375051786, 24744.752552474245, 27468.450630987667, 27967.720322797326, 28478.57282140811, 32709.0, 33365.16587085342, 33448.498272525445, 33584.66415335816, 33774.83112584421, 29179.7082029177, 29225.269368265963, 29561.113166605035, 29708.55437168401, 29956.850215748782, 24028.0, 27557.31106722348, 28825.279368015967, 29130.56304155482, 29180.124596262147, 28888.711112889443, 29352.706472935, 29415.705842941297, 29474.263143421587, 29527.409451811272, 24637.50724985526, 24933.252002439956, 25811.354716132246, 25886.482270354863, 26524.204273871816, 19963.0, 20366.185352586323, 21048.158073677492, 21174.788252117283, 21232.787672123082], "prototype.html": [290.29748009816245, 299.1862134992819, 304.5340628837877, 305.1562429881378, 307.6554259229663, 5666.433356664332, 5679.204911312419, 5698.430156984305, 5810.215620891178, 5839.416058394169, 5641.52047075998, 5656.971715141419, 5726.427357264271, 5770.567207459437, 5770.826875193751, 4375.4530683664625, 4402.0, 4448.221561226788, 4450.4881938577155, 4461.665375096864, 4227.175700738358, 4346.652267818583, 4384.276594361926, 4446.577575130357, 4471.6199123074575, 4391.385206071152, 4399.604035636792, 4402.25161722508, 4406.140802543506, 4429.6899217054815, 4367.628751556105, 4399.604035636776, 4428.202923473774, 4429.025614364835, 4452.443444569429, 3842.769433833979, 3843.0392401899526, 3845.8461661533784, 3849.2493963677093, 3862.9034274143373, 5415.268938693265, 5593.300837395325, 5624.409437009096, 5653.349864765522, 5661.66030038199, 4994.400671919357, 5006.974965125188, 5007.048660754482, 5009.849704508871, 5020.1214787412055, 6043.576949613529, 6045.214122164134, 6068.484178844804, 6100.69496525172, 6110.327863934964, 4157.8960525987095, 4159.896002599929, 4161.2301724181125, 4165.875023749262, 4174.853880114181, 5606.383297837235, 5984.222051133324, 6057.636541807506, 6289.496840252768, 6461.709223084972, 4237.385579091015, 4245.957540424618, 4246.087091275391, 4248.086661367821, 4250.638695710899, 4252.425922500484, 4270.935935960963, 4321.092570560218, 4337.869863904088, 4347.3044312910415, 4889.095517329305, 4918.262260660858, 4955.1576232039915, 4966.056449274626, 4968.056069346812, 5788.421157884262, 6026.758929642765, 6055.969720151371, 6058.242719660043, 6070.848228794315, 1448.739226939161, 1467.2736995187452, 1478.135290854862, 1481.451862810767, 1482.0366761605044, 942.7360339105062, 943.580106852444, 950.235060776068, 958.726762872592, 971.2133172130597, 5863.061910094364, 5880.470757631833, 5895.587308888423, 5896.204012458349, 5898.380670029585, 6145.539084568675, 6198.752049918043, 6313.873722525524, 6324.873502530016, 6370.267419246753, 5863.5309175265875, 5894.056950887837, 5898.852528686817, 5922.526197904157, 5930.792422265286, 3404.4382676858536, 3444.7781181138025, 3447.8103704296504, 3450.1719587298867, 3453.32660131272, 6494.6103233806125, 6635.2037755469655, 6655.86688266232, 6660.100886380374, 6693.631850248185, 6564.081028655992, 6564.146660934143, 6573.737050517926, 6574.243961944438, 6637.402633762985, 6637.933620663925, 6662.500312476487, 6706.765263215764, 6761.5604985676755, 6781.6609169541325, 6295.622262664255, 6304.0, 6364.427201551883, 6401.807945761635, 6415.903761443488, 6184.350643182581, 6234.376562343729, 6241.968790156202, 6253.843653908689, 6260.904341740268, 6611.404973552211, 6661.467082633475, 6673.9332606673315, 6680.799576012728, 6729.327067293231, 4023.1953609277675, 4118.6499147572795, 4130.421740956209, 4137.296659567941, 4142.2751018571025, 6287.685615719104, 6346.397092276226, 6355.650439225702, 6370.140031095742, 6448.742050318122, 6175.814725558061, 6178.443940045418, 6192.504599632109, 6199.473044791243, 6202.534809889186, 5995.340512543704, 6052.818415447544, 6167.290761562478, 6169.198004259418, 6170.352113028068, 5957.821265362046, 5959.940400595939, 6005.099235114768, 6008.87982240344, 6019.036954087325, 6148.385161483816, 6150.7539698411565, 6156.322804491592, 6166.290876549075, 6179.28938172098, 5640.830775076754, 5703.201551782837, 5983.910241346296, 6046.455818976313, 6054.606450580698], "yui.html": [677.840707433753, 693.829274448574, 696.1402667705386, 718.1131302840992, 733.431590517349, 11389.145814063948, 11651.184417090806, 11687.649370518877, 11743.354115523643, 11765.882341176597, 11876.0, 11982.0, 12006.519739210422, 12014.03887688985, 12107.394630268496, 652.4682383857141, 667.2160211751197, 820.0733171516179, 825.286127499713, 840.3991146330372, 820.4379999700195, 831.7380025292044, 835.2607941971369, 838.8280402517499, 845.7166849105557, 865.1391865094208, 872.376250980552, 876.5135349880813, 881.9603117859712, 888.7778055486128, 676.5500941873648, 685.1504134872772, 829.5769157729601, 838.4047326398264, 858.4591707224471, 635.6694518850218, 638.3999040901584, 639.48840927258, 640.5996252342286, 648.7567162314132, 12554.937225313815, 12687.365631718378, 12687.936560317232, 12715.237085774883, 12726.23642581448, 10575.312604680672, 10744.892551074545, 10760.892391076146, 10764.569417223302, 11038.724031899186, 8450.154984501562, 8693.304535637133, 8783.253423459013, 8826.338024648112, 9181.770455738593, 3001.3847161331873, 3129.765267604939, 3133.8903138390274, 3153.952690709641, 3172.888948886778, 43574.56425435737, 43645.12709745787, 43756.56243437557, 44186.779066104784, 44323.11353772906, 6028.909566356509, 6044.425779550936, 6077.513798896078, 6262.3111457739615, 6263.342349053284, 11065.225434219688, 11247.606333778442, 11306.321620702784, 11337.376444295478, 11348.546058157746, 5477.45225477457, 5706.172604972256, 5829.242198514166, 5865.325487568899, 6033.939660603338, 3523.735719821024, 3581.71346292296, 3610.8736194233074, 3636.1091532574687, 3776.6789822864816, 2449.902003919823, 2465.506898620283, 2497.0760818497274, 2501.7373175816642, 2502.1617758050893, 375.1166004060398, 378.8219536817691, 382.7512117123892, 383.9635234652703, 388.2099926649255, 473.25462396725294, 489.59852920605476, 510.45636397236825, 553.4576115406843, 580.6545105662125, 7238.819029524198, 7364.742234021891, 7389.0, 7633.5419874807685, 7684.308412242814, 480.0988087174757, 483.27026190451966, 521.3144714700157, 539.4794023767005, 540.9964515823092, 3331.267121233325, 3343.715784158374, 3472.6353732857688, 3610.4764809102535, 3713.9071523211596, 53772.0, 54198.45801542013, 54357.0, 54410.0, 54601.72699136479, 47135.764321178176, 48031.27953080706, 49030.26454603184, 49310.0, 49421.25868111981, 39719.0, 40040.799796000836, 40497.19005619871, 41385.793071035056, 43574.34638480425, 7273.163586187656, 7597.772066838003, 7691.5769632670745, 7791.766247012599, 7929.112363070378, 32912.506312404854, 32933.0, 33729.83135084309, 33764.49353259654, 33797.66202337945, 39958.2008359837, 40532.0, 43105.784471077444, 43239.0, 44030.55969440265, 6958.721651133898, 6967.581945083311, 7146.857062858818, 7199.316064973819, 7347.448941329529, 10147.695569132939, 10272.948635256776, 10381.172754669115, 10655.0, 10988.0, 9693.369930954465, 9701.272404569545, 9704.175145112744, 9993.700188994051, 10429.895701042893, 9347.906520934976, 9401.435913845191, 9551.522423878918, 10010.44942528168, 10334.741631459274, 9328.860067099135, 9511.904880951102, 9584.616615335588, 9718.9028109718, 10186.796264074825, 8056.154103819014, 8148.0, 8195.262426381654, 8509.0, 8908.643654253758, 7662.310392064518, 7795.623457793425, 7932.48025057253, 8519.318454523747, 8552.786180345542]}, "summary": {"lowerIsBetter": false, "suite": 6362.635163180034, "subtests": {"jquery.html": {"lowerIsBetter": false, "filtered": 7714.5576905615, "unit": "ms", "value": 7714.5576905615}, "dojo.html": {"lowerIsBetter": false, "filtered": 4944.556291307953, "unit": "ms", "value": 4944.556291307953}, "mootools.html": {"lowerIsBetter": false, "filtered": 4169.774633885472, "unit": "ms", "value": 4169.774633885472}, "ext.html": {"lowerIsBetter": false, "filtered": 16876.268124555172, "unit": "ms", "value": 16876.268124555172}, "prototype.html": {"lowerIsBetter": false, "filtered": 4573.240507795948, "unit": "ms", "value": 4573.240507795948}, "yui.html": {"lowerIsBetter": false, "filtered": 5404.1941265136775, "unit": "ms", "value": 5404.1941265136775}}}, "test_machine": {"platform": "x86_64", "osversion": "OS X 10.10.2", "os": "mac", "name": "t-yosemite-r5-0097"}, "testrun": {"date": 1447694220, "suite": "dromaeo_css", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151116070823", "branch": "Fx-Team", "revision": "2443d724b1a233074f2ffdee1f2ed3e1f6792650"}}, {"talos_counters": {}, "results": {"audio-dft": [166.0, 171.0, 175.0, 181.0, 178.0, 174.0, 174.0, 173.0, 173.0, 182.0], "ai-astar": [107.0, 105.0, 106.0, 112.0, 108.0, 107.0, 107.0, 110.0, 101.0, 112.0], "imaging-gaussian-blur": [126.0, 128.0, 124.0, 130.0, 135.0, 134.0, 130.0, 125.0, 125.0, 135.0], "audio-beat-detection": [164.0, 133.0, 157.0, 170.0, 137.0, 169.0, 166.0, 142.0, 175.0, 142.0], "json-stringify-tinderbox": [68.0, 70.0, 74.0, 74.0, 78.0, 79.0, 78.0, 75.0, 76.0, 75.0], "stanford-crypto-pbkdf2": [151.0, 169.0, 175.0, 176.0, 165.0, 168.0, 174.0, 173.0, 171.0, 166.0], "json-parse-financial": [60.0, 62.0, 60.0, 60.0, 63.0, 63.0, 65.0, 76.0, 66.0, 68.0], "stanford-crypto-sha256-iterative": [72.0, 72.0, 74.0, 80.0, 79.0, 80.0, 79.0, 78.0, 77.0, 79.0], "audio-fft": [76.0, 84.0, 94.0, 91.0, 76.0, 78.0, 79.0, 86.0, 88.0, 71.0], "imaging-darkroom": [130.0, 141.0, 142.0, 136.0, 141.0, 140.0, 135.0, 136.0, 142.0, 139.0], "stanford-crypto-ccm": [139.0, 150.0, 153.0, 146.0, 147.0, 141.0, 137.0, 141.0, 147.0, 136.0], "audio-oscillator": [101.0, 101.0, 144.0, 99.0, 101.0, 96.0, 130.0, 96.0, 94.0, 135.0], "stanford-crypto-aes": [89.0, 95.0, 98.0, 98.0, 96.0, 96.0, 93.0, 95.0, 98.0, 98.0], "imaging-desaturate": [105.0, 112.0, 105.0, 114.0, 112.0, 108.0, 113.0, 114.0, 113.0, 105.0]}, "summary": {"lowerIsBetter": true, "suite": 1631.3000000000002, "subtests": {"audio-fft": {"lowerIsBetter": true, "filtered": 82.3, "unit": "ms", "value": 82.3}, "stanford-crypto-pbkdf2": {"lowerIsBetter": true, "filtered": 168.8, "unit": "ms", "value": 168.8}, "audio-beat-detection": {"lowerIsBetter": true, "filtered": 155.5, "unit": "ms", "value": 155.5}, "stanford-crypto-ccm": {"lowerIsBetter": true, "filtered": 143.7, "unit": "ms", "value": 143.7}, "imaging-darkroom": {"lowerIsBetter": true, "filtered": 138.2, "unit": "ms", "value": 138.2}, "json-parse-financial": {"lowerIsBetter": true, "filtered": 64.3, "unit": "ms", "value": 64.3}, "audio-oscillator": {"lowerIsBetter": true, "filtered": 109.7, "unit": "ms", "value": 109.7}, "ai-astar": {"lowerIsBetter": true, "filtered": 107.5, "unit": "ms", "value": 107.5}, "audio-dft": {"lowerIsBetter": true, "filtered": 174.7, "unit": "ms", "value": 174.7}, "stanford-crypto-sha256-iterative": {"lowerIsBetter": true, "filtered": 77.0, "unit": "ms", "value": 77.0}, "json-stringify-tinderbox": {"lowerIsBetter": true, "filtered": 74.7, "unit": "ms", "value": 74.7}, "imaging-gaussian-blur": {"lowerIsBetter": true, "filtered": 129.2, "unit": "ms", "value": 129.2}, "stanford-crypto-aes": {"lowerIsBetter": true, "filtered": 95.6, "unit": "ms", "value": 95.6}, "imaging-desaturate": {"lowerIsBetter": true, "filtered": 110.1, "unit": "ms", "value": 110.1}}}, "test_machine": {"platform": "x86_64", "osversion": "OS X 10.10.2", "os": "mac", "name": "t-yosemite-r5-0097"}, "testrun": {"date": 1447694220, "suite": "kraken", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151116070823", "branch": "Fx-Team", "revision": "2443d724b1a233074f2ffdee1f2ed3e1f6792650"}}, {"talos_counters": {}, "results": {"DeltaBlue": [192.1598770176787], "Encrypt": [345.05342985177526], "NavierStokes": [6141.104294478528], "Decrypt": [6217.391304347826], "Splay": [644.7453255963894], "Richards": [174.76406850751485], "RegExp": [31625.0], "Earley": [1055.439330543933], "Boyer": [14705.882352941177], "RayTrace": [2022.2222222222222]}, "summary": {"lowerIsBetter": false, "suite": 17161.004287057436, "subtests": {"DeltaBlue": {"lowerIsBetter": false, "filtered": 344.07807199999985, "unit": "score", "value": 344.07807199999985}, "Encrypt": {"lowerIsBetter": false, "filtered": 771.4196613386615, "unit": "score", "value": 771.4196613386615}, "NavierStokes": {"lowerIsBetter": false, "filtered": 241.65034965034977, "unit": "score", "value": 241.65034965034977}, "Decrypt": {"lowerIsBetter": false, "filtered": 42.812328671328686, "unit": "score", "value": 42.812328671328686}, "Splay": {"lowerIsBetter": false, "filtered": 126.39254099999998, "unit": "score", "value": 126.39254099999998}, "RegExp": {"lowerIsBetter": false, "filtered": 28.80584980237153, "unit": "score", "value": 28.80584980237153}, "Richards": {"lowerIsBetter": false, "filtered": 201.998044, "unit": "score", "value": 201.998044}, "Earley": {"lowerIsBetter": false, "filtered": 631.4555282457882, "unit": "score", "value": 631.4555282457882}, "Boyer": {"lowerIsBetter": false, "filtered": 45.319484000000024, "unit": "score", "value": 45.319484000000024}, "RayTrace": {"lowerIsBetter": false, "filtered": 365.9286263736265, "unit": "score", "value": 365.9286263736265}}}, "test_machine": {"platform": "x86_64", "osversion": "OS X 10.10.2", "os": "mac", "name": "t-yosemite-r5-0097"}, "testrun": {"date": 1447694220, "suite": "v8_7", "options": {"responsiveness": false, "tpmozafterpaint": false, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151116070823", "branch": "Fx-Team", "revision": "2443d724b1a233074f2ffdee1f2ed3e1f6792650"}}] 09:37:13 INFO - RETURN: dromaeo_css: 6362.63 09:37:13 INFO - RETURN: kraken: 1631.30 09:37:13 INFO - RETURN: v8_7: 17161.00 09:37:13 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,55]]", "result": "17161.00"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,55]]", "result": "1631.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,55]]", "result": "6362.63"}}} 09:37:13 INFO - Return code: 0 09:37:13 INFO - # TBPL SUCCESS # 09:37:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:13 INFO - 2015-11-16 09:37:13.298 screenresolution[1658:9963] starting screenresolution argv=screenresolution get 09:37:13 INFO - 2015-11-16 09:37:13.302 screenresolution[1658:9963] Display 0: 1600x1200x32@60 09:37:13 INFO - 2015-11-16 09:37:13.308 screenresolution[1659:9966] starting screenresolution argv=screenresolution list 09:37:13 INFO - Available Modes on Display 0 09:37:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:13 INFO - Intel HD Graphics 3000: 09:37:13 INFO - Chipset Model: Intel HD Graphics 3000 09:37:13 INFO - Type: GPU 09:37:13 INFO - Bus: Built-In 09:37:13 INFO - VRAM (Total): 512 MB 09:37:13 INFO - VRAM (Dynamic, Max): 10 09:37:13 INFO - Vendor: Intel (0x8086) 09:37:13 INFO - Device ID: 0x0116 09:37:13 INFO - Revision ID: 0x0009 09:37:13 INFO - Displays: 09:37:13 INFO - Display: 09:37:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:13 INFO - Display Serial Number: 1600x1200 60 09:37:13 INFO - Main Display: Yes 09:37:13 INFO - Mirror: Off 09:37:13 INFO - Online: Yes 09:37:13 INFO - Rotation: Supported 09:37:13 INFO - Return code: 0 09:37:13 INFO - Running post-action listener: _resource_record_post_action 09:37:13 INFO - Running post-run listener: _resource_record_post_run 09:37:14 INFO - Total resource usage - Wall time: 1252s; CPU: 30.0%; Read bytes: 41538560; Write bytes: 689804288; Read time: 4558; Write time: 22283 09:37:14 INFO - install - Wall time: 38s; CPU: 30.0%; Read bytes: 339524096; Write bytes: 337547264; Read time: 33784; Write time: 6641 09:37:14 INFO - run-tests - Wall time: 1214s; CPU: 30.0%; Read bytes: 39638016; Write bytes: 341058560; Read time: 4243; Write time: 15275 09:37:14 INFO - Running post-run listener: _upload_blobber_files 09:37:14 INFO - Blob upload gear active. 09:37:14 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:37:14 INFO - Copying logs to upload dir... 09:37:14 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1305.317745 ========= master_lag: 1.75 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 47 secs) (at 2015-11-16 09:37:16.428128) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-16 09:37:16.432155) ========= 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.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners TMPDIR=/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/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:0x0:0x0 using PTY: False build_url:https://queue.taskcluster.net/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010048 build_url: 'https://queue.taskcluster.net/v1/task/iou59pQjRkCPZMLnLcEUmA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-16 09:37:16.465116) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 09:37:16.465506) ========= 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.QBrq3jWLRq/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.24ZLbGbaiQ/Listeners TMPDIR=/var/folders/8v/skhgdwld40j_ymk_9yz3bdqh00000w/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:0x0:0x0 using PTY: False program finished with exit code 0 elapsedTime=0.005504 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-16 09:37:16.516097) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-16 09:37:16.516584) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-16 09:37:16.517302) ========= ========= Total master_lag: 6.46 =========