builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0019 starttime: 1448308319.46 results: success (0) buildid: 20151123050554 builduid: 1951b743c8f04fd2b9d29c2608cfd14d revision: 5c614c8507b7c7928ffbe5cc3a884d7c55c9405b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.455804) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.456356) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.456775) ========= 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.swVBe8eiM8/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.XeipRAgqSs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.005369 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.479223) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.479548) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.496537) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.496891) ========= 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.swVBe8eiM8/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.XeipRAgqSs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.006008 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.547193) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.547606) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.548041) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.548391) ========= 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.swVBe8eiM8/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.XeipRAgqSs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2015-11-23 11:51:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 1.62G=0s 2015-11-23 11:51:59 (1.62 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119918 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.681442) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.681867) ========= 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.swVBe8eiM8/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.XeipRAgqSs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.026471 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 11:51:59.734927) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 11:51:59.735341) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5c614c8507b7c7928ffbe5cc3a884d7c55c9405b --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 5c614c8507b7c7928ffbe5cc3a884d7c55c9405b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.swVBe8eiM8/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.XeipRAgqSs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2015-11-23 11:51:59,783 truncating revision to first 12 chars 2015-11-23 11:51:59,783 Setting DEBUG logging. 2015-11-23 11:51:59,784 attempt 1/10 2015-11-23 11:51:59,784 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5c614c8507b7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-23 11:52:01,432 unpacking tar archive at: fx-team-5c614c8507b7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.890942 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 11:52:01.641833) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 11:52:01.642226) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 11:52:01.654102) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 11:52:01.654348) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 11:52:01.654886) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2015-11-23 11:52:01.655338) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.swVBe8eiM8/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.XeipRAgqSs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 11:52:01 INFO - MultiFileLogger online at 20151123 11:52:01 in /builds/slave/test 11:52:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:52:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:52:01 INFO - {'append_to_log': False, 11:52:01 INFO - 'base_work_dir': '/builds/slave/test', 11:52:01 INFO - 'blob_upload_branch': 'Fx-Team', 11:52:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:52:01 INFO - 'branch': 'Fx-Team', 11:52:01 INFO - 'buildbot_json_path': 'buildprops.json', 11:52:01 INFO - 'config_files': ('talos/mac_config.py',), 11:52:01 INFO - 'default_actions': ('clobber', 11:52:01 INFO - 'read-buildbot-config', 11:52:01 INFO - 'download-and-extract', 11:52:01 INFO - 'populate-webroot', 11:52:01 INFO - 'create-virtualenv', 11:52:01 INFO - 'install', 11:52:01 INFO - 'run-tests'), 11:52:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:52:01 INFO - 'download_minidump_stackwalk': True, 11:52:01 INFO - 'download_symbols': 'ondemand', 11:52:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:52:01 INFO - 'tooltool.py': '/tools/tooltool.py', 11:52:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:52:01 INFO - '/tools/misc-python/virtualenv.py')}, 11:52:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:52:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:52:01 INFO - 'installer_path': 'installer.exe', 11:52:01 INFO - 'log_level': 'info', 11:52:01 INFO - 'log_name': 'talos', 11:52:01 INFO - 'log_to_console': True, 11:52:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:52:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:52:01 INFO - 'opt_config_files': (), 11:52:01 INFO - 'pip_index': False, 11:52:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:52:01 INFO - 'cmd': ('bash', 11:52:01 INFO - '-c', 11:52:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:52:01 INFO - 'enabled': True, 11:52:01 INFO - 'halt_on_failure': False, 11:52:01 INFO - 'name': 'check_screen_resolution'},), 11:52:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:52:01 INFO - 'cmd': ('bash', 11:52:01 INFO - '-c', 11:52:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:52:01 INFO - 'enabled': True, 11:52:01 INFO - 'halt_on_failure': False, 11:52:01 INFO - 'name': 'check_screen_resolution'},), 11:52:01 INFO - 'run_cmd_checks_enabled': True, 11:52:01 INFO - 'sps_profile': False, 11:52:01 INFO - 'sps_profile_interval': 0, 11:52:01 INFO - 'suite': 'dromaeojs', 11:52:01 INFO - 'system_bits': '64', 11:52:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:52:01 INFO - 'title': 't-yosemite-r7-0019', 11:52:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:52:01 INFO - 'use_talos_json': True, 11:52:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:52:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:52:01 INFO - 'work_dir': 'build'} 11:52:01 INFO - ##### 11:52:01 INFO - ##### Running clobber step. 11:52:01 INFO - ##### 11:52:01 INFO - Running pre-action listener: _resource_record_pre_action 11:52:01 INFO - Running main action method: clobber 11:52:01 INFO - rmtree: /builds/slave/test/build 11:52:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:52:04 INFO - Running post-action listener: _resource_record_post_action 11:52:04 INFO - ##### 11:52:04 INFO - ##### Running read-buildbot-config step. 11:52:04 INFO - ##### 11:52:04 INFO - Running pre-action listener: _resource_record_pre_action 11:52:04 INFO - Running main action method: read_buildbot_config 11:52:04 INFO - Using buildbot properties: 11:52:04 INFO - { 11:52:04 INFO - "properties": { 11:52:04 INFO - "buildnumber": 15, 11:52:04 INFO - "product": "firefox", 11:52:04 INFO - "script_repo_revision": "production", 11:52:04 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:52:04 INFO - "repository": "", 11:52:04 INFO - "buildername": "Rev5 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:52:04 INFO - "buildid": "20151123050554", 11:52:04 INFO - "slavename": "t-yosemite-r7-0019", 11:52:04 INFO - "pgo_build": "False", 11:52:04 INFO - "basedir": "/builds/slave/test", 11:52:04 INFO - "project": "", 11:52:04 INFO - "platform": "yosemite_r7", 11:52:04 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:52:04 INFO - "slavebuilddir": "test", 11:52:04 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:52:04 INFO - "branch": "fx-team", 11:52:04 INFO - "repo_path": "integration/fx-team", 11:52:04 INFO - "stage_platform": "macosx64", 11:52:04 INFO - "builduid": "1951b743c8f04fd2b9d29c2608cfd14d", 11:52:04 INFO - "revision": "5c614c8507b7c7928ffbe5cc3a884d7c55c9405b" 11:52:04 INFO - }, 11:52:04 INFO - "sourcestamp": { 11:52:04 INFO - "repository": "", 11:52:04 INFO - "hasPatch": false, 11:52:04 INFO - "project": "", 11:52:04 INFO - "branch": "fx-team-macosx64-talos", 11:52:04 INFO - "changes": [ 11:52:04 INFO - { 11:52:04 INFO - "category": null, 11:52:04 INFO - "files": [ 11:52:04 INFO - { 11:52:04 INFO - "url": null, 11:52:04 INFO - "name": "https://queue.taskcluster.net/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg" 11:52:04 INFO - } 11:52:04 INFO - ], 11:52:04 INFO - "repository": "", 11:52:04 INFO - "rev": "5c614c8507b7c7928ffbe5cc3a884d7c55c9405b", 11:52:04 INFO - "who": "pbrosset@mozilla.com", 11:52:04 INFO - "when": 1448289287, 11:52:04 INFO - "number": 6695012, 11:52:04 INFO - "comments": "Bug 1198073 - Introduce a pref to set the max length of attributes or not truncat them; r=pbro", 11:52:04 INFO - "project": "", 11:52:04 INFO - "at": "Mon 23 Nov 2015 06:34:47", 11:52:04 INFO - "branch": "fx-team-macosx64-talos", 11:52:04 INFO - "revlink": "", 11:52:04 INFO - "properties": [ 11:52:04 INFO - [ 11:52:04 INFO - "buildid", 11:52:04 INFO - "20151123050554", 11:52:04 INFO - "Change" 11:52:04 INFO - ], 11:52:04 INFO - [ 11:52:04 INFO - "builduid", 11:52:04 INFO - "1951b743c8f04fd2b9d29c2608cfd14d", 11:52:04 INFO - "Change" 11:52:04 INFO - ], 11:52:04 INFO - [ 11:52:04 INFO - "pgo_build", 11:52:04 INFO - "False", 11:52:04 INFO - "Change" 11:52:04 INFO - ] 11:52:04 INFO - ], 11:52:04 INFO - "revision": "5c614c8507b7c7928ffbe5cc3a884d7c55c9405b" 11:52:04 INFO - } 11:52:04 INFO - ], 11:52:04 INFO - "revision": "5c614c8507b7c7928ffbe5cc3a884d7c55c9405b" 11:52:04 INFO - } 11:52:04 INFO - } 11:52:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg. 11:52:04 INFO - Running post-action listener: _resource_record_post_action 11:52:04 INFO - ##### 11:52:04 INFO - ##### Running download-and-extract step. 11:52:04 INFO - ##### 11:52:04 INFO - Running pre-action listener: _resource_record_pre_action 11:52:04 INFO - Running main action method: download_and_extract 11:52:04 INFO - mkdir: /builds/slave/test/build/tests 11:52: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')]} 11:52:04 INFO - https://queue.taskcluster.net/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 11:52:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/test_packages.json 11:52:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/test_packages.json 11:52:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 11:52:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 11:52:05 INFO - Downloaded 1149 bytes. 11:52:05 INFO - Reading from file /builds/slave/test/build/test_packages.json 11:52:05 INFO - Using the following test package requirements: 11:52:05 INFO - {u'common': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 11:52:05 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 11:52:05 INFO - u'firefox-45.0a1.en-US.mac.cppunittest.tests.zip'], 11:52:05 INFO - u'jittest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 11:52:05 INFO - u'jsshell-mac.zip'], 11:52:05 INFO - u'mochitest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 11:52:05 INFO - u'firefox-45.0a1.en-US.mac.mochitest.tests.zip'], 11:52:05 INFO - u'mozbase': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 11:52:05 INFO - u'reftest': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 11:52:05 INFO - u'firefox-45.0a1.en-US.mac.reftest.tests.zip'], 11:52:05 INFO - u'talos': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 11:52:05 INFO - u'firefox-45.0a1.en-US.mac.talos.tests.zip'], 11:52:05 INFO - u'web-platform': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 11:52:05 INFO - u'firefox-45.0a1.en-US.mac.web-platform.tests.zip'], 11:52:05 INFO - u'webapprt': [u'firefox-45.0a1.en-US.mac.common.tests.zip'], 11:52:05 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.mac.common.tests.zip', 11:52:05 INFO - u'firefox-45.0a1.en-US.mac.xpcshell.tests.zip']} 11:52:05 INFO - Downloading packages: [u'firefox-45.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:52:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:05 INFO - https://queue.taskcluster.net/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:52:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 11:52:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 11:52:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/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 11:52:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/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 11:52:06 INFO - Downloaded 17525254 bytes. 11:52:06 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 11:52:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 11:52:06 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 11:52:09 INFO - Return code: 0 11:52:09 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 11:52: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')]} 11:52:09 INFO - https://queue.taskcluster.net/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:52:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 11:52:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.common.tests.zip 11:52:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/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 11:52:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/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 11:52:10 INFO - Downloaded 17525254 bytes. 11:52:10 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 11:52:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip 11:52:10 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.common.tests.zip'] with output_timeout 1760 11:52:14 INFO - Return code: 0 11:52:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:14 INFO - https://queue.taskcluster.net/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:52:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 11:52:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.talos.tests.zip 11:52:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/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 11:52:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/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 11:52:14 INFO - Downloaded 11130997 bytes. 11:52:14 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 11:52:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip 11:52:14 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.mac.talos.tests.zip'] with output_timeout 1760 11:52:15 INFO - Return code: 0 11:52:15 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')]} 11:52:15 INFO - https://queue.taskcluster.net/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:52:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 11:52:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 11:52:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-45.0a1.en-US.mac.dmg 11:52:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PHG0k_EmRGi4_A98lmq0OA/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 11:52:18 INFO - Downloaded 109634929 bytes. 11:52:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 11:52:18 INFO - mkdir: /builds/slave/test/properties 11:52:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:52:18 INFO - Writing to file /builds/slave/test/properties/build_url 11:52:18 INFO - Contents: 11:52:18 INFO - build_url:https://queue.taskcluster.net/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg 11:52:18 INFO - Running post-action listener: _resource_record_post_action 11:52:18 INFO - Running post-action listener: set_extra_try_arguments 11:52:18 INFO - ##### 11:52:18 INFO - ##### Running populate-webroot step. 11:52:18 INFO - ##### 11:52:18 INFO - Running pre-action listener: _resource_record_pre_action 11:52:18 INFO - Running main action method: populate_webroot 11:52:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:52:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:52:18 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 11:52:18 INFO - '../fennec_ids.txt'], 11:52:18 INFO - 'tests': ['tcheck2']}, 11:52:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:52:18 INFO - '--tppagecycles', 11:52:18 INFO - '7'], 11:52:18 INFO - 'tests': ['tsvgm']}}, 11:52:18 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 11:52:18 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 11:52:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:52:18 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 11:52:18 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 11:52:18 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 11:52:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:52:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:18 INFO - 'talos_options': ['--e10s'], 11:52:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:52:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:18 INFO - 'tests': ['damp', 'tps']}, 11:52:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:18 INFO - 'talos_options': ['--e10s'], 11:52:18 INFO - 'tests': ['damp', 'tps']}, 11:52:18 INFO - 'g3': {'tests': ['dromaeo_dom']}, 11:52:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:52:18 INFO - 'other': {'tests': ['a11yr', 11:52:18 INFO - 'ts_paint', 11:52:18 INFO - 'tpaint', 11:52:18 INFO - 'sessionrestore', 11:52:18 INFO - 'sessionrestore_no_auto_restore']}, 11:52:18 INFO - 'other-e10s': {'talos_options': ['--e10s'], 11:52:18 INFO - 'tests': ['a11yr', 11:52:18 INFO - 'ts_paint', 11:52:18 INFO - 'tpaint', 11:52:18 INFO - 'sessionrestore', 11:52:18 INFO - 'sessionrestore_no_auto_restore']}, 11:52:18 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 11:52:18 INFO - 'tests': ['a11yr', 11:52:18 INFO - 'ts_paint', 11:52:18 INFO - 'tpaint', 11:52:18 INFO - 'sessionrestore', 11:52:18 INFO - 'sessionrestore_no_auto_restore']}, 11:52:18 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 11:52:18 INFO - 'tests': ['a11yr', 11:52:18 INFO - 'ts_paint', 11:52:18 INFO - 'tpaint', 11:52:18 INFO - 'sessionrestore', 11:52:18 INFO - 'sessionrestore_no_auto_restore']}, 11:52:18 INFO - 'other_l64': {'tests': ['a11yr', 11:52:18 INFO - 'ts_paint', 11:52:18 INFO - 'tpaint', 11:52:18 INFO - 'sessionrestore', 11:52:18 INFO - 'sessionrestore_no_auto_restore']}, 11:52:18 INFO - 'other_nol64': {'tests': ['a11yr', 11:52:18 INFO - 'ts_paint', 11:52:18 INFO - 'tpaint', 11:52:18 INFO - 'sessionrestore', 11:52:18 INFO - 'sessionrestore_no_auto_restore']}, 11:52:18 INFO - 'svgr': {'tests': ['tsvgx', 11:52:18 INFO - 'tsvgr_opacity', 11:52:18 INFO - 'tart', 11:52:18 INFO - 'tscrollx', 11:52:18 INFO - 'cart']}, 11:52:18 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 11:52:18 INFO - 'tests': ['tsvgx', 11:52:18 INFO - 'tsvgr_opacity', 11:52:18 INFO - 'tart', 11:52:18 INFO - 'tscrollx', 11:52:18 INFO - 'cart']}, 11:52:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:18 INFO - 'tests': ['tp5o']}, 11:52:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:18 INFO - 'talos_options': ['--e10s'], 11:52:18 INFO - 'tests': ['tp5o']}, 11:52:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:18 INFO - 'talos_options': ['--xperf_path', 11:52:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:52:18 INFO - 'tests': ['tp5n']}, 11:52:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:52:18 INFO - 'talos_options': ['--e10s', 11:52:18 INFO - '--xperf_path', 11:52:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:52:18 INFO - 'tests': ['tp5n']}}, 11:52:18 INFO - 'talos.zip': {'path': '', 11:52:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:52:18 INFO - Running post-action listener: _resource_record_post_action 11:52:18 INFO - ##### 11:52:18 INFO - ##### Running create-virtualenv step. 11:52:18 INFO - ##### 11:52:18 INFO - Running pre-action listener: _resource_record_pre_action 11:52:18 INFO - Running main action method: create_virtualenv 11:52:18 INFO - Creating virtualenv /builds/slave/test/build/venv 11:52:18 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 11:52:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:52:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:52:18 INFO - Using real prefix '/tools/python27' 11:52:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:52:18 INFO - Installing distribute.............................................................................................................................................................................................done. 11:52:21 INFO - Installing pip.................done. 11:52:21 INFO - Return code: 0 11:52:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:52:21 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')]} 11:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:21 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')]} 11:52:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:21 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 0x10eb92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb6f228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da4999b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eab69f0>, '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 0x10e815db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa98c8>, '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.swVBe8eiM8/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.XeipRAgqSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 11:52:21 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 11:52:21 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 11:52:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.swVBe8eiM8/Render', 11:52:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:21 INFO - 'HOME': '/Users/cltbld', 11:52:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:21 INFO - 'LOGNAME': 'cltbld', 11:52:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:21 INFO - 'MOZ_NO_REMOTE': '1', 11:52:21 INFO - 'NO_EM_RESTART': '1', 11:52:21 INFO - 'PAGER': '/bin/cat', 11:52:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:21 INFO - 'PWD': '/builds/slave/test', 11:52:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:21 INFO - 'SHELL': '/bin/bash', 11:52:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XeipRAgqSs/Listeners', 11:52:21 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:52:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:21 INFO - 'USER': 'cltbld', 11:52:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:21 INFO - 'XPC_FLAGS': '0x0', 11:52:21 INFO - 'XPC_SERVICE_NAME': '0', 11:52:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:22 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 11:52:22 INFO - Cleaning up... 11:52:22 INFO - Return code: 0 11:52:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 11:52:22 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')]} 11:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:22 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')]} 11:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:22 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 0x10eb92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb6f228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da4999b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eab69f0>, '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 0x10e815db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa98c8>, '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.swVBe8eiM8/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.XeipRAgqSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 11:52:22 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 11:52:22 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 11:52:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.swVBe8eiM8/Render', 11:52:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:22 INFO - 'HOME': '/Users/cltbld', 11:52:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:22 INFO - 'LOGNAME': 'cltbld', 11:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:22 INFO - 'MOZ_NO_REMOTE': '1', 11:52:22 INFO - 'NO_EM_RESTART': '1', 11:52:22 INFO - 'PAGER': '/bin/cat', 11:52:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:22 INFO - 'PWD': '/builds/slave/test', 11:52:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:22 INFO - 'SHELL': '/bin/bash', 11:52:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XeipRAgqSs/Listeners', 11:52:22 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:52:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:22 INFO - 'USER': 'cltbld', 11:52:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:22 INFO - 'XPC_FLAGS': '0x0', 11:52:22 INFO - 'XPC_SERVICE_NAME': '0', 11:52:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:22 INFO - Downloading/unpacking psutil>=0.7.1 11:52:22 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 11:52:22 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 11:52:22 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 11:52:22 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 11:52:22 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 11:52:22 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 11:52:24 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 11:52:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 11:52:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:52:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:52:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:52:24 INFO - Installing collected packages: psutil 11:52:24 INFO - Running setup.py install for psutil 11:52:24 INFO - building 'psutil._psutil_osx' extension 11:52:24 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 11:52:25 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 11:52:25 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 11:52:25 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 11:52:25 INFO - building 'psutil._psutil_posix' extension 11:52:25 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 11:52:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:52:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:52:25 INFO - ^ 11:52:25 INFO - 1 warning generated. 11:52:25 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 11:52:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:52:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:52:25 INFO - Successfully installed psutil 11:52:25 INFO - Cleaning up... 11:52:25 INFO - Return code: 0 11:52:25 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 11:52:25 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')]} 11:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:25 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')]} 11:52:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:25 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 0x10eb92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb6f228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da4999b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eab69f0>, '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 0x10e815db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa98c8>, '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.swVBe8eiM8/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.XeipRAgqSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 11:52:25 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 11:52:25 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 11:52:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.swVBe8eiM8/Render', 11:52:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:25 INFO - 'HOME': '/Users/cltbld', 11:52:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:25 INFO - 'LOGNAME': 'cltbld', 11:52:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:25 INFO - 'MOZ_NO_REMOTE': '1', 11:52:25 INFO - 'NO_EM_RESTART': '1', 11:52:25 INFO - 'PAGER': '/bin/cat', 11:52:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:25 INFO - 'PWD': '/builds/slave/test', 11:52:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:25 INFO - 'SHELL': '/bin/bash', 11:52:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XeipRAgqSs/Listeners', 11:52:25 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:52:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:25 INFO - 'USER': 'cltbld', 11:52:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:25 INFO - 'XPC_FLAGS': '0x0', 11:52:25 INFO - 'XPC_SERVICE_NAME': '0', 11:52:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:52:26 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 11:52:26 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 11:52:26 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 11:52:26 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 11:52:26 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 11:52:26 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 11:52:28 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:52:28 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 11:52:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:52:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:52:28 INFO - Installing collected packages: mozsystemmonitor 11:52:28 INFO - Running setup.py install for mozsystemmonitor 11:52:28 INFO - Successfully installed mozsystemmonitor 11:52:28 INFO - Cleaning up... 11:52:28 INFO - Return code: 0 11:52:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:52:28 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')]} 11:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:28 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')]} 11:52:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:28 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 0x10eb92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb6f228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da4999b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eab69f0>, '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 0x10e815db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa98c8>, '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.swVBe8eiM8/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.XeipRAgqSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 11:52:28 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 11:52:28 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 11:52:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.swVBe8eiM8/Render', 11:52:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:28 INFO - 'HOME': '/Users/cltbld', 11:52:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:28 INFO - 'LOGNAME': 'cltbld', 11:52:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:28 INFO - 'MOZ_NO_REMOTE': '1', 11:52:28 INFO - 'NO_EM_RESTART': '1', 11:52:28 INFO - 'PAGER': '/bin/cat', 11:52:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:28 INFO - 'PWD': '/builds/slave/test', 11:52:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:28 INFO - 'SHELL': '/bin/bash', 11:52:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XeipRAgqSs/Listeners', 11:52:28 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:52:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:28 INFO - 'USER': 'cltbld', 11:52:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:28 INFO - 'XPC_FLAGS': '0x0', 11:52:28 INFO - 'XPC_SERVICE_NAME': '0', 11:52:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:28 INFO - Downloading/unpacking blobuploader==1.2.4 11:52:28 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 11:52:28 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 11:52:28 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 11:52:28 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 11:52:28 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 11:52:28 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 11:52:30 INFO - Downloading blobuploader-1.2.4.tar.gz 11:52:30 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 11:52:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:52:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:52:31 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 11:52:31 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 11:52:31 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 11:52:31 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 11:52:31 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 11:52:31 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 11:52:31 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 11:52:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:52:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:52:31 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 11:52:31 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 11:52:31 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 11:52:31 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 11:52:31 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 11:52:31 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 11:52:32 INFO - Downloading docopt-0.6.1.tar.gz 11:52:32 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 11:52:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:52:32 INFO - Installing collected packages: blobuploader, requests, docopt 11:52:32 INFO - Running setup.py install for blobuploader 11:52:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:52:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:52:32 INFO - Running setup.py install for requests 11:52:32 INFO - Running setup.py install for docopt 11:52:32 INFO - Successfully installed blobuploader requests docopt 11:52:32 INFO - Cleaning up... 11:52:33 INFO - Return code: 0 11:52:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:33 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 0x10eb92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb6f228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da4999b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eab69f0>, '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 0x10e815db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa98c8>, '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.swVBe8eiM8/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.XeipRAgqSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 11:52:33 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 11:52:33 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 11:52:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.swVBe8eiM8/Render', 11:52:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:33 INFO - 'HOME': '/Users/cltbld', 11:52:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:33 INFO - 'LOGNAME': 'cltbld', 11:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:33 INFO - 'MOZ_NO_REMOTE': '1', 11:52:33 INFO - 'NO_EM_RESTART': '1', 11:52:33 INFO - 'PAGER': '/bin/cat', 11:52:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:33 INFO - 'PWD': '/builds/slave/test', 11:52:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:33 INFO - 'SHELL': '/bin/bash', 11:52:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XeipRAgqSs/Listeners', 11:52:33 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:52:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:33 INFO - 'USER': 'cltbld', 11:52:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:33 INFO - 'XPC_FLAGS': '0x0', 11:52:33 INFO - 'XPC_SERVICE_NAME': '0', 11:52:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:52:33 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-V0vP7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:52:33 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-8Jdxpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:52:33 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-rqqGaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:52:33 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-tFOVmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:52:33 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-oN4gbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:52:33 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-WQKz0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:52:33 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-_ryTdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:52:33 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-YnnN2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:52:34 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-ZbzJnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:52:34 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-yYAKvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:52:34 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-wkOKpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:52:34 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-rCI8lk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:52:34 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Kc47ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:52:34 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-cIREqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:34 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Eou2Gp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:52:34 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-QvyW4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:52:34 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-cSaPcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:52:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:52:35 INFO - Running setup.py install for manifestparser 11:52:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:52:35 INFO - Running setup.py install for mozcrash 11:52:35 INFO - Running setup.py install for mozdebug 11:52:35 INFO - Running setup.py install for mozdevice 11:52:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:52:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:52:35 INFO - Running setup.py install for mozfile 11:52:35 INFO - Running setup.py install for mozhttpd 11:52:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:52:35 INFO - Running setup.py install for mozinfo 11:52:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Running setup.py install for mozInstall 11:52:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Running setup.py install for mozleak 11:52:36 INFO - Running setup.py install for mozlog 11:52:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Running setup.py install for moznetwork 11:52:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Running setup.py install for mozprocess 11:52:36 INFO - Running setup.py install for mozprofile 11:52:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:52:36 INFO - Running setup.py install for mozrunner 11:52:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:52:37 INFO - Running setup.py install for mozscreenshot 11:52:37 INFO - Running setup.py install for moztest 11:52:37 INFO - Running setup.py install for mozversion 11:52:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:52:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:52:37 INFO - Cleaning up... 11:52:37 INFO - Return code: 0 11:52:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:37 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 0x10eb92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb6f228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da4999b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eab69f0>, '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 0x10e815db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa98c8>, '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.swVBe8eiM8/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.XeipRAgqSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 11:52:37 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 11:52:37 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 11:52:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.swVBe8eiM8/Render', 11:52:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:37 INFO - 'HOME': '/Users/cltbld', 11:52:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:37 INFO - 'LOGNAME': 'cltbld', 11:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:37 INFO - 'MOZ_NO_REMOTE': '1', 11:52:37 INFO - 'NO_EM_RESTART': '1', 11:52:37 INFO - 'PAGER': '/bin/cat', 11:52:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:37 INFO - 'PWD': '/builds/slave/test', 11:52:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:37 INFO - 'SHELL': '/bin/bash', 11:52:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XeipRAgqSs/Listeners', 11:52:37 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:52:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:37 INFO - 'USER': 'cltbld', 11:52:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:37 INFO - 'XPC_FLAGS': '0x0', 11:52:37 INFO - 'XPC_SERVICE_NAME': '0', 11:52:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:52:37 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-RbpwoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:52:37 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)) 11:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:52:37 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-LnGh8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:52:38 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)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:52:38 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-xQAYsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:52:38 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)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:52:38 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-vph_3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:52:38 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)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:52:38 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-2zxTZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:52:38 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)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:52:38 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-R0sLbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:52:38 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)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:52:38 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-_nHypX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:52:38 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)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:52:38 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-k9dzO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:52:38 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)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:52:38 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-ybBmz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:52:38 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)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:52:38 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Lp9bpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:52:38 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)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:52:38 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-SK8iGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:52:38 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)) 11:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:52:38 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-2_fQGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:52:39 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)) 11:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:52:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-B3GiVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:52:39 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)) 11:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:52:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-iyL6ZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:52:39 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)) 11:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-OC2BjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:39 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)) 11:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:52:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-0ObDRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:52:39 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)) 11:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:52:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-U15w93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:52:39 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)) 11:52:39 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)) 11:52:39 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)) 11:52:39 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)) 11:52:39 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)) 11:52:39 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)) 11:52:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:52:39 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 11:52:39 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 11:52:39 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 11:52:39 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 11:52:39 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 11:52:39 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 11:52:41 INFO - Downloading blessings-1.5.1.tar.gz 11:52:41 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 11:52:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:52:42 INFO - Installing collected packages: blessings 11:52:42 INFO - Running setup.py install for blessings 11:52:42 INFO - Successfully installed blessings 11:52:42 INFO - Cleaning up... 11:52:42 INFO - Return code: 0 11:52:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:52:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:52:42 INFO - Reading from file tmpfile_stdout 11:52:42 INFO - Current package versions: 11:52:42 INFO - blessings == 1.5.1 11:52:42 INFO - blobuploader == 1.2.4 11:52:42 INFO - docopt == 0.6.1 11:52:42 INFO - manifestparser == 1.1 11:52:42 INFO - mozInstall == 1.12 11:52:42 INFO - mozcrash == 0.16 11:52:42 INFO - mozdebug == 0.1 11:52:42 INFO - mozdevice == 0.47 11:52:42 INFO - mozfile == 1.2 11:52:42 INFO - mozhttpd == 0.7 11:52:42 INFO - mozinfo == 0.9 11:52:42 INFO - mozleak == 0.1 11:52:42 INFO - mozlog == 3.0 11:52:42 INFO - moznetwork == 0.27 11:52:42 INFO - mozprocess == 0.22 11:52:42 INFO - mozprofile == 0.27 11:52:42 INFO - mozrunner == 6.11 11:52:42 INFO - mozscreenshot == 0.1 11:52:42 INFO - mozsystemmonitor == 0.0 11:52:42 INFO - moztest == 0.7 11:52:42 INFO - mozversion == 1.4 11:52:42 INFO - psutil == 3.1.1 11:52:42 INFO - requests == 1.2.3 11:52:42 INFO - wsgiref == 0.1.2 11:52:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52: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')]} 11:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52: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')]} 11:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:42 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 0x10eb92490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb6f228>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99da4999b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eab69f0>, '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 0x10e815db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa98c8>, '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.swVBe8eiM8/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.XeipRAgqSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 11:52:42 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 11:52:42 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 11:52:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.swVBe8eiM8/Render', 11:52:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:42 INFO - 'HOME': '/Users/cltbld', 11:52:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:42 INFO - 'LOGNAME': 'cltbld', 11:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:42 INFO - 'MOZ_NO_REMOTE': '1', 11:52:42 INFO - 'NO_EM_RESTART': '1', 11:52:42 INFO - 'PAGER': '/bin/cat', 11:52:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:42 INFO - 'PWD': '/builds/slave/test', 11:52:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:42 INFO - 'SHELL': '/bin/bash', 11:52:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XeipRAgqSs/Listeners', 11:52:42 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:52:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:42 INFO - 'USER': 'cltbld', 11:52:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:42 INFO - 'XPC_FLAGS': '0x0', 11:52:42 INFO - 'XPC_SERVICE_NAME': '0', 11:52:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:42 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)) 11:52:42 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)) 11:52:42 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)) 11:52:42 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)) 11:52:42 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)) 11:52:42 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)) 11:52:42 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)) 11:52:42 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)) 11:52:42 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)) 11:52:42 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)) 11:52:42 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)) 11:52:42 INFO - Cleaning up... 11:52:42 INFO - Return code: 0 11:52:42 INFO - Running post-action listener: _resource_record_post_action 11:52:42 INFO - Running post-action listener: _start_resource_monitoring 11:52:42 INFO - Starting resource monitoring. 11:52:42 INFO - ##### 11:52:42 INFO - ##### Running install step. 11:52:42 INFO - ##### 11:52:42 INFO - Running pre-action listener: _resource_record_pre_action 11:52:42 INFO - Running main action method: install 11:52:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:52:43 INFO - Reading from file tmpfile_stdout 11:52:43 INFO - Detecting whether we're running mozinstall >=1.0... 11:52:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:52:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:52:43 INFO - Reading from file tmpfile_stdout 11:52:43 INFO - Output received: 11:52:43 INFO - Usage: mozinstall [options] installer 11:52:43 INFO - Options: 11:52:43 INFO - -h, --help show this help message and exit 11:52:43 INFO - -d DEST, --destination=DEST 11:52:43 INFO - Directory to install application into. [default: 11:52:43 INFO - "/builds/slave/test"] 11:52:43 INFO - --app=APP Application being installed. [default: firefox] 11:52:43 INFO - mkdir: /builds/slave/test/build/application 11:52:43 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'] 11:52:43 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 11:53:14 INFO - Reading from file tmpfile_stdout 11:53:14 INFO - Output received: 11:53:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:53:14 INFO - Running post-action listener: _resource_record_post_action 11:53:14 INFO - ##### 11:53:14 INFO - ##### Running run-tests step. 11:53:14 INFO - ##### 11:53:14 INFO - Running pre-action listener: _resource_record_pre_action 11:53:14 INFO - Running main action method: run_tests 11:53:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:53:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:53:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:53:14 INFO - 2015-11-23 11:53:14.032 screenresolution[1648:7397] starting screenresolution argv=screenresolution get 11:53:14 INFO - 2015-11-23 11:53:14.034 screenresolution[1648:7397] Display 0: 1600x1200x32@60 11:53:14 INFO - 2015-11-23 11:53:14.040 screenresolution[1649:7399] starting screenresolution argv=screenresolution list 11:53:14 INFO - Available Modes on Display 0 11:53:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:53:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:53:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:53:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:53:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:53:14 INFO - Intel Iris: 11:53:14 INFO - Chipset Model: Intel Iris 11:53:14 INFO - Type: GPU 11:53:14 INFO - Bus: Built-In 11:53:14 INFO - VRAM (Dynamic, Max): 1536 MB 11:53:14 INFO - Vendor: Intel (0x8086) 11:53:14 INFO - Device ID: 0x0a2e 11:53:14 INFO - Revision ID: 0x0009 11:53:14 INFO - Displays: 11:53:14 INFO - Display: 11:53:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:53:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:53:14 INFO - Display Serial Number: 1600x1200 60 11:53:14 INFO - Main Display: Yes 11:53:14 INFO - Mirror: Off 11:53:14 INFO - Online: Yes 11:53:14 INFO - Rotation: Supported 11:53:14 INFO - Return code: 0 11:53:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:53:14 INFO - Python 2.7.3 11:53:14 INFO - Return code: 0 11:53:14 INFO - grabbing minidump binary from tooltool 11:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:53:14 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 0x10eab69f0>, '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 0x10e815db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa98c8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:53:14 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 11:53:14 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 11:53:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:53:14 INFO - Return code: 0 11:53:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:53:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:53:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:53:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:53:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:53:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost'] in /builds/slave/test/build 11:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0019 --webServer localhost 11:53:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.swVBe8eiM8/Render', 11:53:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:14 INFO - 'HOME': '/Users/cltbld', 11:53:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:14 INFO - 'LOGNAME': 'cltbld', 11:53:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:53:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:53:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:14 INFO - 'MOZ_NO_REMOTE': '1', 11:53:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:53:14 INFO - 'NO_EM_RESTART': '1', 11:53:14 INFO - 'PAGER': '/bin/cat', 11:53:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:14 INFO - 'PWD': '/builds/slave/test', 11:53:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:53:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:14 INFO - 'SHELL': '/bin/bash', 11:53:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XeipRAgqSs/Listeners', 11:53:14 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 11:53:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:14 INFO - 'USER': 'cltbld', 11:53:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:14 INFO - 'XPC_FLAGS': '0x0', 11:53:14 INFO - 'XPC_SERVICE_NAME': '0', 11:53:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost'] with output_timeout 3600 11:53:14 INFO - mozversion INFO | application_buildid: 20151123050554 11:53:14 INFO - mozversion INFO | application_changeset: 5c614c8507b7c7928ffbe5cc3a884d7c55c9405b 11:53:14 INFO - mozversion INFO | application_display_name: Nightly 11:53:14 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:53:14 INFO - mozversion INFO | application_name: Firefox 11:53:14 INFO - mozversion INFO | application_remotingname: firefox 11:53:14 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 11:53:14 INFO - mozversion INFO | application_vendor: Mozilla 11:53:14 INFO - mozversion INFO | application_version: 45.0a1 11:53:14 INFO - mozversion INFO | platform_buildid: 20151123050554 11:53:14 INFO - mozversion INFO | platform_changeset: 5c614c8507b7c7928ffbe5cc3a884d7c55c9405b 11:53:14 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 11:53:14 INFO - mozversion INFO | platform_version: 45.0a1 11:53:14 INFO - 2015-11-23 11:53:14,586 DEBUG : using testdate: 1448308394 11:53:14 INFO - 2015-11-23 11:53:14,586 DEBUG : actual date: 1448308394 11:53:14 INFO - 2015-11-23 11:53:14,634 INFO : starting webserver on 'localhost:49270' 11:53:14 INFO - 2015-11-23 11:53:14,635 INFO : Starting test suite t-yosemite-r7-0019 11:53:14 INFO - 2015-11-23 11:53:14,635 INFO : Starting test dromaeo_css 11:53:14 INFO - 2015-11-23 11:53:14,635 DEBUG : operating with platform_type : mac_ 11:53:14 INFO - 2015-11-23 11:53:14,636 INFO : Initialising browser for dromaeo_css test... 11:53:14 INFO - 2015-11-23 11:53:14,648 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmp0LVahL/profile http://localhost:49270/getInfo.html 11:53:16 INFO - 2015-11-23 11:53:16,999 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 11:53:16 INFO - 2015-11-23 11:53:16,999 DEBUG : BROWSER_OUTPUT: colorDepth:24 11:53:16 INFO - 2015-11-23 11:53:16,999 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 11:53:17 INFO - 2015-11-23 11:53:16,999 DEBUG : BROWSER_OUTPUT: __metrics 11:53:17 INFO - 2015-11-23 11:53:17,015 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:53:17 INFO - 2015-11-23 11:53:17,275 DEBUG : BROWSER_OUTPUT: console.error: 11:53:17 INFO - 2015-11-23 11:53:17,276 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction. 11:53:17 INFO - 2015-11-23 11:53:17,276 DEBUG : BROWSER_OUTPUT: Stack: 11:53:17 INFO - 2015-11-23 11:53:17,276 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 11:53:17 INFO - 2015-11-23 11:53:17,277 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 11:53:17 INFO - 2015-11-23 11:53:17,277 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 11:53:17 INFO - 2015-11-23 11:53:17,277 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 11:53:17 INFO - 2015-11-23 11:53:17,277 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 11:53:17 INFO - 2015-11-23 11:53:17,277 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 11:53:17 INFO - 2015-11-23 11:53:17,277 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 11:53:17 INFO - 2015-11-23 11:53:17,277 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7 11:53:17 INFO - 2015-11-23 11:53:17,277 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 11:53:17 INFO - 2015-11-23 11:53:17,277 DEBUG : BROWSER_OUTPUT: 11:53:17 INFO - 2015-11-23 11:53:17,664 INFO : Browser initialized. 11:53:17 INFO - 2015-11-23 11:53:17,664 INFO : Running cycle 1/1 for dromaeo_css test... 11:53:17 INFO - 2015-11-23 11:53:17,664 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmp0LVahL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 11:53:29 INFO - 2015-11-23 11:53:29,535 DEBUG : BROWSER_OUTPUT: RSS: Main: 224399360 11:53:29 INFO - 2015-11-23 11:53:29,536 DEBUG : BROWSER_OUTPUT: 11:54:27 INFO - 2015-11-23 11:54:26,999 DEBUG : BROWSER_OUTPUT: 1448308466998 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] 11:56:32 INFO - 2015-11-23 11:56:32,690 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 11:56:33 INFO - 2015-11-23 11:56:33,937 DEBUG : BROWSER_OUTPUT: RSS: Main: 344653824 11:56:33 INFO - 2015-11-23 11:56:33,938 DEBUG : BROWSER_OUTPUT: 11:59:36 INFO - 2015-11-23 11:59:36,678 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 11:59:37 INFO - 2015-11-23 11:59:37,276 DEBUG : BROWSER_OUTPUT: RSS: Main: 363474944 11:59:37 INFO - 2015-11-23 11:59:37,277 DEBUG : BROWSER_OUTPUT: 12:02:39 INFO - 2015-11-23 12:02:39,265 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 12:02:39 INFO - 2015-11-23 12:02:39,852 DEBUG : BROWSER_OUTPUT: RSS: Main: 336973824 12:02:39 INFO - 2015-11-23 12:02:39,852 DEBUG : BROWSER_OUTPUT: 12:05:41 INFO - 2015-11-23 12:05:41,909 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 12:05:42 INFO - 2015-11-23 12:05:42,510 DEBUG : BROWSER_OUTPUT: RSS: Main: 319127552 12:05:42 INFO - 2015-11-23 12:05:42,510 DEBUG : BROWSER_OUTPUT: 12:08:45 INFO - 2015-11-23 12:08:45,575 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 12:08:46 INFO - 2015-11-23 12:08:46,204 DEBUG : BROWSER_OUTPUT: RSS: Main: 323112960 12:08:46 INFO - 2015-11-23 12:08:46,204 DEBUG : BROWSER_OUTPUT: 12:11:48 INFO - 2015-11-23 12:11:48,705 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 12:11:48 INFO - 2015-11-23 12:11:48,966 DEBUG : BROWSER_OUTPUT: RSS: Main: 370073600 12:11:48 INFO - 2015-11-23 12:11:48,967 DEBUG : BROWSER_OUTPUT: 12:11:48 INFO - 2015-11-23 12:11:48,967 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:11:48 INFO - 2015-11-23 12:11:48,967 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:11:48 INFO - 2015-11-23 12:11:48,967 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:11:48 INFO - 2015-11-23 12:11:48,967 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:11:48 INFO - 2015-11-23 12:11:48,967 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3413.7439692023095;3477.374072666922;3496.8460408065357;3511.806850623215;3532.6114127445976;5738.770449182028;5795.942040579604;5936.198613187218;5961.0760332148475;5993.970030149854;5851.4148585141465;5913.970430147854;5920.082387229975;5929.19955805966;5945.643261404319;5010.123228435018;5320.414754377015;5381;5388.568914486832;5425.945740542603;5406.432324605918;5444.101723215665;5445.782168713266;5446.618736688433;5453.509184173424;5420.34955805302;5429.0227759003365;5436;5442.782288708447;5446.019716451037;5422.8373148805595;5425.294711687495;5426.375966763813;5433.293671822677;5435.972820135894;5230.607704422184;5255.605829562798;5366.651167674088;5384.138537833967;5408.540274076709;5858.443447872445;6012.789552365646;6056.152138700585;6069;6086.847828804271;6292.036236544644;6314.557980941336;6320.146780184662;6342.4926005919415;6345.397187267248;6218.906716399258;6291.905621415683;6336.6831658416895;6341.8097457076365;6387.712552935129;6030.396960303934;6096.542759293071;6139.600925939844;6145.539084568631;6145.96927015371;6508.219013718384;6523.478121750249;6530.118434011442;6540.738370465223;6549.279579246279;6134.957077260912;6241.78153764616;6245.906311405332;6251.4061164189325;6258.843528911813;6275.3724627537795;6309.432151106423;6378.904316435257;6382.968085159638;6414.9679251604375;6286.937130628727;6304.117423560705;6351.4283714465;6363.649999249993;6365.936340636535;6308.243010838729;6322.146510221153;6330.52521060913;6338.936610633835;6371.458426033746;4925.1381008324115;5011.574016208663;5016.874578135576;5034.9426620409395;5049.394072711299;4554.134714404253;4564.6348292136545;4568.040711450632;4578.2903649934315;4592.563706447882;5935.73289201987;5936.1095835625;5952.821415357546;5979.5814292999075;5986.850328741816;6222.564420490613;6263.655498947511;6329.556931014877;6361.01404282337;6385.265694445198;5909.970450147722;5918.644881307136;5951.315598706205;5974.163617093611;5998.070299103559;5557.194206840067;5564.415736347706;5565.16522521625;5566.192902029183;5572.136318870582;6216.813495595139;6335.334789847;6337.112804207322;6349.936500635027;6358.205224346957;6248.375162483897;6259.217597800275;6281.52888533371;6344.71448784815;6350.396712312323;6328.082428047999;6330.683465826598;6342.746290148342;6353.936460635335;6355.968220158869;6346.873062538631;6358.682065896779;6372.490200784019;6378.904316435164;6398.200224971879;5384.9730751346;6173.351798061319;6291.685415729286;6316.526260530571;6332.11350410952;6145.938540614537;6299.6535190564955;6339.96830015847;6361.522885783492;6363.87272254543;6311.621302721851;6320.178376810985;6329.019002054689;6334;6340.778072767431;5167.431582525995;5926.022206335906;6046.244219472566;6049.848753781191;6067.029275315928;5153.330067091254;6270.686465676789;6313.779017734358;6342.841428964312;6365.077063825626;6195.380461953769;6228.937710623017;6241.687915604292;6255.593386429868;6261.186045814015 12:11:48 INFO - 2015-11-23 12:11:48,968 DEBUG : BROWSER_OUTPUT: |1;ext.html;547.321321561264;555.2615022020713;564.5257983294038;598.2581598817468;633.6768248193423;32450;33124;42240.57759422405;42444.78777606115;42488.57511424885;45032.54967450324;45430.091398172015;45561;45604.77197614007;45799.08401831961;8602.784930376729;8772.12278772121;9808.31341806074;9977.201823854111;9987.600495980152;8850.645974161058;8944.01615822259;12078.577249796259;12174.695632609211;12210.694732631711;10646.095081918047;11351.659450216506;11704.941475292611;11762.705932351673;11783.705407364841;8970.730878073668;9103.908960910372;11862.52549898003;11888.940555297255;11902.940485297562;8786.472811631258;12024.87975120255;12158.696032599168;12188.51245950161;12196.14626976112;42033.789831050955;42246;42250.788746056074;42256.366154507705;42274.78862605698;2778.472090302836;2982.835944023078;3273.6071671399586;3533.611302756695;3676.6323367663285;30353;30411.695883041106;30420.391592168253;30644.693553064408;31148.377032459222;2811.1847564206355;2855.7572606328486;2892.6962668919673;2925.224815423914;2979;491170;492919;493012.9999999964;495282;495986;59879.40120598825;60727.69636151791;60817.69591152104;66650.66674666596;71244.72531648117;74415.88376174362;74701.6264918672;74711.25288747043;74712.5057498847;74999.62500187464;16684.74972875408;17135.400260990806;17175.96944183353;17180;17180.656386872193;12841.97264218879;12922.030847686274;12924.224546527239;12945.482180712666;13057.477700892048;21855;22111.889440552695;22176.778232217795;22179.44551386196;22223.55552888933;8218;9161.633534658538;9522.428654280766;9544.52277386128;9581.754371931744;979.2215188924822;990.8662330585423;1032.488917985602;1104.304288298383;1182.905812123782;30020.69979300179;30079;30242.941497047497;30880.38239235203;31030.379392412025;1110.2228440091967;1187.269829055142;1197.3115458611333;1248.1200753468784;1259.552858735136;15680;15744.763828542582;16351.182440877908;16542.0901850397;16798;544891;545796;546375;546539;549647;62771.74456510936;63208.41978950563;63329.05006424815;63333.683331583044;63488.04767928485;1457.9416823326949;1483.5623491069944;1498.7077519883735;1499.272852666456;1637.3450619752196;31147.909823156082;31284.217894552818;31362.902298419445;31455.842720786248;31859;54302.45697542974;54885.90228195334;54944;54952.450475496335;55114.17328740152;498501;500242;500955.49522252154;501850;502364;28991;29555;33271.83364083164;34447.55524447535;34452.310953781285;44262;45717.771411142734;46299.84250393632;46463.53536464592;47034.76482617565;37402.43896341611;37967;38455.23089538138;38562.22875542417;38872.805635971636;30967;36537.81731091328;36843.81578092092;37836.05409864776;38387.6161238384;36615;37959.81020094882;38649.03377415587;38680.41979370368;38987.41518877276;30173.698263017966;30517.847410762803;33263;33340.66659333376;33529;19459.318923837596;24229.75770242275;24539.509209815347;25116.372090697878;26253.081142159932 12:11:48 INFO - 2015-11-23 12:11:48,971 DEBUG : BROWSER_OUTPUT: |2;jquery.html;357.30682475996565;359.6816817116851;363.2100182103923;364.5588837506616;369.4144780522871;15779.116345510309;18045.909770451162;19376.93426861522;20900.477488062807;21614.89192554037;20244.190232390687;20276.898615506903;20562.897185514088;20615.587688246225;20635.48411289715;3968.987908083435;5163.380394352665;5320.787168513255;5323.441038690939;5332.200169974515;3697.5008373869587;3737.9626203737885;5836.854078648046;6024.5180385569265;6025.035994240922;5859.238299021143;5865.061590145578;5928.792492262772;5977.850553736148;5978.312494063173;5101.770420331112;5886.6468011919205;5924.348321684654;5927;5935.28776546815;4878.609711223129;5525.419830917736;5649.745761440704;5658.632188907749;5690.857728556778;22746;22784.54430911389;24610.507789844283;25300.493990120096;26247;2011.718359429681;2041.8468614853796;2125.914963401462;2141.539568992674;2197.6593627987686;9687.70936871895;9951.850722239173;12239.510419583206;14713.632159195999;15293.235338233155;1672.9414470493475;1674.1294526846093;1707.0952395230547;1726.7064599018072;1732.8613710903098;87070;104871.95128048699;107825;108229;109804;10908.727281817859;10917.890821091847;10946.890531094587;11007.339559626447;11068.501917413736;12973.870261297456;13038.41327140281;13040.673983150496;13072.738545229233;13076;8479.152084791594;8518.275946544612;8539.14608539141;8568.314534837198;8583;7051.788446346515;8669.609867555975;8673.956630216935;8748.606312715943;8768.167024132825;3970.960290397059;4240.045989652303;4315.741055536678;4348.108637729258;4372.344148377769;1554.564721877876;1715.2452920714845;1732.2984191402502;1761.8414342709218;1770.3184274054581;917.0783362720423;933.8187193200607;960.7454024683465;985.1183191043975;1006.0744115413837;16835.74746378805;19525.51186220356;19607.627466077523;20115.49711257201;20336.389908302775;814.3647954595425;910.5583791860943;935.4854829843558;990.1929927109383;995.9940460135316;9258.488434322508;10934.671959841218;10975.780484390267;10999.23005389615;11009.394483303495;753135;759626;765754;768182;778756;100995;120336;122948.38525807313;123462.14806777905;127003;1222.028487352539;1225.08730995409;1234.1977714485656;1234.327291626066;1241.2490443281865;17933.910330448263;17969.460916172535;18132.546686332946;18142.002189879684;18319.816801832345;51863.22205167001;51882;51954.74022629863;52379.73810130925;52690.47309526856;75098.87351689838;75284.62357688177;75494;76444;77085;9656.613735450785;9865.506724663595;9992.10071093605;10083.848742269018;10137.341072830242;11804.645860624196;17972.55068623295;19937.00314984274;20001.19995200234;20222;15816;15881.444149454714;16077.598060048593;16184.757228641345;16316.26576804023;15623.765643515584;15760.60598485047;15847.049177049414;15876.52370428889;16046.919765401099;11857.940710296738;15813.920930395274;15834.841651583798;16479.34082636729;16504.092274924515;9524.619015239312;9591.520423978913;9592.376495527767;9633.133018028411;9760.365576237522;10039.096481316716;10758.784824303626;15304.846951530342;15816.367345306058;15887.205639717737 12:11:48 INFO - 2015-11-23 12:11:48,973 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1567.498400511836;1572.5911263071591;1658.8589969852562;1888.1786422906036;1895.5355937795243;7292.854142917138;7741;8018.91981080189;8179.263866252036;8182.427230093896;7987.880181797278;8025.23760242776;8150.9592452037805;8160.26557609815;8169.30560902324;2021.969670454937;2096.3186964236684;2328.7321957974796;2353.1646265575732;2353.188150088217;2171.6525355943054;2347.7065366829147;2389.9641505377435;2390.1514962188435;2390.8445951013214;2300.608896487601;2339.4853132310864;2345.144022431817;2348.38941875112;2350.5063936573342;2107.4309936317263;2152.806247437738;2157.8165855902266;2243;2266.286119872235;2317.5712493188644;2362.692849929498;2364.397078744926;2367.490989437279;2368.940776480602;7718.922810771876;8082.757517274491;8094;8094.838103237902;8116.35069194463;5048.444671086178;5098.464661210556;5104.310918026056;5130.846074617767;5131.717755523445;3478.6869181773764;3488.5988111367265;3502.1069627245142;3566.857325706969;3572.821358932043;1062.5909025025344;1078.0459293525207;1619.9433019844248;1621.2056092514702;1650.1996531682125;10756.247062705614;10872.728181795439;10884.29252098619;10887.564497420093;10918.126549875991;4463.419755431773;4475.977620111878;4530.909381812346;4541.3869127668;4577.725336479822;8827.602757875911;8842.778930526789;8844;8850.336224783166;8850.645974160961;5044.974775126101;5056.191009438546;5078.466760990117;5085.160948443466;5094.566961808214;4690.530946905282;4701.388819453449;4706.905861882743;4732.313814496879;4751.714897106184;3496.5279687241896;3569.268299998494;3592.964070359263;3616.692581130581;3623.710103191738;1132.2413982631656;1152.153167421944;1152.573547787324;1156.8033434316187;1162.5001249462812;1357.267075779088;1358.273323771797;1414.7099844531847;1414.7877818327127;1416.3060100550656;7727.922720772833;7768.689252429953;7843.019622547182;7845.921540784633;7960.4825686330205;1182.7279725662975;1185.6561597136733;1194.3132698698282;1204.5121725701106;1217.969550761238;5757.913631295534;5877;5905.881882362329;5913.201717768046;6138.693065346715;8776.736697899074;9390.295727820305;9637;9638.42169469834;9744.756381090388;9481.573329200064;9579.616815327448;9606.951965240129;9619.807603848023;9678.95160524193;7690.846373043976;7731.188225236382;7837.1770964047955;7864.016997875266;7899;7554;7629.16079231273;7642.5032372895585;7662.272084151997;7664.961675191588;7530.510516816389;8683.956580217311;8850.33622478304;8952.865707014269;8987.146221108984;8990.190882820578;9078.591463384031;9111.954440227757;9115.225205857576;9153.221976132103;6948.965255173692;7215.314545118205;7245.673944672397;7267.854642907007;7279.162896266997;7829.647665854936;7863.331616812635;7872.606369681607;7879.448438609243;7897.605119744105;7220.74727384539;7325.194228634952;7356.705731770883;7439.628018599156;7473.701051958078;7167.92481127835;7191.3887319578425;7207.891881621675;7368.226336234832;7370.44721645868;7349.77950661481;7383.889241661487;7401.888971665322;7404.222556631476;7405.037345145097;6457.61161350312;6706;6713.1944166700305;6739.0902228198565;6766.5263431559315;6272.968635156795;6877.277885822116;6902.482313826383;6903.0680858083515;6942.2363540011565 12:11:48 INFO - 2015-11-23 12:11:48,974 DEBUG : BROWSER_OUTPUT: |4;prototype.html;324.87817068599276;329.1934759838397;333.65800054943696;335.43479237484837;338.80180094644646;6751.426128779053;6773.288804675512;6779.559328643635;6785.592864428138;6810.421114205286;5401.918971215425;6549.672516374186;6557.278699343068;6567.916293811527;6577.901331480031;4984.526469985355;5005.1491246488;5056.140456122468;5062.215356619729;5083.974580127113;4456.3315502674695;4788.329633851264;5049.267856160873;5088.4911508849;5107.336046313974;4185.665146788249;4763.404574428197;4841;4865.099956508039;4984.925226121611;4948.925766113512;4953.851384458472;4959.628027897904;4991.07665081961;4994.35073440454;3569.8750543731103;4276.080642661812;4279.721818081845;4280.550542193056;4283.571642835723;6393.808185754435;6474.093626892239;6550.475961923083;6567.408933195988;6589.143411356541;5958.821235362946;5967.343592204854;5984.820455386345;5998.700064996733;6005.6997150142315;5927.496162826165;5935.9406405935815;6955.6869940852785;7263.60050197239;7278.781636550859;4810.687305325143;4815.518448155191;4817.927731084036;4822.638302127354;4831.202851529493;7441.776746697607;7717.228277172294;7723.343515801163;7735.497192682514;7746.147923728386;4891.608671306287;4895.290182923456;4899.387576552931;4904.435989861212;4909.484504127086;4973.403191617029;4977.452480227172;4995.275685025724;4997.85006449807;5000.049990501793;4887.437944636412;5763.769449221984;5872.148538461985;5958.285005799332;5964.433378829004;7287.453440991947;7294.781156565312;7308.5614863108385;7326.267373262738;7329.827227643552;1704.0797969096798;1729.6886560419246;1734.0982689001648;1735.2104792319717;1736.5484973906623;1104.2325583719398;1113.209621168963;1131.6548452721834;1136.0400461609927;1139.418896362861;6777.864442711217;6780.118584584071;6790.5925644461495;6797.320267973262;6804.21751498584;7352.301531354513;7578.658960346797;7582.393408527305;7592.658330375147;7593.202713715091;5371;6293.842653933688;6609.438197753245;6640.734370625218;6705.195376554747;4024.476818013698;4056.087380339459;4056.728232189983;4057.411675307123;4063.309237429644;7370.6683199255085;7662.233776622294;7665.463417560717;7709.3447057000785;7712.15166331703;7629.122650895107;7885.408594355446;7911.802204944923;7915.287624113857;7923.7226697065325;7684.6926122954455;7690.115636701739;7696.576688282198;7708.884366734392;7709.807254818675;7632.045994250719;7638.541687498546;7639.236076392316;7645.61771911391;7646.694132234871;7252.202257751537;7486.139094004041;7518.736844210426;7538.849223015619;7548.660310285941;7667.0416197975255;7669.693212271447;7674.61626918663;7690.577018264049;7692.538447693157;4807.278908163804;4919.508049195052;4926.753662316942;4928.112939670894;4935.136351138464;7615.581143037186;7616.8476630468185;7622.733204337822;7623.618819058914;7635.46551741395;7277.708891644275;7297.562146271241;7300.671469783979;7302.598357090198;7314.597697126496;7071.469639776935;7298.525595836254;7311.049563556916;7312.341889229995;7349.522281051714;7152.606606636685;7154;7155.499115062104;7157.606331651809;7172.641367931687;6969.128858892638;7212.783616491513;7215.567065976058;7232;7396.963015185105;7216.386607138242;7218.133823940951;7220.375415531674;7244.4566657499845;7256.673449694882 12:11:48 INFO - 2015-11-23 12:11:48,977 DEBUG : BROWSER_OUTPUT: |5;yui.html;737.7639155470249;803.3693550562809;806.1173015547977;822.7613991942335;874.7725591346256;15122.168280744554;15559;15559.910806243568;15599.220038998033;15601.453949111756;13326.800097998537;13486;14272.429102835875;14342.928285358585;14369.281535923188;801.6793282686942;931.455098767222;952.8618350339196;956.5695437053319;957.602594923106;948.8102379524088;961.2886464016613;963.7542426681186;965.3580369054571;966.5553845231167;997.1424574865609;1012.7417508535314;1013.8681971343715;1016.547636301846;1020.3724709303762;1004.5529739266029;1005.8642083318731;1006.4011912911806;1006.7634105985088;1009.8788145422523;756.3042001358763;760.4904713841739;761.8095476130967;762.6377470701428;762.9542227466314;13546.187228766206;13702.177869327872;13714.93142534291;13721;15200.923995380062;12407.44166512509;12436;12649.241045537297;12729.745405091846;13288.800667989892;10264.846027309597;10373.948130259301;10400.384575356462;10454.738631534197;10887.292325998786;3451.7411194160286;3550.1479644885044;3566;3615.07815507044;3616.132128289218;66388.6680566594;75330.24669753287;75649;76787.46425071468;85243;9547.236221102296;9621.711348659552;9644.276679249084;9683.031696830261;10057.647982320585;21145.68281475779;21790.237341692966;21819.345419637437;21864.45338866541;21868.78131218699;7551.509151905108;7596.3922886168975;7642;7671.462997590227;7674.309312161932;4145.067359844011;4172;4178.22702799983;4179.770112643773;4187.91624167515;3577.123604716861;3623.4927110204594;3623.836927338276;3623.8912832615065;3627.111357717376;609.0438012320702;624.2633692243197;640.5996252342286;654.1692051095083;661.3221448015822;510.92080727487297;512.6733241619576;519.7661052526378;523.9607029472804;526.7182057599166;8617.35369847264;8656.8701469478;8680.435771674822;8728.519931403835;8895.644174233088;585.4321308330913;603.818854343704;616.1743264026227;619.6746707978257;629.7418058595954;3894.571597124314;4004.379321205218;4010.3583426651594;4135.793210339471;4199.202151591188;100514.4974275124;100575;100962;104110;104322;63351;80630;81409;82232.76650850242;89113.55443222742;74520.44933035388;75281;76755;77708.61145694235;79192;7537.397008239437;8946.463212207289;8988.820223595361;9175.54122293896;9189.172974432333;53430.46569534255;54054.45945540494;54060.459395405545;54293;54642;73522.89715654163;77791;79207.20792791998;79541;80198;9684.757881052748;11200.159988001096;11227.989480946753;11328;11725.41372931367;11752.471138798946;12144.089193310714;12577.245365277746;12615.747685046064;13336.19982801035;11478.713032174262;11642.226122133237;11693.532258709556;12149.878501215228;12424.75150497003;10047.949760251153;10127.797444051224;10489.055984961391;10590.576376944837;10995.615153469591;11646.068314534987;11655.825162622737;11958.940205298917;12038.398080095785;12085.69785755328;9688.709338719567;9891.604335826487;9992.601035855132;10241.385516869012;10327;9224.425154263377;9556.904430955601;9980.500974951368;9999.800004000024;10067.798644027225 12:11:48 INFO - 2015-11-23 12:11:48,978 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:11:48 INFO - 2015-11-23 12:11:48,978 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:11:48 INFO - 2015-11-23 12:11:48,978 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1053 12:11:48 INFO - 2015-11-23 12:11:48,978 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:11:48 INFO - 2015-11-23 12:11:48,978 DEBUG : BROWSER_OUTPUT: __startTimestamp1448309508963__endTimestamp 12:11:48 INFO - 2015-11-23 12:11:48,978 DEBUG : BROWSER_OUTPUT: 12:11:48 INFO - 2015-11-23 12:11:48,978 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:11:48 INFO - 2015-11-23 12:11:48,978 DEBUG : BROWSER_OUTPUT: Number of tests: 6 12:11:48 INFO - 2015-11-23 12:11:48,978 DEBUG : BROWSER_OUTPUT: 12:11:48 INFO - 2015-11-23 12:11:48,978 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5889.89 Median:6145.94 stddev:637.59 (10.4%) stddev-sans-first:609.24 12:11:48 INFO - 2015-11-23 12:11:48,978 DEBUG : BROWSER_OUTPUT: Values: 3413.7 3477.4 3496.8 3511.8 3532.6 5738.8 5795.9 5936.2 5961.1 5994.0 5851.4 5914.0 5920.1 5929.2 5945.6 5010.1 5320.4 5381.0 5388.6 5425.9 5406.4 5444.1 5445.8 5446.6 5453.5 5420.3 5429.0 5436.0 5442.8 5446.0 5422.8 5425.3 5426.4 5433.3 5436.0 5230.6 5255.6 5366.7 5384.1 5408.5 5858.4 6012.8 6056.2 6069.0 6086.8 6292.0 6314.6 6320.1 6342.5 6345.4 6218.9 6291.9 6336.7 6341.8 6387.7 6030.4 6096.5 6139.6 6145.5 6146.0 6508.2 6523.5 6530.1 6540.7 6549.3 6135.0 6241.8 6245.9 6251.4 6258.8 6275.4 6309.4 6378.9 6383.0 6415.0 6286.9 6304.1 6351.4 6363.6 6365.9 6308.2 6322.1 6330.5 6338.9 6371.5 4925.1 5011.6 5016.9 5034.9 5049.4 4554.1 4564.6 4568.0 4578.3 4592.6 5935.7 5936.1 5952.8 5979.6 5986.9 6222.6 6263.7 6329.6 6361.0 6385.3 5910.0 5918.6 5951.3 5974.2 5998.1 5557.2 5564.4 5565.2 5566.2 5572.1 6216.8 6335.3 6337.1 6349.9 6358.2 6248.4 6259.2 6281.5 6344.7 6350.4 6328.1 6330.7 6342.7 6353.9 6356.0 6346.9 6358.7 6372.5 6378.9 6398.2 5385.0 6173.4 6291.7 6316.5 6332.1 6145.9 6299.7 6340.0 6361.5 6363.9 6311.6 6320.2 6329.0 6334.0 6340.8 5167.4 5926.0 6046.2 6049.8 6067.0 5153.3 6270.7 6313.8 6342.8 6365.1 6195.4 6228.9 6241.7 6255.6 6261.2 12:11:48 INFO - 2015-11-23 12:11:48,978 DEBUG : BROWSER_OUTPUT: 12:11:48 INFO - 2015-11-23 12:11:48,979 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:66810.54 Median:36576.41 stddev:136644.23 (373.6%) stddev-sans-first:136936.99 12:11:48 INFO - 2015-11-23 12:11:48,980 DEBUG : BROWSER_OUTPUT: Values: 547.3 555.3 564.5 598.3 633.7 32450.0 33124.0 42240.6 42444.8 42488.6 45032.5 45430.1 45561.0 45604.8 45799.1 8602.8 8772.1 9808.3 9977.2 9987.6 8850.6 8944.0 12078.6 12174.7 12210.7 10646.1 11351.7 11704.9 11762.7 11783.7 8970.7 9103.9 11862.5 11888.9 11902.9 8786.5 12024.9 12158.7 12188.5 12196.1 42033.8 42246.0 42250.8 42256.4 42274.8 2778.5 2982.8 3273.6 3533.6 3676.6 30353.0 30411.7 30420.4 30644.7 31148.4 2811.2 2855.8 2892.7 2925.2 2979.0 491170.0 492919.0 493013.0 495282.0 495986.0 59879.4 60727.7 60817.7 66650.7 71244.7 74415.9 74701.6 74711.3 74712.5 74999.6 16684.7 17135.4 17176.0 17180.0 17180.7 12842.0 12922.0 12924.2 12945.5 13057.5 21855.0 22111.9 22176.8 22179.4 22223.6 8218.0 9161.6 9522.4 9544.5 9581.8 979.2 990.9 1032.5 1104.3 1182.9 30020.7 30079.0 30242.9 30880.4 31030.4 1110.2 1187.3 1197.3 1248.1 1259.6 15680.0 15744.8 16351.2 16542.1 16798.0 544891.0 545796.0 546375.0 546539.0 549647.0 62771.7 63208.4 63329.1 63333.7 63488.0 1457.9 1483.6 1498.7 1499.3 1637.3 31147.9 31284.2 31362.9 31455.8 31859.0 54302.5 54885.9 54944.0 54952.5 55114.2 498501.0 500242.0 500955.5 501850.0 502364.0 28991.0 29555.0 33271.8 34447.6 34452.3 44262.0 45717.8 46299.8 46463.5 47034.8 37402.4 37967.0 38455.2 38562.2 38872.8 30967.0 36537.8 36843.8 37836.1 38387.6 36615.0 37959.8 38649.0 38680.4 38987.4 30173.7 30517.8 33263.0 33340.7 33529.0 19459.3 24229.8 24539.5 25116.4 26253.1 12:11:48 INFO - 2015-11-23 12:11:48,980 DEBUG : BROWSER_OUTPUT: 12:11:48 INFO - 2015-11-23 12:11:48,980 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:39436.13 Median:2133.73 stddev:125892.70 (5900.1%) stddev-sans-first:126211.66 12:11:48 INFO - 2015-11-23 12:11:48,980 DEBUG : BROWSER_OUTPUT: Values: 357.3 359.7 363.2 364.6 369.4 15779.1 18045.9 19376.9 20900.5 21614.9 20244.2 20276.9 20562.9 20615.6 20635.5 3969.0 5163.4 5320.8 5323.4 5332.2 3697.5 3738.0 5836.9 6024.5 6025.0 5859.2 5865.1 5928.8 5977.9 5978.3 5101.8 5886.6 5924.3 5927.0 5935.3 4878.6 5525.4 5649.7 5658.6 5690.9 22746.0 22784.5 24610.5 25300.5 26247.0 2011.7 2041.8 2125.9 2141.5 2197.7 9687.7 9951.9 12239.5 14713.6 15293.2 1672.9 1674.1 1707.1 1726.7 1732.9 87070.0 104872.0 107825.0 108229.0 109804.0 10908.7 10917.9 10946.9 11007.3 11068.5 12973.9 13038.4 13040.7 13072.7 13076.0 8479.2 8518.3 8539.1 8568.3 8583.0 7051.8 8669.6 8674.0 8748.6 8768.2 3971.0 4240.0 4315.7 4348.1 4372.3 1554.6 1715.2 1732.3 1761.8 1770.3 917.1 933.8 960.7 985.1 1006.1 16835.7 19525.5 19607.6 20115.5 20336.4 814.4 910.6 935.5 990.2 996.0 9258.5 10934.7 10975.8 10999.2 11009.4 753135.0 759626.0 765754.0 768182.0 778756.0 100995.0 120336.0 122948.4 123462.1 127003.0 1222.0 1225.1 1234.2 1234.3 1241.2 17933.9 17969.5 18132.5 18142.0 18319.8 51863.2 51882.0 51954.7 52379.7 52690.5 75098.9 75284.6 75494.0 76444.0 77085.0 9656.6 9865.5 9992.1 10083.8 10137.3 11804.6 17972.6 19937.0 20001.2 20222.0 15816.0 15881.4 16077.6 16184.8 16316.3 15623.8 15760.6 15847.0 15876.5 16046.9 11857.9 15813.9 15834.8 16479.3 16504.1 9524.6 9591.5 9592.4 9633.1 9760.4 10039.1 10758.8 15304.8 15816.4 15887.2 12:11:48 INFO - 2015-11-23 12:11:48,980 DEBUG : BROWSER_OUTPUT: 12:11:48 INFO - 2015-11-23 12:11:48,981 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5693.47 Median:6365.29 stddev:2878.04 (45.2%) stddev-sans-first:2869.40 12:11:48 INFO - 2015-11-23 12:11:48,982 DEBUG : BROWSER_OUTPUT: Values: 1567.5 1572.6 1658.9 1888.2 1895.5 7292.9 7741.0 8018.9 8179.3 8182.4 7987.9 8025.2 8151.0 8160.3 8169.3 2022.0 2096.3 2328.7 2353.2 2353.2 2171.7 2347.7 2390.0 2390.2 2390.8 2300.6 2339.5 2345.1 2348.4 2350.5 2107.4 2152.8 2157.8 2243.0 2266.3 2317.6 2362.7 2364.4 2367.5 2368.9 7718.9 8082.8 8094.0 8094.8 8116.4 5048.4 5098.5 5104.3 5130.8 5131.7 3478.7 3488.6 3502.1 3566.9 3572.8 1062.6 1078.0 1619.9 1621.2 1650.2 10756.2 10872.7 10884.3 10887.6 10918.1 4463.4 4476.0 4530.9 4541.4 4577.7 8827.6 8842.8 8844.0 8850.3 8850.6 5045.0 5056.2 5078.5 5085.2 5094.6 4690.5 4701.4 4706.9 4732.3 4751.7 3496.5 3569.3 3593.0 3616.7 3623.7 1132.2 1152.2 1152.6 1156.8 1162.5 1357.3 1358.3 1414.7 1414.8 1416.3 7727.9 7768.7 7843.0 7845.9 7960.5 1182.7 1185.7 1194.3 1204.5 1218.0 5757.9 5877.0 5905.9 5913.2 6138.7 8776.7 9390.3 9637.0 9638.4 9744.8 9481.6 9579.6 9607.0 9619.8 9679.0 7690.8 7731.2 7837.2 7864.0 7899.0 7554.0 7629.2 7642.5 7662.3 7665.0 7530.5 8684.0 8850.3 8952.9 8987.1 8990.2 9078.6 9112.0 9115.2 9153.2 6949.0 7215.3 7245.7 7267.9 7279.2 7829.6 7863.3 7872.6 7879.4 7897.6 7220.7 7325.2 7356.7 7439.6 7473.7 7167.9 7191.4 7207.9 7368.2 7370.4 7349.8 7383.9 7401.9 7404.2 7405.0 6457.6 6706.0 6713.2 6739.1 6766.5 6273.0 6877.3 6902.5 6903.1 6942.2 12:11:48 INFO - 2015-11-23 12:11:48,982 DEBUG : BROWSER_OUTPUT: 12:11:48 INFO - 2015-11-23 12:11:48,982 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5943.76 Median:6672.96 stddev:1892.92 (28.4%) stddev-sans-first:1850.65 12:11:48 INFO - 2015-11-23 12:11:48,983 DEBUG : BROWSER_OUTPUT: Values: 324.9 329.2 333.7 335.4 338.8 6751.4 6773.3 6779.6 6785.6 6810.4 5401.9 6549.7 6557.3 6567.9 6577.9 4984.5 5005.1 5056.1 5062.2 5084.0 4456.3 4788.3 5049.3 5088.5 5107.3 4185.7 4763.4 4841.0 4865.1 4984.9 4948.9 4953.9 4959.6 4991.1 4994.4 3569.9 4276.1 4279.7 4280.6 4283.6 6393.8 6474.1 6550.5 6567.4 6589.1 5958.8 5967.3 5984.8 5998.7 6005.7 5927.5 5935.9 6955.7 7263.6 7278.8 4810.7 4815.5 4817.9 4822.6 4831.2 7441.8 7717.2 7723.3 7735.5 7746.1 4891.6 4895.3 4899.4 4904.4 4909.5 4973.4 4977.5 4995.3 4997.9 5000.0 4887.4 5763.8 5872.1 5958.3 5964.4 7287.5 7294.8 7308.6 7326.3 7329.8 1704.1 1729.7 1734.1 1735.2 1736.5 1104.2 1113.2 1131.7 1136.0 1139.4 6777.9 6780.1 6790.6 6797.3 6804.2 7352.3 7578.7 7582.4 7592.7 7593.2 5371.0 6293.8 6609.4 6640.7 6705.2 4024.5 4056.1 4056.7 4057.4 4063.3 7370.7 7662.2 7665.5 7709.3 7712.2 7629.1 7885.4 7911.8 7915.3 7923.7 7684.7 7690.1 7696.6 7708.9 7709.8 7632.0 7638.5 7639.2 7645.6 7646.7 7252.2 7486.1 7518.7 7538.8 7548.7 7667.0 7669.7 7674.6 7690.6 7692.5 4807.3 4919.5 4926.8 4928.1 4935.1 7615.6 7616.8 7622.7 7623.6 7635.5 7277.7 7297.6 7300.7 7302.6 7314.6 7071.5 7298.5 7311.0 7312.3 7349.5 7152.6 7154.0 7155.5 7157.6 7172.6 6969.1 7212.8 7215.6 7232.0 7397.0 7216.4 7218.1 7220.4 7244.5 7256.7 12:11:48 INFO - 2015-11-23 12:11:48,983 DEBUG : BROWSER_OUTPUT: 12:11:48 INFO - 2015-11-23 12:11:48,983 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:19151.02 Median:4193.56 stddev:27414.20 (653.7%) stddev-sans-first:27456.24 12:11:48 INFO - 2015-11-23 12:11:48,985 DEBUG : BROWSER_OUTPUT: Values: 737.8 803.4 806.1 822.8 874.8 15122.2 15559.0 15559.9 15599.2 15601.5 13326.8 13486.0 14272.4 14342.9 14369.3 801.7 931.5 952.9 956.6 957.6 948.8 961.3 963.8 965.4 966.6 997.1 1012.7 1013.9 1016.5 1020.4 1004.6 1005.9 1006.4 1006.8 1009.9 756.3 760.5 761.8 762.6 763.0 13546.2 13702.2 13714.9 13721.0 15200.9 12407.4 12436.0 12649.2 12729.7 13288.8 10264.8 10373.9 10400.4 10454.7 10887.3 3451.7 3550.1 3566.0 3615.1 3616.1 66388.7 75330.2 75649.0 76787.5 85243.0 9547.2 9621.7 9644.3 9683.0 10057.6 21145.7 21790.2 21819.3 21864.5 21868.8 7551.5 7596.4 7642.0 7671.5 7674.3 4145.1 4172.0 4178.2 4179.8 4187.9 3577.1 3623.5 3623.8 3623.9 3627.1 609.0 624.3 640.6 654.2 661.3 510.9 512.7 519.8 524.0 526.7 8617.4 8656.9 8680.4 8728.5 8895.6 585.4 603.8 616.2 619.7 629.7 3894.6 4004.4 4010.4 4135.8 4199.2 100514.5 100575.0 100962.0 104110.0 104322.0 63351.0 80630.0 81409.0 82232.8 89113.6 74520.4 75281.0 76755.0 77708.6 79192.0 7537.4 8946.5 8988.8 9175.5 9189.2 53430.5 54054.5 54060.5 54293.0 54642.0 73522.9 77791.0 79207.2 79541.0 80198.0 9684.8 11200.2 11228.0 11328.0 11725.4 11752.5 12144.1 12577.2 12615.7 13336.2 11478.7 11642.2 11693.5 12149.9 12424.8 10047.9 10127.8 10489.1 10590.6 10995.6 11646.1 11655.8 11958.9 12038.4 12085.7 9688.7 9891.6 9992.6 10241.4 10327.0 9224.4 9556.9 9980.5 9999.8 10067.8 12:11:48 INFO - 2015-11-23 12:11:48,985 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:11:48 INFO - 2015-11-23 12:11:48,985 DEBUG : BROWSER_OUTPUT: 12:11:49 INFO - 2015-11-23 12:11:49,424 INFO : Browser exited with error code: 0 12:11:49 INFO - 2015-11-23 12:11:49,446 INFO : Completed test dromaeo_css (00:18:34) 12:11:49 INFO - 2015-11-23 12:11:49,447 INFO : Starting test kraken 12:11:49 INFO - 2015-11-23 12:11:49,447 DEBUG : operating with platform_type : mac_ 12:11:49 INFO - 2015-11-23 12:11:49,447 INFO : Initialising browser for kraken test... 12:11:49 INFO - 2015-11-23 12:11:49,460 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpIu0cxv/profile http://localhost:49270/getInfo.html 12:11:51 INFO - 2015-11-23 12:11:51,453 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:11:51 INFO - 2015-11-23 12:11:51,453 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:11:51 INFO - 2015-11-23 12:11:51,453 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 12:11:51 INFO - 2015-11-23 12:11:51,453 DEBUG : BROWSER_OUTPUT: __metrics 12:11:51 INFO - 2015-11-23 12:11:51,470 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:11:52 INFO - 2015-11-23 12:11:52,203 INFO : Browser initialized. 12:11:52 INFO - 2015-11-23 12:11:52,203 INFO : Running cycle 1/1 for kraken test... 12:11:52 INFO - 2015-11-23 12:11:52,204 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpIu0cxv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:12:03 INFO - 2015-11-23 12:12:03,982 DEBUG : BROWSER_OUTPUT: RSS: Main: 227815424 12:12:03 INFO - 2015-11-23 12:12:03,982 DEBUG : BROWSER_OUTPUT: 12:12:06 INFO - 2015-11-23 12:12:06,653 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 12:12:07 INFO - 2015-11-23 12:12:07,254 DEBUG : BROWSER_OUTPUT: RSS: Main: 276983808 12:12:07 INFO - 2015-11-23 12:12:07,254 DEBUG : BROWSER_OUTPUT: 12:12:09 INFO - 2015-11-23 12:12:09,891 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 12:12:10 INFO - 2015-11-23 12:12:10,492 DEBUG : BROWSER_OUTPUT: RSS: Main: 291000320 12:12:10 INFO - 2015-11-23 12:12:10,492 DEBUG : BROWSER_OUTPUT: 12:12:13 INFO - 2015-11-23 12:12:13,783 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 12:12:14 INFO - 2015-11-23 12:12:14,352 DEBUG : BROWSER_OUTPUT: RSS: Main: 277471232 12:12:14 INFO - 2015-11-23 12:12:14,352 DEBUG : BROWSER_OUTPUT: 12:12:16 INFO - 2015-11-23 12:12:16,187 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 12:12:16 INFO - 2015-11-23 12:12:16,774 DEBUG : BROWSER_OUTPUT: RSS: Main: 279048192 12:12:16 INFO - 2015-11-23 12:12:16,774 DEBUG : BROWSER_OUTPUT: 12:12:18 INFO - 2015-11-23 12:12:18,771 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:12:19 INFO - 2015-11-23 12:12:19,352 DEBUG : BROWSER_OUTPUT: RSS: Main: 278941696 12:12:19 INFO - 2015-11-23 12:12:19,352 DEBUG : BROWSER_OUTPUT: 12:12:22 INFO - 2015-11-23 12:12:22,177 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 12:12:22 INFO - 2015-11-23 12:12:22,769 DEBUG : BROWSER_OUTPUT: RSS: Main: 282341376 12:12:22 INFO - 2015-11-23 12:12:22,769 DEBUG : BROWSER_OUTPUT: 12:12:25 INFO - 2015-11-23 12:12:25,754 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 12:12:26 INFO - 2015-11-23 12:12:26,353 DEBUG : BROWSER_OUTPUT: RSS: Main: 280526848 12:12:26 INFO - 2015-11-23 12:12:26,353 DEBUG : BROWSER_OUTPUT: 12:12:29 INFO - 2015-11-23 12:12:29,038 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 12:12:29 INFO - 2015-11-23 12:12:29,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 281722880 12:12:29 INFO - 2015-11-23 12:12:29,640 DEBUG : BROWSER_OUTPUT: 12:12:31 INFO - 2015-11-23 12:12:31,138 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:12:31 INFO - 2015-11-23 12:12:31,720 DEBUG : BROWSER_OUTPUT: RSS: Main: 315826176 12:12:31 INFO - 2015-11-23 12:12:31,720 DEBUG : BROWSER_OUTPUT: 12:12:33 INFO - 2015-11-23 12:12:33,488 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:12:34 INFO - 2015-11-23 12:12:34,118 DEBUG : BROWSER_OUTPUT: RSS: Main: 316657664 12:12:34 INFO - 2015-11-23 12:12:34,119 DEBUG : BROWSER_OUTPUT: 12:12:36 INFO - 2015-11-23 12:12:36,138 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:12:36 INFO - 2015-11-23 12:12:36,724 DEBUG : BROWSER_OUTPUT: RSS: Main: 323194880 12:12:36 INFO - 2015-11-23 12:12:36,724 DEBUG : BROWSER_OUTPUT: 12:12:39 INFO - 2015-11-23 12:12:39,220 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:12:39 INFO - 2015-11-23 12:12:39,822 DEBUG : BROWSER_OUTPUT: RSS: Main: 323788800 12:12:39 INFO - 2015-11-23 12:12:39,823 DEBUG : BROWSER_OUTPUT: 12:12:42 INFO - 2015-11-23 12:12:42,198 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:12:42 INFO - 2015-11-23 12:12:42,782 DEBUG : BROWSER_OUTPUT: RSS: Main: 323325952 12:12:42 INFO - 2015-11-23 12:12:42,783 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,423 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 12:12:44 INFO - 2015-11-23 12:12:44,683 DEBUG : BROWSER_OUTPUT: RSS: Main: 383098880 12:12:44 INFO - 2015-11-23 12:12:44,683 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,683 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:12:44 INFO - 2015-11-23 12:12:44,683 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:12:44 INFO - 2015-11-23 12:12:44,683 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:12:44 INFO - 2015-11-23 12:12:44,683 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:12:44 INFO - 2015-11-23 12:12:44,683 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;105;112;151;157;99;109;109;106 12:12:44 INFO - 2015-11-23 12:12:44,683 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;154;140;170;155;118;140;155;140;154;114 12:12:44 INFO - 2015-11-23 12:12:44,683 DEBUG : BROWSER_OUTPUT: |2;audio-dft;180;178;180;183;184;186;184;186;195;237 12:12:44 INFO - 2015-11-23 12:12:44,683 DEBUG : BROWSER_OUTPUT: |3;audio-fft;63;68;74;72;55;54;56;68;69;55 12:12:44 INFO - 2015-11-23 12:12:44,683 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;82;85;116;74;75;74;110;74;74;113 12:12:44 INFO - 2015-11-23 12:12:44,683 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;116;131;101;87;86;88;88;90;86;87 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;96;93;93;99;141;110;94;108;157;103 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;86;84;84;84;84;85;84;83;86;83 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;53;54;54;53;54;53;54;64;52;53 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;65;64;63;64;65;66;66;66;66;69 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;81;82;83;79;80;82;82;81;82;82 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;121;125;122;119;122;123;120;128;119;117 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;134;133;132;134;133;122;134;134;133;120 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;64;65;64;65;65;66;64;64;65 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: __start_cc_report 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1047 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: __end_cc_report 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: __startTimestamp1448309564674__endTimestamp 12:12:44 INFO - 2015-11-23 12:12:44,684 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: Number of tests: 14 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:116.60 Median:111.00 stddev:20.07 (18.1%) stddev-sans-first:21.05 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 105.0 112.0 151.0 157.0 99.0 109.0 109.0 106.0 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.00 Median:154.00 stddev:17.45 (11.3%) stddev-sans-first:18.13 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: Values: 154.0 140.0 170.0 155.0 118.0 140.0 155.0 140.0 154.0 114.0 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:189.30 Median:185.00 stddev:17.40 (9.4%) stddev-sans-first:18.13 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: Values: 180.0 178.0 180.0 183.0 184.0 186.0 184.0 186.0 195.0 237.0 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:63.40 Median:68.00 stddev:7.78 (11.4%) stddev-sans-first:8.25 12:12:44 INFO - 2015-11-23 12:12:44,685 DEBUG : BROWSER_OUTPUT: Values: 63.0 68.0 74.0 72.0 55.0 54.0 56.0 68.0 69.0 55.0 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:87.70 Median:74.00 stddev:17.92 (24.2%) stddev-sans-first:18.89 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: Values: 82.0 85.0 116.0 74.0 75.0 74.0 110.0 74.0 74.0 113.0 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:96.00 Median:87.00 stddev:15.55 (17.9%) stddev-sans-first:14.71 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: Values: 116.0 131.0 101.0 87.0 86.0 88.0 88.0 90.0 86.0 87.0 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:109.40 Median:93.00 stddev:22.04 (23.7%) stddev-sans-first:22.83 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: Values: 96.0 93.0 93.0 99.0 141.0 110.0 94.0 108.0 157.0 103.0 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.00 stddev:1.06 (1.3%) stddev-sans-first:0.93 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: Values: 86.0 84.0 84.0 84.0 84.0 85.0 84.0 83.0 86.0 83.0 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:3.44 (6.4%) stddev-sans-first:3.61 12:12:44 INFO - 2015-11-23 12:12:44,686 DEBUG : BROWSER_OUTPUT: Values: 53.0 54.0 54.0 53.0 54.0 53.0 54.0 64.0 52.0 53.0 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:66.00 stddev:1.65 (2.5%) stddev-sans-first:1.74 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: Values: 65.0 64.0 63.0 64.0 65.0 66.0 66.0 66.0 66.0 69.0 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:82.00 stddev:1.17 (1.4%) stddev-sans-first:1.24 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: Values: 81.0 82.0 83.0 79.0 80.0 82.0 82.0 81.0 82.0 82.0 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.60 Median:122.00 stddev:3.20 (2.6%) stddev-sans-first:3.39 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: Values: 121.0 125.0 122.0 119.0 122.0 123.0 120.0 128.0 119.0 117.0 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:130.90 Median:133.50 stddev:5.28 (4.0%) stddev-sans-first:5.48 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: Values: 134.0 133.0 132.0 134.0 133.0 122.0 134.0 134.0 133.0 120.0 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: 12:12:44 INFO - 2015-11-23 12:12:44,687 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:65.00 stddev:0.85 (1.3%) stddev-sans-first:0.71 12:12:44 INFO - 2015-11-23 12:12:44,688 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 65.0 64.0 65.0 65.0 66.0 64.0 64.0 65.0 12:12:44 INFO - 2015-11-23 12:12:44,688 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:12:44 INFO - 2015-11-23 12:12:44,688 DEBUG : BROWSER_OUTPUT: 12:12:45 INFO - 2015-11-23 12:12:45,340 INFO : Browser exited with error code: 0 12:12:45 INFO - 2015-11-23 12:12:45,386 INFO : Completed test kraken (00:00:55) 12:12:45 INFO - 2015-11-23 12:12:45,386 INFO : Starting test v8_7 12:12:45 INFO - 2015-11-23 12:12:45,386 DEBUG : operating with platform_type : mac_ 12:12:45 INFO - 2015-11-23 12:12:45,386 INFO : Initialising browser for v8_7 test... 12:12:45 INFO - 2015-11-23 12:12:45,398 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpIxvn15/profile http://localhost:49270/getInfo.html 12:12:47 INFO - 2015-11-23 12:12:47,351 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 12:12:47 INFO - 2015-11-23 12:12:47,352 DEBUG : BROWSER_OUTPUT: colorDepth:24 12:12:47 INFO - 2015-11-23 12:12:47,352 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/689 12:12:47 INFO - 2015-11-23 12:12:47,352 DEBUG : BROWSER_OUTPUT: __metrics 12:12:47 INFO - 2015-11-23 12:12:47,368 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:12:48 INFO - 2015-11-23 12:12:48,037 INFO : Browser initialized. 12:12:48 INFO - 2015-11-23 12:12:48,037 INFO : Running cycle 1/1 for v8_7 test... 12:12:48 INFO - 2015-11-23 12:12:48,037 DEBUG : command line: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpIxvn15/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 12:12:59 INFO - 2015-11-23 12:12:59,816 DEBUG : BROWSER_OUTPUT: RSS: Main: 226217984 12:12:59 INFO - 2015-11-23 12:12:59,816 DEBUG : BROWSER_OUTPUT: 12:13:21 INFO - 2015-11-23 12:13:21,234 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49270/tests/v8_7/run.html (next: http://localhost:49270/tests/v8_7/run.html) 12:13:21 INFO - 2015-11-23 12:13:21,492 DEBUG : BROWSER_OUTPUT: RSS: Main: 743051264 12:13:21 INFO - 2015-11-23 12:13:21,492 DEBUG : BROWSER_OUTPUT: 12:13:21 INFO - 2015-11-23 12:13:21,492 DEBUG : BROWSER_OUTPUT: __start_tp_report 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: |0;Richards;148.434021077631 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;160.926939169617 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.1120448179272 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4726.415094339623 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1715.2658662092624 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: |5;Earley;786.1635220125786 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: |6;Boyer;13821.917808219177 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: |7;RegExp;28194.444444444445 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: |8;Splay;637.7551020408164 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4524.886877828054 12:13:21 INFO - 2015-11-23 12:13:21,493 DEBUG : BROWSER_OUTPUT: __end_tp_report 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: __startTimestamp1448309601485__endTimestamp 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: Number of tests: 10 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:148.43 Median:148.43 stddev:0.00 (0.0%) 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: Values: 148.4 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:160.93 Median:160.93 stddev:0.00 (0.0%) 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: Values: 160.9 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.11 Median:280.11 stddev:0.00 (0.0%) 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: Values: 280.1 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: 12:13:21 INFO - 2015-11-23 12:13:21,494 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4726.42 Median:4726.42 stddev:0.00 (0.0%) 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: Values: 4726.4 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1715.27 Median:1715.27 stddev:0.00 (0.0%) 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: Values: 1715.3 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:786.16 Median:786.16 stddev:0.00 (0.0%) 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: Values: 786.2 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:13821.92 Median:13821.92 stddev:0.00 (0.0%) 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: Values: 13821.9 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:28194.44 Median:28194.44 stddev:0.00 (0.0%) 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: Values: 28194.4 12:13:21 INFO - 2015-11-23 12:13:21,495 DEBUG : BROWSER_OUTPUT: 12:13:21 INFO - 2015-11-23 12:13:21,496 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:637.76 Median:637.76 stddev:0.00 (0.0%) 12:13:21 INFO - 2015-11-23 12:13:21,496 DEBUG : BROWSER_OUTPUT: Values: 637.8 12:13:21 INFO - 2015-11-23 12:13:21,496 DEBUG : BROWSER_OUTPUT: 12:13:21 INFO - 2015-11-23 12:13:21,496 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4524.89 Median:4524.89 stddev:0.00 (0.0%) 12:13:21 INFO - 2015-11-23 12:13:21,496 DEBUG : BROWSER_OUTPUT: Values: 4524.9 12:13:21 INFO - 2015-11-23 12:13:21,496 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 12:13:21 INFO - 2015-11-23 12:13:21,496 DEBUG : BROWSER_OUTPUT: 12:13:22 INFO - 2015-11-23 12:13:22,149 INFO : Browser exited with error code: 0 12:13:22 INFO - 2015-11-23 12:13:22,166 INFO : Completed test v8_7 (00:00:36) 12:13:22 INFO - 2015-11-23 12:13:22,537 INFO : Completed test suite (00:20:07) 12:13:22 INFO - 2015-11-23 12:13:22,538 DEBUG : Working with test: dromaeo_css 12:13:22 INFO - 2015-11-23 12:13:22,538 DEBUG : Generating results file: dromaeo_css 12:13:22 INFO - 2015-11-23 12:13:22,539 DEBUG : Working with test: kraken 12:13:22 INFO - 2015-11-23 12:13:22,539 DEBUG : Generating results file: kraken 12:13:22 INFO - 2015-11-23 12:13:22,539 INFO : javascript benchmark 12:13:22 INFO - 2015-11-23 12:13:22,539 DEBUG : Working with test: v8_7 12:13:22 INFO - 2015-11-23 12:13:22,539 DEBUG : Generating results file: v8_7 12:13:22 INFO - 2015-11-23 12:13:22,539 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:13:22 INFO - 2015-11-23 12:13:22,937 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 12:13:22 INFO - 2015-11-23 12:13:22,937 DEBUG : process_Request line: dromaeo_css 7749.88 graph.html#tests=[[72,64,59]] 12:13:22 INFO - 2015-11-23 12:13:22,937 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:13:22 INFO - 2015-11-23 12:13:22,968 DEBUG : process_Request line: kraken 1408.90 graph.html#tests=[[232,64,59]] 12:13:22 INFO - 2015-11-23 12:13:22,969 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 12:13:23 INFO - 2015-11-23 12:13:23,003 DEBUG : process_Request line: v8_7 20486.37 graph.html#tests=[[230,64,59]] 12:13:23 INFO - 2015-11-23 12:13:23,004 INFO : javascript benchmark 12:13:23 INFO - 2015-11-23 12:13:23,006 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3413.7439692023095, 3477.374072666922, 3496.8460408065357, 3511.806850623215, 3532.6114127445976, 5738.770449182028, 5795.942040579604, 5936.198613187218, 5961.0760332148475, 5993.970030149854, 5851.4148585141465, 5913.970430147854, 5920.082387229975, 5929.19955805966, 5945.643261404319, 5010.123228435018, 5320.414754377015, 5381.0, 5388.568914486832, 5425.945740542603, 5406.432324605918, 5444.101723215665, 5445.782168713266, 5446.618736688433, 5453.509184173424, 5420.34955805302, 5429.0227759003365, 5436.0, 5442.782288708447, 5446.019716451037, 5422.8373148805595, 5425.294711687495, 5426.375966763813, 5433.293671822677, 5435.972820135894, 5230.607704422184, 5255.605829562798, 5366.651167674088, 5384.138537833967, 5408.540274076709, 5858.443447872445, 6012.789552365646, 6056.152138700585, 6069.0, 6086.847828804271, 6292.036236544644, 6314.557980941336, 6320.146780184662, 6342.4926005919415, 6345.397187267248, 6218.906716399258, 6291.905621415683, 6336.6831658416895, 6341.8097457076365, 6387.712552935129, 6030.396960303934, 6096.542759293071, 6139.600925939844, 6145.539084568631, 6145.96927015371, 6508.219013718384, 6523.478121750249, 6530.118434011442, 6540.738370465223, 6549.279579246279, 6134.957077260912, 6241.78153764616, 6245.906311405332, 6251.4061164189325, 6258.843528911813, 6275.3724627537795, 6309.432151106423, 6378.904316435257, 6382.968085159638, 6414.9679251604375, 6286.937130628727, 6304.117423560705, 6351.4283714465, 6363.649999249993, 6365.936340636535, 6308.243010838729, 6322.146510221153, 6330.52521060913, 6338.936610633835, 6371.458426033746, 4925.1381008324115, 5011.574016208663, 5016.874578135576, 5034.9426620409395, 5049.394072711299, 4554.134714404253, 4564.6348292136545, 4568.040711450632, 4578.2903649934315, 4592.563706447882, 5935.73289201987, 5936.1095835625, 5952.821415357546, 5979.5814292999075, 5986.850328741816, 6222.564420490613, 6263.655498947511, 6329.556931014877, 6361.01404282337, 6385.265694445198, 5909.970450147722, 5918.644881307136, 5951.315598706205, 5974.163617093611, 5998.070299103559, 5557.194206840067, 5564.415736347706, 5565.16522521625, 5566.192902029183, 5572.136318870582, 6216.813495595139, 6335.334789847, 6337.112804207322, 6349.936500635027, 6358.205224346957, 6248.375162483897, 6259.217597800275, 6281.52888533371, 6344.71448784815, 6350.396712312323, 6328.082428047999, 6330.683465826598, 6342.746290148342, 6353.936460635335, 6355.968220158869, 6346.873062538631, 6358.682065896779, 6372.490200784019, 6378.904316435164, 6398.200224971879, 5384.9730751346, 6173.351798061319, 6291.685415729286, 6316.526260530571, 6332.11350410952, 6145.938540614537, 6299.6535190564955, 6339.96830015847, 6361.522885783492, 6363.87272254543, 6311.621302721851, 6320.178376810985, 6329.019002054689, 6334.0, 6340.778072767431, 5167.431582525995, 5926.022206335906, 6046.244219472566, 6049.848753781191, 6067.029275315928, 5153.330067091254, 6270.686465676789, 6313.779017734358, 6342.841428964312, 6365.077063825626, 6195.380461953769, 6228.937710623017, 6241.687915604292, 6255.593386429868, 6261.186045814015], "unit": "ms", "name": "dojo.html", "value": 5850.383986679883}, {"lowerIsBetter": false, "replicates": [547.321321561264, 555.2615022020713, 564.5257983294038, 598.2581598817468, 633.6768248193423, 32450.0, 33124.0, 42240.57759422405, 42444.78777606115, 42488.57511424885, 45032.54967450324, 45430.091398172015, 45561.0, 45604.77197614007, 45799.08401831961, 8602.784930376729, 8772.12278772121, 9808.31341806074, 9977.201823854111, 9987.600495980152, 8850.645974161058, 8944.01615822259, 12078.577249796259, 12174.695632609211, 12210.694732631711, 10646.095081918047, 11351.659450216506, 11704.941475292611, 11762.705932351673, 11783.705407364841, 8970.730878073668, 9103.908960910372, 11862.52549898003, 11888.940555297255, 11902.940485297562, 8786.472811631258, 12024.87975120255, 12158.696032599168, 12188.51245950161, 12196.14626976112, 42033.789831050955, 42246.0, 42250.788746056074, 42256.366154507705, 42274.78862605698, 2778.472090302836, 2982.835944023078, 3273.6071671399586, 3533.611302756695, 3676.6323367663285, 30353.0, 30411.695883041106, 30420.391592168253, 30644.693553064408, 31148.377032459222, 2811.1847564206355, 2855.7572606328486, 2892.6962668919673, 2925.224815423914, 2979.0, 491170.0, 492919.0, 493012.9999999964, 495282.0, 495986.0, 59879.40120598825, 60727.69636151791, 60817.69591152104, 66650.66674666596, 71244.72531648117, 74415.88376174362, 74701.6264918672, 74711.25288747043, 74712.5057498847, 74999.62500187464, 16684.74972875408, 17135.400260990806, 17175.96944183353, 17180.0, 17180.656386872193, 12841.97264218879, 12922.030847686274, 12924.224546527239, 12945.482180712666, 13057.477700892048, 21855.0, 22111.889440552695, 22176.778232217795, 22179.44551386196, 22223.55552888933, 8218.0, 9161.633534658538, 9522.428654280766, 9544.52277386128, 9581.754371931744, 979.2215188924822, 990.8662330585423, 1032.488917985602, 1104.304288298383, 1182.905812123782, 30020.69979300179, 30079.0, 30242.941497047497, 30880.38239235203, 31030.379392412025, 1110.2228440091967, 1187.269829055142, 1197.3115458611333, 1248.1200753468784, 1259.552858735136, 15680.0, 15744.763828542582, 16351.182440877908, 16542.0901850397, 16798.0, 544891.0, 545796.0, 546375.0, 546539.0, 549647.0, 62771.74456510936, 63208.41978950563, 63329.05006424815, 63333.683331583044, 63488.04767928485, 1457.9416823326949, 1483.5623491069944, 1498.7077519883735, 1499.272852666456, 1637.3450619752196, 31147.909823156082, 31284.217894552818, 31362.902298419445, 31455.842720786248, 31859.0, 54302.45697542974, 54885.90228195334, 54944.0, 54952.450475496335, 55114.17328740152, 498501.0, 500242.0, 500955.49522252154, 501850.0, 502364.0, 28991.0, 29555.0, 33271.83364083164, 34447.55524447535, 34452.310953781285, 44262.0, 45717.771411142734, 46299.84250393632, 46463.53536464592, 47034.76482617565, 37402.43896341611, 37967.0, 38455.23089538138, 38562.22875542417, 38872.805635971636, 30967.0, 36537.81731091328, 36843.81578092092, 37836.05409864776, 38387.6161238384, 36615.0, 37959.81020094882, 38649.03377415587, 38680.41979370368, 38987.41518877276, 30173.698263017966, 30517.847410762803, 33263.0, 33340.66659333376, 33529.0, 19459.318923837596, 24229.75770242275, 24539.509209815347, 25116.372090697878, 26253.081142159932], "unit": "ms", "name": "ext.html", "value": 20877.045571463215}, {"lowerIsBetter": false, "replicates": [357.30682475996565, 359.6816817116851, 363.2100182103923, 364.5588837506616, 369.4144780522871, 15779.116345510309, 18045.909770451162, 19376.93426861522, 20900.477488062807, 21614.89192554037, 20244.190232390687, 20276.898615506903, 20562.897185514088, 20615.587688246225, 20635.48411289715, 3968.987908083435, 5163.380394352665, 5320.787168513255, 5323.441038690939, 5332.200169974515, 3697.5008373869587, 3737.9626203737885, 5836.854078648046, 6024.5180385569265, 6025.035994240922, 5859.238299021143, 5865.061590145578, 5928.792492262772, 5977.850553736148, 5978.312494063173, 5101.770420331112, 5886.6468011919205, 5924.348321684654, 5927.0, 5935.28776546815, 4878.609711223129, 5525.419830917736, 5649.745761440704, 5658.632188907749, 5690.857728556778, 22746.0, 22784.54430911389, 24610.507789844283, 25300.493990120096, 26247.0, 2011.718359429681, 2041.8468614853796, 2125.914963401462, 2141.539568992674, 2197.6593627987686, 9687.70936871895, 9951.850722239173, 12239.510419583206, 14713.632159195999, 15293.235338233155, 1672.9414470493475, 1674.1294526846093, 1707.0952395230547, 1726.7064599018072, 1732.8613710903098, 87070.0, 104871.95128048699, 107825.0, 108229.0, 109804.0, 10908.727281817859, 10917.890821091847, 10946.890531094587, 11007.339559626447, 11068.501917413736, 12973.870261297456, 13038.41327140281, 13040.673983150496, 13072.738545229233, 13076.0, 8479.152084791594, 8518.275946544612, 8539.14608539141, 8568.314534837198, 8583.0, 7051.788446346515, 8669.609867555975, 8673.956630216935, 8748.606312715943, 8768.167024132825, 3970.960290397059, 4240.045989652303, 4315.741055536678, 4348.108637729258, 4372.344148377769, 1554.564721877876, 1715.2452920714845, 1732.2984191402502, 1761.8414342709218, 1770.3184274054581, 917.0783362720423, 933.8187193200607, 960.7454024683465, 985.1183191043975, 1006.0744115413837, 16835.74746378805, 19525.51186220356, 19607.627466077523, 20115.49711257201, 20336.389908302775, 814.3647954595425, 910.5583791860943, 935.4854829843558, 990.1929927109383, 995.9940460135316, 9258.488434322508, 10934.671959841218, 10975.780484390267, 10999.23005389615, 11009.394483303495, 753135.0, 759626.0, 765754.0, 768182.0, 778756.0, 100995.0, 120336.0, 122948.38525807313, 123462.14806777905, 127003.0, 1222.028487352539, 1225.08730995409, 1234.1977714485656, 1234.327291626066, 1241.2490443281865, 17933.910330448263, 17969.460916172535, 18132.546686332946, 18142.002189879684, 18319.816801832345, 51863.22205167001, 51882.0, 51954.74022629863, 52379.73810130925, 52690.47309526856, 75098.87351689838, 75284.62357688177, 75494.0, 76444.0, 77085.0, 9656.613735450785, 9865.506724663595, 9992.10071093605, 10083.848742269018, 10137.341072830242, 11804.645860624196, 17972.55068623295, 19937.00314984274, 20001.19995200234, 20222.0, 15816.0, 15881.444149454714, 16077.598060048593, 16184.757228641345, 16316.26576804023, 15623.765643515584, 15760.60598485047, 15847.049177049414, 15876.52370428889, 16046.919765401099, 11857.940710296738, 15813.920930395274, 15834.841651583798, 16479.34082636729, 16504.092274924515, 9524.619015239312, 9591.520423978913, 9592.376495527767, 9633.133018028411, 9760.365576237522, 10039.096481316716, 10758.784824303626, 15304.846951530342, 15816.367345306058, 15887.205639717737], "unit": "ms", "name": "jquery.html", "value": 9921.194767160428}, {"lowerIsBetter": false, "replicates": [1567.498400511836, 1572.5911263071591, 1658.8589969852562, 1888.1786422906036, 1895.5355937795243, 7292.854142917138, 7741.0, 8018.91981080189, 8179.263866252036, 8182.427230093896, 7987.880181797278, 8025.23760242776, 8150.9592452037805, 8160.26557609815, 8169.30560902324, 2021.969670454937, 2096.3186964236684, 2328.7321957974796, 2353.1646265575732, 2353.188150088217, 2171.6525355943054, 2347.7065366829147, 2389.9641505377435, 2390.1514962188435, 2390.8445951013214, 2300.608896487601, 2339.4853132310864, 2345.144022431817, 2348.38941875112, 2350.5063936573342, 2107.4309936317263, 2152.806247437738, 2157.8165855902266, 2243.0, 2266.286119872235, 2317.5712493188644, 2362.692849929498, 2364.397078744926, 2367.490989437279, 2368.940776480602, 7718.922810771876, 8082.757517274491, 8094.0, 8094.838103237902, 8116.35069194463, 5048.444671086178, 5098.464661210556, 5104.310918026056, 5130.846074617767, 5131.717755523445, 3478.6869181773764, 3488.5988111367265, 3502.1069627245142, 3566.857325706969, 3572.821358932043, 1062.5909025025344, 1078.0459293525207, 1619.9433019844248, 1621.2056092514702, 1650.1996531682125, 10756.247062705614, 10872.728181795439, 10884.29252098619, 10887.564497420093, 10918.126549875991, 4463.419755431773, 4475.977620111878, 4530.909381812346, 4541.3869127668, 4577.725336479822, 8827.602757875911, 8842.778930526789, 8844.0, 8850.336224783166, 8850.645974160961, 5044.974775126101, 5056.191009438546, 5078.466760990117, 5085.160948443466, 5094.566961808214, 4690.530946905282, 4701.388819453449, 4706.905861882743, 4732.313814496879, 4751.714897106184, 3496.5279687241896, 3569.268299998494, 3592.964070359263, 3616.692581130581, 3623.710103191738, 1132.2413982631656, 1152.153167421944, 1152.573547787324, 1156.8033434316187, 1162.5001249462812, 1357.267075779088, 1358.273323771797, 1414.7099844531847, 1414.7877818327127, 1416.3060100550656, 7727.922720772833, 7768.689252429953, 7843.019622547182, 7845.921540784633, 7960.4825686330205, 1182.7279725662975, 1185.6561597136733, 1194.3132698698282, 1204.5121725701106, 1217.969550761238, 5757.913631295534, 5877.0, 5905.881882362329, 5913.201717768046, 6138.693065346715, 8776.736697899074, 9390.295727820305, 9637.0, 9638.42169469834, 9744.756381090388, 9481.573329200064, 9579.616815327448, 9606.951965240129, 9619.807603848023, 9678.95160524193, 7690.846373043976, 7731.188225236382, 7837.1770964047955, 7864.016997875266, 7899.0, 7554.0, 7629.16079231273, 7642.5032372895585, 7662.272084151997, 7664.961675191588, 7530.510516816389, 8683.956580217311, 8850.33622478304, 8952.865707014269, 8987.146221108984, 8990.190882820578, 9078.591463384031, 9111.954440227757, 9115.225205857576, 9153.221976132103, 6948.965255173692, 7215.314545118205, 7245.673944672397, 7267.854642907007, 7279.162896266997, 7829.647665854936, 7863.331616812635, 7872.606369681607, 7879.448438609243, 7897.605119744105, 7220.74727384539, 7325.194228634952, 7356.705731770883, 7439.628018599156, 7473.701051958078, 7167.92481127835, 7191.3887319578425, 7207.891881621675, 7368.226336234832, 7370.44721645868, 7349.77950661481, 7383.889241661487, 7401.888971665322, 7404.222556631476, 7405.037345145097, 6457.61161350312, 6706.0, 6713.1944166700305, 6739.0902228198565, 6766.5263431559315, 6272.968635156795, 6877.277885822116, 6902.482313826383, 6903.0680858083515, 6942.2363540011565], "unit": "ms", "name": "mootools.html", "value": 4729.812936769754}, {"lowerIsBetter": false, "replicates": [324.87817068599276, 329.1934759838397, 333.65800054943696, 335.43479237484837, 338.80180094644646, 6751.426128779053, 6773.288804675512, 6779.559328643635, 6785.592864428138, 6810.421114205286, 5401.918971215425, 6549.672516374186, 6557.278699343068, 6567.916293811527, 6577.901331480031, 4984.526469985355, 5005.1491246488, 5056.140456122468, 5062.215356619729, 5083.974580127113, 4456.3315502674695, 4788.329633851264, 5049.267856160873, 5088.4911508849, 5107.336046313974, 4185.665146788249, 4763.404574428197, 4841.0, 4865.099956508039, 4984.925226121611, 4948.925766113512, 4953.851384458472, 4959.628027897904, 4991.07665081961, 4994.35073440454, 3569.8750543731103, 4276.080642661812, 4279.721818081845, 4280.550542193056, 4283.571642835723, 6393.808185754435, 6474.093626892239, 6550.475961923083, 6567.408933195988, 6589.143411356541, 5958.821235362946, 5967.343592204854, 5984.820455386345, 5998.700064996733, 6005.6997150142315, 5927.496162826165, 5935.9406405935815, 6955.6869940852785, 7263.60050197239, 7278.781636550859, 4810.687305325143, 4815.518448155191, 4817.927731084036, 4822.638302127354, 4831.202851529493, 7441.776746697607, 7717.228277172294, 7723.343515801163, 7735.497192682514, 7746.147923728386, 4891.608671306287, 4895.290182923456, 4899.387576552931, 4904.435989861212, 4909.484504127086, 4973.403191617029, 4977.452480227172, 4995.275685025724, 4997.85006449807, 5000.049990501793, 4887.437944636412, 5763.769449221984, 5872.148538461985, 5958.285005799332, 5964.433378829004, 7287.453440991947, 7294.781156565312, 7308.5614863108385, 7326.267373262738, 7329.827227643552, 1704.0797969096798, 1729.6886560419246, 1734.0982689001648, 1735.2104792319717, 1736.5484973906623, 1104.2325583719398, 1113.209621168963, 1131.6548452721834, 1136.0400461609927, 1139.418896362861, 6777.864442711217, 6780.118584584071, 6790.5925644461495, 6797.320267973262, 6804.21751498584, 7352.301531354513, 7578.658960346797, 7582.393408527305, 7592.658330375147, 7593.202713715091, 5371.0, 6293.842653933688, 6609.438197753245, 6640.734370625218, 6705.195376554747, 4024.476818013698, 4056.087380339459, 4056.728232189983, 4057.411675307123, 4063.309237429644, 7370.6683199255085, 7662.233776622294, 7665.463417560717, 7709.3447057000785, 7712.15166331703, 7629.122650895107, 7885.408594355446, 7911.802204944923, 7915.287624113857, 7923.7226697065325, 7684.6926122954455, 7690.115636701739, 7696.576688282198, 7708.884366734392, 7709.807254818675, 7632.045994250719, 7638.541687498546, 7639.236076392316, 7645.61771911391, 7646.694132234871, 7252.202257751537, 7486.139094004041, 7518.736844210426, 7538.849223015619, 7548.660310285941, 7667.0416197975255, 7669.693212271447, 7674.61626918663, 7690.577018264049, 7692.538447693157, 4807.278908163804, 4919.508049195052, 4926.753662316942, 4928.112939670894, 4935.136351138464, 7615.581143037186, 7616.8476630468185, 7622.733204337822, 7623.618819058914, 7635.46551741395, 7277.708891644275, 7297.562146271241, 7300.671469783979, 7302.598357090198, 7314.597697126496, 7071.469639776935, 7298.525595836254, 7311.049563556916, 7312.341889229995, 7349.522281051714, 7152.606606636685, 7154.0, 7155.499115062104, 7157.606331651809, 7172.641367931687, 6969.128858892638, 7212.783616491513, 7215.567065976058, 7232.0, 7396.963015185105, 7216.386607138242, 7218.133823940951, 7220.375415531674, 7244.4566657499845, 7256.673449694882], "unit": "ms", "name": "prototype.html", "value": 5311.134793632579}, {"lowerIsBetter": false, "replicates": [737.7639155470249, 803.3693550562809, 806.1173015547977, 822.7613991942335, 874.7725591346256, 15122.168280744554, 15559.0, 15559.910806243568, 15599.220038998033, 15601.453949111756, 13326.800097998537, 13486.0, 14272.429102835875, 14342.928285358585, 14369.281535923188, 801.6793282686942, 931.455098767222, 952.8618350339196, 956.5695437053319, 957.602594923106, 948.8102379524088, 961.2886464016613, 963.7542426681186, 965.3580369054571, 966.5553845231167, 997.1424574865609, 1012.7417508535314, 1013.8681971343715, 1016.547636301846, 1020.3724709303762, 1004.5529739266029, 1005.8642083318731, 1006.4011912911806, 1006.7634105985088, 1009.8788145422523, 756.3042001358763, 760.4904713841739, 761.8095476130967, 762.6377470701428, 762.9542227466314, 13546.187228766206, 13702.177869327872, 13714.93142534291, 13721.0, 15200.923995380062, 12407.44166512509, 12436.0, 12649.241045537297, 12729.745405091846, 13288.800667989892, 10264.846027309597, 10373.948130259301, 10400.384575356462, 10454.738631534197, 10887.292325998786, 3451.7411194160286, 3550.1479644885044, 3566.0, 3615.07815507044, 3616.132128289218, 66388.6680566594, 75330.24669753287, 75649.0, 76787.46425071468, 85243.0, 9547.236221102296, 9621.711348659552, 9644.276679249084, 9683.031696830261, 10057.647982320585, 21145.68281475779, 21790.237341692966, 21819.345419637437, 21864.45338866541, 21868.78131218699, 7551.509151905108, 7596.3922886168975, 7642.0, 7671.462997590227, 7674.309312161932, 4145.067359844011, 4172.0, 4178.22702799983, 4179.770112643773, 4187.91624167515, 3577.123604716861, 3623.4927110204594, 3623.836927338276, 3623.8912832615065, 3627.111357717376, 609.0438012320702, 624.2633692243197, 640.5996252342286, 654.1692051095083, 661.3221448015822, 510.92080727487297, 512.6733241619576, 519.7661052526378, 523.9607029472804, 526.7182057599166, 8617.35369847264, 8656.8701469478, 8680.435771674822, 8728.519931403835, 8895.644174233088, 585.4321308330913, 603.818854343704, 616.1743264026227, 619.6746707978257, 629.7418058595954, 3894.571597124314, 4004.379321205218, 4010.3583426651594, 4135.793210339471, 4199.202151591188, 100514.4974275124, 100575.0, 100962.0, 104110.0, 104322.0, 63351.0, 80630.0, 81409.0, 82232.76650850242, 89113.55443222742, 74520.44933035388, 75281.0, 76755.0, 77708.61145694235, 79192.0, 7537.397008239437, 8946.463212207289, 8988.820223595361, 9175.54122293896, 9189.172974432333, 53430.46569534255, 54054.45945540494, 54060.459395405545, 54293.0, 54642.0, 73522.89715654163, 77791.0, 79207.20792791998, 79541.0, 80198.0, 9684.757881052748, 11200.159988001096, 11227.989480946753, 11328.0, 11725.41372931367, 11752.471138798946, 12144.089193310714, 12577.245365277746, 12615.747685046064, 13336.19982801035, 11478.713032174262, 11642.226122133237, 11693.532258709556, 12149.878501215228, 12424.75150497003, 10047.949760251153, 10127.797444051224, 10489.055984961391, 10590.576376944837, 10995.615153469591, 11646.068314534987, 11655.825162622737, 11958.940205298917, 12038.398080095785, 12085.69785755328, 9688.709338719567, 9891.604335826487, 9992.601035855132, 10241.385516869012, 10327.0, 9224.425154263377, 9556.904430955601, 9980.500974951368, 9999.800004000024, 10067.798644027225], "unit": "ms", "name": "yui.html", "value": 7116.7884930380915}], "name": "dromaeo_css", "value": 7749.880148552196}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 105.0, 112.0, 151.0, 157.0, 99.0, 109.0, 109.0, 106.0], "unit": "ms", "name": "ai-astar", "value": 116.6}, {"lowerIsBetter": true, "replicates": [154.0, 140.0, 170.0, 155.0, 118.0, 140.0, 155.0, 140.0, 154.0, 114.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.0}, {"lowerIsBetter": true, "replicates": [180.0, 178.0, 180.0, 183.0, 184.0, 186.0, 184.0, 186.0, 195.0, 237.0], "unit": "ms", "name": "audio-dft", "value": 189.3}, {"lowerIsBetter": true, "replicates": [63.0, 68.0, 74.0, 72.0, 55.0, 54.0, 56.0, 68.0, 69.0, 55.0], "unit": "ms", "name": "audio-fft", "value": 63.4}, {"lowerIsBetter": true, "replicates": [82.0, 85.0, 116.0, 74.0, 75.0, 74.0, 110.0, 74.0, 74.0, 113.0], "unit": "ms", "name": "audio-oscillator", "value": 87.7}, {"lowerIsBetter": true, "replicates": [116.0, 131.0, 101.0, 87.0, 86.0, 88.0, 88.0, 90.0, 86.0, 87.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 96.0}, {"lowerIsBetter": true, "replicates": [96.0, 93.0, 93.0, 99.0, 141.0, 110.0, 94.0, 108.0, 157.0, 103.0], "unit": "ms", "name": "imaging-darkroom", "value": 109.4}, {"lowerIsBetter": true, "replicates": [86.0, 84.0, 84.0, 84.0, 84.0, 85.0, 84.0, 83.0, 86.0, 83.0], "unit": "ms", "name": "imaging-desaturate", "value": 84.3}, {"lowerIsBetter": true, "replicates": [53.0, 54.0, 54.0, 53.0, 54.0, 53.0, 54.0, 64.0, 52.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 54.4}, {"lowerIsBetter": true, "replicates": [65.0, 64.0, 63.0, 64.0, 65.0, 66.0, 66.0, 66.0, 66.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 65.4}, {"lowerIsBetter": true, "replicates": [81.0, 82.0, 83.0, 79.0, 80.0, 82.0, 82.0, 81.0, 82.0, 82.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 81.4}, {"lowerIsBetter": true, "replicates": [121.0, 125.0, 122.0, 119.0, 122.0, 123.0, 120.0, 128.0, 119.0, 117.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 121.6}, {"lowerIsBetter": true, "replicates": [134.0, 133.0, 132.0, 134.0, 133.0, 122.0, 134.0, 134.0, 133.0, 120.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 130.9}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 65.0, 64.0, 65.0, 65.0, 66.0, 64.0, 64.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.5}], "name": "kraken", "value": 1408.9}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [148.434021077631], "unit": "score", "name": "Richards", "value": 237.82957399999995}, {"lowerIsBetter": false, "replicates": [160.926939169617], "unit": "score", "name": "DeltaBlue", "value": 410.85725199999996}, {"lowerIsBetter": false, "replicates": [280.1120448179272], "unit": "score", "name": "Encrypt", "value": 950.2661699999999}, {"lowerIsBetter": false, "replicates": [4726.415094339623], "unit": "score", "name": "Decrypt", "value": 56.317736526946064}, {"lowerIsBetter": false, "replicates": [1715.2658662092624], "unit": "score", "name": "RayTrace", "value": 431.413587}, {"lowerIsBetter": false, "replicates": [786.1635220125786], "unit": "score", "name": "Earley", "value": 847.7409359999999}, {"lowerIsBetter": false, "replicates": [13821.917808219177], "unit": "score", "name": "Boyer", "value": 48.21783845391475}, {"lowerIsBetter": false, "replicates": [28194.444444444445], "unit": "score", "name": "RegExp", "value": 32.31079802955666}, {"lowerIsBetter": false, "replicates": [637.7551020408164], "unit": "score", "name": "Splay", "value": 127.77788800000003}, {"lowerIsBetter": false, "replicates": [4524.886877828054], "unit": "score", "name": "NavierStokes", "value": 327.9640000000003}], "name": "v8_7", "value": 20486.369483138336}]} 12:13:23 INFO - RETURN: dromaeo_css: 7749.88 12:13:23 INFO - RETURN: kraken: 1408.90 12:13:23 INFO - RETURN: v8_7: 20486.37 12:13:23 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,59]]", "result": "20486.37"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1408.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7749.88"}}} 12:13:23 INFO - Return code: 0 12:13:23 INFO - # TBPL SUCCESS # 12:13:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:13:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:13:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:13:23 INFO - 2015-11-23 12:13:23.107 screenresolution[1681:10070] starting screenresolution argv=screenresolution get 12:13:23 INFO - 2015-11-23 12:13:23.109 screenresolution[1681:10070] Display 0: 1600x1200x32@60 12:13:23 INFO - 2015-11-23 12:13:23.115 screenresolution[1682:10073] starting screenresolution argv=screenresolution list 12:13:23 INFO - Available Modes on Display 0 12:13:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:13:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:13:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:13:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:13:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:13:23 INFO - Intel Iris: 12:13:23 INFO - Chipset Model: Intel Iris 12:13:23 INFO - Type: GPU 12:13:23 INFO - Bus: Built-In 12:13:23 INFO - VRAM (Dynamic, Max): 1536 MB 12:13:23 INFO - Vendor: Intel (0x8086) 12:13:23 INFO - Device ID: 0x0a2e 12:13:23 INFO - Revision ID: 0x0009 12:13:23 INFO - Displays: 12:13:23 INFO - Display: 12:13:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:13:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:13:23 INFO - Display Serial Number: 1600x1200 60 12:13:23 INFO - Main Display: Yes 12:13:23 INFO - Mirror: Off 12:13:23 INFO - Online: Yes 12:13:23 INFO - Rotation: Supported 12:13:23 INFO - Return code: 0 12:13:23 INFO - Running post-action listener: _resource_record_post_action 12:13:23 INFO - Running post-run listener: _resource_record_post_run 12:13:24 INFO - Total resource usage - Wall time: 1240s; CPU: 53.0%; Read bytes: 39044608; Write bytes: 692024320; Read time: 421; Write time: 2194 12:13:24 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 342454784; Write bytes: 339740672; Read time: 26671; Write time: 659 12:13:24 INFO - run-tests - Wall time: 1209s; CPU: 53.0%; Read bytes: 36800000; Write bytes: 341597184; Read time: 395; Write time: 1499 12:13:24 INFO - Running post-run listener: _upload_blobber_files 12:13:24 INFO - Blob upload gear active. 12:13:24 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 12:13:24 INFO - Copying logs to upload dir... 12:13:24 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1282.820409 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2015-11-23 12:13:24.560389) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 12:13:24.564060) ========= 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.swVBe8eiM8/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.XeipRAgqSs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009661 build_url: 'https://queue.taskcluster.net/v1/task/PHG0k_EmRGi4_A98lmq0OA/artifacts/public/build/firefox-45.0a1.en-US.mac.dmg' ========= master_lag: 0.01 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 12:13:24.588190) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 12:13:24.588697) ========= 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.swVBe8eiM8/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.XeipRAgqSs/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.005097 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 12:13:24.638413) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 12:13:24.638822) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 12:13:24.639242) ========= ========= Total master_lag: 0.26 =========