builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0014 starttime: 1448000844.45 results: success (0) buildid: 20151119211036 builduid: dcd87b7a8c9b4a67a2ac813c610d7193 revision: 1b2e15608f34fef0f23369731c80138f55f00cf2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:24.451183) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:24.451576) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:24.451855) ========= 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.ISeJD9gAU6/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.K41mFqqjYH/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.005816 basedir: '/builds/slave/test' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:24.482883) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:24.483366) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:24.519579) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:24.519852) ========= 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.ISeJD9gAU6/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.K41mFqqjYH/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005318 ========= master_lag: 0.25 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:24.773828) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:24.774131) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:24.774524) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:24.774799) ========= 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.ISeJD9gAU6/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.K41mFqqjYH/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2015-11-19 22:27:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 9.21M=0.001s 2015-11-19 22:27:25 (9.21 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.622777 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:25.427269) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:25.427566) ========= 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.ISeJD9gAU6/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.K41mFqqjYH/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.018671 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:25.476566) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 22:27:25.476932) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1b2e15608f34fef0f23369731c80138f55f00cf2 --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 1b2e15608f34fef0f23369731c80138f55f00cf2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ISeJD9gAU6/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.K41mFqqjYH/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2015-11-19 22:27:25,548 truncating revision to first 12 chars 2015-11-19 22:27:25,549 Setting DEBUG logging. 2015-11-19 22:27:25,549 attempt 1/10 2015-11-19 22:27:25,549 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1b2e15608f34?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-19 22:27:27,157 unpacking tar archive at: fx-team-1b2e15608f34/testing/mozharness/ program finished with exit code 0 elapsedTime=1.841640 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 22:27:27.335400) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:27.335700) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:27.348112) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:27.348394) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 22:27:27.348744) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2015-11-19 22:27:27.349019) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.ISeJD9gAU6/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.K41mFqqjYH/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:27:27 INFO - MultiFileLogger online at 20151119 22:27:27 in /builds/slave/test 22:27:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 22:27:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:27:27 INFO - {'append_to_log': False, 22:27:27 INFO - 'base_work_dir': '/builds/slave/test', 22:27:27 INFO - 'blob_upload_branch': 'Fx-Team', 22:27:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:27:27 INFO - 'branch': 'Fx-Team', 22:27:27 INFO - 'buildbot_json_path': 'buildprops.json', 22:27:27 INFO - 'config_files': ('talos/mac_config.py',), 22:27:27 INFO - 'default_actions': ('clobber', 22:27:27 INFO - 'read-buildbot-config', 22:27:27 INFO - 'download-and-extract', 22:27:27 INFO - 'populate-webroot', 22:27:27 INFO - 'create-virtualenv', 22:27:27 INFO - 'install', 22:27:27 INFO - 'run-tests'), 22:27:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:27:27 INFO - 'download_minidump_stackwalk': True, 22:27:27 INFO - 'download_symbols': 'ondemand', 22:27:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:27:27 INFO - 'tooltool.py': '/tools/tooltool.py', 22:27:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:27:27 INFO - '/tools/misc-python/virtualenv.py')}, 22:27:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:27:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:27:27 INFO - 'installer_path': 'installer.exe', 22:27:27 INFO - 'log_level': 'info', 22:27:27 INFO - 'log_name': 'talos', 22:27:27 INFO - 'log_to_console': True, 22:27:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:27:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:27:27 INFO - 'opt_config_files': (), 22:27:27 INFO - 'pip_index': False, 22:27:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:27:27 INFO - 'cmd': ('bash', 22:27:27 INFO - '-c', 22:27:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:27:27 INFO - 'enabled': True, 22:27:27 INFO - 'halt_on_failure': False, 22:27:27 INFO - 'name': 'check_screen_resolution'},), 22:27:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:27:27 INFO - 'cmd': ('bash', 22:27:27 INFO - '-c', 22:27:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:27:27 INFO - 'enabled': True, 22:27:27 INFO - 'halt_on_failure': False, 22:27:27 INFO - 'name': 'check_screen_resolution'},), 22:27:27 INFO - 'run_cmd_checks_enabled': True, 22:27:27 INFO - 'sps_profile': False, 22:27:27 INFO - 'sps_profile_interval': 0, 22:27:27 INFO - 'suite': 'dromaeojs-e10s', 22:27:27 INFO - 'system_bits': '64', 22:27:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:27:27 INFO - 'title': 't-yosemite-r7-0014', 22:27:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:27:27 INFO - 'use_talos_json': True, 22:27:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:27:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:27:27 INFO - 'work_dir': 'build'} 22:27:27 INFO - ##### 22:27:27 INFO - ##### Running clobber step. 22:27:27 INFO - ##### 22:27:27 INFO - Running pre-action listener: _resource_record_pre_action 22:27:27 INFO - Running main action method: clobber 22:27:27 INFO - rmtree: /builds/slave/test/build 22:27:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:27:29 INFO - Running post-action listener: _resource_record_post_action 22:27:29 INFO - ##### 22:27:29 INFO - ##### Running read-buildbot-config step. 22:27:29 INFO - ##### 22:27:29 INFO - Running pre-action listener: _resource_record_pre_action 22:27:29 INFO - Running main action method: read_buildbot_config 22:27:29 INFO - Using buildbot properties: 22:27:29 INFO - { 22:27:29 INFO - "properties": { 22:27:29 INFO - "buildnumber": 8, 22:27:29 INFO - "product": "firefox", 22:27:29 INFO - "script_repo_revision": "production", 22:27:29 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 22:27:29 INFO - "repository": "", 22:27:29 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 22:27:29 INFO - "buildid": "20151119211036", 22:27:29 INFO - "slavename": "t-yosemite-r7-0014", 22:27:29 INFO - "pgo_build": "False", 22:27:29 INFO - "basedir": "/builds/slave/test", 22:27:29 INFO - "project": "", 22:27:29 INFO - "platform": "yosemite_r7", 22:27:29 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 22:27:29 INFO - "slavebuilddir": "test", 22:27:29 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:27:29 INFO - "branch": "fx-team", 22:27:29 INFO - "repo_path": "integration/fx-team", 22:27:29 INFO - "stage_platform": "macosx64", 22:27:29 INFO - "builduid": "dcd87b7a8c9b4a67a2ac813c610d7193", 22:27:29 INFO - "revision": "1b2e15608f34fef0f23369731c80138f55f00cf2" 22:27:29 INFO - }, 22:27:29 INFO - "sourcestamp": { 22:27:29 INFO - "repository": "", 22:27:29 INFO - "hasPatch": false, 22:27:29 INFO - "project": "", 22:27:29 INFO - "branch": "fx-team-macosx64-talos", 22:27:29 INFO - "changes": [ 22:27:29 INFO - { 22:27:29 INFO - "category": null, 22:27:29 INFO - "files": [ 22:27:29 INFO - { 22:27:29 INFO - "url": null, 22:27:29 INFO - "name": "https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 22:27:29 INFO - } 22:27:29 INFO - ], 22:27:29 INFO - "repository": "", 22:27:29 INFO - "rev": "1b2e15608f34fef0f23369731c80138f55f00cf2", 22:27:29 INFO - "who": "philringnalda@gmail.com", 22:27:29 INFO - "when": 1448000787, 22:27:29 INFO - "number": 6687063, 22:27:29 INFO - "comments": "Merge m-c to f-t", 22:27:29 INFO - "project": "", 22:27:29 INFO - "at": "Thu 19 Nov 2015 22:26:27", 22:27:29 INFO - "branch": "fx-team-macosx64-talos", 22:27:29 INFO - "revlink": "", 22:27:29 INFO - "properties": [ 22:27:29 INFO - [ 22:27:29 INFO - "buildid", 22:27:29 INFO - "20151119211036", 22:27:29 INFO - "Change" 22:27:29 INFO - ], 22:27:29 INFO - [ 22:27:29 INFO - "builduid", 22:27:29 INFO - "dcd87b7a8c9b4a67a2ac813c610d7193", 22:27:29 INFO - "Change" 22:27:29 INFO - ], 22:27:29 INFO - [ 22:27:29 INFO - "pgo_build", 22:27:29 INFO - "False", 22:27:29 INFO - "Change" 22:27:29 INFO - ] 22:27:29 INFO - ], 22:27:29 INFO - "revision": "1b2e15608f34fef0f23369731c80138f55f00cf2" 22:27:29 INFO - } 22:27:29 INFO - ], 22:27:29 INFO - "revision": "1b2e15608f34fef0f23369731c80138f55f00cf2" 22:27:29 INFO - } 22:27:29 INFO - } 22:27:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 22:27:29 INFO - Running post-action listener: _resource_record_post_action 22:27:29 INFO - ##### 22:27:29 INFO - ##### Running download-and-extract step. 22:27:29 INFO - ##### 22:27:29 INFO - Running pre-action listener: _resource_record_pre_action 22:27:29 INFO - Running main action method: download_and_extract 22:27:29 INFO - mkdir: /builds/slave/test/build/tests 22:27:29 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')]} 22:27:29 INFO - https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:27:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/test_packages.json 22:27:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/test_packages.json 22:27:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 22:27:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 22:27:31 INFO - Downloaded 1149 bytes. 22:27:31 INFO - Reading from file /builds/slave/test/build/test_packages.json 22:27:31 INFO - Using the following test package requirements: 22:27:31 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 22:27:31 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 22:27:31 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 22:27:31 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 22:27:31 INFO - u'jsshell-mac.zip'], 22:27:31 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 22:27:31 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 22:27:31 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 22:27:31 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 22:27:31 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 22:27:31 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 22:27:31 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 22:27:31 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 22:27:31 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 22:27:31 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 22:27:31 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 22:27:31 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 22:27:31 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:27:31 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')]} 22:27:31 INFO - https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:27:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 22:27:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 22:27:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/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 22:27:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/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 22:27:33 INFO - Downloaded 17525138 bytes. 22:27:33 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 22:27:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 22:27:33 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 22:27:36 INFO - Return code: 0 22:27:36 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 22:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:36 INFO - https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:27:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 22:27:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 22:27:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/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 22:27:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/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 22:27:37 INFO - Downloaded 17525138 bytes. 22:27:37 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 22:27:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 22:27:37 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 22:27:40 INFO - Return code: 0 22:27:40 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')]} 22:27:40 INFO - https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:27:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 22:27:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 22:27:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/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 22:27:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/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 22:27:41 INFO - Downloaded 11130997 bytes. 22:27:41 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 22:27:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 22:27:41 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 22:27:42 INFO - Return code: 0 22:27:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:42 INFO - https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:27:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 22:27:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 22:27:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg 22:27:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/elKApx6KTOGP4UeVaVpX6Q/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 22:27:45 INFO - Downloaded 109413877 bytes. 22:27:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 22:27:45 INFO - mkdir: /builds/slave/test/properties 22:27:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:27:45 INFO - Writing to file /builds/slave/test/properties/build_url 22:27:45 INFO - Contents: 22:27:45 INFO - build_url:https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 22:27:45 INFO - Running post-action listener: _resource_record_post_action 22:27:45 INFO - Running post-action listener: set_extra_try_arguments 22:27:45 INFO - ##### 22:27:45 INFO - ##### Running populate-webroot step. 22:27:45 INFO - ##### 22:27:45 INFO - Running pre-action listener: _resource_record_pre_action 22:27:45 INFO - Running main action method: populate_webroot 22:27:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:27:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:27:45 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 22:27:45 INFO - '../fennec_ids.txt'], 22:27:45 INFO - 'tests': ['tcheck2']}, 22:27:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:27:45 INFO - '--tppagecycles', 22:27:45 INFO - '7'], 22:27:45 INFO - 'tests': ['tsvgm']}}, 22:27:45 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:27:45 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:27:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:27:45 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:27:45 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:27:45 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:27:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:27:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:45 INFO - 'talos_options': ['--e10s'], 22:27:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:27:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:45 INFO - 'tests': ['damp', 'tps']}, 22:27:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:45 INFO - 'talos_options': ['--e10s'], 22:27:45 INFO - 'tests': ['damp', 'tps']}, 22:27:45 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:27:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:27:45 INFO - 'other': {'tests': ['a11yr', 22:27:45 INFO - 'ts_paint', 22:27:45 INFO - 'tpaint', 22:27:45 INFO - 'sessionrestore', 22:27:45 INFO - 'sessionrestore_no_auto_restore']}, 22:27:45 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:27:45 INFO - 'tests': ['a11yr', 22:27:45 INFO - 'ts_paint', 22:27:45 INFO - 'tpaint', 22:27:45 INFO - 'sessionrestore', 22:27:45 INFO - 'sessionrestore_no_auto_restore']}, 22:27:45 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:27:45 INFO - 'tests': ['a11yr', 22:27:45 INFO - 'ts_paint', 22:27:45 INFO - 'tpaint', 22:27:45 INFO - 'sessionrestore', 22:27:45 INFO - 'sessionrestore_no_auto_restore']}, 22:27:45 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:27:45 INFO - 'tests': ['a11yr', 22:27:45 INFO - 'ts_paint', 22:27:45 INFO - 'tpaint', 22:27:45 INFO - 'sessionrestore', 22:27:45 INFO - 'sessionrestore_no_auto_restore']}, 22:27:45 INFO - 'other_l64': {'tests': ['a11yr', 22:27:45 INFO - 'ts_paint', 22:27:45 INFO - 'tpaint', 22:27:45 INFO - 'sessionrestore', 22:27:45 INFO - 'sessionrestore_no_auto_restore']}, 22:27:45 INFO - 'other_nol64': {'tests': ['a11yr', 22:27:45 INFO - 'ts_paint', 22:27:45 INFO - 'tpaint', 22:27:45 INFO - 'sessionrestore', 22:27:45 INFO - 'sessionrestore_no_auto_restore']}, 22:27:45 INFO - 'svgr': {'tests': ['tsvgx', 22:27:45 INFO - 'tsvgr_opacity', 22:27:45 INFO - 'tart', 22:27:45 INFO - 'tscrollx', 22:27:45 INFO - 'cart']}, 22:27:45 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:27:45 INFO - 'tests': ['tsvgx', 22:27:45 INFO - 'tsvgr_opacity', 22:27:45 INFO - 'tart', 22:27:45 INFO - 'tscrollx', 22:27:45 INFO - 'cart']}, 22:27:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:45 INFO - 'tests': ['tp5o']}, 22:27:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:45 INFO - 'talos_options': ['--e10s'], 22:27:45 INFO - 'tests': ['tp5o']}, 22:27:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:45 INFO - 'talos_options': ['--xperf_path', 22:27:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:27:45 INFO - 'tests': ['tp5n']}, 22:27:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:27:45 INFO - 'talos_options': ['--e10s', 22:27:45 INFO - '--xperf_path', 22:27:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:27:45 INFO - 'tests': ['tp5n']}}, 22:27:45 INFO - 'talos.zip': {'path': '', 22:27:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:27:45 INFO - Running post-action listener: _resource_record_post_action 22:27:45 INFO - ##### 22:27:45 INFO - ##### Running create-virtualenv step. 22:27:45 INFO - ##### 22:27:45 INFO - Running pre-action listener: _resource_record_pre_action 22:27:45 INFO - Running main action method: create_virtualenv 22:27:45 INFO - Creating virtualenv /builds/slave/test/build/venv 22:27:45 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 22:27:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:27:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:27:45 INFO - Using real prefix '/tools/python27' 22:27:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:27:46 INFO - Installing distribute.............................................................................................................................................................................................done. 22:27:49 INFO - Installing pip.................done. 22:27:49 INFO - Return code: 0 22:27:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:49 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 0x100da9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d86228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd918c88ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ccd9f0>, '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 0x100a2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc08c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/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.K41mFqqjYH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:27:49 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 22:27:49 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 22:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/Render', 22:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:49 INFO - 'HOME': '/Users/cltbld', 22:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:49 INFO - 'LOGNAME': 'cltbld', 22:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:49 INFO - 'MOZ_NO_REMOTE': '1', 22:27:49 INFO - 'NO_EM_RESTART': '1', 22:27:49 INFO - 'PAGER': '/bin/cat', 22:27:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:49 INFO - 'PWD': '/builds/slave/test', 22:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:49 INFO - 'SHELL': '/bin/bash', 22:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K41mFqqjYH/Listeners', 22:27:49 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 22:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:49 INFO - 'USER': 'cltbld', 22:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:49 INFO - 'XPC_FLAGS': '0x0', 22:27:49 INFO - 'XPC_SERVICE_NAME': '0', 22:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:49 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 22:27:49 INFO - Cleaning up... 22:27:49 INFO - Return code: 0 22:27:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:49 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 0x100da9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d86228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd918c88ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ccd9f0>, '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 0x100a2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc08c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/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.K41mFqqjYH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:27:49 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 22:27:49 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 22:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/Render', 22:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:49 INFO - 'HOME': '/Users/cltbld', 22:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:49 INFO - 'LOGNAME': 'cltbld', 22:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:49 INFO - 'MOZ_NO_REMOTE': '1', 22:27:49 INFO - 'NO_EM_RESTART': '1', 22:27:49 INFO - 'PAGER': '/bin/cat', 22:27:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:49 INFO - 'PWD': '/builds/slave/test', 22:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:49 INFO - 'SHELL': '/bin/bash', 22:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K41mFqqjYH/Listeners', 22:27:49 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 22:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:49 INFO - 'USER': 'cltbld', 22:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:49 INFO - 'XPC_FLAGS': '0x0', 22:27:49 INFO - 'XPC_SERVICE_NAME': '0', 22:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:49 INFO - Downloading/unpacking psutil>=0.7.1 22:27:49 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 22:27:49 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 22:27:49 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 22:27:49 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 22:27:49 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 22:27:49 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 22:27:51 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 22:27:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 22:27:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:27:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:27:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:27:52 INFO - Installing collected packages: psutil 22:27:52 INFO - Running setup.py install for psutil 22:27:52 INFO - building 'psutil._psutil_osx' extension 22:27:52 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 22:27:52 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 22:27:52 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 22:27:52 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 22:27:52 INFO - building 'psutil._psutil_posix' extension 22:27:52 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 22:27:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:27:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:27:52 INFO - ^ 22:27:52 INFO - 1 warning generated. 22:27:52 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 22:27:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:27:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:27:52 INFO - Successfully installed psutil 22:27:52 INFO - Cleaning up... 22:27:52 INFO - Return code: 0 22:27:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:27:52 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')]} 22:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:52 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')]} 22:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:52 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 0x100da9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d86228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd918c88ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ccd9f0>, '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 0x100a2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc08c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/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.K41mFqqjYH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:27:52 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 22:27:52 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 22:27:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/Render', 22:27:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:52 INFO - 'HOME': '/Users/cltbld', 22:27:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:52 INFO - 'LOGNAME': 'cltbld', 22:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:52 INFO - 'MOZ_NO_REMOTE': '1', 22:27:52 INFO - 'NO_EM_RESTART': '1', 22:27:52 INFO - 'PAGER': '/bin/cat', 22:27:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:52 INFO - 'PWD': '/builds/slave/test', 22:27:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:52 INFO - 'SHELL': '/bin/bash', 22:27:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K41mFqqjYH/Listeners', 22:27:52 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 22:27:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:52 INFO - 'USER': 'cltbld', 22:27:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:52 INFO - 'XPC_FLAGS': '0x0', 22:27:52 INFO - 'XPC_SERVICE_NAME': '0', 22:27:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:27:53 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 22:27:53 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 22:27:53 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 22:27:53 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 22:27:53 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 22:27:53 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 22:27:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:27:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 22:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:27:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:27:55 INFO - Installing collected packages: mozsystemmonitor 22:27:55 INFO - Running setup.py install for mozsystemmonitor 22:27:55 INFO - Successfully installed mozsystemmonitor 22:27:55 INFO - Cleaning up... 22:27:55 INFO - Return code: 0 22:27:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:27:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100da9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d86228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd918c88ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ccd9f0>, '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 0x100a2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc08c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/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.K41mFqqjYH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:27:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:27:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/Render', 22:27:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:55 INFO - 'HOME': '/Users/cltbld', 22:27:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:55 INFO - 'LOGNAME': 'cltbld', 22:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:55 INFO - 'MOZ_NO_REMOTE': '1', 22:27:55 INFO - 'NO_EM_RESTART': '1', 22:27:55 INFO - 'PAGER': '/bin/cat', 22:27:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:55 INFO - 'PWD': '/builds/slave/test', 22:27:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:55 INFO - 'SHELL': '/bin/bash', 22:27:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K41mFqqjYH/Listeners', 22:27:55 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 22:27:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:55 INFO - 'USER': 'cltbld', 22:27:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:55 INFO - 'XPC_FLAGS': '0x0', 22:27:55 INFO - 'XPC_SERVICE_NAME': '0', 22:27:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:27:55 INFO - Downloading/unpacking blobuploader==1.2.4 22:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:58 INFO - Downloading blobuploader-1.2.4.tar.gz 22:27:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 22:27:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:27:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 22:27:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:27:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:27:59 INFO - Downloading docopt-0.6.1.tar.gz 22:27:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 22:27:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:27:59 INFO - Installing collected packages: blobuploader, requests, docopt 22:27:59 INFO - Running setup.py install for blobuploader 22:27:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:27:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:27:59 INFO - Running setup.py install for requests 22:27:59 INFO - Running setup.py install for docopt 22:27:59 INFO - Successfully installed blobuploader requests docopt 22:27:59 INFO - Cleaning up... 22:28:00 INFO - Return code: 0 22:28:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28:00 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 0x100da9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d86228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd918c88ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ccd9f0>, '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 0x100a2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc08c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/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.K41mFqqjYH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:28:00 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 22:28:00 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 22:28:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/Render', 22:28:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:00 INFO - 'HOME': '/Users/cltbld', 22:28:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:00 INFO - 'LOGNAME': 'cltbld', 22:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:00 INFO - 'MOZ_NO_REMOTE': '1', 22:28:00 INFO - 'NO_EM_RESTART': '1', 22:28:00 INFO - 'PAGER': '/bin/cat', 22:28:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:00 INFO - 'PWD': '/builds/slave/test', 22:28:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:00 INFO - 'SHELL': '/bin/bash', 22:28:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K41mFqqjYH/Listeners', 22:28:00 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 22:28:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:00 INFO - 'USER': 'cltbld', 22:28:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:00 INFO - 'XPC_FLAGS': '0x0', 22:28:00 INFO - 'XPC_SERVICE_NAME': '0', 22:28:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:28:00 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ahBJcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:28:00 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-RPMD58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:28:00 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-yRD6zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:28:00 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-MZgHeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:28:00 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-N4nEgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:28:00 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-yM9Wd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:28:00 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-vUvBpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:28:01 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-hN9Jgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:28:01 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Zp6He9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:28:01 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-jhYP5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:28:01 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-m_1rkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:28:01 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-uZx6FZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:28:01 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-wiOwX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:28:01 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-w1gj0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:28:01 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-LxW4NU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:28:01 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-N72HXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:28:01 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-7UQSni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:28:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:28:02 INFO - Running setup.py install for manifestparser 22:28:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:28:02 INFO - Running setup.py install for mozcrash 22:28:02 INFO - Running setup.py install for mozdebug 22:28:02 INFO - Running setup.py install for mozdevice 22:28:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:28:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:28:02 INFO - Running setup.py install for mozfile 22:28:02 INFO - Running setup.py install for mozhttpd 22:28:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:28:02 INFO - Running setup.py install for mozinfo 22:28:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:28:03 INFO - Running setup.py install for mozInstall 22:28:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:28:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:28:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:28:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:28:03 INFO - Running setup.py install for mozleak 22:28:03 INFO - Running setup.py install for mozlog 22:28:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:28:03 INFO - Running setup.py install for moznetwork 22:28:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:28:03 INFO - Running setup.py install for mozprocess 22:28:03 INFO - Running setup.py install for mozprofile 22:28:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:28:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:28:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:28:04 INFO - Running setup.py install for mozrunner 22:28:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:28:04 INFO - Running setup.py install for mozscreenshot 22:28:04 INFO - Running setup.py install for moztest 22:28:04 INFO - Running setup.py install for mozversion 22:28:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:28:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:28:04 INFO - Cleaning up... 22:28:04 INFO - Return code: 0 22:28:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:28: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')]} 22:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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')]} 22:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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', '-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 0x100da9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d86228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd918c88ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ccd9f0>, '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 0x100a2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc08c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/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.K41mFqqjYH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:28:04 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 22:28:04 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 22:28:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/Render', 22:28:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:04 INFO - 'HOME': '/Users/cltbld', 22:28:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:04 INFO - 'LOGNAME': 'cltbld', 22:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:04 INFO - 'MOZ_NO_REMOTE': '1', 22:28:04 INFO - 'NO_EM_RESTART': '1', 22:28:04 INFO - 'PAGER': '/bin/cat', 22:28:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:04 INFO - 'PWD': '/builds/slave/test', 22:28:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:04 INFO - 'SHELL': '/bin/bash', 22:28:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K41mFqqjYH/Listeners', 22:28:04 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 22:28:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:04 INFO - 'USER': 'cltbld', 22:28:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:04 INFO - 'XPC_FLAGS': '0x0', 22:28:04 INFO - 'XPC_SERVICE_NAME': '0', 22:28:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:28:04 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-g8qD1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:28:05 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)) 22:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:28:05 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-NeRdt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:28:05 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)) 22:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:28:05 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-47OCYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:28:05 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)) 22:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:28:05 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-wgMjA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:28:05 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)) 22:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:28:05 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Zw9fVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:28:05 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)) 22:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:28:05 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-u2exYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:28:05 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)) 22:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:28:05 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-AsXhwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:28:05 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)) 22:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:28:05 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-PmPU65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:28:05 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)) 22:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:28:05 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-OHzZLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:28:05 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)) 22:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:28:05 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ktfDFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:28:06 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)) 22:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:28:06 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-KLdxgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:28:06 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)) 22:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:28:06 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-pcC5MA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:28:06 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)) 22:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:28:06 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-4QwV2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:28:06 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)) 22:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:28:06 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-tb7ATc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:28:06 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)) 22:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:28:06 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-zupMDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:28:06 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)) 22:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:28:06 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-4VN5Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:28:06 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)) 22:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:28:06 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-E2aEMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:28:06 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)) 22:28:06 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)) 22:28:06 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)) 22:28:06 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)) 22:28:06 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)) 22:28:06 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)) 22:28:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:28:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:08 INFO - Downloading blessings-1.5.1.tar.gz 22:28:08 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 22:28:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:28:09 INFO - Installing collected packages: blessings 22:28:09 INFO - Running setup.py install for blessings 22:28:09 INFO - Successfully installed blessings 22:28:09 INFO - Cleaning up... 22:28:09 INFO - Return code: 0 22:28:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:28:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:28:09 INFO - Reading from file tmpfile_stdout 22:28:09 INFO - Current package versions: 22:28:09 INFO - blessings == 1.5.1 22:28:09 INFO - blobuploader == 1.2.4 22:28:09 INFO - docopt == 0.6.1 22:28:09 INFO - manifestparser == 1.1 22:28:09 INFO - mozInstall == 1.12 22:28:09 INFO - mozcrash == 0.16 22:28:09 INFO - mozdebug == 0.1 22:28:09 INFO - mozdevice == 0.47 22:28:09 INFO - mozfile == 1.2 22:28:09 INFO - mozhttpd == 0.7 22:28:09 INFO - mozinfo == 0.9 22:28:09 INFO - mozleak == 0.1 22:28:09 INFO - mozlog == 3.0 22:28:09 INFO - moznetwork == 0.27 22:28:09 INFO - mozprocess == 0.22 22:28:09 INFO - mozprofile == 0.27 22:28:09 INFO - mozrunner == 6.11 22:28:09 INFO - mozscreenshot == 0.1 22:28:09 INFO - mozsystemmonitor == 0.0 22:28:09 INFO - moztest == 0.7 22:28:09 INFO - mozversion == 1.4 22:28:09 INFO - psutil == 3.1.1 22:28:09 INFO - requests == 1.2.3 22:28:09 INFO - wsgiref == 0.1.2 22:28:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:28: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')]} 22:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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')]} 22:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-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 0x100da9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d86228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd918c88ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ccd9f0>, '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 0x100a2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc08c8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/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.K41mFqqjYH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:28:09 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 22:28:09 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 22:28:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/Render', 22:28:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:09 INFO - 'HOME': '/Users/cltbld', 22:28:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:09 INFO - 'LOGNAME': 'cltbld', 22:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:09 INFO - 'MOZ_NO_REMOTE': '1', 22:28:09 INFO - 'NO_EM_RESTART': '1', 22:28:09 INFO - 'PAGER': '/bin/cat', 22:28:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:09 INFO - 'PWD': '/builds/slave/test', 22:28:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:09 INFO - 'SHELL': '/bin/bash', 22:28:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K41mFqqjYH/Listeners', 22:28:09 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 22:28:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:09 INFO - 'USER': 'cltbld', 22:28:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:09 INFO - 'XPC_FLAGS': '0x0', 22:28:09 INFO - 'XPC_SERVICE_NAME': '0', 22:28:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:09 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)) 22:28:09 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)) 22:28:09 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)) 22:28:09 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)) 22:28:09 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)) 22:28:09 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)) 22:28:09 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)) 22:28:09 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)) 22:28:09 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)) 22:28:09 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)) 22:28:09 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)) 22:28:09 INFO - Cleaning up... 22:28:09 INFO - Return code: 0 22:28:09 INFO - Running post-action listener: _resource_record_post_action 22:28:09 INFO - Running post-action listener: _start_resource_monitoring 22:28:09 INFO - Starting resource monitoring. 22:28:09 INFO - ##### 22:28:09 INFO - ##### Running install step. 22:28:09 INFO - ##### 22:28:09 INFO - Running pre-action listener: _resource_record_pre_action 22:28:09 INFO - Running main action method: install 22:28:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:28:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:28:10 INFO - Reading from file tmpfile_stdout 22:28:10 INFO - Detecting whether we're running mozinstall >=1.0... 22:28:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:28:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:28:10 INFO - Reading from file tmpfile_stdout 22:28:10 INFO - Output received: 22:28:10 INFO - Usage: mozinstall [options] installer 22:28:10 INFO - Options: 22:28:10 INFO - -h, --help show this help message and exit 22:28:10 INFO - -d DEST, --destination=DEST 22:28:10 INFO - Directory to install application into. [default: 22:28:10 INFO - "/builds/slave/test"] 22:28:10 INFO - --app=APP Application being installed. [default: firefox] 22:28:10 INFO - mkdir: /builds/slave/test/build/application 22:28:10 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'] 22:28:10 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 22:28:40 INFO - Reading from file tmpfile_stdout 22:28:40 INFO - Output received: 22:28:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:28:40 INFO - Running post-action listener: _resource_record_post_action 22:28:40 INFO - ##### 22:28:40 INFO - ##### Running run-tests step. 22:28:40 INFO - ##### 22:28:40 INFO - Running pre-action listener: _resource_record_pre_action 22:28:40 INFO - Running main action method: run_tests 22:28:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:28:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:28:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:28:40 INFO - 2015-11-19 22:28:40.661 screenresolution[2509:27820] starting screenresolution argv=screenresolution get 22:28:40 INFO - 2015-11-19 22:28:40.664 screenresolution[2509:27820] Display 0: 1600x1200x32@60 22:28:40 INFO - 2015-11-19 22:28:40.670 screenresolution[2510:27824] starting screenresolution argv=screenresolution list 22:28:40 INFO - Available Modes on Display 0 22:28:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:28:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:28:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:28:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:28:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:28:40 INFO - Intel Iris: 22:28:40 INFO - Chipset Model: Intel Iris 22:28:40 INFO - Type: GPU 22:28:40 INFO - Bus: Built-In 22:28:40 INFO - VRAM (Dynamic, Max): 1536 MB 22:28:40 INFO - Vendor: Intel (0x8086) 22:28:40 INFO - Device ID: 0x0a2e 22:28:40 INFO - Revision ID: 0x0009 22:28:40 INFO - Displays: 22:28:40 INFO - Display: 22:28:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:28:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:28:40 INFO - Display Serial Number: 1600x1200 60 22:28:40 INFO - Main Display: Yes 22:28:40 INFO - Mirror: Off 22:28:40 INFO - Online: Yes 22:28:40 INFO - Rotation: Supported 22:28:40 INFO - Return code: 0 22:28:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:28:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:28:40 INFO - Python 2.7.3 22:28:40 INFO - Return code: 0 22:28:40 INFO - grabbing minidump binary from tooltool 22:28:40 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')]} 22:28:40 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 0x100ccd9f0>, '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 0x100a2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc08c8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:28:40 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 22:28:40 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 22:28:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:28:40 INFO - Return code: 0 22:28:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:28:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:28:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:28:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:28:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:28:41 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--webServer', 'localhost'] in /builds/slave/test/build 22:28:41 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0014 --webServer localhost 22:28:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ISeJD9gAU6/Render', 22:28:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:41 INFO - 'HOME': '/Users/cltbld', 22:28:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:41 INFO - 'LOGNAME': 'cltbld', 22:28:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:28:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:28:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:41 INFO - 'MOZ_NO_REMOTE': '1', 22:28:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:28:41 INFO - 'NO_EM_RESTART': '1', 22:28:41 INFO - 'PAGER': '/bin/cat', 22:28:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:41 INFO - 'PWD': '/builds/slave/test', 22:28:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:28:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:41 INFO - 'SHELL': '/bin/bash', 22:28:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K41mFqqjYH/Listeners', 22:28:41 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 22:28:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:41 INFO - 'USER': 'cltbld', 22:28:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:41 INFO - 'XPC_FLAGS': '0x0', 22:28:41 INFO - 'XPC_SERVICE_NAME': '0', 22:28:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:41 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--webServer', 'localhost'] with output_timeout 3600 22:28:41 INFO - mozversion INFO | application_buildid: 20151119211036 22:28:41 INFO - mozversion INFO | application_changeset: 1b2e15608f34fef0f23369731c80138f55f00cf2 22:28:41 INFO - mozversion INFO | application_display_name: Nightly 22:28:41 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:28:41 INFO - mozversion INFO | application_name: Firefox 22:28:41 INFO - mozversion INFO | application_remotingname: firefox 22:28:41 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 22:28:41 INFO - mozversion INFO | application_vendor: Mozilla 22:28:41 INFO - mozversion INFO | application_version: 45.0a1 22:28:41 INFO - mozversion INFO | platform_buildid: 20151119211036 22:28:41 INFO - mozversion INFO | platform_changeset: 1b2e15608f34fef0f23369731c80138f55f00cf2 22:28:41 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 22:28:41 INFO - mozversion INFO | platform_version: 45.0a1 22:28:41 INFO - 2015-11-19 22:28:41,220 DEBUG : using testdate: 1448000921 22:28:41 INFO - 2015-11-19 22:28:41,220 DEBUG : actual date: 1448000921 22:28:41 INFO - 2015-11-19 22:28:41,229 INFO : starting webserver on 'localhost:57566' 22:28:41 INFO - 2015-11-19 22:28:41,230 INFO : Starting test suite t-yosemite-r7-0014.e 22:28:41 INFO - 2015-11-19 22:28:41,230 INFO : Starting test dromaeo_css 22:28:41 INFO - 2015-11-19 22:28:41,230 DEBUG : operating with platform_type : mac_ 22:28:41 INFO - 2015-11-19 22:28:41,231 INFO : Initialising browser for dromaeo_css test... 22:28:41 INFO - 2015-11-19 22:28:41,243 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpjygdxu/profile http://localhost:57566/getInfo.html 22:28:43 INFO - 2015-11-19 22:28:43,846 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:28:43 INFO - 2015-11-19 22:28:43,846 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:28:43 INFO - 2015-11-19 22:28:43,846 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 22:28:43 INFO - 2015-11-19 22:28:43,846 DEBUG : BROWSER_OUTPUT: __metrics 22:28:44 INFO - 2015-11-19 22:28:44,440 INFO : Browser initialized. 22:28:44 INFO - 2015-11-19 22:28:44,440 INFO : Running cycle 1/1 for dromaeo_css test... 22:28:44 INFO - 2015-11-19 22:28:44,440 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpjygdxu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:28:56 INFO - 2015-11-19 22:28:56,294 DEBUG : BROWSER_OUTPUT: RSS: Main: 236843008 22:28:56 INFO - 2015-11-19 22:28:56,294 DEBUG : BROWSER_OUTPUT: 22:29:46 INFO - 2015-11-19 22:29:46,065 DEBUG : BROWSER_OUTPUT: 1448000986064 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] 22:31:56 INFO - 2015-11-19 22:31:56,626 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57566/tests/dromaeo/cssquery-ext.html) 22:31:57 INFO - 2015-11-19 22:31:57,812 DEBUG : BROWSER_OUTPUT: RSS: Main: 253218816 22:31:57 INFO - 2015-11-19 22:31:57,813 DEBUG : BROWSER_OUTPUT: 22:34:59 INFO - 2015-11-19 22:34:59,795 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/dromaeo/cssquery-ext.html (next: http://localhost:57566/tests/dromaeo/cssquery-jquery.html) 22:35:00 INFO - 2015-11-19 22:35:00,381 DEBUG : BROWSER_OUTPUT: RSS: Main: 262483968 22:35:00 INFO - 2015-11-19 22:35:00,381 DEBUG : BROWSER_OUTPUT: 22:38:01 INFO - 2015-11-19 22:38:01,937 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57566/tests/dromaeo/cssquery-mootools.html) 22:38:02 INFO - 2015-11-19 22:38:02,531 DEBUG : BROWSER_OUTPUT: RSS: Main: 263409664 22:38:02 INFO - 2015-11-19 22:38:02,531 DEBUG : BROWSER_OUTPUT: 22:41:04 INFO - 2015-11-19 22:41:04,121 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57566/tests/dromaeo/cssquery-prototype.html) 22:41:04 INFO - 2015-11-19 22:41:04,706 DEBUG : BROWSER_OUTPUT: RSS: Main: 266018816 22:41:04 INFO - 2015-11-19 22:41:04,706 DEBUG : BROWSER_OUTPUT: 22:44:07 INFO - 2015-11-19 22:44:07,228 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57566/tests/dromaeo/cssquery-yui.html) 22:44:07 INFO - 2015-11-19 22:44:07,814 DEBUG : BROWSER_OUTPUT: RSS: Main: 269725696 22:44:07 INFO - 2015-11-19 22:44:07,814 DEBUG : BROWSER_OUTPUT: 22:47:09 INFO - 2015-11-19 22:47:09,978 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/dromaeo/cssquery-yui.html (next: http://localhost:57566/tests/dromaeo/cssquery-dojo.html) 22:47:10 INFO - 2015-11-19 22:47:10,235 DEBUG : BROWSER_OUTPUT: RSS: Main: 271671296 22:47:10 INFO - 2015-11-19 22:47:10,235 DEBUG : BROWSER_OUTPUT: 22:47:10 INFO - 2015-11-19 22:47:10,235 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:47:10 INFO - 2015-11-19 22:47:10,235 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:47:10 INFO - 2015-11-19 22:47:10,235 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:47:10 INFO - 2015-11-19 22:47:10,235 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:47:10 INFO - 2015-11-19 22:47:10,235 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3393.268418922767;3427.9828600856995;3481.6170221275656;3500.0549851540068;3539.9469007964867;5842.88314233715;5865;5913.881722365551;5917.011908985962;5947.375525569812;5807.796727114552;5930.436608522195;5967.8209653710455;6014.097885317205;6053.727582258798;5387.622866399353;5433.022056029913;5433.293671822657;5489.862753431176;5551.2228288039705;844.0276358539973;5225.921611175835;5397.379301380332;5414.377346605152;5521.641093328941;4995.200767877122;5228.738563071831;5276.3932147802725;5288.471152884681;5317.202419637066;5354.839354819323;5355.812546560852;5368.275282836845;5388.218708287315;5418.756155972991;5197.038547868657;5217.243499692523;5320.813771518017;5365.704886231256;5380.838574842722;5496.670199787985;5814.273215848019;5818.738156782955;5881.2354393928945;5896.469317761422;6254.906176407358;6281.905771413432;6288.842778930518;6293.653849038301;6299.055141728777;6125.938740612626;6180.949238629444;6268.78059267928;6385.361463853624;6394.58435201715;6076.422739839708;6079.179310793074;6090.5736598438125;6098.02781050674;6108.969455152695;6370.74517019314;6410.583312084699;6446.677666116675;6450.774222902176;6470.838229044311;5945.435183657545;6073.726682299307;6127.097902969301;6263.686815659199;6267.1539342189135;6248.4376406123665;6351.872962540723;6367.076773867763;6371.203599550056;6384.776532821329;4996.1007018736245;6132.110843927606;6243.531735119885;6271.529635277372;6291.02489114188;6104.4200800923845;6209.223847019122;6227.595206311576;6231.657258850807;6232.626042437469;5021.397432308147;5034.471380505068;5049.242613607915;5054.317667114892;5080.110980578384;4407.36050063328;4559.726416414959;4562.817487300471;4580.335851301609;4581.175388430114;4989;4990.3762029746285;5381.354237491526;5932.555058370639;5962.552808539377;6258.123862659231;6312.179416675803;6338.397852204033;6342.334054924258;6393.2328120625825;5026.798928042837;5665.5467562594895;5790.10253410722;5989.64062156272;6054.273487181478;5555.416681248411;5559.999200143932;5561.721913904288;5566.721663916788;5573.804916827891;6490.610563366213;6504.512161587805;6513.1532900722605;6520.804375868636;6556.311587283362;6094.350380525191;6186.566940314135;6224.968875155595;6340.556161068681;6360.745570177326;5441.863953401196;6364.840878978062;6369.927806821011;6403.327650596807;6423.132877061724;6241.96879015602;6274.184356033869;6277.560570760185;6287.119803227643;6290;6134;6159.4456498915315;6226.097215903578;6269.404406581368;6293;6226.875462490815;6248.718807653576;6262.342454042442;6263.373662633882;6276.6547839869245;4918.48355922623;6109.419605137505;6229.781957631461;6240;6274.74901003973;5953.690188158598;6014.278286605635;6114.449699527064;6124.908126378019;6130.295016073209;5006.899862002812;6217.595856269328;6237.126802247598;6242.063690446469;6248.843778905563;5956.731947062479;6122.245686735438;6205.193324867739;6207.131001659862;6223.844403889758 22:47:10 INFO - 2015-11-19 22:47:10,236 DEBUG : BROWSER_OUTPUT: |1;ext.html;548.8051361312566;560.9775608975641;571.9285089363831;613.9293981192163;636.1634450697333;42003.57996420043;42164.52424165155;42177.57822421775;42282.36576451355;42315.942101447516;45264.54735452645;45387.092258154815;45708;45880.08239835201;45957.54042459575;9265.95367023164;9918.65284715037;10090.545925433336;10093.343932644355;10105.090541851232;9800.41197528147;9886.209103271722;9887.209023278156;9887.505624718771;9901.306908516406;9890.357126786736;9982.201423886108;10001.399916005063;10006.84989725151;10029.799404011916;9902.405855648649;9923.603055877793;9939;9956.850647240297;9967.252456065751;9838.852417213748;9910.603575856958;9921.851172232422;9942.154916832078;9964.70105896817;42348.78825605883;42389.36415953763;42464;42640;42730;2966.851657417142;3314.386838434873;3386.847591858372;3467.289205712823;3809.847606095753;24892.879820408125;26855.865720671467;29842.253943651365;29943.70056299431;30076;2475.900963961439;2890.262982939358;2895.623568936046;2921.5471601901527;2998.34036511967;506382;506408;506717;506753;507458.99999999633;57967.4203257962;58454;68683.65658171677;73801.6309918447;74403.62798186083;71307.93038104329;71378.2862171382;71534;71692;71863;16454.588635284214;16677;16696.916515417346;16747.497575072768;16776.664466710597;12285.1400401971;12438.067144964163;12448.879600835968;12492.562760303346;12693.682657933441;19547.804521954884;21521.89239053826;21574.460638484165;21609.243676471564;21649.67525487119;7733.381329493627;8902.109789021175;9006.189442950166;9116.361854670242;9143.954280228556;934.1736904351976;953.6566835939057;994.3884511025667;1067.3169171730099;1116.9497372618252;29559.556606650916;29795.255118621775;29943;30125;30166.547501787492;1058.5977328615231;1069.2942657845786;1077.1436707817304;1147.1740346950182;1248.7627350803318;14128.94032947533;15004.099754014795;15300.387984480718;15310.311036003406;15381.154036528098;738934;740647;742338;753254;756126;45580;57704.711476442346;57783;57843.71078144582;57960;1434.863687949643;1438.0437009388847;1443;1451.165579791624;1603.214424931775;29751.404971900873;30103.946361877228;30159.69840301569;30326.393472130876;30417.087487375415;50030;50120.74939625279;50254;50316.74841625769;50341;455348;458075;459190;459520;459871;31012.379752404373;32668.34663306673;33423;33425.33149336951;33499.66500335063;43933.56066439295;44819.55180448284;44849;45092.87267818331;45096.54903450923;36491.817540912125;36910.63089369072;37368.43947340738;37733.24533509369;37782.055448612904;36970;36973;37423.06442338855;37703;37929;35669.46495802513;36077.45883811797;36534;36750;36784.26431471409;32836.83581582077;33537.66462335443;33562.664373355954;33686.663133368354;33873.830630846685;20730.75615463077;23820.642690360004;24087.879560602785;24766.752332476444;25562.872185638953 22:47:10 INFO - 2015-11-19 22:47:10,236 DEBUG : BROWSER_OUTPUT: |2;jquery.html;310.76847748427423;365.8756022952197;370.5090754749957;370.51462584014934;372.61248301766165;15539;19293;20252.29116980906;20699.379018629446;21226.57546849062;11792.174547781658;11895.4052297385;11957.940210298959;12105.576304829332;19721.40835774929;5197.8960420791755;5240.082985477536;5330.840074797762;5332.760025798849;5345.572354211673;3684.3736564783917;3912.68698504119;4061.8984525386804;5753.453421924932;5900.203472531218;5877.353491115974;5886.20536227608;5896.351401345849;5912.142739302799;5960.1953736245705;6124.571280010401;6427.775027874002;6467.385598368148;6469.773557925473;6489.286178520382;4819.927701084486;5553.805616803393;5620.747066382022;5639.971800141014;5686.687232202268;24769.87615061931;25020.624690629655;25232.747672523223;25585.48829023409;25672.614910776352;2008.5982803439224;2043.427840204745;2089.039042040663;2097.1820989813987;2112.2290364017176;10299;11818.763624727544;12874;13635.522756703567;14260.500882469167;1720.5698575356162;1723.5087999919963;1727.9827201727946;1731.3161301285922;1738.5666550296694;87887.56056219678;94245.52877235638;96228;97457;97826.51086744592;8978.910210897939;9898.703038908845;10899.455027248765;11049.060829829394;11051.447427628586;11006.779864402668;12093.637190884288;13045.282509462071;13083;13207.40566674502;7179.748708795167;8073.6770529178175;8318.37612179089;8335.249827515552;8393.24460798531;6143.416375444325;8507.021692505441;8598.914010859811;8665.480071195623;8681.348898832486;4396.758178300161;4423.093265880487;4434.800433980478;4437.689361744711;4466.240739074366;1832.5418645338666;1906.4280715785487;1910.4077735901915;1915.6839121545067;1918.1176658737138;901.1754244866005;940.1350757303297;976.702105857706;1014.573878970834;1017.1964148322742;17295.39466118705;20011.99940002994;20062.899685501478;20225.88757618345;20284.898575507323;959.6737109382843;964.5129209749172;965;1003.7440452684665;1040.885502594714;11329.320240785579;11338.979491845774;11444.771104578029;11450.083993280517;11499.425028748528;681912;721777;731034;741500;759032;122161;128639;129091.70908290797;130685.03972440421;131892;1180.6694125644663;1185.5554167187304;1246.1712960881093;1251.2429979862052;1290.1613950931783;18320.633587328444;18703.719444208076;19159.616807664046;19271.421857344303;19341.613167736286;49024.50975490342;49170.75414622904;49262.50737492579;52251.95496090133;52584.737076315905;65643.34356656372;66083.67832643416;66585;67100;76559.23440765744;9455.338126331091;9799.069088436587;9842.950785246028;9880.802383952137;9917.950410247902;11780.410979450822;13223.933880330536;19869.900650496656;19949.301774437827;20023.199072036954;15622.687546249239;15918.363265469252;16229.94505357148;16356.34574617002;16382.918085409498;12849.357532123544;13953.581392558239;15476;15596.610084747519;16138.919305403399;5433.809816656398;15213.163276019483;16277.348906044099;16690.582735431224;16836.663266734842;9827.52587111939;10041.548130334299;10115.089641932262;10118.898811011795;10146.086852183338;15331.463398780988;15821.525354239391;15942.920285398499;16009.679806403574;16077.115758633387 22:47:10 INFO - 2015-11-19 22:47:10,237 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1536.8770498360132;1695.8982461052333;1847.1503108570066;1872.6348362069398;1911.6845720456126;7156.749513767019;7248.13022437307;8177.509349439038;8296.08743038266;8345.874811877819;8148.185181481849;8290.544020078893;8393.370497212718;8413.66345346187;8481.406301558893;2359.2922123362964;2391.8923648435775;2393.916212932539;2396.2930935374106;2403.807695384365;2391.820613453989;2405.5910495215767;2441.7802397784194;2442.3894026493376;2447.706275246973;2375;2386.305722936717;2401.3756423329974;2405.302462285935;2426.708794944609;2358.929232123039;2363.9054437822465;2395.5448464791634;2396.5326761281644;2404.8557086574774;2402.651615515758;2406.1217655555597;2428.514297140579;2430.9878450607634;2435.561598912195;7110.395616372614;8390.83218335636;8414.495130292202;8481.575921203916;8519.275861551776;4578.839740609096;5077.543021128079;5147.202183661539;5149.0474262261605;5189.221616757479;3152.8896488622786;3445.500402441632;3455.809930453824;3531.170175008871;3542.096765324826;828.9637952559301;967.2552134856121;1051.484772461496;1053.3785066810542;1073.1039581949083;11124.443777811159;11136.554537818478;11136.99767020964;11170.441477926071;11328.660140195725;4512.751798651106;4687.460941991646;4739.573438390561;4757.857264282077;4835.854924352275;9562;9657.420554766737;9662.855057174149;9666.275029372826;9669.274804389699;4479.372887795711;5121.07820592297;5150.201718733602;5153.201253805666;5159.587233021424;4728.858134255978;4731.905361892812;4733.95266047342;4736.786844592001;4779.139754844092;3859.3246181918053;3874.903127421837;3918.8432462701744;3922.764634121962;3928.8821335359985;1238.38699843578;1296.6628676544167;1296.8897643700202;1301.3363184775833;1310.1876836361516;1254.404158024931;1335.3923964596086;1352.8444228913604;1356.8439629442742;1396.8812650924785;8027.237412445808;8197.016358037074;8207.7948051298;8238.217369349903;8241.546714930617;1180.6576092933126;1204.084895479442;1206.5535751771724;1217.5799349224503;1221.2367270455966;5350.705711185922;5793.015187418149;6318.8420289492215;6351.714172862232;6365.554411191172;9260.749798777213;10258.025487578669;10286;10335.22485813567;10398.428086455318;9896.406215627085;10043.69868903934;10124.392536447836;10157;10201.744956376002;7956.482828616237;7991.640376183086;8022.959885200653;8026.197380262044;8109.675612975533;7799;7836.37309015266;7865.960670196612;7896.763097107096;7980.680772769024;9014.23379012765;9093.590788414405;9218.815623687355;9284.442933424016;9364.01677823846;9272.443653380818;9387.530623468936;9492.572834222614;9507.619695212114;9531.714048578553;7389.482736208413;7403.703851845866;7478.364339031244;7496.475246732676;7505.66224519887;8047.356211502949;8082.434229604107;8110.432269741297;8128.63421146038;8162.632681529227;7522.172561018393;7530.397568194641;7622.618869056414;7702.268284512962;7729.227077292226;6243;7478.06524184477;7480.289372509603;7490;7689.384849211938;7577.621118943921;7606.391488681003;7606.467547271638;7615.314621684075;7617.961910190414;6887.793366199022;6893.827654308682;6904.896426553505;6906.066301435606;6914.896276555755;6664.666766661744;6839.350261725128;6839.62382068991;6859.965700171467;6872.759453419107 22:47:10 INFO - 2015-11-19 22:47:10,241 DEBUG : BROWSER_OUTPUT: |4;prototype.html;326.7516687317638;329.4810673189727;329.9406106900757;333.1421589407276;337.7416276548439;6939.397725852805;7058.364747172754;7126.07361043065;7150.427965762733;7157.42740580753;5673.8297851064435;6832.897506537406;6838.965805170979;6907.102076730018;7035.331643493872;4504.8287445264305;5185.585153187736;5195.038917800201;5218.530332270095;5223.71269580173;3995.6803455723616;5044.722540260284;5194.740262986836;5198.168293073109;5201.193814958669;5128.358955130609;5147.047796157704;5153.742312884359;5171.17261238202;5187.896242075156;5137.6403651744395;5137.717425541594;5144.151215049513;5156.639035267533;5164.664296820694;3910.902227444308;4169.958300416987;4392.1874453226255;4403.911921761547;4416.911661766779;5305.310309659758;6796.150481189851;6851.6981773462885;6855.897161542581;6857.074294970215;5988.041913293896;5992.8501787455225;6002.909956350658;6009.09863520471;6016.977113890649;7540.73607423743;7548.773536793905;7550.471466997313;7576.128745194318;7584.582847943361;4640.86077417678;4924.064427758714;4927.827526036572;4930.4330002049865;4936.160852655057;7303.634818259119;7486.288802563748;7546.867969804518;8009.559474228857;8036.316913062396;4572.719638501225;4854.393200849894;4854.611631069577;4875.0493653737185;4878.341423907798;4474.351219073216;4966.652334336562;4968.677035992721;4987.678265259709;5002.224655178453;4950.356453661001;5764.538836893039;5940.851478713067;5967.671778052249;5985.940140598538;6934.583924964519;7667.539947603162;7667.616619169019;7687.500312479783;7690.461667683208;1429.756941319978;1596.3774128090054;1679.7480377943407;1725.6548690261998;1725.6807490614276;1077.3643550305358;1080.708208783624;1085.234909388879;1092.819684752023;1098.8351747237978;5754.827355179351;6811.9659401702675;7024.929750702529;7069.6111713856235;7075.2217256101785;7812.648430820626;7817.530948143016;7820.452568320276;7840.13758486566;7853.921460785319;6840.657967101625;6996.475264355194;7030.54301470413;7031.402330801837;7166.641667916583;4183.079722461053;4222.21888950539;4230.175115852379;4232.449781528381;4245.91508169841;8178.304844088319;8191.017077950565;8204.753857384288;8205.261526462647;8209.220124088202;7946.7615971520945;8221.712240071569;8238.588070596566;8266.173382661806;8294.668213271521;7480.1023877134;8182.509049457051;8190.099089100194;8217.178282171855;8241.969753780777;5885.969955257899;6263.65549894742;7447.88828167567;7795.33739632115;7968.521888686697;7391.4086873048955;7830.491018083806;7869.881951770613;7883.172266911892;7917.524948503108;8172.387070969582;8181.877271841046;8188.549629770419;8188.713395031145;8208.630611622611;4230.513490948457;4747.976260118816;4762.166620841408;4857.805687772449;4887.364642596582;7602.7339043133225;7776.92223077762;7900.881486777818;7901.288884000238;7905.209479052049;6583.210014798255;7255.600941948032;7434.479586428898;7539.660715267716;7589.810254743676;7586.279303466162;7587.772366829004;7609.961950190213;7619.580923049286;7631.3513351365755;6250.718717657807;6813.727450901908;7046.084009078787;7422.554646721214;7445.553266804009;7579.090509138939;7586.24137586237;7588.392928565591;7605.809854753676;7622;5998.700064996646;7206.495545311987;7409.222031686595;7410.11078670563;7455 22:47:10 INFO - 2015-11-19 22:47:10,242 DEBUG : BROWSER_OUTPUT: |5;yui.html;748.9925100748993;764.5374548398219;781.5115552779513;815.6818840652143;818.1164342510086;11932.64202073937;13978.580642580713;14122.858771412295;14133.71732565348;14175.716485670293;13653.863461365409;14120.082194657365;14121.223332716696;14121.364538595762;14163.858361416384;857.3055824781947;922.3128769067038;929.2055292724724;944.0700909604069;976.9706908792748;975.3855071305068;978.3005151316808;981.5141504955058;982.3909176310661;983.0759086458742;1004.0862814838498;1006.572206812102;1008.793197394536;1013.2350075692841;1019.1235537437798;1000.6647772996055;1001.184035011467;1002.6039714312842;1006.5017816180964;1006.8439391894268;748.0686545251197;749.9400047996147;750.8685979953486;753.9623018849036;755.1052003376017;13858.514951976733;13910.026298159133;13954.023218374718;14061.929690351584;14087.577372678836;12192.146549741521;12252.877471225263;12270.447829847679;12427.875721242763;12480.592678385108;10035.74910627233;10068.244881633833;10349.948250258776;10350.378977261389;10356;3427.2288735034667;3474.4788281757687;3489.8953031409096;3542.71658267338;3649.580298265707;79150.20499440924;79670.20329796744;80367.5981620094;81048.59475702643;81732.59133704293;9403.57683904226;9462.29032822541;9773.07155820196;9809.558569864374;10092.848607270897;20596.897015514827;20945.371638850553;21005.789942100688;21109.68335474969;21329.146834126772;7426.220246874109;7431.777046688608;7607.505512141693;7640.656170472342;7799.883001754978;4086.7645457335625;4151.7924103794685;4171.561985991488;4225.556316586775;4368.257396242647;3404.1149301181376;3445.3109378124477;3451.2579795344122;3461.7403694723;3484.4250698634946;546.9617126801086;554.5092593055175;609.6646844235653;623.382850977529;636.0491065856575;515.8007632255022;545.929029226198;552.8562573730778;578.9247397838254;594.7323704333068;8820.779480513038;9333.486658233862;9379.765505862408;9385.624575016924;9611.086946740048;508.8905885234692;513.622487471708;525.6057956532601;573.6701396696916;617.7652492052991;3953.367461206193;4189.664826813909;4219.852305169305;4283.164782867373;4289.013526888833;99190;101647;102540;103251.48374258081;103541;84792;85349.57325213334;85655;85849;87288;74441.25558744451;75367.62316188494;75384.62307688536;75606.62196689092;75862;6953.860922781617;8631.741047768577;8660.307175426076;9046.185843274137;9175.632974681204;51978.22032669589;52248.17131400383;52339.47660523498;54205.72897135489;55625.165622516506;78619.03452413507;78658.4268314642;79061;79631.60184199041;80149.59925200336;11123.72190695239;11322.660320190407;11520.827187592025;11821.881781182077;11982.820257695967;12258.754824903273;12421.582175914577;12544.30651861987;12555.372231388577;12673.746525069631;11512.36681982499;11558.12535021978;11586.76826463483;11627.18609697347;12558.372081396077;11495.585347451015;11600.30398176112;11787.351695656822;11940.641780746591;12391.690207744878;10449.791004179722;11146.386948717574;11312.547498099984;11422.94288528552;11825.645230643095;9683.757906052124;9684.515774211402;10109;10138;10369.46905964665;7745.757716535058;9058.954705226695;9096.1813436791;9625.951870240884;10017.849732253877 22:47:10 INFO - 2015-11-19 22:47:10,242 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:47:10 INFO - 2015-11-19 22:47:10,242 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:47:10 INFO - 2015-11-19 22:47:10,242 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,316 22:47:10 INFO - 2015-11-19 22:47:10,242 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:47:10 INFO - 2015-11-19 22:47:10,243 DEBUG : BROWSER_OUTPUT: __startTimestamp1448002030228__endTimestamp 22:47:10 INFO - 2015-11-19 22:47:10,243 DEBUG : BROWSER_OUTPUT: 22:47:10 INFO - 2015-11-19 22:47:10,243 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:47:10 INFO - 2015-11-19 22:47:10,243 DEBUG : BROWSER_OUTPUT: Number of tests: 6 22:47:10 INFO - 2015-11-19 22:47:10,243 DEBUG : BROWSER_OUTPUT: 22:47:10 INFO - 2015-11-19 22:47:10,243 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5793.20 Median:6098.03 stddev:754.02 (12.4%) stddev-sans-first:732.45 22:47:10 INFO - 2015-11-19 22:47:10,243 DEBUG : BROWSER_OUTPUT: Values: 3393.3 3428.0 3481.6 3500.1 3539.9 5842.9 5865.0 5913.9 5917.0 5947.4 5807.8 5930.4 5967.8 6014.1 6053.7 5387.6 5433.0 5433.3 5489.9 5551.2 844.0 5225.9 5397.4 5414.4 5521.6 4995.2 5228.7 5276.4 5288.5 5317.2 5354.8 5355.8 5368.3 5388.2 5418.8 5197.0 5217.2 5320.8 5365.7 5380.8 5496.7 5814.3 5818.7 5881.2 5896.5 6254.9 6281.9 6288.8 6293.7 6299.1 6125.9 6180.9 6268.8 6385.4 6394.6 6076.4 6079.2 6090.6 6098.0 6109.0 6370.7 6410.6 6446.7 6450.8 6470.8 5945.4 6073.7 6127.1 6263.7 6267.2 6248.4 6351.9 6367.1 6371.2 6384.8 4996.1 6132.1 6243.5 6271.5 6291.0 6104.4 6209.2 6227.6 6231.7 6232.6 5021.4 5034.5 5049.2 5054.3 5080.1 4407.4 4559.7 4562.8 4580.3 4581.2 4989.0 4990.4 5381.4 5932.6 5962.6 6258.1 6312.2 6338.4 6342.3 6393.2 5026.8 5665.5 5790.1 5989.6 6054.3 5555.4 5560.0 5561.7 5566.7 5573.8 6490.6 6504.5 6513.2 6520.8 6556.3 6094.4 6186.6 6225.0 6340.6 6360.7 5441.9 6364.8 6369.9 6403.3 6423.1 6242.0 6274.2 6277.6 6287.1 6290.0 6134.0 6159.4 6226.1 6269.4 6293.0 6226.9 6248.7 6262.3 6263.4 6276.7 4918.5 6109.4 6229.8 6240.0 6274.7 5953.7 6014.3 6114.4 6124.9 6130.3 5006.9 6217.6 6237.1 6242.1 6248.8 5956.7 6122.2 6205.2 6207.1 6223.8 22:47:10 INFO - 2015-11-19 22:47:10,243 DEBUG : BROWSER_OUTPUT: 22:47:10 INFO - 2015-11-19 22:47:10,243 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:70943.62 Median:37855.53 stddev:156496.32 (413.4%) stddev-sans-first:156846.08 22:47:10 INFO - 2015-11-19 22:47:10,245 DEBUG : BROWSER_OUTPUT: Values: 548.8 561.0 571.9 613.9 636.2 42003.6 42164.5 42177.6 42282.4 42315.9 45264.5 45387.1 45708.0 45880.1 45957.5 9266.0 9918.7 10090.5 10093.3 10105.1 9800.4 9886.2 9887.2 9887.5 9901.3 9890.4 9982.2 10001.4 10006.8 10029.8 9902.4 9923.6 9939.0 9956.9 9967.3 9838.9 9910.6 9921.9 9942.2 9964.7 42348.8 42389.4 42464.0 42640.0 42730.0 2966.9 3314.4 3386.8 3467.3 3809.8 24892.9 26855.9 29842.3 29943.7 30076.0 2475.9 2890.3 2895.6 2921.5 2998.3 506382.0 506408.0 506717.0 506753.0 507459.0 57967.4 58454.0 68683.7 73801.6 74403.6 71307.9 71378.3 71534.0 71692.0 71863.0 16454.6 16677.0 16696.9 16747.5 16776.7 12285.1 12438.1 12448.9 12492.6 12693.7 19547.8 21521.9 21574.5 21609.2 21649.7 7733.4 8902.1 9006.2 9116.4 9144.0 934.2 953.7 994.4 1067.3 1116.9 29559.6 29795.3 29943.0 30125.0 30166.5 1058.6 1069.3 1077.1 1147.2 1248.8 14128.9 15004.1 15300.4 15310.3 15381.2 738934.0 740647.0 742338.0 753254.0 756126.0 45580.0 57704.7 57783.0 57843.7 57960.0 1434.9 1438.0 1443.0 1451.2 1603.2 29751.4 30103.9 30159.7 30326.4 30417.1 50030.0 50120.7 50254.0 50316.7 50341.0 455348.0 458075.0 459190.0 459520.0 459871.0 31012.4 32668.3 33423.0 33425.3 33499.7 43933.6 44819.6 44849.0 45092.9 45096.5 36491.8 36910.6 37368.4 37733.2 37782.1 36970.0 36973.0 37423.1 37703.0 37929.0 35669.5 36077.5 36534.0 36750.0 36784.3 32836.8 33537.7 33562.7 33686.7 33873.8 20730.8 23820.6 24087.9 24766.8 25562.9 22:47:10 INFO - 2015-11-19 22:47:10,245 DEBUG : BROWSER_OUTPUT: 22:47:10 INFO - 2015-11-19 22:47:10,245 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:37996.34 Median:11394.21 stddev:119860.60 (1051.9%) stddev-sans-first:120163.43 22:47:10 INFO - 2015-11-19 22:47:10,247 DEBUG : BROWSER_OUTPUT: Values: 310.8 365.9 370.5 370.5 372.6 15539.0 19293.0 20252.3 20699.4 21226.6 11792.2 11895.4 11957.9 12105.6 19721.4 5197.9 5240.1 5330.8 5332.8 5345.6 3684.4 3912.7 4061.9 5753.5 5900.2 5877.4 5886.2 5896.4 5912.1 5960.2 6124.6 6427.8 6467.4 6469.8 6489.3 4819.9 5553.8 5620.7 5640.0 5686.7 24769.9 25020.6 25232.7 25585.5 25672.6 2008.6 2043.4 2089.0 2097.2 2112.2 10299.0 11818.8 12874.0 13635.5 14260.5 1720.6 1723.5 1728.0 1731.3 1738.6 87887.6 94245.5 96228.0 97457.0 97826.5 8978.9 9898.7 10899.5 11049.1 11051.4 11006.8 12093.6 13045.3 13083.0 13207.4 7179.7 8073.7 8318.4 8335.2 8393.2 6143.4 8507.0 8598.9 8665.5 8681.3 4396.8 4423.1 4434.8 4437.7 4466.2 1832.5 1906.4 1910.4 1915.7 1918.1 901.2 940.1 976.7 1014.6 1017.2 17295.4 20012.0 20062.9 20225.9 20284.9 959.7 964.5 965.0 1003.7 1040.9 11329.3 11339.0 11444.8 11450.1 11499.4 681912.0 721777.0 731034.0 741500.0 759032.0 122161.0 128639.0 129091.7 130685.0 131892.0 1180.7 1185.6 1246.2 1251.2 1290.2 18320.6 18703.7 19159.6 19271.4 19341.6 49024.5 49170.8 49262.5 52252.0 52584.7 65643.3 66083.7 66585.0 67100.0 76559.2 9455.3 9799.1 9843.0 9880.8 9918.0 11780.4 13223.9 19869.9 19949.3 20023.2 15622.7 15918.4 16229.9 16356.3 16382.9 12849.4 13953.6 15476.0 15596.6 16138.9 5433.8 15213.2 16277.3 16690.6 16836.7 9827.5 10041.5 10115.1 10118.9 10146.1 15331.5 15821.5 15942.9 16009.7 16077.1 22:47:10 INFO - 2015-11-19 22:47:10,247 DEBUG : BROWSER_OUTPUT: 22:47:10 INFO - 2015-11-19 22:47:10,247 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5870.96 Median:5269.96 stddev:3027.59 (57.4%) stddev-sans-first:3018.55 22:47:10 INFO - 2015-11-19 22:47:10,248 DEBUG : BROWSER_OUTPUT: Values: 1536.9 1695.9 1847.2 1872.6 1911.7 7156.7 7248.1 8177.5 8296.1 8345.9 8148.2 8290.5 8393.4 8413.7 8481.4 2359.3 2391.9 2393.9 2396.3 2403.8 2391.8 2405.6 2441.8 2442.4 2447.7 2375.0 2386.3 2401.4 2405.3 2426.7 2358.9 2363.9 2395.5 2396.5 2404.9 2402.7 2406.1 2428.5 2431.0 2435.6 7110.4 8390.8 8414.5 8481.6 8519.3 4578.8 5077.5 5147.2 5149.0 5189.2 3152.9 3445.5 3455.8 3531.2 3542.1 829.0 967.3 1051.5 1053.4 1073.1 11124.4 11136.6 11137.0 11170.4 11328.7 4512.8 4687.5 4739.6 4757.9 4835.9 9562.0 9657.4 9662.9 9666.3 9669.3 4479.4 5121.1 5150.2 5153.2 5159.6 4728.9 4731.9 4734.0 4736.8 4779.1 3859.3 3874.9 3918.8 3922.8 3928.9 1238.4 1296.7 1296.9 1301.3 1310.2 1254.4 1335.4 1352.8 1356.8 1396.9 8027.2 8197.0 8207.8 8238.2 8241.5 1180.7 1204.1 1206.6 1217.6 1221.2 5350.7 5793.0 6318.8 6351.7 6365.6 9260.7 10258.0 10286.0 10335.2 10398.4 9896.4 10043.7 10124.4 10157.0 10201.7 7956.5 7991.6 8023.0 8026.2 8109.7 7799.0 7836.4 7866.0 7896.8 7980.7 9014.2 9093.6 9218.8 9284.4 9364.0 9272.4 9387.5 9492.6 9507.6 9531.7 7389.5 7403.7 7478.4 7496.5 7505.7 8047.4 8082.4 8110.4 8128.6 8162.6 7522.2 7530.4 7622.6 7702.3 7729.2 6243.0 7478.1 7480.3 7490.0 7689.4 7577.6 7606.4 7606.5 7615.3 7618.0 6887.8 6893.8 6904.9 6906.1 6914.9 6664.7 6839.4 6839.6 6860.0 6872.8 22:47:10 INFO - 2015-11-19 22:47:10,248 DEBUG : BROWSER_OUTPUT: 22:47:10 INFO - 2015-11-19 22:47:10,248 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:6092.43 Median:6856.49 stddev:2000.69 (29.2%) stddev-sans-first:1958.94 22:47:10 INFO - 2015-11-19 22:47:10,249 DEBUG : BROWSER_OUTPUT: Values: 326.8 329.5 329.9 333.1 337.7 6939.4 7058.4 7126.1 7150.4 7157.4 5673.8 6832.9 6839.0 6907.1 7035.3 4504.8 5185.6 5195.0 5218.5 5223.7 3995.7 5044.7 5194.7 5198.2 5201.2 5128.4 5147.0 5153.7 5171.2 5187.9 5137.6 5137.7 5144.2 5156.6 5164.7 3910.9 4170.0 4392.2 4403.9 4416.9 5305.3 6796.2 6851.7 6855.9 6857.1 5988.0 5992.9 6002.9 6009.1 6017.0 7540.7 7548.8 7550.5 7576.1 7584.6 4640.9 4924.1 4927.8 4930.4 4936.2 7303.6 7486.3 7546.9 8009.6 8036.3 4572.7 4854.4 4854.6 4875.0 4878.3 4474.4 4966.7 4968.7 4987.7 5002.2 4950.4 5764.5 5940.9 5967.7 5985.9 6934.6 7667.5 7667.6 7687.5 7690.5 1429.8 1596.4 1679.7 1725.7 1725.7 1077.4 1080.7 1085.2 1092.8 1098.8 5754.8 6812.0 7024.9 7069.6 7075.2 7812.6 7817.5 7820.5 7840.1 7853.9 6840.7 6996.5 7030.5 7031.4 7166.6 4183.1 4222.2 4230.2 4232.4 4245.9 8178.3 8191.0 8204.8 8205.3 8209.2 7946.8 8221.7 8238.6 8266.2 8294.7 7480.1 8182.5 8190.1 8217.2 8242.0 5886.0 6263.7 7447.9 7795.3 7968.5 7391.4 7830.5 7869.9 7883.2 7917.5 8172.4 8181.9 8188.5 8188.7 8208.6 4230.5 4748.0 4762.2 4857.8 4887.4 7602.7 7776.9 7900.9 7901.3 7905.2 6583.2 7255.6 7434.5 7539.7 7589.8 7586.3 7587.8 7610.0 7619.6 7631.4 6250.7 6813.7 7046.1 7422.6 7445.6 7579.1 7586.2 7588.4 7605.8 7622.0 5998.7 7206.5 7409.2 7410.1 7455.0 22:47:10 INFO - 2015-11-19 22:47:10,249 DEBUG : BROWSER_OUTPUT: 22:47:10 INFO - 2015-11-19 22:47:10,249 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:19357.72 Median:4254.36 stddev:28002.88 (658.2%) stddev-sans-first:28046.57 22:47:10 INFO - 2015-11-19 22:47:10,250 DEBUG : BROWSER_OUTPUT: Values: 749.0 764.5 781.5 815.7 818.1 11932.6 13978.6 14122.9 14133.7 14175.7 13653.9 14120.1 14121.2 14121.4 14163.9 857.3 922.3 929.2 944.1 977.0 975.4 978.3 981.5 982.4 983.1 1004.1 1006.6 1008.8 1013.2 1019.1 1000.7 1001.2 1002.6 1006.5 1006.8 748.1 749.9 750.9 754.0 755.1 13858.5 13910.0 13954.0 14061.9 14087.6 12192.1 12252.9 12270.4 12427.9 12480.6 10035.7 10068.2 10349.9 10350.4 10356.0 3427.2 3474.5 3489.9 3542.7 3649.6 79150.2 79670.2 80367.6 81048.6 81732.6 9403.6 9462.3 9773.1 9809.6 10092.8 20596.9 20945.4 21005.8 21109.7 21329.1 7426.2 7431.8 7607.5 7640.7 7799.9 4086.8 4151.8 4171.6 4225.6 4368.3 3404.1 3445.3 3451.3 3461.7 3484.4 547.0 554.5 609.7 623.4 636.0 515.8 545.9 552.9 578.9 594.7 8820.8 9333.5 9379.8 9385.6 9611.1 508.9 513.6 525.6 573.7 617.8 3953.4 4189.7 4219.9 4283.2 4289.0 99190.0 101647.0 102540.0 103251.5 103541.0 84792.0 85349.6 85655.0 85849.0 87288.0 74441.3 75367.6 75384.6 75606.6 75862.0 6953.9 8631.7 8660.3 9046.2 9175.6 51978.2 52248.2 52339.5 54205.7 55625.2 78619.0 78658.4 79061.0 79631.6 80149.6 11123.7 11322.7 11520.8 11821.9 11982.8 12258.8 12421.6 12544.3 12555.4 12673.7 11512.4 11558.1 11586.8 11627.2 12558.4 11495.6 11600.3 11787.4 11940.6 12391.7 10449.8 11146.4 11312.5 11422.9 11825.6 9683.8 9684.5 10109.0 10138.0 10369.5 7745.8 9059.0 9096.2 9626.0 10017.8 22:47:10 INFO - 2015-11-19 22:47:10,250 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:47:10 INFO - 2015-11-19 22:47:10,250 DEBUG : BROWSER_OUTPUT: 22:47:10 INFO - 2015-11-19 22:47:10,649 INFO : Browser exited with error code: 0 22:47:10 INFO - 2015-11-19 22:47:10,674 INFO : Completed test dromaeo_css (00:18:29) 22:47:10 INFO - 2015-11-19 22:47:10,674 INFO : Starting test kraken 22:47:10 INFO - 2015-11-19 22:47:10,674 DEBUG : operating with platform_type : mac_ 22:47:10 INFO - 2015-11-19 22:47:10,675 INFO : Initialising browser for kraken test... 22:47:10 INFO - 2015-11-19 22:47:10,688 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpf1fMGR/profile http://localhost:57566/getInfo.html 22:47:13 INFO - 2015-11-19 22:47:13,302 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:47:13 INFO - 2015-11-19 22:47:13,302 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:47:13 INFO - 2015-11-19 22:47:13,302 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 22:47:13 INFO - 2015-11-19 22:47:13,302 DEBUG : BROWSER_OUTPUT: __metrics 22:47:13 INFO - 2015-11-19 22:47:13,880 INFO : Browser initialized. 22:47:13 INFO - 2015-11-19 22:47:13,881 INFO : Running cycle 1/1 for kraken test... 22:47:13 INFO - 2015-11-19 22:47:13,881 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpf1fMGR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:47:25 INFO - 2015-11-19 22:47:25,839 DEBUG : BROWSER_OUTPUT: RSS: Main: 237076480 22:47:25 INFO - 2015-11-19 22:47:25,840 DEBUG : BROWSER_OUTPUT: 22:47:28 INFO - 2015-11-19 22:47:28,737 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57566/tests/kraken/driver.html?testName=audio-beat-detection) 22:47:29 INFO - 2015-11-19 22:47:29,342 DEBUG : BROWSER_OUTPUT: RSS: Main: 249004032 22:47:29 INFO - 2015-11-19 22:47:29,343 DEBUG : BROWSER_OUTPUT: 22:47:31 INFO - 2015-11-19 22:47:31,954 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57566/tests/kraken/driver.html?testName=audio-dft) 22:47:32 INFO - 2015-11-19 22:47:32,563 DEBUG : BROWSER_OUTPUT: RSS: Main: 258703360 22:47:32 INFO - 2015-11-19 22:47:32,563 DEBUG : BROWSER_OUTPUT: 22:47:35 INFO - 2015-11-19 22:47:35,956 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57566/tests/kraken/driver.html?testName=audio-fft) 22:47:36 INFO - 2015-11-19 22:47:36,535 DEBUG : BROWSER_OUTPUT: RSS: Main: 260628480 22:47:36 INFO - 2015-11-19 22:47:36,535 DEBUG : BROWSER_OUTPUT: 22:47:38 INFO - 2015-11-19 22:47:38,327 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57566/tests/kraken/driver.html?testName=audio-oscillator) 22:47:38 INFO - 2015-11-19 22:47:38,931 DEBUG : BROWSER_OUTPUT: RSS: Main: 263634944 22:47:38 INFO - 2015-11-19 22:47:38,932 DEBUG : BROWSER_OUTPUT: 22:47:40 INFO - 2015-11-19 22:47:40,869 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57566/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:47:41 INFO - 2015-11-19 22:47:41,458 DEBUG : BROWSER_OUTPUT: RSS: Main: 263180288 22:47:41 INFO - 2015-11-19 22:47:41,459 DEBUG : BROWSER_OUTPUT: 22:47:44 INFO - 2015-11-19 22:47:44,432 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57566/tests/kraken/driver.html?testName=imaging-darkroom) 22:47:45 INFO - 2015-11-19 22:47:45,021 DEBUG : BROWSER_OUTPUT: RSS: Main: 265506816 22:47:45 INFO - 2015-11-19 22:47:45,021 DEBUG : BROWSER_OUTPUT: 22:47:47 INFO - 2015-11-19 22:47:47,754 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57566/tests/kraken/driver.html?testName=imaging-desaturate) 22:47:48 INFO - 2015-11-19 22:47:48,354 DEBUG : BROWSER_OUTPUT: RSS: Main: 265748480 22:47:48 INFO - 2015-11-19 22:47:48,354 DEBUG : BROWSER_OUTPUT: 22:47:51 INFO - 2015-11-19 22:47:51,424 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57566/tests/kraken/driver.html?testName=json-parse-financial) 22:47:52 INFO - 2015-11-19 22:47:52,012 DEBUG : BROWSER_OUTPUT: RSS: Main: 263987200 22:47:52 INFO - 2015-11-19 22:47:52,012 DEBUG : BROWSER_OUTPUT: 22:47:53 INFO - 2015-11-19 22:47:53,657 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57566/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:47:54 INFO - 2015-11-19 22:47:54,242 DEBUG : BROWSER_OUTPUT: RSS: Main: 264540160 22:47:54 INFO - 2015-11-19 22:47:54,242 DEBUG : BROWSER_OUTPUT: 22:47:56 INFO - 2015-11-19 22:47:56,057 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57566/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:47:56 INFO - 2015-11-19 22:47:56,701 DEBUG : BROWSER_OUTPUT: RSS: Main: 264597504 22:47:56 INFO - 2015-11-19 22:47:56,702 DEBUG : BROWSER_OUTPUT: 22:47:58 INFO - 2015-11-19 22:47:58,823 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57566/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:47:59 INFO - 2015-11-19 22:47:59,431 DEBUG : BROWSER_OUTPUT: RSS: Main: 264589312 22:47:59 INFO - 2015-11-19 22:47:59,431 DEBUG : BROWSER_OUTPUT: 22:48:02 INFO - 2015-11-19 22:48:02,061 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:48:02 INFO - 2015-11-19 22:48:02,651 DEBUG : BROWSER_OUTPUT: RSS: Main: 263987200 22:48:02 INFO - 2015-11-19 22:48:02,651 DEBUG : BROWSER_OUTPUT: 22:48:05 INFO - 2015-11-19 22:48:05,236 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:48:05 INFO - 2015-11-19 22:48:05,833 DEBUG : BROWSER_OUTPUT: RSS: Main: 264753152 22:48:05 INFO - 2015-11-19 22:48:05,834 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,651 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57566/tests/kraken/driver.html?testName=ai-astar) 22:48:07 INFO - 2015-11-19 22:48:07,896 DEBUG : BROWSER_OUTPUT: RSS: Main: 264384512 22:48:07 INFO - 2015-11-19 22:48:07,896 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,896 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:48:07 INFO - 2015-11-19 22:48:07,897 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:48:07 INFO - 2015-11-19 22:48:07,897 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:48:07 INFO - 2015-11-19 22:48:07,897 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:48:07 INFO - 2015-11-19 22:48:07,897 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;106;112;111;121;169;161;162;164;113 22:48:07 INFO - 2015-11-19 22:48:07,897 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;125;145;155;122;147;150;148;188;122 22:48:07 INFO - 2015-11-19 22:48:07,898 DEBUG : BROWSER_OUTPUT: |2;audio-dft;190;198;194;208;238;265;273;203;199;197 22:48:07 INFO - 2015-11-19 22:48:07,898 DEBUG : BROWSER_OUTPUT: |3;audio-fft;59;64;72;125;54;58;62;59;88;55 22:48:07 INFO - 2015-11-19 22:48:07,898 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;77;77;110;75;77;76;111;76;78;110 22:48:07 INFO - 2015-11-19 22:48:07,898 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;86;92;73;79;133;118;113;133;132;95 22:48:07 INFO - 2015-11-19 22:48:07,898 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;95;95;95;95;95;95;96;95;95;95 22:48:07 INFO - 2015-11-19 22:48:07,898 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;77;148;141;93;93;91;131;144;95;145 22:48:07 INFO - 2015-11-19 22:48:07,898 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;59;59;60;57;62;77;89;94;90;64 22:48:07 INFO - 2015-11-19 22:48:07,898 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;67;66;67;67;68;70;85;67;92 22:48:07 INFO - 2015-11-19 22:48:07,898 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;86;85;85;82;84;83;108;108;78;103 22:48:07 INFO - 2015-11-19 22:48:07,898 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;136;117;129;134;122;131;136;135;124;135 22:48:07 INFO - 2015-11-19 22:48:07,898 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;140;142;139;143;176;125;170;181;174 22:48:07 INFO - 2015-11-19 22:48:07,898 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;62;88;92;87;92;90;94;61;75;93 22:48:07 INFO - 2015-11-19 22:48:07,899 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:48:07 INFO - 2015-11-19 22:48:07,899 DEBUG : BROWSER_OUTPUT: __start_cc_report 22:48:07 INFO - 2015-11-19 22:48:07,899 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,802 22:48:07 INFO - 2015-11-19 22:48:07,899 DEBUG : BROWSER_OUTPUT: __end_cc_report 22:48:07 INFO - 2015-11-19 22:48:07,899 DEBUG : BROWSER_OUTPUT: __startTimestamp1448002087896__endTimestamp 22:48:07 INFO - 2015-11-19 22:48:07,899 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,901 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:48:07 INFO - 2015-11-19 22:48:07,901 DEBUG : BROWSER_OUTPUT: Number of tests: 14 22:48:07 INFO - 2015-11-19 22:48:07,901 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,901 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:133.20 Median:141.00 stddev:26.83 (19.0%) stddev-sans-first:27.45 22:48:07 INFO - 2015-11-19 22:48:07,901 DEBUG : BROWSER_OUTPUT: Values: 113.0 106.0 112.0 111.0 121.0 169.0 161.0 162.0 164.0 113.0 22:48:07 INFO - 2015-11-19 22:48:07,901 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,902 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.80 Median:147.50 stddev:19.57 (13.3%) stddev-sans-first:20.75 22:48:07 INFO - 2015-11-19 22:48:07,902 DEBUG : BROWSER_OUTPUT: Values: 146.0 125.0 145.0 155.0 122.0 147.0 150.0 148.0 188.0 122.0 22:48:07 INFO - 2015-11-19 22:48:07,902 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,902 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:216.50 Median:205.50 stddev:30.73 (15.0%) stddev-sans-first:31.06 22:48:07 INFO - 2015-11-19 22:48:07,902 DEBUG : BROWSER_OUTPUT: Values: 190.0 198.0 194.0 208.0 238.0 265.0 273.0 203.0 199.0 197.0 22:48:07 INFO - 2015-11-19 22:48:07,902 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,902 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:69.60 Median:60.50 stddev:21.90 (36.2%) stddev-sans-first:22.90 22:48:07 INFO - 2015-11-19 22:48:07,902 DEBUG : BROWSER_OUTPUT: Values: 59.0 64.0 72.0 125.0 54.0 58.0 62.0 59.0 88.0 55.0 22:48:07 INFO - 2015-11-19 22:48:07,902 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,902 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:86.70 Median:76.50 stddev:16.33 (21.3%) stddev-sans-first:16.94 22:48:07 INFO - 2015-11-19 22:48:07,903 DEBUG : BROWSER_OUTPUT: Values: 77.0 77.0 110.0 75.0 77.0 76.0 111.0 76.0 78.0 110.0 22:48:07 INFO - 2015-11-19 22:48:07,903 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,903 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:105.40 Median:76.00 stddev:23.23 (30.6%) stddev-sans-first:23.56 22:48:07 INFO - 2015-11-19 22:48:07,903 DEBUG : BROWSER_OUTPUT: Values: 86.0 92.0 73.0 79.0 133.0 118.0 113.0 133.0 132.0 95.0 22:48:07 INFO - 2015-11-19 22:48:07,903 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,903 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:48:07 INFO - 2015-11-19 22:48:07,903 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 22:48:07 INFO - 2015-11-19 22:48:07,903 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,903 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:115.80 Median:84.00 stddev:28.17 (33.5%) stddev-sans-first:26.15 22:48:07 INFO - 2015-11-19 22:48:07,903 DEBUG : BROWSER_OUTPUT: Values: 77.0 148.0 141.0 93.0 93.0 91.0 131.0 144.0 95.0 145.0 22:48:07 INFO - 2015-11-19 22:48:07,904 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,904 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:71.10 Median:70.50 stddev:14.85 (21.1%) stddev-sans-first:15.09 22:48:07 INFO - 2015-11-19 22:48:07,904 DEBUG : BROWSER_OUTPUT: Values: 59.0 59.0 60.0 57.0 62.0 77.0 89.0 94.0 90.0 64.0 22:48:07 INFO - 2015-11-19 22:48:07,904 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,904 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:67.50 stddev:9.12 (13.5%) stddev-sans-first:9.52 22:48:07 INFO - 2015-11-19 22:48:07,904 DEBUG : BROWSER_OUTPUT: Values: 67.0 67.0 66.0 67.0 67.0 68.0 70.0 85.0 67.0 92.0 22:48:07 INFO - 2015-11-19 22:48:07,904 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,904 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:83.50 stddev:11.43 (13.7%) stddev-sans-first:12.02 22:48:07 INFO - 2015-11-19 22:48:07,904 DEBUG : BROWSER_OUTPUT: Values: 86.0 85.0 85.0 82.0 84.0 83.0 108.0 108.0 78.0 103.0 22:48:07 INFO - 2015-11-19 22:48:07,905 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,905 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:129.90 Median:134.50 stddev:6.74 (5.0%) stddev-sans-first:6.78 22:48:07 INFO - 2015-11-19 22:48:07,905 DEBUG : BROWSER_OUTPUT: Values: 136.0 117.0 129.0 134.0 122.0 131.0 136.0 135.0 124.0 135.0 22:48:07 INFO - 2015-11-19 22:48:07,905 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,905 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.90 Median:156.50 stddev:20.02 (12.8%) stddev-sans-first:20.60 22:48:07 INFO - 2015-11-19 22:48:07,905 DEBUG : BROWSER_OUTPUT: Values: 139.0 140.0 142.0 139.0 143.0 176.0 125.0 170.0 181.0 174.0 22:48:07 INFO - 2015-11-19 22:48:07,905 DEBUG : BROWSER_OUTPUT: 22:48:07 INFO - 2015-11-19 22:48:07,905 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.40 Median:91.00 stddev:12.74 (14.0%) stddev-sans-first:10.91 22:48:07 INFO - 2015-11-19 22:48:07,906 DEBUG : BROWSER_OUTPUT: Values: 62.0 88.0 92.0 87.0 92.0 90.0 94.0 61.0 75.0 93.0 22:48:07 INFO - 2015-11-19 22:48:07,906 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:48:07 INFO - 2015-11-19 22:48:07,906 DEBUG : BROWSER_OUTPUT: 22:48:08 INFO - 2015-11-19 22:48:08,498 INFO : Browser exited with error code: 0 22:48:08 INFO - 2015-11-19 22:48:08,545 INFO : Completed test kraken (00:00:57) 22:48:08 INFO - 2015-11-19 22:48:08,545 INFO : Starting test v8_7 22:48:08 INFO - 2015-11-19 22:48:08,545 DEBUG : operating with platform_type : mac_ 22:48:08 INFO - 2015-11-19 22:48:08,546 INFO : Initialising browser for v8_7 test... 22:48:08 INFO - 2015-11-19 22:48:08,558 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpwWg1SP/profile http://localhost:57566/getInfo.html 22:48:11 INFO - 2015-11-19 22:48:11,113 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 22:48:11 INFO - 2015-11-19 22:48:11,113 DEBUG : BROWSER_OUTPUT: colorDepth:24 22:48:11 INFO - 2015-11-19 22:48:11,114 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 22:48:11 INFO - 2015-11-19 22:48:11,114 DEBUG : BROWSER_OUTPUT: __metrics 22:48:11 INFO - 2015-11-19 22:48:11,841 INFO : Browser initialized. 22:48:11 INFO - 2015-11-19 22:48:11,841 INFO : Running cycle 1/1 for v8_7 test... 22:48:11 INFO - 2015-11-19 22:48:11,841 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpwWg1SP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 22:48:23 INFO - 2015-11-19 22:48:23,689 DEBUG : BROWSER_OUTPUT: RSS: Main: 235982848 22:48:23 INFO - 2015-11-19 22:48:23,689 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,046 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57566/tests/v8_7/run.html (next: http://localhost:57566/tests/v8_7/run.html) 22:48:45 INFO - 2015-11-19 22:48:45,296 DEBUG : BROWSER_OUTPUT: RSS: Main: 246480896 22:48:45 INFO - 2015-11-19 22:48:45,296 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,296 DEBUG : BROWSER_OUTPUT: __start_tp_report 22:48:45 INFO - 2015-11-19 22:48:45,296 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 22:48:45 INFO - 2015-11-19 22:48:45,296 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 22:48:45 INFO - 2015-11-19 22:48:45,296 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 22:48:45 INFO - 2015-11-19 22:48:45,296 DEBUG : BROWSER_OUTPUT: |0;Richards;636.5372374283895 22:48:45 INFO - 2015-11-19 22:48:45,296 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;156.25 22:48:45 INFO - 2015-11-19 22:48:45,296 DEBUG : BROWSER_OUTPUT: |2;Encrypt;290.9514111143439 22:48:45 INFO - 2015-11-19 22:48:45,296 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4887.804878048781 22:48:45 INFO - 2015-11-19 22:48:45,296 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1014.1843971631206 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: |5;Earley;844.5945945945946 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: |6;Boyer;13554.054054054053 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: |7;RegExp;28472.222222222223 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: |8;Splay;636.5372374283895 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4533.93665158371 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: __end_tp_report 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: __startTimestamp1448002125293__endTimestamp 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: Number of tests: 10 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:636.54 Median:636.54 stddev:0.00 (0.0%) 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: Values: 636.5 22:48:45 INFO - 2015-11-19 22:48:45,297 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:156.25 Median:156.25 stddev:0.00 (0.0%) 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: Values: 156.3 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:290.95 Median:290.95 stddev:0.00 (0.0%) 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: Values: 291.0 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4887.80 Median:4887.80 stddev:0.00 (0.0%) 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: Values: 4887.8 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1014.18 Median:1014.18 stddev:0.00 (0.0%) 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: Values: 1014.2 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:844.59 Median:844.59 stddev:0.00 (0.0%) 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: Values: 844.6 22:48:45 INFO - 2015-11-19 22:48:45,298 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,299 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:13554.05 Median:13554.05 stddev:0.00 (0.0%) 22:48:45 INFO - 2015-11-19 22:48:45,299 DEBUG : BROWSER_OUTPUT: Values: 13554.1 22:48:45 INFO - 2015-11-19 22:48:45,299 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,299 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:28472.22 Median:28472.22 stddev:0.00 (0.0%) 22:48:45 INFO - 2015-11-19 22:48:45,299 DEBUG : BROWSER_OUTPUT: Values: 28472.2 22:48:45 INFO - 2015-11-19 22:48:45,299 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,299 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:636.54 Median:636.54 stddev:0.00 (0.0%) 22:48:45 INFO - 2015-11-19 22:48:45,299 DEBUG : BROWSER_OUTPUT: Values: 636.5 22:48:45 INFO - 2015-11-19 22:48:45,299 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,299 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4533.94 Median:4533.94 stddev:0.00 (0.0%) 22:48:45 INFO - 2015-11-19 22:48:45,299 DEBUG : BROWSER_OUTPUT: Values: 4533.9 22:48:45 INFO - 2015-11-19 22:48:45,299 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 22:48:45 INFO - 2015-11-19 22:48:45,299 DEBUG : BROWSER_OUTPUT: 22:48:45 INFO - 2015-11-19 22:48:45,904 INFO : Browser exited with error code: 0 22:48:45 INFO - 2015-11-19 22:48:45,922 INFO : Completed test v8_7 (00:00:37) 22:48:46 INFO - 2015-11-19 22:48:46,415 INFO : Completed test suite (00:20:05) 22:48:46 INFO - 2015-11-19 22:48:46,416 DEBUG : Working with test: dromaeo_css 22:48:46 INFO - 2015-11-19 22:48:46,416 DEBUG : Generating results file: dromaeo_css 22:48:46 INFO - 2015-11-19 22:48:46,416 DEBUG : Working with test: kraken 22:48:46 INFO - 2015-11-19 22:48:46,417 DEBUG : Generating results file: kraken 22:48:46 INFO - 2015-11-19 22:48:46,417 INFO : javascript benchmark 22:48:46 INFO - 2015-11-19 22:48:46,417 DEBUG : Working with test: v8_7 22:48:46 INFO - 2015-11-19 22:48:46,417 DEBUG : Generating results file: v8_7 22:48:46 INFO - 2015-11-19 22:48:46,417 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:48:46 INFO - 2015-11-19 22:48:46,798 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,61]] 22:48:46 INFO - 2015-11-19 22:48:46,798 DEBUG : process_Request line: dromaeo_css 7733.82 graph.html#tests=[[72,64,61]] 22:48:46 INFO - 2015-11-19 22:48:46,798 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:48:46 INFO - 2015-11-19 22:48:46,825 DEBUG : process_Request line: kraken 1566.20 graph.html#tests=[[232,64,61]] 22:48:46 INFO - 2015-11-19 22:48:46,825 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:48:46 INFO - 2015-11-19 22:48:46,851 DEBUG : process_Request line: v8_7 18487.86 graph.html#tests=[[230,64,61]] 22:48:46 INFO - 2015-11-19 22:48:46,852 INFO : javascript benchmark 22:48:46 INFO - 2015-11-19 22:48:46,856 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3393.268418922767, 3427.9828600856995, 3481.6170221275656, 3500.0549851540068, 3539.9469007964867, 5842.88314233715, 5865.0, 5913.881722365551, 5917.011908985962, 5947.375525569812, 5807.796727114552, 5930.436608522195, 5967.8209653710455, 6014.097885317205, 6053.727582258798, 5387.622866399353, 5433.022056029913, 5433.293671822657, 5489.862753431176, 5551.2228288039705, 844.0276358539973, 5225.921611175835, 5397.379301380332, 5414.377346605152, 5521.641093328941, 4995.200767877122, 5228.738563071831, 5276.3932147802725, 5288.471152884681, 5317.202419637066, 5354.839354819323, 5355.812546560852, 5368.275282836845, 5388.218708287315, 5418.756155972991, 5197.038547868657, 5217.243499692523, 5320.813771518017, 5365.704886231256, 5380.838574842722, 5496.670199787985, 5814.273215848019, 5818.738156782955, 5881.2354393928945, 5896.469317761422, 6254.906176407358, 6281.905771413432, 6288.842778930518, 6293.653849038301, 6299.055141728777, 6125.938740612626, 6180.949238629444, 6268.78059267928, 6385.361463853624, 6394.58435201715, 6076.422739839708, 6079.179310793074, 6090.5736598438125, 6098.02781050674, 6108.969455152695, 6370.74517019314, 6410.583312084699, 6446.677666116675, 6450.774222902176, 6470.838229044311, 5945.435183657545, 6073.726682299307, 6127.097902969301, 6263.686815659199, 6267.1539342189135, 6248.4376406123665, 6351.872962540723, 6367.076773867763, 6371.203599550056, 6384.776532821329, 4996.1007018736245, 6132.110843927606, 6243.531735119885, 6271.529635277372, 6291.02489114188, 6104.4200800923845, 6209.223847019122, 6227.595206311576, 6231.657258850807, 6232.626042437469, 5021.397432308147, 5034.471380505068, 5049.242613607915, 5054.317667114892, 5080.110980578384, 4407.36050063328, 4559.726416414959, 4562.817487300471, 4580.335851301609, 4581.175388430114, 4989.0, 4990.3762029746285, 5381.354237491526, 5932.555058370639, 5962.552808539377, 6258.123862659231, 6312.179416675803, 6338.397852204033, 6342.334054924258, 6393.2328120625825, 5026.798928042837, 5665.5467562594895, 5790.10253410722, 5989.64062156272, 6054.273487181478, 5555.416681248411, 5559.999200143932, 5561.721913904288, 5566.721663916788, 5573.804916827891, 6490.610563366213, 6504.512161587805, 6513.1532900722605, 6520.804375868636, 6556.311587283362, 6094.350380525191, 6186.566940314135, 6224.968875155595, 6340.556161068681, 6360.745570177326, 5441.863953401196, 6364.840878978062, 6369.927806821011, 6403.327650596807, 6423.132877061724, 6241.96879015602, 6274.184356033869, 6277.560570760185, 6287.119803227643, 6290.0, 6134.0, 6159.4456498915315, 6226.097215903578, 6269.404406581368, 6293.0, 6226.875462490815, 6248.718807653576, 6262.342454042442, 6263.373662633882, 6276.6547839869245, 4918.48355922623, 6109.419605137505, 6229.781957631461, 6240.0, 6274.74901003973, 5953.690188158598, 6014.278286605635, 6114.449699527064, 6124.908126378019, 6130.295016073209, 5006.899862002812, 6217.595856269328, 6237.126802247598, 6242.063690446469, 6248.843778905563, 5956.731947062479, 6122.245686735438, 6205.193324867739, 6207.131001659862, 6223.844403889758], "unit": "ms", "name": "dojo.html", "value": 5750.029766695938}, {"lowerIsBetter": false, "replicates": [548.8051361312566, 560.9775608975641, 571.9285089363831, 613.9293981192163, 636.1634450697333, 42003.57996420043, 42164.52424165155, 42177.57822421775, 42282.36576451355, 42315.942101447516, 45264.54735452645, 45387.092258154815, 45708.0, 45880.08239835201, 45957.54042459575, 9265.95367023164, 9918.65284715037, 10090.545925433336, 10093.343932644355, 10105.090541851232, 9800.41197528147, 9886.209103271722, 9887.209023278156, 9887.505624718771, 9901.306908516406, 9890.357126786736, 9982.201423886108, 10001.399916005063, 10006.84989725151, 10029.799404011916, 9902.405855648649, 9923.603055877793, 9939.0, 9956.850647240297, 9967.252456065751, 9838.852417213748, 9910.603575856958, 9921.851172232422, 9942.154916832078, 9964.70105896817, 42348.78825605883, 42389.36415953763, 42464.0, 42640.0, 42730.0, 2966.851657417142, 3314.386838434873, 3386.847591858372, 3467.289205712823, 3809.847606095753, 24892.879820408125, 26855.865720671467, 29842.253943651365, 29943.70056299431, 30076.0, 2475.900963961439, 2890.262982939358, 2895.623568936046, 2921.5471601901527, 2998.34036511967, 506382.0, 506408.0, 506717.0, 506753.0, 507458.99999999633, 57967.4203257962, 58454.0, 68683.65658171677, 73801.6309918447, 74403.62798186083, 71307.93038104329, 71378.2862171382, 71534.0, 71692.0, 71863.0, 16454.588635284214, 16677.0, 16696.916515417346, 16747.497575072768, 16776.664466710597, 12285.1400401971, 12438.067144964163, 12448.879600835968, 12492.562760303346, 12693.682657933441, 19547.804521954884, 21521.89239053826, 21574.460638484165, 21609.243676471564, 21649.67525487119, 7733.381329493627, 8902.109789021175, 9006.189442950166, 9116.361854670242, 9143.954280228556, 934.1736904351976, 953.6566835939057, 994.3884511025667, 1067.3169171730099, 1116.9497372618252, 29559.556606650916, 29795.255118621775, 29943.0, 30125.0, 30166.547501787492, 1058.5977328615231, 1069.2942657845786, 1077.1436707817304, 1147.1740346950182, 1248.7627350803318, 14128.94032947533, 15004.099754014795, 15300.387984480718, 15310.311036003406, 15381.154036528098, 738934.0, 740647.0, 742338.0, 753254.0, 756126.0, 45580.0, 57704.711476442346, 57783.0, 57843.71078144582, 57960.0, 1434.863687949643, 1438.0437009388847, 1443.0, 1451.165579791624, 1603.214424931775, 29751.404971900873, 30103.946361877228, 30159.69840301569, 30326.393472130876, 30417.087487375415, 50030.0, 50120.74939625279, 50254.0, 50316.74841625769, 50341.0, 455348.0, 458075.0, 459190.0, 459520.0, 459871.0, 31012.379752404373, 32668.34663306673, 33423.0, 33425.33149336951, 33499.66500335063, 43933.56066439295, 44819.55180448284, 44849.0, 45092.87267818331, 45096.54903450923, 36491.817540912125, 36910.63089369072, 37368.43947340738, 37733.24533509369, 37782.055448612904, 36970.0, 36973.0, 37423.06442338855, 37703.0, 37929.0, 35669.46495802513, 36077.45883811797, 36534.0, 36750.0, 36784.26431471409, 32836.83581582077, 33537.66462335443, 33562.664373355954, 33686.663133368354, 33873.830630846685, 20730.75615463077, 23820.642690360004, 24087.879560602785, 24766.752332476444, 25562.872185638953], "unit": "ms", "name": "ext.html", "value": 20441.371421927335}, {"lowerIsBetter": false, "replicates": [310.76847748427423, 365.8756022952197, 370.5090754749957, 370.51462584014934, 372.61248301766165, 15539.0, 19293.0, 20252.29116980906, 20699.379018629446, 21226.57546849062, 11792.174547781658, 11895.4052297385, 11957.940210298959, 12105.576304829332, 19721.40835774929, 5197.8960420791755, 5240.082985477536, 5330.840074797762, 5332.760025798849, 5345.572354211673, 3684.3736564783917, 3912.68698504119, 4061.8984525386804, 5753.453421924932, 5900.203472531218, 5877.353491115974, 5886.20536227608, 5896.351401345849, 5912.142739302799, 5960.1953736245705, 6124.571280010401, 6427.775027874002, 6467.385598368148, 6469.773557925473, 6489.286178520382, 4819.927701084486, 5553.805616803393, 5620.747066382022, 5639.971800141014, 5686.687232202268, 24769.87615061931, 25020.624690629655, 25232.747672523223, 25585.48829023409, 25672.614910776352, 2008.5982803439224, 2043.427840204745, 2089.039042040663, 2097.1820989813987, 2112.2290364017176, 10299.0, 11818.763624727544, 12874.0, 13635.522756703567, 14260.500882469167, 1720.5698575356162, 1723.5087999919963, 1727.9827201727946, 1731.3161301285922, 1738.5666550296694, 87887.56056219678, 94245.52877235638, 96228.0, 97457.0, 97826.51086744592, 8978.910210897939, 9898.703038908845, 10899.455027248765, 11049.060829829394, 11051.447427628586, 11006.779864402668, 12093.637190884288, 13045.282509462071, 13083.0, 13207.40566674502, 7179.748708795167, 8073.6770529178175, 8318.37612179089, 8335.249827515552, 8393.24460798531, 6143.416375444325, 8507.021692505441, 8598.914010859811, 8665.480071195623, 8681.348898832486, 4396.758178300161, 4423.093265880487, 4434.800433980478, 4437.689361744711, 4466.240739074366, 1832.5418645338666, 1906.4280715785487, 1910.4077735901915, 1915.6839121545067, 1918.1176658737138, 901.1754244866005, 940.1350757303297, 976.702105857706, 1014.573878970834, 1017.1964148322742, 17295.39466118705, 20011.99940002994, 20062.899685501478, 20225.88757618345, 20284.898575507323, 959.6737109382843, 964.5129209749172, 965.0, 1003.7440452684665, 1040.885502594714, 11329.320240785579, 11338.979491845774, 11444.771104578029, 11450.083993280517, 11499.425028748528, 681912.0, 721777.0, 731034.0, 741500.0, 759032.0, 122161.0, 128639.0, 129091.70908290797, 130685.03972440421, 131892.0, 1180.6694125644663, 1185.5554167187304, 1246.1712960881093, 1251.2429979862052, 1290.1613950931783, 18320.633587328444, 18703.719444208076, 19159.616807664046, 19271.421857344303, 19341.613167736286, 49024.50975490342, 49170.75414622904, 49262.50737492579, 52251.95496090133, 52584.737076315905, 65643.34356656372, 66083.67832643416, 66585.0, 67100.0, 76559.23440765744, 9455.338126331091, 9799.069088436587, 9842.950785246028, 9880.802383952137, 9917.950410247902, 11780.410979450822, 13223.933880330536, 19869.900650496656, 19949.301774437827, 20023.199072036954, 15622.687546249239, 15918.363265469252, 16229.94505357148, 16356.34574617002, 16382.918085409498, 12849.357532123544, 13953.581392558239, 15476.0, 15596.610084747519, 16138.919305403399, 5433.809816656398, 15213.163276019483, 16277.348906044099, 16690.582735431224, 16836.663266734842, 9827.52587111939, 10041.548130334299, 10115.089641932262, 10118.898811011795, 10146.086852183338, 15331.463398780988, 15821.525354239391, 15942.920285398499, 16009.679806403574, 16077.115758633387], "unit": "ms", "name": "jquery.html", "value": 9822.994585549752}, {"lowerIsBetter": false, "replicates": [1536.8770498360132, 1695.8982461052333, 1847.1503108570066, 1872.6348362069398, 1911.6845720456126, 7156.749513767019, 7248.13022437307, 8177.509349439038, 8296.08743038266, 8345.874811877819, 8148.185181481849, 8290.544020078893, 8393.370497212718, 8413.66345346187, 8481.406301558893, 2359.2922123362964, 2391.8923648435775, 2393.916212932539, 2396.2930935374106, 2403.807695384365, 2391.820613453989, 2405.5910495215767, 2441.7802397784194, 2442.3894026493376, 2447.706275246973, 2375.0, 2386.305722936717, 2401.3756423329974, 2405.302462285935, 2426.708794944609, 2358.929232123039, 2363.9054437822465, 2395.5448464791634, 2396.5326761281644, 2404.8557086574774, 2402.651615515758, 2406.1217655555597, 2428.514297140579, 2430.9878450607634, 2435.561598912195, 7110.395616372614, 8390.83218335636, 8414.495130292202, 8481.575921203916, 8519.275861551776, 4578.839740609096, 5077.543021128079, 5147.202183661539, 5149.0474262261605, 5189.221616757479, 3152.8896488622786, 3445.500402441632, 3455.809930453824, 3531.170175008871, 3542.096765324826, 828.9637952559301, 967.2552134856121, 1051.484772461496, 1053.3785066810542, 1073.1039581949083, 11124.443777811159, 11136.554537818478, 11136.99767020964, 11170.441477926071, 11328.660140195725, 4512.751798651106, 4687.460941991646, 4739.573438390561, 4757.857264282077, 4835.854924352275, 9562.0, 9657.420554766737, 9662.855057174149, 9666.275029372826, 9669.274804389699, 4479.372887795711, 5121.07820592297, 5150.201718733602, 5153.201253805666, 5159.587233021424, 4728.858134255978, 4731.905361892812, 4733.95266047342, 4736.786844592001, 4779.139754844092, 3859.3246181918053, 3874.903127421837, 3918.8432462701744, 3922.764634121962, 3928.8821335359985, 1238.38699843578, 1296.6628676544167, 1296.8897643700202, 1301.3363184775833, 1310.1876836361516, 1254.404158024931, 1335.3923964596086, 1352.8444228913604, 1356.8439629442742, 1396.8812650924785, 8027.237412445808, 8197.016358037074, 8207.7948051298, 8238.217369349903, 8241.546714930617, 1180.6576092933126, 1204.084895479442, 1206.5535751771724, 1217.5799349224503, 1221.2367270455966, 5350.705711185922, 5793.015187418149, 6318.8420289492215, 6351.714172862232, 6365.554411191172, 9260.749798777213, 10258.025487578669, 10286.0, 10335.22485813567, 10398.428086455318, 9896.406215627085, 10043.69868903934, 10124.392536447836, 10157.0, 10201.744956376002, 7956.482828616237, 7991.640376183086, 8022.959885200653, 8026.197380262044, 8109.675612975533, 7799.0, 7836.37309015266, 7865.960670196612, 7896.763097107096, 7980.680772769024, 9014.23379012765, 9093.590788414405, 9218.815623687355, 9284.442933424016, 9364.01677823846, 9272.443653380818, 9387.530623468936, 9492.572834222614, 9507.619695212114, 9531.714048578553, 7389.482736208413, 7403.703851845866, 7478.364339031244, 7496.475246732676, 7505.66224519887, 8047.356211502949, 8082.434229604107, 8110.432269741297, 8128.63421146038, 8162.632681529227, 7522.172561018393, 7530.397568194641, 7622.618869056414, 7702.268284512962, 7729.227077292226, 6243.0, 7478.06524184477, 7480.289372509603, 7490.0, 7689.384849211938, 7577.621118943921, 7606.391488681003, 7606.467547271638, 7615.314621684075, 7617.961910190414, 6887.793366199022, 6893.827654308682, 6904.896426553505, 6906.066301435606, 6914.896276555755, 6664.666766661744, 6839.350261725128, 6839.62382068991, 6859.965700171467, 6872.759453419107], "unit": "ms", "name": "mootools.html", "value": 4831.676316549491}, {"lowerIsBetter": false, "replicates": [326.7516687317638, 329.4810673189727, 329.9406106900757, 333.1421589407276, 337.7416276548439, 6939.397725852805, 7058.364747172754, 7126.07361043065, 7150.427965762733, 7157.42740580753, 5673.8297851064435, 6832.897506537406, 6838.965805170979, 6907.102076730018, 7035.331643493872, 4504.8287445264305, 5185.585153187736, 5195.038917800201, 5218.530332270095, 5223.71269580173, 3995.6803455723616, 5044.722540260284, 5194.740262986836, 5198.168293073109, 5201.193814958669, 5128.358955130609, 5147.047796157704, 5153.742312884359, 5171.17261238202, 5187.896242075156, 5137.6403651744395, 5137.717425541594, 5144.151215049513, 5156.639035267533, 5164.664296820694, 3910.902227444308, 4169.958300416987, 4392.1874453226255, 4403.911921761547, 4416.911661766779, 5305.310309659758, 6796.150481189851, 6851.6981773462885, 6855.897161542581, 6857.074294970215, 5988.041913293896, 5992.8501787455225, 6002.909956350658, 6009.09863520471, 6016.977113890649, 7540.73607423743, 7548.773536793905, 7550.471466997313, 7576.128745194318, 7584.582847943361, 4640.86077417678, 4924.064427758714, 4927.827526036572, 4930.4330002049865, 4936.160852655057, 7303.634818259119, 7486.288802563748, 7546.867969804518, 8009.559474228857, 8036.316913062396, 4572.719638501225, 4854.393200849894, 4854.611631069577, 4875.0493653737185, 4878.341423907798, 4474.351219073216, 4966.652334336562, 4968.677035992721, 4987.678265259709, 5002.224655178453, 4950.356453661001, 5764.538836893039, 5940.851478713067, 5967.671778052249, 5985.940140598538, 6934.583924964519, 7667.539947603162, 7667.616619169019, 7687.500312479783, 7690.461667683208, 1429.756941319978, 1596.3774128090054, 1679.7480377943407, 1725.6548690261998, 1725.6807490614276, 1077.3643550305358, 1080.708208783624, 1085.234909388879, 1092.819684752023, 1098.8351747237978, 5754.827355179351, 6811.9659401702675, 7024.929750702529, 7069.6111713856235, 7075.2217256101785, 7812.648430820626, 7817.530948143016, 7820.452568320276, 7840.13758486566, 7853.921460785319, 6840.657967101625, 6996.475264355194, 7030.54301470413, 7031.402330801837, 7166.641667916583, 4183.079722461053, 4222.21888950539, 4230.175115852379, 4232.449781528381, 4245.91508169841, 8178.304844088319, 8191.017077950565, 8204.753857384288, 8205.261526462647, 8209.220124088202, 7946.7615971520945, 8221.712240071569, 8238.588070596566, 8266.173382661806, 8294.668213271521, 7480.1023877134, 8182.509049457051, 8190.099089100194, 8217.178282171855, 8241.969753780777, 5885.969955257899, 6263.65549894742, 7447.88828167567, 7795.33739632115, 7968.521888686697, 7391.4086873048955, 7830.491018083806, 7869.881951770613, 7883.172266911892, 7917.524948503108, 8172.387070969582, 8181.877271841046, 8188.549629770419, 8188.713395031145, 8208.630611622611, 4230.513490948457, 4747.976260118816, 4762.166620841408, 4857.805687772449, 4887.364642596582, 7602.7339043133225, 7776.92223077762, 7900.881486777818, 7901.288884000238, 7905.209479052049, 6583.210014798255, 7255.600941948032, 7434.479586428898, 7539.660715267716, 7589.810254743676, 7586.279303466162, 7587.772366829004, 7609.961950190213, 7619.580923049286, 7631.3513351365755, 6250.718717657807, 6813.727450901908, 7046.084009078787, 7422.554646721214, 7445.553266804009, 7579.090509138939, 7586.24137586237, 7588.392928565591, 7605.809854753676, 7622.0, 5998.700064996646, 7206.495545311987, 7409.222031686595, 7410.11078670563, 7455.0], "unit": "ms", "name": "prototype.html", "value": 5415.366015965598}, {"lowerIsBetter": false, "replicates": [748.9925100748993, 764.5374548398219, 781.5115552779513, 815.6818840652143, 818.1164342510086, 11932.64202073937, 13978.580642580713, 14122.858771412295, 14133.71732565348, 14175.716485670293, 13653.863461365409, 14120.082194657365, 14121.223332716696, 14121.364538595762, 14163.858361416384, 857.3055824781947, 922.3128769067038, 929.2055292724724, 944.0700909604069, 976.9706908792748, 975.3855071305068, 978.3005151316808, 981.5141504955058, 982.3909176310661, 983.0759086458742, 1004.0862814838498, 1006.572206812102, 1008.793197394536, 1013.2350075692841, 1019.1235537437798, 1000.6647772996055, 1001.184035011467, 1002.6039714312842, 1006.5017816180964, 1006.8439391894268, 748.0686545251197, 749.9400047996147, 750.8685979953486, 753.9623018849036, 755.1052003376017, 13858.514951976733, 13910.026298159133, 13954.023218374718, 14061.929690351584, 14087.577372678836, 12192.146549741521, 12252.877471225263, 12270.447829847679, 12427.875721242763, 12480.592678385108, 10035.74910627233, 10068.244881633833, 10349.948250258776, 10350.378977261389, 10356.0, 3427.2288735034667, 3474.4788281757687, 3489.8953031409096, 3542.71658267338, 3649.580298265707, 79150.20499440924, 79670.20329796744, 80367.5981620094, 81048.59475702643, 81732.59133704293, 9403.57683904226, 9462.29032822541, 9773.07155820196, 9809.558569864374, 10092.848607270897, 20596.897015514827, 20945.371638850553, 21005.789942100688, 21109.68335474969, 21329.146834126772, 7426.220246874109, 7431.777046688608, 7607.505512141693, 7640.656170472342, 7799.883001754978, 4086.7645457335625, 4151.7924103794685, 4171.561985991488, 4225.556316586775, 4368.257396242647, 3404.1149301181376, 3445.3109378124477, 3451.2579795344122, 3461.7403694723, 3484.4250698634946, 546.9617126801086, 554.5092593055175, 609.6646844235653, 623.382850977529, 636.0491065856575, 515.8007632255022, 545.929029226198, 552.8562573730778, 578.9247397838254, 594.7323704333068, 8820.779480513038, 9333.486658233862, 9379.765505862408, 9385.624575016924, 9611.086946740048, 508.8905885234692, 513.622487471708, 525.6057956532601, 573.6701396696916, 617.7652492052991, 3953.367461206193, 4189.664826813909, 4219.852305169305, 4283.164782867373, 4289.013526888833, 99190.0, 101647.0, 102540.0, 103251.48374258081, 103541.0, 84792.0, 85349.57325213334, 85655.0, 85849.0, 87288.0, 74441.25558744451, 75367.62316188494, 75384.62307688536, 75606.62196689092, 75862.0, 6953.860922781617, 8631.741047768577, 8660.307175426076, 9046.185843274137, 9175.632974681204, 51978.22032669589, 52248.17131400383, 52339.47660523498, 54205.72897135489, 55625.165622516506, 78619.03452413507, 78658.4268314642, 79061.0, 79631.60184199041, 80149.59925200336, 11123.72190695239, 11322.660320190407, 11520.827187592025, 11821.881781182077, 11982.820257695967, 12258.754824903273, 12421.582175914577, 12544.30651861987, 12555.372231388577, 12673.746525069631, 11512.36681982499, 11558.12535021978, 11586.76826463483, 11627.18609697347, 12558.372081396077, 11495.585347451015, 11600.30398176112, 11787.351695656822, 11940.641780746591, 12391.690207744878, 10449.791004179722, 11146.386948717574, 11312.547498099984, 11422.94288528552, 11825.645230643095, 9683.757906052124, 9684.515774211402, 10109.0, 10138.0, 10369.46905964665, 7745.757716535058, 9058.954705226695, 9096.1813436791, 9625.951870240884, 10017.849732253877], "unit": "ms", "name": "yui.html", "value": 7082.368947952525}], "name": "dromaeo_css", "value": 7733.817955700728}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 106.0, 112.0, 111.0, 121.0, 169.0, 161.0, 162.0, 164.0, 113.0], "unit": "ms", "name": "ai-astar", "value": 133.2}, {"lowerIsBetter": true, "replicates": [146.0, 125.0, 145.0, 155.0, 122.0, 147.0, 150.0, 148.0, 188.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.8}, {"lowerIsBetter": true, "replicates": [190.0, 198.0, 194.0, 208.0, 238.0, 265.0, 273.0, 203.0, 199.0, 197.0], "unit": "ms", "name": "audio-dft", "value": 216.5}, {"lowerIsBetter": true, "replicates": [59.0, 64.0, 72.0, 125.0, 54.0, 58.0, 62.0, 59.0, 88.0, 55.0], "unit": "ms", "name": "audio-fft", "value": 69.6}, {"lowerIsBetter": true, "replicates": [77.0, 77.0, 110.0, 75.0, 77.0, 76.0, 111.0, 76.0, 78.0, 110.0], "unit": "ms", "name": "audio-oscillator", "value": 86.7}, {"lowerIsBetter": true, "replicates": [86.0, 92.0, 73.0, 79.0, 133.0, 118.0, 113.0, 133.0, 132.0, 95.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 105.4}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-darkroom", "value": 95.1}, {"lowerIsBetter": true, "replicates": [77.0, 148.0, 141.0, 93.0, 93.0, 91.0, 131.0, 144.0, 95.0, 145.0], "unit": "ms", "name": "imaging-desaturate", "value": 115.8}, {"lowerIsBetter": true, "replicates": [59.0, 59.0, 60.0, 57.0, 62.0, 77.0, 89.0, 94.0, 90.0, 64.0], "unit": "ms", "name": "json-parse-financial", "value": 71.1}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 66.0, 67.0, 67.0, 68.0, 70.0, 85.0, 67.0, 92.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 71.6}, {"lowerIsBetter": true, "replicates": [86.0, 85.0, 85.0, 82.0, 84.0, 83.0, 108.0, 108.0, 78.0, 103.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 90.2}, {"lowerIsBetter": true, "replicates": [136.0, 117.0, 129.0, 134.0, 122.0, 131.0, 136.0, 135.0, 124.0, 135.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 129.9}, {"lowerIsBetter": true, "replicates": [139.0, 140.0, 142.0, 139.0, 143.0, 176.0, 125.0, 170.0, 181.0, 174.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 152.9}, {"lowerIsBetter": true, "replicates": [62.0, 88.0, 92.0, 87.0, 92.0, 90.0, 94.0, 61.0, 75.0, 93.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 83.4}], "name": "kraken", "value": 1566.2000000000003}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [636.5372374283895], "unit": "score", "name": "Richards", "value": 55.45944199999998}, {"lowerIsBetter": false, "replicates": [156.25], "unit": "score", "name": "DeltaBlue", "value": 423.1551999999999}, {"lowerIsBetter": false, "replicates": [290.9514111143439], "unit": "score", "name": "Encrypt", "value": 914.8640969999997}, {"lowerIsBetter": false, "replicates": [4887.804878048781], "unit": "score", "name": "Decrypt", "value": 54.4581886227545}, {"lowerIsBetter": false, "replicates": [1014.1843971631206], "unit": "score", "name": "RayTrace", "value": 729.6395034965032}, {"lowerIsBetter": false, "replicates": [844.5945945945946], "unit": "score", "name": "Earley", "value": 789.0921920000003}, {"lowerIsBetter": false, "replicates": [13554.054054054053], "unit": "score", "name": "Boyer", "value": 49.17074975074771}, {"lowerIsBetter": false, "replicates": [28472.222222222223], "unit": "score", "name": "RegExp", "value": 31.99557073170733}, {"lowerIsBetter": false, "replicates": [636.5372374283895], "unit": "score", "name": "Splay", "value": 128.02236099999996}, {"lowerIsBetter": false, "replicates": [4533.93665158371], "unit": "score", "name": "NavierStokes", "value": 327.3093812375252}], "name": "v8_7", "value": 18487.858276098974}]} 22:48:46 INFO - RETURN: dromaeo_css: 7733.82 22:48:46 INFO - RETURN: kraken: 1566.20 22:48:46 INFO - RETURN: v8_7: 18487.86 22:48:46 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,61]]", "result": "18487.86"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,61]]", "result": "1566.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,61]]", "result": "7733.82"}}} 22:48:46 INFO - Return code: 0 22:48:46 INFO - # TBPL SUCCESS # 22:48:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:48:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:48:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:48:46 INFO - 2015-11-19 22:48:46.942 screenresolution[2541:30797] starting screenresolution argv=screenresolution get 22:48:46 INFO - 2015-11-19 22:48:46.944 screenresolution[2541:30797] Display 0: 1600x1200x32@60 22:48:46 INFO - 2015-11-19 22:48:46.950 screenresolution[2542:30800] starting screenresolution argv=screenresolution list 22:48:46 INFO - Available Modes on Display 0 22:48:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:48:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:48:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:48:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:48:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:48:47 INFO - Intel Iris: 22:48:47 INFO - Chipset Model: Intel Iris 22:48:47 INFO - Type: GPU 22:48:47 INFO - Bus: Built-In 22:48:47 INFO - VRAM (Dynamic, Max): 1536 MB 22:48:47 INFO - Vendor: Intel (0x8086) 22:48:47 INFO - Device ID: 0x0a2e 22:48:47 INFO - Revision ID: 0x0009 22:48:47 INFO - Displays: 22:48:47 INFO - Display: 22:48:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:48:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:48:47 INFO - Display Serial Number: 1600x1200 60 22:48:47 INFO - Main Display: Yes 22:48:47 INFO - Mirror: Off 22:48:47 INFO - Online: Yes 22:48:47 INFO - Rotation: Supported 22:48:47 INFO - Return code: 0 22:48:47 INFO - Running post-action listener: _resource_record_post_action 22:48:47 INFO - Running post-run listener: _resource_record_post_run 22:48:48 INFO - Total resource usage - Wall time: 1236s; CPU: 54.0%; Read bytes: 0; Write bytes: 694190592; Read time: 0; Write time: 2121 22:48:48 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 336286208; Write bytes: 337778688; Read time: 26169; Write time: 637 22:48:48 INFO - run-tests - Wall time: 1207s; CPU: 54.0%; Read bytes: 0; Write bytes: 346044928; Read time: 0; Write time: 1449 22:48:48 INFO - Running post-run listener: _upload_blobber_files 22:48:48 INFO - Blob upload gear active. 22:48:48 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 22:48:48 INFO - Copying logs to upload dir... 22:48:48 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1280.922820 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2015-11-19 22:48:48.303394) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 22:48:48.306768) ========= 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.ISeJD9gAU6/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.K41mFqqjYH/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False build_url:https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009822 build_url: 'https://queue.taskcluster.net/v1/task/elKApx6KTOGP4UeVaVpX6Q/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg' ========= master_lag: 0.01 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 22:48:48.331561) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:48:48.331892) ========= 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.ISeJD9gAU6/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.K41mFqqjYH/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005361 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 22:48:48.380265) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 22:48:48.380599) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 22:48:48.380999) ========= ========= Total master_lag: 0.44 =========