builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0082 starttime: 1454763677.03 results: success (0) buildid: 20160206045244 builduid: 9c002d9694954eff859a039f9806fd69 revision: 6826dfd0e85b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.030612) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.030995) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.031272) ========= 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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.005848 basedir: '/builds/slave/test' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.076436) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.076705) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.102979) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.103223) ========= 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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.005386 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.164485) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.164790) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.165229) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.165498) ========= 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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 --2016-02-06 05:01:16-- 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% 50.1M=0s 2016-02-06 05:01:17 (50.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.251821 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.432638) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.432982) ========= 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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.020136 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:17.481952) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-06 05:01:17.482295) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6826dfd0e85b --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 6826dfd0e85b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9tYlBkQmdi/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.SsZJUOcCOB/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 2016-02-06 05:01:17,382 Setting DEBUG logging. 2016-02-06 05:01:17,382 attempt 1/10 2016-02-06 05:01:17,382 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6826dfd0e85b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-06 05:01:18,565 unpacking tar archive at: fx-team-6826dfd0e85b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.432229 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-06 05:01:18.930424) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:18.930753) ========= echo 6826dfd0e85b in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'6826dfd0e85b'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9tYlBkQmdi/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.SsZJUOcCOB/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 6826dfd0e85b program finished with exit code 0 elapsedTime=0.005139 script_repo_revision: '6826dfd0e85b' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:18.980894) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:18.981256) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:18.993954) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-02-06 05:01:18.994328) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --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', '--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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:01:18 INFO - MultiFileLogger online at 20160206 05:01:18 in /builds/slave/test 05:01:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:01:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:01:18 INFO - {'append_to_log': False, 05:01:19 INFO - 'base_work_dir': '/builds/slave/test', 05:01:19 INFO - 'blob_upload_branch': 'Fx-Team', 05:01:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:01:19 INFO - 'branch': 'Fx-Team', 05:01:19 INFO - 'buildbot_json_path': 'buildprops.json', 05:01:19 INFO - 'config_files': ('talos/mac_config.py',), 05:01:19 INFO - 'default_actions': ('clobber', 05:01:19 INFO - 'read-buildbot-config', 05:01:19 INFO - 'download-and-extract', 05:01:19 INFO - 'populate-webroot', 05:01:19 INFO - 'create-virtualenv', 05:01:19 INFO - 'install', 05:01:19 INFO - 'run-tests'), 05:01:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:01:19 INFO - 'download_minidump_stackwalk': True, 05:01:19 INFO - 'download_symbols': 'ondemand', 05:01:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:01:19 INFO - 'tooltool.py': '/tools/tooltool.py', 05:01:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:01:19 INFO - '/tools/misc-python/virtualenv.py')}, 05:01:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:01:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:01:19 INFO - 'installer_path': 'installer.exe', 05:01:19 INFO - 'log_level': 'info', 05:01:19 INFO - 'log_name': 'talos', 05:01:19 INFO - 'log_to_console': True, 05:01:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:01:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:01:19 INFO - 'opt_config_files': (), 05:01:19 INFO - 'pip_index': False, 05:01:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:01:19 INFO - 'cmd': ('bash', 05:01:19 INFO - '-c', 05:01:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:01:19 INFO - 'enabled': True, 05:01:19 INFO - 'halt_on_failure': False, 05:01:19 INFO - 'name': 'check_screen_resolution'},), 05:01:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:01:19 INFO - 'cmd': ('bash', 05:01:19 INFO - '-c', 05:01:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:01:19 INFO - 'enabled': True, 05:01:19 INFO - 'halt_on_failure': False, 05:01:19 INFO - 'name': 'check_screen_resolution'},), 05:01:19 INFO - 'run_cmd_checks_enabled': True, 05:01:19 INFO - 'sps_profile': False, 05:01:19 INFO - 'sps_profile_interval': 0, 05:01:19 INFO - 'suite': 'dromaeojs', 05:01:19 INFO - 'system_bits': '32', 05:01:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:01:19 INFO - 'title': 't-yosemite-r7-0082', 05:01:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:01:19 INFO - 'use_talos_json': True, 05:01:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:01:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:01:19 INFO - 'work_dir': 'build'} 05:01:19 INFO - ##### 05:01:19 INFO - ##### Running clobber step. 05:01:19 INFO - ##### 05:01:19 INFO - Running pre-action listener: _resource_record_pre_action 05:01:19 INFO - Running main action method: clobber 05:01:19 INFO - rmtree: /builds/slave/test/build 05:01:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:01:19 INFO - Running post-action listener: _resource_record_post_action 05:01:19 INFO - ##### 05:01:19 INFO - ##### Running read-buildbot-config step. 05:01:19 INFO - ##### 05:01:19 INFO - Running pre-action listener: _resource_record_pre_action 05:01:19 INFO - Running main action method: read_buildbot_config 05:01:19 INFO - Using buildbot properties: 05:01:19 INFO - { 05:01:19 INFO - "project": "", 05:01:19 INFO - "product": "firefox", 05:01:19 INFO - "script_repo_revision": "production", 05:01:19 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:01:19 INFO - "repository": "", 05:01:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:01:19 INFO - "buildid": "20160206045244", 05:01:19 INFO - "stage_platform": "macosx64", 05:01:19 INFO - "basedir": "/builds/slave/test", 05:01:19 INFO - "buildnumber": 339, 05:01:19 INFO - "platform": "yosemite_r7", 05:01:19 INFO - "mozci_request": { 05:01:19 INFO - "priority": -1, 05:01:19 INFO - "type": "trigger_all_talos_jobs", 05:01:19 INFO - "times": 6 05:01:19 INFO - }, 05:01:19 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:01:19 INFO - "slavebuilddir": "test", 05:01:19 INFO - "branch": "fx-team", 05:01:19 INFO - "revision": "6826dfd0e85b", 05:01:19 INFO - "slavename": "t-yosemite-r7-0082", 05:01:19 INFO - "builduid": "9c002d9694954eff859a039f9806fd69", 05:01:19 INFO - "repo_path": "integration/fx-team" 05:01:19 INFO - } 05:01:19 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg. 05:01:19 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/test_packages.json. 05:01:20 INFO - Running post-action listener: _resource_record_post_action 05:01:20 INFO - ##### 05:01:20 INFO - ##### Running download-and-extract step. 05:01:20 INFO - ##### 05:01:20 INFO - Running pre-action listener: _resource_record_pre_action 05:01:20 INFO - Running main action method: download_and_extract 05:01:20 INFO - mkdir: /builds/slave/test/build/tests 05:01:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:01:20 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/test_packages.json 05:01:20 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/test_packages.json to /builds/slave/test/build/test_packages.json 05:01:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 05:01:20 INFO - Downloaded 1277 bytes. 05:01:20 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:01:20 INFO - Using the following test package requirements: 05:01:20 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:01:20 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:20 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 05:01:20 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:20 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 05:01:20 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:20 INFO - u'jsshell-mac.zip'], 05:01:20 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:20 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 05:01:20 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:01:20 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:20 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 05:01:20 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:20 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 05:01:20 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:20 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 05:01:20 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:01:20 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:20 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 05:01:20 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:01:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:01:20 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.common.tests.zip 05:01:20 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:01:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:01:20 INFO - Downloaded 17118709 bytes. 05:01:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:01:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:01:23 INFO - Return code: 0 05:01:23 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip', u'firefox-47.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:01:23 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')]} 05:01:23 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.common.tests.zip 05:01:23 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip 05:01:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:01:23 INFO - Downloaded 17118709 bytes. 05:01:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:01:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:01:27 INFO - Return code: 0 05:01:27 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')]} 05:01:27 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.talos.tests.zip 05:01:27 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip 05:01:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:01:27 INFO - Downloaded 10706582 bytes. 05:01:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:01:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:01:27 INFO - Return code: 0 05:01:27 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')]} 05:01:27 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg 05:01:27 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg 05:01:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg'}, attempt #1 05:01:28 INFO - Downloaded 111102939 bytes. 05:01:28 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg 05:01:28 INFO - mkdir: /builds/slave/test/properties 05:01:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:01:28 INFO - Writing to file /builds/slave/test/properties/build_url 05:01:28 INFO - Contents: 05:01:28 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg 05:01:28 INFO - Running post-action listener: _resource_record_post_action 05:01:28 INFO - Running post-action listener: set_extra_try_arguments 05:01:28 INFO - ##### 05:01:28 INFO - ##### Running populate-webroot step. 05:01:28 INFO - ##### 05:01:28 INFO - Running pre-action listener: _resource_record_pre_action 05:01:28 INFO - Running main action method: populate_webroot 05:01:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:01:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:01:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:01:28 INFO - '--tppagecycles', 05:01:28 INFO - '7'], 05:01:28 INFO - 'tests': ['tsvgm']}}, 05:01:28 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:01:28 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:01:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:01:28 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:01:28 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:01:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:01:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:01:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:28 INFO - 'talos_options': ['--e10s'], 05:01:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:01:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:28 INFO - 'tests': ['damp', 'tps']}, 05:01:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:28 INFO - 'talos_options': ['--e10s'], 05:01:28 INFO - 'tests': ['damp', 'tps']}, 05:01:28 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:01:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:01:28 INFO - 'other': {'tests': ['a11yr', 05:01:28 INFO - 'ts_paint', 05:01:28 INFO - 'tpaint', 05:01:28 INFO - 'sessionrestore', 05:01:28 INFO - 'sessionrestore_no_auto_restore']}, 05:01:28 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:01:28 INFO - 'tests': ['a11yr', 05:01:28 INFO - 'ts_paint', 05:01:28 INFO - 'tpaint', 05:01:28 INFO - 'sessionrestore', 05:01:28 INFO - 'sessionrestore_no_auto_restore']}, 05:01:28 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:01:28 INFO - 'tests': ['a11yr', 05:01:28 INFO - 'ts_paint', 05:01:28 INFO - 'tpaint', 05:01:28 INFO - 'sessionrestore', 05:01:28 INFO - 'sessionrestore_no_auto_restore']}, 05:01:28 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:01:28 INFO - 'tests': ['a11yr', 05:01:28 INFO - 'ts_paint', 05:01:28 INFO - 'tpaint', 05:01:28 INFO - 'sessionrestore', 05:01:28 INFO - 'sessionrestore_no_auto_restore']}, 05:01:28 INFO - 'other_l64': {'tests': ['a11yr', 05:01:28 INFO - 'ts_paint', 05:01:28 INFO - 'tpaint', 05:01:28 INFO - 'sessionrestore', 05:01:28 INFO - 'sessionrestore_no_auto_restore']}, 05:01:28 INFO - 'other_nol64': {'tests': ['a11yr', 05:01:28 INFO - 'ts_paint', 05:01:28 INFO - 'tpaint', 05:01:28 INFO - 'sessionrestore', 05:01:28 INFO - 'sessionrestore_no_auto_restore']}, 05:01:28 INFO - 'svgr': {'tests': ['tsvgx', 05:01:28 INFO - 'tsvgr_opacity', 05:01:28 INFO - 'tart', 05:01:28 INFO - 'tscrollx', 05:01:28 INFO - 'cart']}, 05:01:28 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:01:28 INFO - 'tests': ['tsvgx', 05:01:28 INFO - 'tsvgr_opacity', 05:01:28 INFO - 'tart', 05:01:28 INFO - 'tscrollx', 05:01:28 INFO - 'cart']}, 05:01:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:28 INFO - 'tests': ['tp5o']}, 05:01:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:28 INFO - 'talos_options': ['--e10s'], 05:01:28 INFO - 'tests': ['tp5o']}, 05:01:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:28 INFO - 'talos_options': ['--xperf_path', 05:01:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:01:28 INFO - 'tests': ['tp5n']}, 05:01:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:28 INFO - 'talos_options': ['--e10s', 05:01:28 INFO - '--xperf_path', 05:01:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:01:28 INFO - 'tests': ['tp5n']}}, 05:01:28 INFO - 'talos.zip': {'path': '', 05:01:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:01:28 INFO - Running post-action listener: _resource_record_post_action 05:01:28 INFO - ##### 05:01:28 INFO - ##### Running create-virtualenv step. 05:01:28 INFO - ##### 05:01:28 INFO - Running pre-action listener: _resource_record_pre_action 05:01:28 INFO - Running main action method: create_virtualenv 05:01:28 INFO - Creating virtualenv /builds/slave/test/build/venv 05:01:28 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 05:01:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:01:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:01:28 INFO - Using real prefix '/tools/python27' 05:01:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:01:29 INFO - Installing distribute.............................................................................................................................................................................................done. 05:01:32 INFO - Installing pip.................done. 05:01:32 INFO - Return code: 0 05:01:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:01:32 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')]} 05:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:32 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')]} 05:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x101a63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a6a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe342d8dab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101908d30>, '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 0x10172bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018feb20>, '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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:01:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 05:01:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 05:01:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tYlBkQmdi/Render', 05:01:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:32 INFO - 'HOME': '/Users/cltbld', 05:01:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:32 INFO - 'LOGNAME': 'cltbld', 05:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:32 INFO - 'MOZ_NO_REMOTE': '1', 05:01:32 INFO - 'NO_EM_RESTART': '1', 05:01:32 INFO - 'PAGER': '/bin/cat', 05:01:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:32 INFO - 'PWD': '/builds/slave/test', 05:01:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:32 INFO - 'SHELL': '/bin/bash', 05:01:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SsZJUOcCOB/Listeners', 05:01:32 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:01:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:32 INFO - 'USER': 'cltbld', 05:01:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:32 INFO - 'XPC_FLAGS': '0x0', 05:01:32 INFO - 'XPC_SERVICE_NAME': '0', 05:01:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:32 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 05:01:32 INFO - Cleaning up... 05:01:32 INFO - Return code: 0 05:01:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:01:32 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')]} 05:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:32 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')]} 05:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x101a63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a6a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe342d8dab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101908d30>, '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 0x10172bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018feb20>, '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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:01:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 05:01:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 05:01:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tYlBkQmdi/Render', 05:01:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:32 INFO - 'HOME': '/Users/cltbld', 05:01:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:32 INFO - 'LOGNAME': 'cltbld', 05:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:32 INFO - 'MOZ_NO_REMOTE': '1', 05:01:32 INFO - 'NO_EM_RESTART': '1', 05:01:32 INFO - 'PAGER': '/bin/cat', 05:01:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:32 INFO - 'PWD': '/builds/slave/test', 05:01:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:32 INFO - 'SHELL': '/bin/bash', 05:01:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SsZJUOcCOB/Listeners', 05:01:32 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:01:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:32 INFO - 'USER': 'cltbld', 05:01:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:32 INFO - 'XPC_FLAGS': '0x0', 05:01:32 INFO - 'XPC_SERVICE_NAME': '0', 05:01:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:32 INFO - Downloading/unpacking psutil>=0.7.1 05:01:32 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 05:01:32 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 05:01:32 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 05:01:32 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 05:01:32 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 05:01:32 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 05:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:01:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:01:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:01:36 INFO - Installing collected packages: psutil 05:01:36 INFO - Running setup.py install for psutil 05:01:36 INFO - building 'psutil._psutil_osx' extension 05:01:36 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 05:01:36 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 05:01:36 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 05:01:36 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 05:01:36 INFO - building 'psutil._psutil_posix' extension 05:01:36 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 05:01:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:01:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:01:36 INFO - ^ 05:01:37 INFO - 1 warning generated. 05:01:37 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 05:01:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:01:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:01:37 INFO - Successfully installed psutil 05:01:37 INFO - Cleaning up... 05:01:37 INFO - Return code: 0 05:01:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:01: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')]} 05:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01: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')]} 05:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x101a63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a6a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe342d8dab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101908d30>, '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 0x10172bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018feb20>, '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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:01:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 05:01:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 05:01:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tYlBkQmdi/Render', 05:01:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:37 INFO - 'HOME': '/Users/cltbld', 05:01:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:37 INFO - 'LOGNAME': 'cltbld', 05:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:37 INFO - 'MOZ_NO_REMOTE': '1', 05:01:37 INFO - 'NO_EM_RESTART': '1', 05:01:37 INFO - 'PAGER': '/bin/cat', 05:01:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:37 INFO - 'PWD': '/builds/slave/test', 05:01:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:37 INFO - 'SHELL': '/bin/bash', 05:01:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SsZJUOcCOB/Listeners', 05:01:37 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:01:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:37 INFO - 'USER': 'cltbld', 05:01:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:37 INFO - 'XPC_FLAGS': '0x0', 05:01:37 INFO - 'XPC_SERVICE_NAME': '0', 05:01:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:01:37 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 05:01:37 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 05:01:37 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 05:01:37 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 05:01:37 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 05:01:37 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 05:01:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:01:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:01:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:01:39 INFO - Installing collected packages: mozsystemmonitor 05:01:39 INFO - Running setup.py install for mozsystemmonitor 05:01:40 INFO - Successfully installed mozsystemmonitor 05:01:40 INFO - Cleaning up... 05:01:40 INFO - Return code: 0 05:01:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:01:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x101a63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a6a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe342d8dab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101908d30>, '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 0x10172bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018feb20>, '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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:01:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 05:01:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 05:01:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tYlBkQmdi/Render', 05:01:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:40 INFO - 'HOME': '/Users/cltbld', 05:01:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:40 INFO - 'LOGNAME': 'cltbld', 05:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:40 INFO - 'MOZ_NO_REMOTE': '1', 05:01:40 INFO - 'NO_EM_RESTART': '1', 05:01:40 INFO - 'PAGER': '/bin/cat', 05:01:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:40 INFO - 'PWD': '/builds/slave/test', 05:01:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:40 INFO - 'SHELL': '/bin/bash', 05:01:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SsZJUOcCOB/Listeners', 05:01:40 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:01:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:40 INFO - 'USER': 'cltbld', 05:01:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:40 INFO - 'XPC_FLAGS': '0x0', 05:01:40 INFO - 'XPC_SERVICE_NAME': '0', 05:01:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:40 INFO - Downloading/unpacking blobuploader==1.2.4 05:01:40 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 05:01:40 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 05:01:40 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 05:01:40 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 05:01:40 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 05:01:40 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 05:01:42 INFO - Downloading blobuploader-1.2.4.tar.gz 05:01:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:01:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:01:42 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 05:01:42 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 05:01:42 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 05:01:42 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 05:01:42 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 05:01:42 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 05:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:01:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:01:43 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 05:01:43 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 05:01:43 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 05:01:43 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 05:01:43 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 05:01:43 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 05:01:43 INFO - Downloading docopt-0.6.1.tar.gz 05:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:01:43 INFO - Installing collected packages: blobuploader, requests, docopt 05:01:43 INFO - Running setup.py install for blobuploader 05:01:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:01:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:01:44 INFO - Running setup.py install for requests 05:01:44 INFO - Running setup.py install for docopt 05:01:44 INFO - Successfully installed blobuploader requests docopt 05:01:44 INFO - Cleaning up... 05:01:44 INFO - Return code: 0 05:01:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:01:44 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')]} 05:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:44 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')]} 05:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--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 0x101a63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a6a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe342d8dab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101908d30>, '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 0x10172bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018feb20>, '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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:01:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--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 05:01:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --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 05:01:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tYlBkQmdi/Render', 05:01:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:44 INFO - 'HOME': '/Users/cltbld', 05:01:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:44 INFO - 'LOGNAME': 'cltbld', 05:01:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:44 INFO - 'MOZ_NO_REMOTE': '1', 05:01:44 INFO - 'NO_EM_RESTART': '1', 05:01:44 INFO - 'PAGER': '/bin/cat', 05:01:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:44 INFO - 'PWD': '/builds/slave/test', 05:01:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:44 INFO - 'SHELL': '/bin/bash', 05:01:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SsZJUOcCOB/Listeners', 05:01:44 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:01:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:44 INFO - 'USER': 'cltbld', 05:01:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:44 INFO - 'XPC_FLAGS': '0x0', 05:01:44 INFO - 'XPC_SERVICE_NAME': '0', 05:01:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:01:45 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-eB_vT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:01:45 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-POMM9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:01:45 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-t6rBKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:01:45 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-eZZBlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:01:45 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-xYYiZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:01:45 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-VN5aBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:01:45 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-yKAXuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:01:45 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-eENxD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:01:45 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-52Uggd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:01:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-I7k_MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:01:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-wiEIDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:01:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Gqu_r7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:01:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-kiMiY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:01:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-EiQOyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:01:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-WNjNqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:01:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-7y34HJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:01:46 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-y6BUdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:01:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:01:46 INFO - Running setup.py install for manifestparser 05:01:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:01:47 INFO - Running setup.py install for mozcrash 05:01:47 INFO - Running setup.py install for mozdebug 05:01:47 INFO - Running setup.py install for mozdevice 05:01:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:01:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:01:47 INFO - Running setup.py install for mozfile 05:01:47 INFO - Running setup.py install for mozhttpd 05:01:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:01:47 INFO - Running setup.py install for mozinfo 05:01:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:01:47 INFO - Running setup.py install for mozInstall 05:01:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:01:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:01:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:01:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:01:48 INFO - Running setup.py install for mozleak 05:01:48 INFO - Running setup.py install for mozlog 05:01:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:01:48 INFO - Running setup.py install for moznetwork 05:01:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:01:48 INFO - Running setup.py install for mozprocess 05:01:48 INFO - Running setup.py install for mozprofile 05:01:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:01:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:01:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:01:48 INFO - Running setup.py install for mozrunner 05:01:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:01:49 INFO - Running setup.py install for mozscreenshot 05:01:49 INFO - Running setup.py install for moztest 05:01:49 INFO - Running setup.py install for mozversion 05:01:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:01:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:01:49 INFO - Cleaning up... 05:01:49 INFO - Return code: 0 05:01:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x101a63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a6a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe342d8dab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101908d30>, '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 0x10172bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018feb20>, '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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:01:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 05:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 05:01:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tYlBkQmdi/Render', 05:01:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:49 INFO - 'HOME': '/Users/cltbld', 05:01:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:49 INFO - 'LOGNAME': 'cltbld', 05:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:49 INFO - 'MOZ_NO_REMOTE': '1', 05:01:49 INFO - 'NO_EM_RESTART': '1', 05:01:49 INFO - 'PAGER': '/bin/cat', 05:01:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:49 INFO - 'PWD': '/builds/slave/test', 05:01:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:49 INFO - 'SHELL': '/bin/bash', 05:01:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SsZJUOcCOB/Listeners', 05:01:49 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:01:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:49 INFO - 'USER': 'cltbld', 05:01:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:49 INFO - 'XPC_FLAGS': '0x0', 05:01:49 INFO - 'XPC_SERVICE_NAME': '0', 05:01:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:01:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-bN_tDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:01:50 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)) 05:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:01:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-FYa9em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:01:50 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)) 05:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:01:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-OwA_XW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:01:50 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)) 05:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:01:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-hjA2CR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 05:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:01:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-TQrpBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:01:50 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)) 05:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:01:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-GSq31S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:01:50 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)) 05:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:01:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-SFWzak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:01:50 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)) 05:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:01:50 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-tTlyY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:01:50 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)) 05:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:01:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-iDQPvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:01:51 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)) 05:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:01:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-g0E_WC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:01:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-fv7Ebh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:01:51 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)) 05:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:01:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-8_cKsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:01:51 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)) 05:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:01:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-O5KRva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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)) 05:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:01:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-7cuRio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:01:51 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)) 05:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:01:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-cZAA8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:01:51 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)) 05:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:01:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-UiG_u3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:01:51 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)) 05:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:01:51 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Eqh0gZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:01:51 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)) 05:01:51 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)) 05:01:51 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)) 05:01:51 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)) 05:01:51 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:01:51 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:01:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:01:51 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 05:01:51 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 05:01:51 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 05:01:51 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 05:01:51 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 05:01:51 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 05:01:54 INFO - Downloading blessings-1.6.tar.gz 05:01:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:01:54 INFO - Installing collected packages: blessings 05:01:54 INFO - Running setup.py install for blessings 05:01:54 INFO - Successfully installed blessings 05:01:54 INFO - Cleaning up... 05:01:54 INFO - Return code: 0 05:01:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:01:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:01:54 INFO - Reading from file tmpfile_stdout 05:01:54 INFO - Current package versions: 05:01:54 INFO - blessings == 1.6 05:01:54 INFO - blobuploader == 1.2.4 05:01:54 INFO - docopt == 0.6.1 05:01:54 INFO - manifestparser == 1.1 05:01:54 INFO - mozInstall == 1.12 05:01:54 INFO - mozcrash == 0.16 05:01:54 INFO - mozdebug == 0.1 05:01:54 INFO - mozdevice == 0.48 05:01:54 INFO - mozfile == 1.2 05:01:54 INFO - mozhttpd == 0.7 05:01:54 INFO - mozinfo == 0.9 05:01:54 INFO - mozleak == 0.1 05:01:54 INFO - mozlog == 3.1 05:01:54 INFO - moznetwork == 0.27 05:01:54 INFO - mozprocess == 0.22 05:01:54 INFO - mozprofile == 0.28 05:01:54 INFO - mozrunner == 6.11 05:01:54 INFO - mozscreenshot == 0.1 05:01:54 INFO - mozsystemmonitor == 0.0 05:01:54 INFO - moztest == 0.7 05:01:54 INFO - mozversion == 1.4 05:01:54 INFO - psutil == 3.1.1 05:01:54 INFO - requests == 1.2.3 05:01:54 INFO - wsgiref == 0.1.2 05:01:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x101a63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a6a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe342d8dab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101908d30>, '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 0x10172bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018feb20>, '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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:01:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 05:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 05:01:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tYlBkQmdi/Render', 05:01:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:54 INFO - 'HOME': '/Users/cltbld', 05:01:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:54 INFO - 'LOGNAME': 'cltbld', 05:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:54 INFO - 'MOZ_NO_REMOTE': '1', 05:01:54 INFO - 'NO_EM_RESTART': '1', 05:01:54 INFO - 'PAGER': '/bin/cat', 05:01:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:54 INFO - 'PWD': '/builds/slave/test', 05:01:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:54 INFO - 'SHELL': '/bin/bash', 05:01:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SsZJUOcCOB/Listeners', 05:01:54 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:01:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:54 INFO - 'USER': 'cltbld', 05:01:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:54 INFO - 'XPC_FLAGS': '0x0', 05:01:54 INFO - 'XPC_SERVICE_NAME': '0', 05:01:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:01:55 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 2)) 05:01:55 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 3)) 05:01:55 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 4)) 05:01:55 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 5)) 05:01:55 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 6)) 05:01:55 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 7)) 05:01:55 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 8)) 05:01:55 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 9)) 05:01:55 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.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:01:55 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 4)) 05:01:55 INFO - Cleaning up... 05:01:55 INFO - Return code: 0 05:01:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:01:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a6a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe342d8dab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101908d30>, '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 0x10172bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018feb20>, '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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:01:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'jsonschema'] in /builds/slave/test/build 05:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 jsonschema 05:01:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tYlBkQmdi/Render', 05:01:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:55 INFO - 'HOME': '/Users/cltbld', 05:01:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:55 INFO - 'LOGNAME': 'cltbld', 05:01:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:55 INFO - 'MOZ_NO_REMOTE': '1', 05:01:55 INFO - 'NO_EM_RESTART': '1', 05:01:55 INFO - 'PAGER': '/bin/cat', 05:01:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:55 INFO - 'PWD': '/builds/slave/test', 05:01:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:55 INFO - 'SHELL': '/bin/bash', 05:01:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SsZJUOcCOB/Listeners', 05:01:55 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:01:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:55 INFO - 'USER': 'cltbld', 05:01:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:55 INFO - 'XPC_FLAGS': '0x0', 05:01:55 INFO - 'XPC_SERVICE_NAME': '0', 05:01:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:55 INFO - Downloading/unpacking jsonschema 05:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:01:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:01:57 INFO - Downloading/unpacking functools32 (from jsonschema) 05:01:57 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 05:01:57 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 05:01:57 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 05:01:57 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 05:01:57 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 05:01:57 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 05:01:58 INFO - Downloading functools32-3.2.3-2.tar.gz 05:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:01:58 INFO - warning: no files found matching '*.txt' 05:01:58 INFO - no previously-included directories found matching 'build' 05:01:58 INFO - no previously-included directories found matching 'dist' 05:01:58 INFO - no previously-included directories found matching '.git*' 05:01:58 INFO - Installing collected packages: jsonschema, functools32 05:01:58 INFO - Running setup.py install for functools32 05:01:58 INFO - warning: no files found matching '*.txt' 05:01:58 INFO - no previously-included directories found matching 'build' 05:01:58 INFO - no previously-included directories found matching 'dist' 05:01:58 INFO - no previously-included directories found matching '.git*' 05:01:58 INFO - Successfully installed jsonschema functools32 05:01:58 INFO - Cleaning up... 05:01:58 INFO - Return code: 0 05:01:58 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:01:58 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')]} 05:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:58 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')]} 05:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a6a4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe342d8dab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101908d30>, '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 0x10172bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018feb20>, '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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 05:01:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'flake8'] in /builds/slave/test/build 05:01:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 flake8 05:01:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tYlBkQmdi/Render', 05:01:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:58 INFO - 'HOME': '/Users/cltbld', 05:01:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:58 INFO - 'LOGNAME': 'cltbld', 05:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:58 INFO - 'MOZ_NO_REMOTE': '1', 05:01:58 INFO - 'NO_EM_RESTART': '1', 05:01:58 INFO - 'PAGER': '/bin/cat', 05:01:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:58 INFO - 'PWD': '/builds/slave/test', 05:01:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:58 INFO - 'SHELL': '/bin/bash', 05:01:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SsZJUOcCOB/Listeners', 05:01:58 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:01:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:58 INFO - 'USER': 'cltbld', 05:01:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:58 INFO - 'XPC_FLAGS': '0x0', 05:01:58 INFO - 'XPC_SERVICE_NAME': '0', 05:01:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:58 INFO - Downloading/unpacking flake8 05:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:01:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:02:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:02:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 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 05:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:02:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:02:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:02:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:02:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:02:02 INFO - no previously-included directories found matching 'docs/_build' 05:02:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:02:02 INFO - Running setup.py install for pep8 05:02:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:02:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:02:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:02:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:02:02 INFO - no previously-included directories found matching 'docs/_build' 05:02:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:02:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:02:02 INFO - Cleaning up... 05:02:02 INFO - Return code: 0 05:02:02 INFO - Running post-action listener: _resource_record_post_action 05:02:02 INFO - Running post-action listener: _start_resource_monitoring 05:02:02 INFO - Starting resource monitoring. 05:02:02 INFO - ##### 05:02:02 INFO - ##### Running install step. 05:02:02 INFO - ##### 05:02:02 INFO - Running pre-action listener: _resource_record_pre_action 05:02:02 INFO - Running main action method: install 05:02:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:02:02 INFO - Reading from file tmpfile_stdout 05:02:02 INFO - Detecting whether we're running mozinstall >=1.0... 05:02:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:02:02 INFO - Reading from file tmpfile_stdout 05:02:02 INFO - Output received: 05:02:02 INFO - Usage: mozinstall [options] installer 05:02:02 INFO - Options: 05:02:02 INFO - -h, --help show this help message and exit 05:02:02 INFO - -d DEST, --destination=DEST 05:02:02 INFO - Directory to install application into. [default: 05:02:02 INFO - "/builds/slave/test"] 05:02:02 INFO - --app=APP Application being installed. [default: firefox] 05:02:02 INFO - mkdir: /builds/slave/test/build/application 05:02:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:02:37 INFO - Reading from file tmpfile_stdout 05:02:37 INFO - Output received: 05:02:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:02:37 INFO - Running post-action listener: _resource_record_post_action 05:02:37 INFO - ##### 05:02:37 INFO - ##### Running run-tests step. 05:02:37 INFO - ##### 05:02:37 INFO - Running pre-action listener: _resource_record_pre_action 05:02:37 INFO - Running main action method: run_tests 05:02:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:02:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:02:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:02:37 INFO - 2016-02-06 05:02:37.487 screenresolution[2352:19595] starting screenresolution argv=screenresolution get 05:02:37 INFO - 2016-02-06 05:02:37.490 screenresolution[2352:19595] Display 0: 1600x1200x32@60 05:02:37 INFO - 2016-02-06 05:02:37.496 screenresolution[2353:19597] starting screenresolution argv=screenresolution list 05:02:37 INFO - Available Modes on Display 0 05:02:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:02:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:02:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:02:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:02:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:02:37 INFO - Intel Iris: 05:02:37 INFO - Chipset Model: Intel Iris 05:02:37 INFO - Type: GPU 05:02:37 INFO - Bus: Built-In 05:02:37 INFO - VRAM (Dynamic, Max): 1536 MB 05:02:37 INFO - Vendor: Intel (0x8086) 05:02:37 INFO - Device ID: 0x0a2e 05:02:37 INFO - Revision ID: 0x0009 05:02:37 INFO - Displays: 05:02:37 INFO - Display: 05:02:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:02:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:02:37 INFO - Display Serial Number: 1600x1200 60 05:02:37 INFO - Main Display: Yes 05:02:37 INFO - Mirror: Off 05:02:37 INFO - Online: Yes 05:02:37 INFO - Rotation: Supported 05:02:37 INFO - Return code: 0 05:02:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:02:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:02:37 INFO - Python 2.7.3 05:02:37 INFO - Return code: 0 05:02:37 INFO - grabbing minidump binary from tooltool 05:02: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')]} 05:02:37 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 0x101908d30>, '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 0x10172bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018feb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:02:37 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 05:02:37 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 05:02:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:02:37 INFO - Return code: 0 05:02:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:02:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:02:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:02:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:02:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:02:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:02:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:02:39 INFO - Return code: 0 05:02:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0082 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:02:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tYlBkQmdi/Render', 05:02:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:39 INFO - 'HOME': '/Users/cltbld', 05:02:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:39 INFO - 'LOGNAME': 'cltbld', 05:02:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:02:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:39 INFO - 'MOZ_NO_REMOTE': '1', 05:02:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:02:39 INFO - 'NO_EM_RESTART': '1', 05:02:39 INFO - 'PAGER': '/bin/cat', 05:02:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:39 INFO - 'PWD': '/builds/slave/test', 05:02:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:02:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:39 INFO - 'SHELL': '/bin/bash', 05:02:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SsZJUOcCOB/Listeners', 05:02:39 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 05:02:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:39 INFO - 'USER': 'cltbld', 05:02:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:39 INFO - 'XPC_FLAGS': '0x0', 05:02:39 INFO - 'XPC_SERVICE_NAME': '0', 05:02:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:02:39 INFO - mozversion application_buildid: 20160203064345 05:02:39 INFO - mozversion application_changeset: 6826dfd0e85b29050e45273d9bfdb27dc2bc1128 05:02:39 INFO - mozversion application_display_name: Nightly 05:02:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:02:39 INFO - mozversion application_name: Firefox 05:02:39 INFO - mozversion application_remotingname: firefox 05:02:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:02:39 INFO - mozversion application_vendor: Mozilla 05:02:39 INFO - mozversion application_version: 47.0a1 05:02:39 INFO - mozversion platform_buildid: 20160203064345 05:02:39 INFO - mozversion platform_changeset: 6826dfd0e85b29050e45273d9bfdb27dc2bc1128 05:02:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:02:39 INFO - mozversion platform_version: 47.0a1 05:02:39 INFO - using testdate: 1454763759 05:02:39 INFO - actual date: 1454763759 05:02:39 INFO - starting webserver on 'localhost:51525' 05:02:39 INFO - SUITE-START | Running 2 tests 05:02:39 INFO - TEST-START | dromaeo_css 05:02:39 INFO - operating with platform_type : mac_ 05:02:39 INFO - Initialising browser for dromaeo_css test... 05:02:39 INFO - TEST-INFO | started process 2371 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpE8hROS/profile http://localhost:51525/getInfo.html) 05:02:39 INFO - PROCESS | 2371 | JavaScript warning: resource://gre/modules/AddonManager.jsm, line 692: Proxy.create and Proxy.createFunction are deprecated, use new Proxy instead 05:02:41 INFO - PROCESS | 2371 | __metrics Screen width/height:1600/1200 05:02:41 INFO - PROCESS | 2371 | colorDepth:24 05:02:41 INFO - PROCESS | 2371 | Browser inner width/height: 1024/689 05:02:41 INFO - PROCESS | 2371 | __metrics 05:02:41 INFO - PROCESS | 2371 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:02:41 INFO - PROCESS | 2371 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:02:41 INFO - PROCESS | 2371 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:02:42 INFO - TEST-INFO | 2371: exit 0 05:02:42 INFO - Browser initialized. 05:02:42 INFO - Running cycle 1/1 for dromaeo_css test... 05:02:42 INFO - TEST-INFO | started process 2374 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpE8hROS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:02:54 INFO - PROCESS | 2374 | RSS: Main: 242929664 05:02:54 INFO - PROCESS | 2374 | 05:03:46 INFO - PROCESS | 2374 | 1454763826024 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] 05:05:56 INFO - PROCESS | 2374 | Cycle 1(1): loaded http://localhost:51525/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51525/tests/dromaeo/cssquery-ext.html) 05:05:57 INFO - PROCESS | 2374 | RSS: Main: 384503808 05:05:57 INFO - PROCESS | 2374 | 05:09:00 INFO - PROCESS | 2374 | Cycle 1(1): loaded http://localhost:51525/tests/dromaeo/cssquery-ext.html (next: http://localhost:51525/tests/dromaeo/cssquery-jquery.html) 05:09:00 INFO - PROCESS | 2374 | RSS: Main: 380305408 05:09:00 INFO - PROCESS | 2374 | 05:12:02 INFO - PROCESS | 2374 | Cycle 1(1): loaded http://localhost:51525/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51525/tests/dromaeo/cssquery-mootools.html) 05:12:03 INFO - PROCESS | 2374 | RSS: Main: 376283136 05:12:03 INFO - PROCESS | 2374 | 05:15:05 INFO - PROCESS | 2374 | Cycle 1(1): loaded http://localhost:51525/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51525/tests/dromaeo/cssquery-prototype.html) 05:15:06 INFO - PROCESS | 2374 | RSS: Main: 346542080 05:15:06 INFO - PROCESS | 2374 | 05:18:09 INFO - PROCESS | 2374 | Cycle 1(1): loaded http://localhost:51525/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51525/tests/dromaeo/cssquery-yui.html) 05:18:10 INFO - PROCESS | 2374 | RSS: Main: 341688320 05:18:10 INFO - PROCESS | 2374 | 05:21:12 INFO - PROCESS | 2374 | Cycle 1(1): loaded http://localhost:51525/tests/dromaeo/cssquery-yui.html (next: http://localhost:51525/tests/dromaeo/cssquery-dojo.html) 05:21:12 INFO - PROCESS | 2374 | RSS: Main: 380383232 05:21:12 INFO - PROCESS | 2374 | 05:21:12 INFO - PROCESS | 2374 | __start_tp_report 05:21:12 INFO - PROCESS | 2374 | _x_x_mozilla_page_load 05:21:12 INFO - PROCESS | 2374 | _x_x_mozilla_page_load_details 05:21:12 INFO - PROCESS | 2374 | |i|pagename|runs| 05:21:12 INFO - PROCESS | 2374 | |0;dojo.html;3392.5080863274825;3417.3336199441105;3439.65603439656;3457.7406694497918;3474.40935041043;5582.91625625616;5658.971705141479;5664.575156863231;5666.688332141735;5674.914876276854;5585.972070139644;5672.716364181785;5714.200011998323;5805.390434004422;5815.505682017034;5094.210397388392;5129.948700513003;5147.6654017488745;5156.793728250885;5190.948090519084;5278.261043453919;5307.495787900143;5313.681179129265;5326.360836699582;5403.054465468547;5109.463506331856;5199.090159222121;5229.686218826882;5300.946990530084;5324.733763311839;5165;5181.4300426953005;5194.636375453682;5197.558207552363;5199;5100.311457953166;5158.69047857126;5166.741662916839;5180.8963820723375;5186.455422180692;5525.723713814334;5530.723463826833;5544.16837474378;5554.9722251388885;5566.526845218162;6132.478739307165;6203.472704820096;6248.625082495065;6256.468200202989;6262.2172228471445;5725.942740572583;6279.403456671609;6305.464035556983;6309.053641953744;6326.93673063268;5639.238702775155;5676.176954341598;5757.07886738124;5762;5775.191473193756;6261.749530018748;6339.746410143634;6368.29948705642;6381.617102973743;6385.393387628168;6126.846828829315;6130.448259656563;6134.110553969649;6159.630422174684;6171.475424588871;6105.481034112061;6275.309715931244;6278.843028924313;6287.434130928238;6298.401651843067;6095.268567771807;6134.294556126013;6138.202033735675;6144.754209831646;6149.954507733696;6133.172021777003;6138.754449822047;6185.659788711574;6201.937980620226;6215.471684906834;4843.927341089887;4861.659683822169;4862.975685121552;4871.391076115486;4914.967856750122;4296.119295544406;4336.219480493542;4344.500382455994;4354.216241076637;4369.191699535596;5570.832875013756;5643.943560564424;5653.208550802891;5671.688057156814;5675.574331925122;5403.595065283054;6180.258368995749;6270;6301.212348456443;6338.429541341301;5647.661140331593;5666.914996275059;5684.77260909559;5693;5701.429857014348;5212.843614691489;5289.68261904287;5298.523132918056;5307.893842123136;5311.3095297611835;5087.898242035212;6269.843253918689;6356.872862542816;6357.96821015892;6392.20097487814;6235.189425374672;6261.966775482177;6264.624122552661;6280.968595157177;6300.243970723543;6252.3122456530655;6352.745890164527;6363.840903977252;6372.776952806629;6423.03654451836;6073.878522429615;6077.756889724362;6100.375751378651;6136.9441237184255;6150.692465376624;6234.812955611339;6270.467010304175;6272.090546870587;6292.182016337847;6300;5749.108888122348;5997.0404735242155;6023.728932197974;6029.095635654687;6046.365131661289;5508.091164957737;5568.972155139199;5918.940810591839;5989.64062156272;6094.11635312886;5801.593888427769;5816.651000939957;5825.0388685866355;5836.212111364912;5843.503302219359;5866.090755932837;5937.079752638348;5986.07215881539;6007.339192688888;6050.485708714808;5745.971270143623;5826.184334193301;5864.618799778001;5878.588498805043;5923.348431672426 05:21:12 INFO - PROCESS | 2374 | |1;ext.html;472.0841567359325;552.2654869024199;561.7303694226769;613.834264748518;631.6178711879313;29102.70897291029;29484.852575737117;37577.81211093944;38014.61985380145;38197.809010954974;40682.79658601703;41132.177356452856;41174.58825411745;41218.7939060305;41393.172136557325;9433.292503062281;9645.662401815938;9654.71035868925;9662.613495460208;9681.70954871355;9511.762205944837;9661.130498255156;9678.516074196263;9690.933997260296;9703.417794932328;9717.61129554817;9796.510174491283;9821.459819709913;9821.901780982205;9832.70501884941;9685.951570242174;9703.98108198643;9704.708858734179;9761.755956101048;9823.705288841345;9477.715668529956;9580.61677532898;9617.759556011086;9628.229741620653;9655.951720241424;39106.608933910866;39316.41025384622;39339.40990885139;39391.60608393937;39405.62080327203;3111.5177147542167;3151.8108913465035;3476.5132881396626;3636.945445818315;3913.0608653922855;28403.57394639082;28767.99312024069;28913.277168070756;29074.41851162965;29408.11775646734;2764.3089227693076;2765.1151631477937;2767.6955534891144;2872.626558547396;2944.5435957426635;439385;442141;443168;443515;444302;53685.1947220792;55152.896942060936;62007.689961549906;62716;63475;62895.6855215721;63479.365206348266;63804;63817.68091159515;63862.36137638564;15944;16141;16148.27332770028;16182.75725864113;16182.838171618369;11388.430578471043;11523.193376463734;11525.423728813526;12460;12538;19652.606947861248;19938.00309984524;19958.70061949072;19984.50038749043;20002.89998549998;8356.623951922178;8560.57197140153;8562.272206862363;8623.180797824196;8699.869501957475;910.3536489092686;992.1764935103846;1014.8071866345371;1031.4224034540682;1088.88022317545;29983.400331993675;31227.68772312248;31243.68756312453;31761.841190793897;32044;1042.1089968077335;1087.526925787285;1098.7967226063204;1101.807183742858;1153.2273376837372;13660.726785464434;13778.862211377958;15316.23418829054;15421.845781542266;15937.681246375008;418064;458287;458382;458455;459006;58107.709461452425;58245.417545824;58390;58412.70793646004;58755.41244587585;1293.683047653312;1362.7274545091022;1434.7704367301183;1439.5537383411177;1573.4099712607772;28921.987730429333;28925.7107428923;29322;29922.102336929926;30229.69770302357;53120.46879531155;53784.73107634437;53883.6529086776;53949.65125871728;54119.72940135274;423286;427147;448142;451981.7400912974;620629.7937020572;28459.857700712193;31381.686183137877;31446.371072577964;31485.370292594478;31583.526247105852;43620;44176;44188.33717494176;44630.10739785251;45451.54548454473;36828.52193707197;37005.629943700216;37576.6848160324;38005.80997095107;38119.618803812715;33619.49570756392;35970.64029359673;36250.63749362472;36412.72555460546;37039.8148009269;36459.81770091133;37035.81482092572;37272.81363593165;37299.44050839294;37310.440343395414;25297;28831.423371532033;31004.604792783928;31074.91237806666;31111.84444077765;17455.650886982443;23873.880630596737;24003.519929601658;24289.75710242875;24438.877805610857 05:21:12 INFO - PROCESS | 2374 | |2;jquery.html;360.9314230296244;363.58551251573226;366.92661467706455;367.21049743052436;369.7688944409744;14936.477223297186;15402.768958465633;18615.255389784408;19599.608007839834;20084.698729519034;19945;20076.196952121896;20386.69419958702;21238;21260.4684882878;6218.347073557279;6490.545661803676;6490.707918143671;6515.153030106079;6520.576162549442;4502.729836209838;4551.499335073139;5202.323697919265;6432.067350234213;7275.854482910338;6990;7187.964060179692;7222.674979625903;7263.854722905538;7266.200717921024;7073.610951397671;7147.392471639917;7159;7177.3540381365665;7180.353768160864;6945.687444064979;7016.403605693522;7018.964905175492;7033.964830175868;7044.7534336298495;25171.748282517125;25602.97588096474;25993.610095848577;26190.9523619055;26913.058042968598;1926.1236137557378;2133.797289257518;2163.2212403534713;2318.5362927414585;2344.988275058614;7649.808754781119;9393.57728902201;9561.808763824685;9646.276529260334;9854.260930430175;1627.275862241303;1689.7380905959596;1757.6748301564123;1774.0508827777207;1821.4171465131162;73117.6344118276;96015.039849602;97017.51491242569;97515;98182.50908745482;10820;10891.945540272249;10918.617848375268;10929.398883061507;10994.450277486094;10675.62635307776;12668;13250.668733281746;13265.137766045176;13270.601881943558;8232.094469608339;8251.793705157299;8345.040320363114;8358.498490090493;8730.738077857675;8210.835783284301;8218.43849668561;8238.50568965852;8321.251087402043;8326.417150799385;3954.525456945185;4143.482064741907;4254.42565253693;4331.891702707395;4394.648428125743;1719.2091637846481;1749.413946327969;1758.156085079169;1760.6654735600196;1764.682357175695;403.91517781265674;779.9883001754978;887.4187407248263;927.8190752803139;937.465644582595;11530.654080377602;11705.824412633818;11883;11921.986631136277;11970.102242331859;441.0913518152616;452.2470087304601;455.9954400456019;456.98172073117365;480.59389815605755;6024.969875150597;6167;10839.072298548617;11474.935379886489;11538.59614913474;729802;741154;746461.5507681799;756380;759234;106311.4684426573;107985.46007269913;108120.99999999843;109498.45250773695;111492;1084.9077828384518;1118.7818375416878;1155.5146838327923;1180.0618508285934;1180.651707746217;16510.17449127563;17963.550911227325;18230.544236394195;18344.633107338046;18389.26442942321;48081.75959120182;49329.01341973068;50676.74661626668;51195.74402127966;51421.74289128531;63414.68292658507;65044;68309.3169068303;68361.31638683549;69253;7433.591152486665;7657.731979380695;7723.961380193063;7743.883841742266;7767.495112817649;11217;11295.887041129483;11899.464524096264;11950.282983021047;12185.87814121883;6596.336367222043;9741.366811157252;9963.50182490858;10168.74578135523;10297.9485102574;8734.606942687722;9823.312368134448;9845.803083938137;10010.699679009642;10236.897631023594;9946.154576860758;10083.4454105022;10283.845742313722;10489.370637761758;10589.788204235718;6057.7879774207695;7548.3961283098315;7728.806779830549;7732.342750866239;7751.6511756972195;3966.36538153894;6000.459958603747;8270.545120018456;9670.404383276962;10341.9482902585 05:21:12 INFO - PROCESS | 2374 | |3;mootools.html;1342.9798553021706;1396.804447377368;1557.4626753769949;1725.6031112844046;1907.9904600476996;7804.024496937883;8069.314108300794;8429.409941304119;8609.311255099592;8618.224359807617;8419.494830310186;8572.399932004762;8607.18231767981;8623.180797824212;8654.134586541342;2167.729033870766;2239.070785623964;2457.4225057111566;2484.7391023942496;2485.9005639774387;2249.8200143988443;2438.4147804526965;2503.674522312097;2509.723930367658;2514.798816094708;2098.2760947473175;2423.3941514621347;2457.3610861176135;2465.6671349367875;2467.8272520923542;2421.5399074176025;2456.1403508771964;2463.0147940823636;2466.7409921958206;2480.9255722328357;2442.8778561071877;2459.053264493165;2460.4218008767925;2462.4459496613117;2469.394998225446;8586.184312490366;8679.696210632597;8683.826323473559;8684.956575217084;8689.826203475895;5368.597355198376;5480.616356855022;5494.560435165177;5498.945010549884;5528.917066244009;3578.4453409721536;3657.9268414631824;3685.041889108851;3710.2579484103017;3714.5542534895717;954.2652157838495;1036.7563622548696;1057.7302787789065;1074.70982834635;1084.1110289562562;12024.69938251542;12069.396530173457;12094.032477401786;12094.455749491293;12110.39448027595;4879.2925025871045;4899.387576552931;4935.062338155739;4949.406071271398;4998.150314446553;10163.593456261668;10182.287239893136;10187.439690817077;10191.286609937233;10194.286399951932;5484.107982745946;5510.9448905510435;5526.530244929226;5532.612717109763;5537.086380747212;4909.337239472697;5038.3198268234055;5043.445221025684;5049.545540901337;5053.84838454847;3632.0919770057485;3711.1629743276712;3711.830773306454;3907.5506316773367;3985.3623420253202;1130.547780887635;1276.8595454499998;1296.2740865115566;1310.423413697989;1312.2389014371643;1319.7228581997888;1325.6950901292757;1333.7132516508802;1335.125492802216;1355.586546103448;7962;7969.9601501993275;7985.08171560278;7989.760307190793;7990.4007199460275;1158.3397463445756;1175.1656324010048;1182.3851597169596;1201.0631707268344;1216.9817452738216;6220.129181914535;6223.471004964538;6313.3686631336495;6321.304656487782;6328;10357.741056473498;10775.83836242457;10803.351798891934;10812.891871081345;10825.729356766144;10232.897671023195;10533.736656583647;10551.84172237417;10554.050135487843;10665.946670266754;8167.673293068329;8230.341572674171;8236.382271329674;8307.83384332322;8339.958300208582;7676.155622881367;7817.218278172138;7872.291493765589;7962.800929976565;7998.640061197144;9151.588178531849;9328.253739700944;9407.717768466957;9447.338686291894;9603.615855365986;9446.480443575394;9666.226701863974;9793.265505087005;9873.210143188671;9883.159931405624;5988;7253.057102576631;7406.777796666109;7517.586532740533;7523.849523009619;7422.777316680508;7629.504082234637;7999.680012799423;8063.516189028677;8077.7576672699915;6890.827729306808;6895.172579290316;7606.581638009741;7660.73187438437;7694.807629809299;7329.486935914648;7427;7449.739259125905;7554.980077689662;7585.16563178061;7443.441741869273;7544.283293087148;7598.506097103671;7621.428392870446;7639.350655194148;6903.861922761416;6956.2695916928;6964.825879353057;7037.190723066913;7044.15470143586;7057.012018317337;7084.858302834017;7127.75052873147;7134.1795693495915;7150.10623672041 05:21:12 INFO - PROCESS | 2374 | |4;prototype.html;317.36843681074663;323.116276982453;329.0064006699768;329.37912035812485;331.8108678053509;5668.008098582742;6505.479561635069;6521.347865213482;6522.608643481389;6567.376099270562;6311.526635502332;6527.184101987252;6527.379898909608;6529.640869752162;6536.182977127859;4061.740860333306;4215;4889.1932831083;4911.16510193268;4923.532264434873;4683.391159149306;4902.730349830758;4911.75441227939;4916.975415122919;4921.753912304371;3970.38459038848;4809.1343558159515;4819.951800482003;4829.323894654751;4837.661363704542;4565.726056436624;4691.366665500148;4839.612830973514;4848.709077455346;4861.902761944777;3985.4221137934837;4016.879493615196;4084.223997440477;4093.1199792044854;4102.3026085565525;6275.592086514362;6337.429631333156;6342.587731797418;6352.110704501373;6358.950773122429;5328.46715328468;5446.128619420873;5464.18037294409;5554.166874968787;5561.610687251894;6623.35715356552;6828.0440738296675;6829.351211634887;6844.8780877928;6851.931480685229;4387.648988080946;4389.846355377546;4399.670024748125;4400.987772812239;4501.977490112561;7272.090988626422;7387.445941554405;7393.519421237603;7398.556086634819;7403.962980185119;4588.426446694163;4602.953970460319;4622.243547451947;4766.928496072562;4774.116788394089;4845.515448455197;4850.466448690641;4857.975710121426;4858.902821943541;4863.10032643962;4393.890152746143;5657.6039677223025;5664.971675141598;5714.314282286152;5826.834633073402;6832.59004459734;7019.89470157948;7027.754028608974;7032.156141262979;7041.471889608198;1674.6064674801412;1700.481353187265;1701.2769572931454;1701.5150682055555;1708.9230984605722;922.3405265235389;1108.778244351133;1120.5237773946203;1123.4214379594516;1125.3135587291745;4857;6572.474202063823;6598.1422415085735;6617.106690596708;6644.269130395653;7218.530795498275;7257.709691612382;7266.1280646322775;7268.418526517866;7268.745593904294;6337.4016653171975;6604;6633.104530888365;6646.833829154213;6646.933530664632;3817.6182381762155;3860.903477413031;3874.1283211277237;3875.0312421894528;3885.572587015426;7540.434467414856;7566.167721550625;7571;7573.545587264782;7588.392928565701;7247.202807691256;7342.4493163012985;7403.481756277009;7447.255274472509;7567.583782891884;6211.3478084801345;7617.085949685962;7670.578118203553;7685.615719214129;7687.807804804925;7064.116985376828;7336.449766267658;7350.301721336465;7354.926450735424;7362.5214361066355;7172.748953786592;7176.928230717626;7233.746818861525;7236.855262894607;7241.275872412716;7656.234376562522;7663.118741344817;7664.8850267244925;7672.6930922764695;7678.308952194329;4507.346434767006;4614.561616646413;4641.257398816173;4650.604698600657;4661.836835710734;7187.712491500491;7231.240719724353;7248.021517095125;7251.709931602888;7253.891191632235;5710.5146062585145;6990.720371185095;7005.194402643761;7010.369066784014;7017.859642807013;5942.346341902474;6745.392914637706;6779.966100169468;7061.505694601534;7070.045543851515;6884.965575172092;6891.793246202622;6892.965535172292;6903;6904.861902762017;7039.8944015840825;7052.541584796972;7055.153381594241;7061.2232654409;7065.505414620927;6650.534462587766;6914.239433662193;6928.272531384334;6930.965345173242;6932.202796678245 05:21:12 INFO - PROCESS | 2374 | |5;yui.html;714.7212587091034;733.2520828754671;734.1007266099033;750.801037725003;782.3780094824614;17174.74237886431;17444;17455.389061382855;17474;17487.562810929732;15700.293486793093;15717.37130514781;15738.97696649717;15771.763423548657;15772.526824195264;687.4328678839952;707.632031343701;844.0293662288356;860.6600392844823;862.4006315610653;700.0689083518907;726.2011787034273;847.1147650704997;850.0267194062794;853.0616322045762;884.2572239318971;891.4205766251923;892.1836519584605;892.8258989497051;894.0121166111451;718.8346680263543;736.8784150615143;737.4100719424439;880.4101252161036;888.2405543260517;654.1430725749284;655.3217419970356;656.1830521001336;656.4977791989132;660.8215781738952;14956.476523321631;14963.70072598542;14971;14990.400383984628;15007.924960375238;12319.076069294839;12336.198147120409;12439.626811195678;12459.95086974501;12583.112533120013;10036.59853605855;10144.492775361203;10173.79652406955;10183.490825458772;10213.489325533694;3451.2752322012407;3481.599616044162;3488.529048578435;3491.5810102787827;3491.91270218244;81441.59279203566;82677.17322826695;83479;83650.74523882147;84208.57895710543;7704.460687751804;9040;9223.262139028862;9370.812583748288;9555.187809036172;20428.387148385573;20468.69296960547;20485.283015094697;20503.589928201352;20515.897420512803;7439.962800186073;7494.437917156235;7503.474756766974;7521.522428636175;7640.73257435987;4090.263752524574;4095.7542547447247;4111.444954931106;4175.68682348825;4222.767747773841;3582.4268117101137;3645.8906232813056;3648.0515066083008;3648.6533779290926;3651.3062518121474;645.4674893213024;655.6787174284566;660.9074729537869;675.0650349266301;679.966001699913;507.908220093565;551.966881987082;559.7979556934329;601.3084952304885;611.3244864424836;7880.408969327209;8410.831783364298;8485.787855303666;8557.743267701979;8669.306455483546;514.9253358263031;578.5602941764289;593.884192582451;615.1142355008773;634.321276234434;3470.774399664014;3844;3867.1878905430176;3945.546262179829;4051.736637118578;90162.64756028664;90256.548717256;90760.6385904212;94109.05890941003;94783.52608236915;73340.53318933591;73594.6320268406;77592;80272;82535.587322063;69806.65096674484;70484.64757676178;70736.3935927681;71023.64488177525;71459;7498.550086994798;7983.32141767933;8016.679332826855;8032.518048917083;8187.877181842158;47823.76088119537;49385.50614493953;49415.0584941503;49763;50073.99852002867;63201.73596527952;67571;68148.6592567034;68783.31216687769;69613;9747.171490423394;9749.268804839523;9796.265280104164;9914.40513569188;10490.842637360292;10684.305520141166;10965.451727413756;11021.228514003942;11215.158863085138;11761.353125578175;8809.779755506164;10160.339577927707;10401.323913945873;10419.94790026045;10602.628907988183;10280.537375818256;10396.116330111723;10514.526846292089;10558.208134389797;10659.626913058006;8647.956760216159;10091.243156763125;11014.118870490502;11138.94430527842;11150;8997.145271199226;9024.36829421934;9468.242540596599;9641.909020140021;9646.131848133702;8994.505302208441;9331.486768227813;9686.176674982706;9721.756956076155;9781.706548803548 05:21:12 INFO - PROCESS | 2374 | __end_tp_report 05:21:12 INFO - PROCESS | 2374 | __start_cc_report 05:21:12 INFO - PROCESS | 2374 | _x_x_mozilla_cycle_collect,1186 05:21:12 INFO - PROCESS | 2374 | __end_cc_report 05:21:12 INFO - PROCESS | 2374 | __startTimestamp1454764872943__endTimestamp 05:21:12 INFO - PROCESS | 2374 | 05:21:12 INFO - PROCESS | 2374 | ------- Summary: start ------- 05:21:12 INFO - PROCESS | 2374 | Number of tests: 6 05:21:12 INFO - PROCESS | 2374 | 05:21:12 INFO - PROCESS | 2374 | [#0] dojo.html Cycles:165 Average:5697.48 Median:5815.51 stddev:635.19 (10.9%) stddev-sans-first:610.86 05:21:12 INFO - PROCESS | 2374 | Values: 3392.5 3417.3 3439.7 3457.7 3474.4 5582.9 5659.0 5664.6 5666.7 5674.9 5586.0 5672.7 5714.2 5805.4 5815.5 5094.2 5129.9 5147.7 5156.8 5190.9 5278.3 5307.5 5313.7 5326.4 5403.1 5109.5 5199.1 5229.7 5300.9 5324.7 5165.0 5181.4 5194.6 5197.6 5199.0 5100.3 5158.7 5166.7 5180.9 5186.5 5525.7 5530.7 5544.2 5555.0 5566.5 6132.5 6203.5 6248.6 6256.5 6262.2 5725.9 6279.4 6305.5 6309.1 6326.9 5639.2 5676.2 5757.1 5762.0 5775.2 6261.7 6339.7 6368.3 6381.6 6385.4 6126.8 6130.4 6134.1 6159.6 6171.5 6105.5 6275.3 6278.8 6287.4 6298.4 6095.3 6134.3 6138.2 6144.8 6150.0 6133.2 6138.8 6185.7 6201.9 6215.5 4843.9 4861.7 4863.0 4871.4 4915.0 4296.1 4336.2 4344.5 4354.2 4369.2 5570.8 5643.9 5653.2 5671.7 5675.6 5403.6 6180.3 6270.0 6301.2 6338.4 5647.7 5666.9 5684.8 5693.0 5701.4 5212.8 5289.7 5298.5 5307.9 5311.3 5087.9 6269.8 6356.9 6358.0 6392.2 6235.2 6262.0 6264.6 6281.0 6300.2 6252.3 6352.7 6363.8 6372.8 6423.0 6073.9 6077.8 6100.4 6136.9 6150.7 6234.8 6270.5 6272.1 6292.2 6300.0 5749.1 5997.0 6023.7 6029.1 6046.4 5508.1 5569.0 5918.9 5989.6 6094.1 5801.6 5816.7 5825.0 5836.2 5843.5 5866.1 5937.1 5986.1 6007.3 6050.5 5746.0 5826.2 5864.6 5878.6 5923.3 05:21:12 INFO - PROCESS | 2374 | 05:21:12 INFO - PROCESS | 2374 | [#1] ext.html Cycles:180 Average:60414.23 Median:38158.71 stddev:121553.27 (318.5%) stddev-sans-first:121810.94 05:21:12 INFO - PROCESS | 2374 | Values: 472.1 552.3 561.7 613.8 631.6 29102.7 29484.9 37577.8 38014.6 38197.8 40682.8 41132.2 41174.6 41218.8 41393.2 9433.3 9645.7 9654.7 9662.6 9681.7 9511.8 9661.1 9678.5 9690.9 9703.4 9717.6 9796.5 9821.5 9821.9 9832.7 9686.0 9704.0 9704.7 9761.8 9823.7 9477.7 9580.6 9617.8 9628.2 9656.0 39106.6 39316.4 39339.4 39391.6 39405.6 3111.5 3151.8 3476.5 3636.9 3913.1 28403.6 28768.0 28913.3 29074.4 29408.1 2764.3 2765.1 2767.7 2872.6 2944.5 439385.0 442141.0 443168.0 443515.0 444302.0 53685.2 55152.9 62007.7 62716.0 63475.0 62895.7 63479.4 63804.0 63817.7 63862.4 15944.0 16141.0 16148.3 16182.8 16182.8 11388.4 11523.2 11525.4 12460.0 12538.0 19652.6 19938.0 19958.7 19984.5 20002.9 8356.6 8560.6 8562.3 8623.2 8699.9 910.4 992.2 1014.8 1031.4 1088.9 29983.4 31227.7 31243.7 31761.8 32044.0 1042.1 1087.5 1098.8 1101.8 1153.2 13660.7 13778.9 15316.2 15421.8 15937.7 418064.0 458287.0 458382.0 458455.0 459006.0 58107.7 58245.4 58390.0 58412.7 58755.4 1293.7 1362.7 1434.8 1439.6 1573.4 28922.0 28925.7 29322.0 29922.1 30229.7 53120.5 53784.7 53883.7 53949.7 54119.7 423286.0 427147.0 448142.0 451981.7 620629.8 28459.9 31381.7 31446.4 31485.4 31583.5 43620.0 44176.0 44188.3 44630.1 45451.5 36828.5 37005.6 37576.7 38005.8 38119.6 33619.5 35970.6 36250.6 36412.7 37039.8 36459.8 37035.8 37272.8 37299.4 37310.4 25297.0 28831.4 31004.6 31074.9 31111.8 17455.7 23873.9 24003.5 24289.8 24438.9 05:21:12 INFO - PROCESS | 2374 | 05:21:12 INFO - PROCESS | 2374 | [#2] jquery.html Cycles:180 Average:36861.97 Median:2477.49 stddev:122746.17 (4954.5%) stddev-sans-first:123059.91 05:21:12 INFO - PROCESS | 2374 | Values: 360.9 363.6 366.9 367.2 369.8 14936.5 15402.8 18615.3 19599.6 20084.7 19945.0 20076.2 20386.7 21238.0 21260.5 6218.3 6490.5 6490.7 6515.2 6520.6 4502.7 4551.5 5202.3 6432.1 7275.9 6990.0 7188.0 7222.7 7263.9 7266.2 7073.6 7147.4 7159.0 7177.4 7180.4 6945.7 7016.4 7019.0 7034.0 7044.8 25171.7 25603.0 25993.6 26191.0 26913.1 1926.1 2133.8 2163.2 2318.5 2345.0 7649.8 9393.6 9561.8 9646.3 9854.3 1627.3 1689.7 1757.7 1774.1 1821.4 73117.6 96015.0 97017.5 97515.0 98182.5 10820.0 10891.9 10918.6 10929.4 10994.5 10675.6 12668.0 13250.7 13265.1 13270.6 8232.1 8251.8 8345.0 8358.5 8730.7 8210.8 8218.4 8238.5 8321.3 8326.4 3954.5 4143.5 4254.4 4331.9 4394.6 1719.2 1749.4 1758.2 1760.7 1764.7 403.9 780.0 887.4 927.8 937.5 11530.7 11705.8 11883.0 11922.0 11970.1 441.1 452.2 456.0 457.0 480.6 6025.0 6167.0 10839.1 11474.9 11538.6 729802.0 741154.0 746461.6 756380.0 759234.0 106311.5 107985.5 108121.0 109498.5 111492.0 1084.9 1118.8 1155.5 1180.1 1180.7 16510.2 17963.6 18230.5 18344.6 18389.3 48081.8 49329.0 50676.7 51195.7 51421.7 63414.7 65044.0 68309.3 68361.3 69253.0 7433.6 7657.7 7724.0 7743.9 7767.5 11217.0 11295.9 11899.5 11950.3 12185.9 6596.3 9741.4 9963.5 10168.7 10297.9 8734.6 9823.3 9845.8 10010.7 10236.9 9946.2 10083.4 10283.8 10489.4 10589.8 6057.8 7548.4 7728.8 7732.3 7751.7 3966.4 6000.5 8270.5 9670.4 10341.9 05:21:12 INFO - PROCESS | 2374 | 05:21:12 INFO - PROCESS | 2374 | [#3] mootools.html Cycles:180 Average:6025.46 Median:5482.36 stddev:3156.23 (57.6%) stddev-sans-first:3145.46 05:21:12 INFO - PROCESS | 2374 | Values: 1343.0 1396.8 1557.5 1725.6 1908.0 7804.0 8069.3 8429.4 8609.3 8618.2 8419.5 8572.4 8607.2 8623.2 8654.1 2167.7 2239.1 2457.4 2484.7 2485.9 2249.8 2438.4 2503.7 2509.7 2514.8 2098.3 2423.4 2457.4 2465.7 2467.8 2421.5 2456.1 2463.0 2466.7 2480.9 2442.9 2459.1 2460.4 2462.4 2469.4 8586.2 8679.7 8683.8 8685.0 8689.8 5368.6 5480.6 5494.6 5498.9 5528.9 3578.4 3657.9 3685.0 3710.3 3714.6 954.3 1036.8 1057.7 1074.7 1084.1 12024.7 12069.4 12094.0 12094.5 12110.4 4879.3 4899.4 4935.1 4949.4 4998.2 10163.6 10182.3 10187.4 10191.3 10194.3 5484.1 5510.9 5526.5 5532.6 5537.1 4909.3 5038.3 5043.4 5049.5 5053.8 3632.1 3711.2 3711.8 3907.6 3985.4 1130.5 1276.9 1296.3 1310.4 1312.2 1319.7 1325.7 1333.7 1335.1 1355.6 7962.0 7970.0 7985.1 7989.8 7990.4 1158.3 1175.2 1182.4 1201.1 1217.0 6220.1 6223.5 6313.4 6321.3 6328.0 10357.7 10775.8 10803.4 10812.9 10825.7 10232.9 10533.7 10551.8 10554.1 10665.9 8167.7 8230.3 8236.4 8307.8 8340.0 7676.2 7817.2 7872.3 7962.8 7998.6 9151.6 9328.3 9407.7 9447.3 9603.6 9446.5 9666.2 9793.3 9873.2 9883.2 5988.0 7253.1 7406.8 7517.6 7523.8 7422.8 7629.5 7999.7 8063.5 8077.8 6890.8 6895.2 7606.6 7660.7 7694.8 7329.5 7427.0 7449.7 7555.0 7585.2 7443.4 7544.3 7598.5 7621.4 7639.4 6903.9 6956.3 6964.8 7037.2 7044.2 7057.0 7084.9 7127.8 7134.2 7150.1 05:21:12 INFO - PROCESS | 2374 | 05:21:12 INFO - PROCESS | 2374 | [#4] prototype.html Cycles:180 Average:5717.94 Median:6528.51 stddev:1835.24 (28.1%) stddev-sans-first:1795.07 05:21:12 INFO - PROCESS | 2374 | Values: 317.4 323.1 329.0 329.4 331.8 5668.0 6505.5 6521.3 6522.6 6567.4 6311.5 6527.2 6527.4 6529.6 6536.2 4061.7 4215.0 4889.2 4911.2 4923.5 4683.4 4902.7 4911.8 4917.0 4921.8 3970.4 4809.1 4820.0 4829.3 4837.7 4565.7 4691.4 4839.6 4848.7 4861.9 3985.4 4016.9 4084.2 4093.1 4102.3 6275.6 6337.4 6342.6 6352.1 6359.0 5328.5 5446.1 5464.2 5554.2 5561.6 6623.4 6828.0 6829.4 6844.9 6851.9 4387.6 4389.8 4399.7 4401.0 4502.0 7272.1 7387.4 7393.5 7398.6 7404.0 4588.4 4603.0 4622.2 4766.9 4774.1 4845.5 4850.5 4858.0 4858.9 4863.1 4393.9 5657.6 5665.0 5714.3 5826.8 6832.6 7019.9 7027.8 7032.2 7041.5 1674.6 1700.5 1701.3 1701.5 1708.9 922.3 1108.8 1120.5 1123.4 1125.3 4857.0 6572.5 6598.1 6617.1 6644.3 7218.5 7257.7 7266.1 7268.4 7268.7 6337.4 6604.0 6633.1 6646.8 6646.9 3817.6 3860.9 3874.1 3875.0 3885.6 7540.4 7566.2 7571.0 7573.5 7588.4 7247.2 7342.4 7403.5 7447.3 7567.6 6211.3 7617.1 7670.6 7685.6 7687.8 7064.1 7336.4 7350.3 7354.9 7362.5 7172.7 7176.9 7233.7 7236.9 7241.3 7656.2 7663.1 7664.9 7672.7 7678.3 4507.3 4614.6 4641.3 4650.6 4661.8 7187.7 7231.2 7248.0 7251.7 7253.9 5710.5 6990.7 7005.2 7010.4 7017.9 5942.3 6745.4 6780.0 7061.5 7070.0 6885.0 6891.8 6893.0 6903.0 6904.9 7039.9 7052.5 7055.2 7061.2 7065.5 6650.5 6914.2 6928.3 6931.0 6932.2 05:21:12 INFO - PROCESS | 2374 | 05:21:12 INFO - PROCESS | 2374 | [#5] yui.html Cycles:180 Average:18237.05 Median:658.66 stddev:25843.36 (3923.6%) stddev-sans-first:25882.36 05:21:12 INFO - PROCESS | 2374 | Values: 714.7 733.3 734.1 750.8 782.4 17174.7 17444.0 17455.4 17474.0 17487.6 15700.3 15717.4 15739.0 15771.8 15772.5 687.4 707.6 844.0 860.7 862.4 700.1 726.2 847.1 850.0 853.1 884.3 891.4 892.2 892.8 894.0 718.8 736.9 737.4 880.4 888.2 654.1 655.3 656.2 656.5 660.8 14956.5 14963.7 14971.0 14990.4 15007.9 12319.1 12336.2 12439.6 12460.0 12583.1 10036.6 10144.5 10173.8 10183.5 10213.5 3451.3 3481.6 3488.5 3491.6 3491.9 81441.6 82677.2 83479.0 83650.7 84208.6 7704.5 9040.0 9223.3 9370.8 9555.2 20428.4 20468.7 20485.3 20503.6 20515.9 7440.0 7494.4 7503.5 7521.5 7640.7 4090.3 4095.8 4111.4 4175.7 4222.8 3582.4 3645.9 3648.1 3648.7 3651.3 645.5 655.7 660.9 675.1 680.0 507.9 552.0 559.8 601.3 611.3 7880.4 8410.8 8485.8 8557.7 8669.3 514.9 578.6 593.9 615.1 634.3 3470.8 3844.0 3867.2 3945.5 4051.7 90162.6 90256.5 90760.6 94109.1 94783.5 73340.5 73594.6 77592.0 80272.0 82535.6 69806.7 70484.6 70736.4 71023.6 71459.0 7498.6 7983.3 8016.7 8032.5 8187.9 47823.8 49385.5 49415.1 49763.0 50074.0 63201.7 67571.0 68148.7 68783.3 69613.0 9747.2 9749.3 9796.3 9914.4 10490.8 10684.3 10965.5 11021.2 11215.2 11761.4 8809.8 10160.3 10401.3 10419.9 10602.6 10280.5 10396.1 10514.5 10558.2 10659.6 8648.0 10091.2 11014.1 11138.9 11150.0 8997.1 9024.4 9468.2 9641.9 9646.1 8994.5 9331.5 9686.2 9721.8 9781.7 05:21:12 INFO - PROCESS | 2374 | -------- Summary: end -------- 05:21:12 INFO - PROCESS | 2374 | 05:21:13 INFO - TEST-INFO | 2374: exit 0 05:21:13 INFO - TEST-OK | dromaeo_css | took 1113769ms 05:21:13 INFO - TEST-START | kraken 05:21:13 INFO - operating with platform_type : mac_ 05:21:13 INFO - Initialising browser for kraken test... 05:21:13 INFO - TEST-INFO | started process 2376 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpBa6s7x/profile http://localhost:51525/getInfo.html) 05:21:13 INFO - PROCESS | 2376 | JavaScript warning: resource://gre/modules/AddonManager.jsm, line 692: Proxy.create and Proxy.createFunction are deprecated, use new Proxy instead 05:21:15 INFO - PROCESS | 2376 | __metrics Screen width/height:1600/1200 05:21:15 INFO - PROCESS | 2376 | colorDepth:24 05:21:15 INFO - PROCESS | 2376 | Browser inner width/height: 1024/689 05:21:15 INFO - PROCESS | 2376 | __metrics 05:21:15 INFO - PROCESS | 2376 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:21:15 INFO - PROCESS | 2376 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 668: Error: Phase "quit-application-granted is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:21:15 INFO - PROCESS | 2376 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:21:16 INFO - TEST-INFO | 2376: exit 0 05:21:16 INFO - Browser initialized. 05:21:16 INFO - Running cycle 1/1 for kraken test... 05:21:16 INFO - TEST-INFO | started process 2378 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpBa6s7x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:28 INFO - PROCESS | 2378 | RSS: Main: 242741248 05:21:28 INFO - PROCESS | 2378 | 05:21:30 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51525/tests/kraken/driver.html?testName=audio-beat-detection) 05:21:31 INFO - PROCESS | 2378 | RSS: Main: 290668544 05:21:31 INFO - PROCESS | 2378 | 05:21:34 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51525/tests/kraken/driver.html?testName=audio-dft) 05:21:34 INFO - PROCESS | 2378 | RSS: Main: 300384256 05:21:34 INFO - PROCESS | 2378 | 05:21:38 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51525/tests/kraken/driver.html?testName=audio-fft) 05:21:38 INFO - PROCESS | 2378 | RSS: Main: 286347264 05:21:38 INFO - PROCESS | 2378 | 05:21:40 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51525/tests/kraken/driver.html?testName=audio-oscillator) 05:21:41 INFO - PROCESS | 2378 | RSS: Main: 288268288 05:21:41 INFO - PROCESS | 2378 | 05:21:43 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51525/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:21:44 INFO - PROCESS | 2378 | RSS: Main: 289718272 05:21:44 INFO - PROCESS | 2378 | 05:21:46 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51525/tests/kraken/driver.html?testName=imaging-darkroom) 05:21:47 INFO - PROCESS | 2378 | RSS: Main: 291639296 05:21:47 INFO - PROCESS | 2378 | 05:21:50 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51525/tests/kraken/driver.html?testName=imaging-desaturate) 05:21:50 INFO - PROCESS | 2378 | RSS: Main: 291852288 05:21:50 INFO - PROCESS | 2378 | 05:21:53 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51525/tests/kraken/driver.html?testName=json-parse-financial) 05:21:54 INFO - PROCESS | 2378 | RSS: Main: 292261888 05:21:54 INFO - PROCESS | 2378 | 05:21:55 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51525/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:21:56 INFO - PROCESS | 2378 | RSS: Main: 325730304 05:21:56 INFO - PROCESS | 2378 | 05:21:58 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51525/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:21:58 INFO - PROCESS | 2378 | RSS: Main: 325287936 05:21:58 INFO - PROCESS | 2378 | 05:22:01 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51525/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:22:01 INFO - PROCESS | 2378 | RSS: Main: 332414976 05:22:01 INFO - PROCESS | 2378 | 05:22:04 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51525/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:22:04 INFO - PROCESS | 2378 | RSS: Main: 333873152 05:22:04 INFO - PROCESS | 2378 | 05:22:07 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51525/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:22:07 INFO - PROCESS | 2378 | RSS: Main: 334204928 05:22:07 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | Cycle 1(1): loaded http://localhost:51525/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51525/tests/kraken/driver.html?testName=ai-astar) 05:22:09 INFO - PROCESS | 2378 | RSS: Main: 394657792 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | __start_tp_report 05:22:09 INFO - PROCESS | 2378 | _x_x_mozilla_page_load 05:22:09 INFO - PROCESS | 2378 | _x_x_mozilla_page_load_details 05:22:09 INFO - PROCESS | 2378 | |i|pagename|runs| 05:22:09 INFO - PROCESS | 2378 | |0;ai-astar;110;109;111;112;112;111;116;111;109;110 05:22:09 INFO - PROCESS | 2378 | |1;audio-beat-detection;163;120;148;182;138;146;139;177;167;118 05:22:09 INFO - PROCESS | 2378 | |2;audio-dft;187;184;187;195;189;185;187;182;185;185 05:22:09 INFO - PROCESS | 2378 | |3;audio-fft;75;78;89;88;81;80;82;93;77;57 05:22:09 INFO - PROCESS | 2378 | |4;audio-oscillator;96;91;128;78;79;81;113;76;76;115 05:22:09 INFO - PROCESS | 2378 | |5;imaging-gaussian-blur;85;92;85;92;84;90;85;92;92;136 05:22:09 INFO - PROCESS | 2378 | |6;imaging-darkroom;136;147;94;95;94;94;95;94;94;95 05:22:09 INFO - PROCESS | 2378 | |7;imaging-desaturate;133;132;138;87;90;89;86;85;85;85 05:22:09 INFO - PROCESS | 2378 | |8;json-parse-financial;55;53;53;53;52;53;53;62;51;51 05:22:09 INFO - PROCESS | 2378 | |9;json-stringify-tinderbox;64;70;73;76;70;72;67;68;70;70 05:22:09 INFO - PROCESS | 2378 | |10;stanford-crypto-aes;80;84;84;81;99;100;100;83;83;84 05:22:09 INFO - PROCESS | 2378 | |11;stanford-crypto-ccm;125;125;124;123;135;116;108;127;126;129 05:22:09 INFO - PROCESS | 2378 | |12;stanford-crypto-pbkdf2;138;122;164;166;147;131;138;137;136;132 05:22:09 INFO - PROCESS | 2378 | |13;stanford-crypto-sha256-iterative;70;67;66;67;67;68;66;67;67;65 05:22:09 INFO - PROCESS | 2378 | __end_tp_report 05:22:09 INFO - PROCESS | 2378 | __start_cc_report 05:22:09 INFO - PROCESS | 2378 | _x_x_mozilla_cycle_collect,1110 05:22:09 INFO - PROCESS | 2378 | __end_cc_report 05:22:09 INFO - PROCESS | 2378 | __startTimestamp1454764929860__endTimestamp 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | ------- Summary: start ------- 05:22:09 INFO - PROCESS | 2378 | Number of tests: 14 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.00 stddev:2.02 (1.8%) stddev-sans-first:2.11 05:22:09 INFO - PROCESS | 2378 | Values: 110.0 109.0 111.0 112.0 112.0 111.0 116.0 111.0 109.0 110.0 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#1] audio-beat-detection Cycles:10 Average:149.80 Median:155.50 stddev:22.16 (14.3%) stddev-sans-first:22.98 05:22:09 INFO - PROCESS | 2378 | Values: 163.0 120.0 148.0 182.0 138.0 146.0 139.0 177.0 167.0 118.0 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#2] audio-dft Cycles:10 Average:186.60 Median:187.00 stddev:3.53 (1.9%) stddev-sans-first:3.75 05:22:09 INFO - PROCESS | 2378 | Values: 187.0 184.0 187.0 195.0 189.0 185.0 187.0 182.0 185.0 185.0 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#3] audio-fft Cycles:10 Average:80.00 Median:81.50 stddev:9.92 (12.2%) stddev-sans-first:10.36 05:22:09 INFO - PROCESS | 2378 | Values: 75.0 78.0 89.0 88.0 81.0 80.0 82.0 93.0 77.0 57.0 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:78.50 stddev:19.04 (24.3%) stddev-sans-first:20.17 05:22:09 INFO - PROCESS | 2378 | Values: 96.0 91.0 128.0 78.0 79.0 81.0 113.0 76.0 76.0 115.0 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:91.00 stddev:15.40 (16.9%) stddev-sans-first:16.04 05:22:09 INFO - PROCESS | 2378 | Values: 85.0 92.0 85.0 92.0 84.0 90.0 85.0 92.0 92.0 136.0 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#6] imaging-darkroom Cycles:10 Average:103.80 Median:94.00 stddev:20.04 (21.3%) stddev-sans-first:17.55 05:22:09 INFO - PROCESS | 2378 | Values: 136.0 147.0 94.0 95.0 94.0 94.0 95.0 94.0 94.0 95.0 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#7] imaging-desaturate Cycles:10 Average:101.00 Median:85.50 stddev:23.11 (27.0%) stddev-sans-first:21.42 05:22:09 INFO - PROCESS | 2378 | Values: 133.0 132.0 138.0 87.0 90.0 89.0 86.0 85.0 85.0 85.0 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:53.00 stddev:3.17 (6.0%) stddev-sans-first:3.32 05:22:09 INFO - PROCESS | 2378 | Values: 55.0 53.0 53.0 53.0 52.0 53.0 53.0 62.0 51.0 51.0 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.00 stddev:3.30 (4.7%) stddev-sans-first:2.69 05:22:09 INFO - PROCESS | 2378 | Values: 64.0 70.0 73.0 76.0 70.0 72.0 67.0 68.0 70.0 70.0 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:83.50 stddev:8.30 (9.9%) stddev-sans-first:8.31 05:22:09 INFO - PROCESS | 2378 | Values: 80.0 84.0 84.0 81.0 99.0 100.0 100.0 83.0 83.0 84.0 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#11] stanford-crypto-ccm Cycles:10 Average:123.80 Median:125.50 stddev:7.32 (5.8%) stddev-sans-first:7.75 05:22:09 INFO - PROCESS | 2378 | Values: 125.0 125.0 124.0 123.0 135.0 116.0 108.0 127.0 126.0 129.0 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:141.10 Median:138.00 stddev:14.11 (10.2%) stddev-sans-first:14.92 05:22:09 INFO - PROCESS | 2378 | Values: 138.0 122.0 164.0 166.0 147.0 131.0 138.0 137.0 136.0 132.0 05:22:09 INFO - PROCESS | 2378 | 05:22:09 INFO - PROCESS | 2378 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.33 (2.0%) stddev-sans-first:0.87 05:22:09 INFO - PROCESS | 2378 | Values: 70.0 67.0 66.0 67.0 67.0 68.0 66.0 67.0 67.0 65.0 05:22:09 INFO - PROCESS | 2378 | -------- Summary: end -------- 05:22:09 INFO - PROCESS | 2378 | 05:22:10 INFO - TEST-INFO | 2378: exit 0 05:22:10 INFO - TEST-OK | kraken | took 57083ms 05:22:10 INFO - SUITE-END | took 1170s 05:22:11 INFO - Completed test suite (00:19:31) 05:22:11 INFO - Working with test: dromaeo_css 05:22:11 INFO - Generating results file: dromaeo_css 05:22:11 INFO - Working with test: kraken 05:22:11 INFO - Generating results file: kraken 05:22:11 INFO - javascript benchmark 05:22:11 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:22:11 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,59]] 05:22:11 INFO - process_Request line: dromaeo_css 7406.39 graph.html#tests=[[72,64,59]] 05:22:11 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:22:11 INFO - process_Request line: kraken 1462.20 graph.html#tests=[[232,64,59]] 05:22:11 INFO - RETURN: dromaeo_css: 7406.39 05:22:11 INFO - RETURN: kraken: 1462.20 05:22:11 INFO - javascript benchmark 05:22:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3392.5080863274825, 3417.3336199441105, 3439.65603439656, 3457.7406694497918, 3474.40935041043, 5582.91625625616, 5658.971705141479, 5664.575156863231, 5666.688332141735, 5674.914876276854, 5585.972070139644, 5672.716364181785, 5714.200011998323, 5805.390434004422, 5815.505682017034, 5094.210397388392, 5129.948700513003, 5147.6654017488745, 5156.793728250885, 5190.948090519084, 5278.261043453919, 5307.495787900143, 5313.681179129265, 5326.360836699582, 5403.054465468547, 5109.463506331856, 5199.090159222121, 5229.686218826882, 5300.946990530084, 5324.733763311839, 5165.0, 5181.4300426953005, 5194.636375453682, 5197.558207552363, 5199.0, 5100.311457953166, 5158.69047857126, 5166.741662916839, 5180.8963820723375, 5186.455422180692, 5525.723713814334, 5530.723463826833, 5544.16837474378, 5554.9722251388885, 5566.526845218162, 6132.478739307165, 6203.472704820096, 6248.625082495065, 6256.468200202989, 6262.2172228471445, 5725.942740572583, 6279.403456671609, 6305.464035556983, 6309.053641953744, 6326.93673063268, 5639.238702775155, 5676.176954341598, 5757.07886738124, 5762.0, 5775.191473193756, 6261.749530018748, 6339.746410143634, 6368.29948705642, 6381.617102973743, 6385.393387628168, 6126.846828829315, 6130.448259656563, 6134.110553969649, 6159.630422174684, 6171.475424588871, 6105.481034112061, 6275.309715931244, 6278.843028924313, 6287.434130928238, 6298.401651843067, 6095.268567771807, 6134.294556126013, 6138.202033735675, 6144.754209831646, 6149.954507733696, 6133.172021777003, 6138.754449822047, 6185.659788711574, 6201.937980620226, 6215.471684906834, 4843.927341089887, 4861.659683822169, 4862.975685121552, 4871.391076115486, 4914.967856750122, 4296.119295544406, 4336.219480493542, 4344.500382455994, 4354.216241076637, 4369.191699535596, 5570.832875013756, 5643.943560564424, 5653.208550802891, 5671.688057156814, 5675.574331925122, 5403.595065283054, 6180.258368995749, 6270.0, 6301.212348456443, 6338.429541341301, 5647.661140331593, 5666.914996275059, 5684.77260909559, 5693.0, 5701.429857014348, 5212.843614691489, 5289.68261904287, 5298.523132918056, 5307.893842123136, 5311.3095297611835, 5087.898242035212, 6269.843253918689, 6356.872862542816, 6357.96821015892, 6392.20097487814, 6235.189425374672, 6261.966775482177, 6264.624122552661, 6280.968595157177, 6300.243970723543, 6252.3122456530655, 6352.745890164527, 6363.840903977252, 6372.776952806629, 6423.03654451836, 6073.878522429615, 6077.756889724362, 6100.375751378651, 6136.9441237184255, 6150.692465376624, 6234.812955611339, 6270.467010304175, 6272.090546870587, 6292.182016337847, 6300.0, 5749.108888122348, 5997.0404735242155, 6023.728932197974, 6029.095635654687, 6046.365131661289, 5508.091164957737, 5568.972155139199, 5918.940810591839, 5989.64062156272, 6094.11635312886, 5801.593888427769, 5816.651000939957, 5825.0388685866355, 5836.212111364912, 5843.503302219359, 5866.090755932837, 5937.079752638348, 5986.07215881539, 6007.339192688888, 6050.485708714808, 5745.971270143623, 5826.184334193301, 5864.618799778001, 5878.588498805043, 5923.348431672426], "unit": "score", "name": "dojo.html", "value": 5657.887826462757}, {"lowerIsBetter": false, "replicates": [472.0841567359325, 552.2654869024199, 561.7303694226769, 613.834264748518, 631.6178711879313, 29102.70897291029, 29484.852575737117, 37577.81211093944, 38014.61985380145, 38197.809010954974, 40682.79658601703, 41132.177356452856, 41174.58825411745, 41218.7939060305, 41393.172136557325, 9433.292503062281, 9645.662401815938, 9654.71035868925, 9662.613495460208, 9681.70954871355, 9511.762205944837, 9661.130498255156, 9678.516074196263, 9690.933997260296, 9703.417794932328, 9717.61129554817, 9796.510174491283, 9821.459819709913, 9821.901780982205, 9832.70501884941, 9685.951570242174, 9703.98108198643, 9704.708858734179, 9761.755956101048, 9823.705288841345, 9477.715668529956, 9580.61677532898, 9617.759556011086, 9628.229741620653, 9655.951720241424, 39106.608933910866, 39316.41025384622, 39339.40990885139, 39391.60608393937, 39405.62080327203, 3111.5177147542167, 3151.8108913465035, 3476.5132881396626, 3636.945445818315, 3913.0608653922855, 28403.57394639082, 28767.99312024069, 28913.277168070756, 29074.41851162965, 29408.11775646734, 2764.3089227693076, 2765.1151631477937, 2767.6955534891144, 2872.626558547396, 2944.5435957426635, 439385.0, 442141.0, 443168.0, 443515.0, 444302.0, 53685.1947220792, 55152.896942060936, 62007.689961549906, 62716.0, 63475.0, 62895.6855215721, 63479.365206348266, 63804.0, 63817.68091159515, 63862.36137638564, 15944.0, 16141.0, 16148.27332770028, 16182.75725864113, 16182.838171618369, 11388.430578471043, 11523.193376463734, 11525.423728813526, 12460.0, 12538.0, 19652.606947861248, 19938.00309984524, 19958.70061949072, 19984.50038749043, 20002.89998549998, 8356.623951922178, 8560.57197140153, 8562.272206862363, 8623.180797824196, 8699.869501957475, 910.3536489092686, 992.1764935103846, 1014.8071866345371, 1031.4224034540682, 1088.88022317545, 29983.400331993675, 31227.68772312248, 31243.68756312453, 31761.841190793897, 32044.0, 1042.1089968077335, 1087.526925787285, 1098.7967226063204, 1101.807183742858, 1153.2273376837372, 13660.726785464434, 13778.862211377958, 15316.23418829054, 15421.845781542266, 15937.681246375008, 418064.0, 458287.0, 458382.0, 458455.0, 459006.0, 58107.709461452425, 58245.417545824, 58390.0, 58412.70793646004, 58755.41244587585, 1293.683047653312, 1362.7274545091022, 1434.7704367301183, 1439.5537383411177, 1573.4099712607772, 28921.987730429333, 28925.7107428923, 29322.0, 29922.102336929926, 30229.69770302357, 53120.46879531155, 53784.73107634437, 53883.6529086776, 53949.65125871728, 54119.72940135274, 423286.0, 427147.0, 448142.0, 451981.7400912974, 620629.7937020572, 28459.857700712193, 31381.686183137877, 31446.371072577964, 31485.370292594478, 31583.526247105852, 43620.0, 44176.0, 44188.33717494176, 44630.10739785251, 45451.54548454473, 36828.52193707197, 37005.629943700216, 37576.6848160324, 38005.80997095107, 38119.618803812715, 33619.49570756392, 35970.64029359673, 36250.63749362472, 36412.72555460546, 37039.8148009269, 36459.81770091133, 37035.81482092572, 37272.81363593165, 37299.44050839294, 37310.440343395414, 25297.0, 28831.423371532033, 31004.604792783928, 31074.91237806666, 31111.84444077765, 17455.650886982443, 23873.880630596737, 24003.519929601658, 24289.75710242875, 24438.877805610857], "unit": "score", "name": "ext.html", "value": 19495.17350587344}, {"lowerIsBetter": false, "replicates": [360.9314230296244, 363.58551251573226, 366.92661467706455, 367.21049743052436, 369.7688944409744, 14936.477223297186, 15402.768958465633, 18615.255389784408, 19599.608007839834, 20084.698729519034, 19945.0, 20076.196952121896, 20386.69419958702, 21238.0, 21260.4684882878, 6218.347073557279, 6490.545661803676, 6490.707918143671, 6515.153030106079, 6520.576162549442, 4502.729836209838, 4551.499335073139, 5202.323697919265, 6432.067350234213, 7275.854482910338, 6990.0, 7187.964060179692, 7222.674979625903, 7263.854722905538, 7266.200717921024, 7073.610951397671, 7147.392471639917, 7159.0, 7177.3540381365665, 7180.353768160864, 6945.687444064979, 7016.403605693522, 7018.964905175492, 7033.964830175868, 7044.7534336298495, 25171.748282517125, 25602.97588096474, 25993.610095848577, 26190.9523619055, 26913.058042968598, 1926.1236137557378, 2133.797289257518, 2163.2212403534713, 2318.5362927414585, 2344.988275058614, 7649.808754781119, 9393.57728902201, 9561.808763824685, 9646.276529260334, 9854.260930430175, 1627.275862241303, 1689.7380905959596, 1757.6748301564123, 1774.0508827777207, 1821.4171465131162, 73117.6344118276, 96015.039849602, 97017.51491242569, 97515.0, 98182.50908745482, 10820.0, 10891.945540272249, 10918.617848375268, 10929.398883061507, 10994.450277486094, 10675.62635307776, 12668.0, 13250.668733281746, 13265.137766045176, 13270.601881943558, 8232.094469608339, 8251.793705157299, 8345.040320363114, 8358.498490090493, 8730.738077857675, 8210.835783284301, 8218.43849668561, 8238.50568965852, 8321.251087402043, 8326.417150799385, 3954.525456945185, 4143.482064741907, 4254.42565253693, 4331.891702707395, 4394.648428125743, 1719.2091637846481, 1749.413946327969, 1758.156085079169, 1760.6654735600196, 1764.682357175695, 403.91517781265674, 779.9883001754978, 887.4187407248263, 927.8190752803139, 937.465644582595, 11530.654080377602, 11705.824412633818, 11883.0, 11921.986631136277, 11970.102242331859, 441.0913518152616, 452.2470087304601, 455.9954400456019, 456.98172073117365, 480.59389815605755, 6024.969875150597, 6167.0, 10839.072298548617, 11474.935379886489, 11538.59614913474, 729802.0, 741154.0, 746461.5507681799, 756380.0, 759234.0, 106311.4684426573, 107985.46007269913, 108120.99999999843, 109498.45250773695, 111492.0, 1084.9077828384518, 1118.7818375416878, 1155.5146838327923, 1180.0618508285934, 1180.651707746217, 16510.17449127563, 17963.550911227325, 18230.544236394195, 18344.633107338046, 18389.26442942321, 48081.75959120182, 49329.01341973068, 50676.74661626668, 51195.74402127966, 51421.74289128531, 63414.68292658507, 65044.0, 68309.3169068303, 68361.31638683549, 69253.0, 7433.591152486665, 7657.731979380695, 7723.961380193063, 7743.883841742266, 7767.495112817649, 11217.0, 11295.887041129483, 11899.464524096264, 11950.282983021047, 12185.87814121883, 6596.336367222043, 9741.366811157252, 9963.50182490858, 10168.74578135523, 10297.9485102574, 8734.606942687722, 9823.312368134448, 9845.803083938137, 10010.699679009642, 10236.897631023594, 9946.154576860758, 10083.4454105022, 10283.845742313722, 10489.370637761758, 10589.788204235718, 6057.7879774207695, 7548.3961283098315, 7728.806779830549, 7732.342750866239, 7751.6511756972195, 3966.36538153894, 6000.459958603747, 8270.545120018456, 9670.404383276962, 10341.9482902585], "unit": "score", "name": "jquery.html", "value": 8813.101756272243}, {"lowerIsBetter": false, "replicates": [1342.9798553021706, 1396.804447377368, 1557.4626753769949, 1725.6031112844046, 1907.9904600476996, 7804.024496937883, 8069.314108300794, 8429.409941304119, 8609.311255099592, 8618.224359807617, 8419.494830310186, 8572.399932004762, 8607.18231767981, 8623.180797824212, 8654.134586541342, 2167.729033870766, 2239.070785623964, 2457.4225057111566, 2484.7391023942496, 2485.9005639774387, 2249.8200143988443, 2438.4147804526965, 2503.674522312097, 2509.723930367658, 2514.798816094708, 2098.2760947473175, 2423.3941514621347, 2457.3610861176135, 2465.6671349367875, 2467.8272520923542, 2421.5399074176025, 2456.1403508771964, 2463.0147940823636, 2466.7409921958206, 2480.9255722328357, 2442.8778561071877, 2459.053264493165, 2460.4218008767925, 2462.4459496613117, 2469.394998225446, 8586.184312490366, 8679.696210632597, 8683.826323473559, 8684.956575217084, 8689.826203475895, 5368.597355198376, 5480.616356855022, 5494.560435165177, 5498.945010549884, 5528.917066244009, 3578.4453409721536, 3657.9268414631824, 3685.041889108851, 3710.2579484103017, 3714.5542534895717, 954.2652157838495, 1036.7563622548696, 1057.7302787789065, 1074.70982834635, 1084.1110289562562, 12024.69938251542, 12069.396530173457, 12094.032477401786, 12094.455749491293, 12110.39448027595, 4879.2925025871045, 4899.387576552931, 4935.062338155739, 4949.406071271398, 4998.150314446553, 10163.593456261668, 10182.287239893136, 10187.439690817077, 10191.286609937233, 10194.286399951932, 5484.107982745946, 5510.9448905510435, 5526.530244929226, 5532.612717109763, 5537.086380747212, 4909.337239472697, 5038.3198268234055, 5043.445221025684, 5049.545540901337, 5053.84838454847, 3632.0919770057485, 3711.1629743276712, 3711.830773306454, 3907.5506316773367, 3985.3623420253202, 1130.547780887635, 1276.8595454499998, 1296.2740865115566, 1310.423413697989, 1312.2389014371643, 1319.7228581997888, 1325.6950901292757, 1333.7132516508802, 1335.125492802216, 1355.586546103448, 7962.0, 7969.9601501993275, 7985.08171560278, 7989.760307190793, 7990.4007199460275, 1158.3397463445756, 1175.1656324010048, 1182.3851597169596, 1201.0631707268344, 1216.9817452738216, 6220.129181914535, 6223.471004964538, 6313.3686631336495, 6321.304656487782, 6328.0, 10357.741056473498, 10775.83836242457, 10803.351798891934, 10812.891871081345, 10825.729356766144, 10232.897671023195, 10533.736656583647, 10551.84172237417, 10554.050135487843, 10665.946670266754, 8167.673293068329, 8230.341572674171, 8236.382271329674, 8307.83384332322, 8339.958300208582, 7676.155622881367, 7817.218278172138, 7872.291493765589, 7962.800929976565, 7998.640061197144, 9151.588178531849, 9328.253739700944, 9407.717768466957, 9447.338686291894, 9603.615855365986, 9446.480443575394, 9666.226701863974, 9793.265505087005, 9873.210143188671, 9883.159931405624, 5988.0, 7253.057102576631, 7406.777796666109, 7517.586532740533, 7523.849523009619, 7422.777316680508, 7629.504082234637, 7999.680012799423, 8063.516189028677, 8077.7576672699915, 6890.827729306808, 6895.172579290316, 7606.581638009741, 7660.73187438437, 7694.807629809299, 7329.486935914648, 7427.0, 7449.739259125905, 7554.980077689662, 7585.16563178061, 7443.441741869273, 7544.283293087148, 7598.506097103671, 7621.428392870446, 7639.350655194148, 6903.861922761416, 6956.2695916928, 6964.825879353057, 7037.190723066913, 7044.15470143586, 7057.012018317337, 7084.858302834017, 7127.75052873147, 7134.1795693495915, 7150.10623672041], "unit": "score", "name": "mootools.html", "value": 4924.679778931459}, {"lowerIsBetter": false, "replicates": [317.36843681074663, 323.116276982453, 329.0064006699768, 329.37912035812485, 331.8108678053509, 5668.008098582742, 6505.479561635069, 6521.347865213482, 6522.608643481389, 6567.376099270562, 6311.526635502332, 6527.184101987252, 6527.379898909608, 6529.640869752162, 6536.182977127859, 4061.740860333306, 4215.0, 4889.1932831083, 4911.16510193268, 4923.532264434873, 4683.391159149306, 4902.730349830758, 4911.75441227939, 4916.975415122919, 4921.753912304371, 3970.38459038848, 4809.1343558159515, 4819.951800482003, 4829.323894654751, 4837.661363704542, 4565.726056436624, 4691.366665500148, 4839.612830973514, 4848.709077455346, 4861.902761944777, 3985.4221137934837, 4016.879493615196, 4084.223997440477, 4093.1199792044854, 4102.3026085565525, 6275.592086514362, 6337.429631333156, 6342.587731797418, 6352.110704501373, 6358.950773122429, 5328.46715328468, 5446.128619420873, 5464.18037294409, 5554.166874968787, 5561.610687251894, 6623.35715356552, 6828.0440738296675, 6829.351211634887, 6844.8780877928, 6851.931480685229, 4387.648988080946, 4389.846355377546, 4399.670024748125, 4400.987772812239, 4501.977490112561, 7272.090988626422, 7387.445941554405, 7393.519421237603, 7398.556086634819, 7403.962980185119, 4588.426446694163, 4602.953970460319, 4622.243547451947, 4766.928496072562, 4774.116788394089, 4845.515448455197, 4850.466448690641, 4857.975710121426, 4858.902821943541, 4863.10032643962, 4393.890152746143, 5657.6039677223025, 5664.971675141598, 5714.314282286152, 5826.834633073402, 6832.59004459734, 7019.89470157948, 7027.754028608974, 7032.156141262979, 7041.471889608198, 1674.6064674801412, 1700.481353187265, 1701.2769572931454, 1701.5150682055555, 1708.9230984605722, 922.3405265235389, 1108.778244351133, 1120.5237773946203, 1123.4214379594516, 1125.3135587291745, 4857.0, 6572.474202063823, 6598.1422415085735, 6617.106690596708, 6644.269130395653, 7218.530795498275, 7257.709691612382, 7266.1280646322775, 7268.418526517866, 7268.745593904294, 6337.4016653171975, 6604.0, 6633.104530888365, 6646.833829154213, 6646.933530664632, 3817.6182381762155, 3860.903477413031, 3874.1283211277237, 3875.0312421894528, 3885.572587015426, 7540.434467414856, 7566.167721550625, 7571.0, 7573.545587264782, 7588.392928565701, 7247.202807691256, 7342.4493163012985, 7403.481756277009, 7447.255274472509, 7567.583782891884, 6211.3478084801345, 7617.085949685962, 7670.578118203553, 7685.615719214129, 7687.807804804925, 7064.116985376828, 7336.449766267658, 7350.301721336465, 7354.926450735424, 7362.5214361066355, 7172.748953786592, 7176.928230717626, 7233.746818861525, 7236.855262894607, 7241.275872412716, 7656.234376562522, 7663.118741344817, 7664.8850267244925, 7672.6930922764695, 7678.308952194329, 4507.346434767006, 4614.561616646413, 4641.257398816173, 4650.604698600657, 4661.836835710734, 7187.712491500491, 7231.240719724353, 7248.021517095125, 7251.709931602888, 7253.891191632235, 5710.5146062585145, 6990.720371185095, 7005.194402643761, 7010.369066784014, 7017.859642807013, 5942.346341902474, 6745.392914637706, 6779.966100169468, 7061.505694601534, 7070.045543851515, 6884.965575172092, 6891.793246202622, 6892.965535172292, 6903.0, 6904.861902762017, 7039.8944015840825, 7052.541584796972, 7055.153381594241, 7061.2232654409, 7065.505414620927, 6650.534462587766, 6914.239433662193, 6928.272531384334, 6930.965345173242, 6932.202796678245], "unit": "score", "name": "prototype.html", "value": 5110.836088352018}, {"lowerIsBetter": false, "replicates": [714.7212587091034, 733.2520828754671, 734.1007266099033, 750.801037725003, 782.3780094824614, 17174.74237886431, 17444.0, 17455.389061382855, 17474.0, 17487.562810929732, 15700.293486793093, 15717.37130514781, 15738.97696649717, 15771.763423548657, 15772.526824195264, 687.4328678839952, 707.632031343701, 844.0293662288356, 860.6600392844823, 862.4006315610653, 700.0689083518907, 726.2011787034273, 847.1147650704997, 850.0267194062794, 853.0616322045762, 884.2572239318971, 891.4205766251923, 892.1836519584605, 892.8258989497051, 894.0121166111451, 718.8346680263543, 736.8784150615143, 737.4100719424439, 880.4101252161036, 888.2405543260517, 654.1430725749284, 655.3217419970356, 656.1830521001336, 656.4977791989132, 660.8215781738952, 14956.476523321631, 14963.70072598542, 14971.0, 14990.400383984628, 15007.924960375238, 12319.076069294839, 12336.198147120409, 12439.626811195678, 12459.95086974501, 12583.112533120013, 10036.59853605855, 10144.492775361203, 10173.79652406955, 10183.490825458772, 10213.489325533694, 3451.2752322012407, 3481.599616044162, 3488.529048578435, 3491.5810102787827, 3491.91270218244, 81441.59279203566, 82677.17322826695, 83479.0, 83650.74523882147, 84208.57895710543, 7704.460687751804, 9040.0, 9223.262139028862, 9370.812583748288, 9555.187809036172, 20428.387148385573, 20468.69296960547, 20485.283015094697, 20503.589928201352, 20515.897420512803, 7439.962800186073, 7494.437917156235, 7503.474756766974, 7521.522428636175, 7640.73257435987, 4090.263752524574, 4095.7542547447247, 4111.444954931106, 4175.68682348825, 4222.767747773841, 3582.4268117101137, 3645.8906232813056, 3648.0515066083008, 3648.6533779290926, 3651.3062518121474, 645.4674893213024, 655.6787174284566, 660.9074729537869, 675.0650349266301, 679.966001699913, 507.908220093565, 551.966881987082, 559.7979556934329, 601.3084952304885, 611.3244864424836, 7880.408969327209, 8410.831783364298, 8485.787855303666, 8557.743267701979, 8669.306455483546, 514.9253358263031, 578.5602941764289, 593.884192582451, 615.1142355008773, 634.321276234434, 3470.774399664014, 3844.0, 3867.1878905430176, 3945.546262179829, 4051.736637118578, 90162.64756028664, 90256.548717256, 90760.6385904212, 94109.05890941003, 94783.52608236915, 73340.53318933591, 73594.6320268406, 77592.0, 80272.0, 82535.587322063, 69806.65096674484, 70484.64757676178, 70736.3935927681, 71023.64488177525, 71459.0, 7498.550086994798, 7983.32141767933, 8016.679332826855, 8032.518048917083, 8187.877181842158, 47823.76088119537, 49385.50614493953, 49415.0584941503, 49763.0, 50073.99852002867, 63201.73596527952, 67571.0, 68148.6592567034, 68783.31216687769, 69613.0, 9747.171490423394, 9749.268804839523, 9796.265280104164, 9914.40513569188, 10490.842637360292, 10684.305520141166, 10965.451727413756, 11021.228514003942, 11215.158863085138, 11761.353125578175, 8809.779755506164, 10160.339577927707, 10401.323913945873, 10419.94790026045, 10602.628907988183, 10280.537375818256, 10396.116330111723, 10514.526846292089, 10558.208134389797, 10659.626913058006, 8647.956760216159, 10091.243156763125, 11014.118870490502, 11138.94430527842, 11150.0, 8997.145271199226, 9024.36829421934, 9468.242540596599, 9641.909020140021, 9646.131848133702, 8994.505302208441, 9331.486768227813, 9686.176674982706, 9721.756956076155, 9781.706548803548], "unit": "score", "name": "yui.html", "value": 6745.649536990586}], "name": "dromaeo_css", "value": 7406.3857532518105}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 109.0, 111.0, 112.0, 112.0, 111.0, 116.0, 111.0, 109.0, 110.0], "unit": "score", "name": "ai-astar", "value": 111.1}, {"lowerIsBetter": true, "replicates": [163.0, 120.0, 148.0, 182.0, 138.0, 146.0, 139.0, 177.0, 167.0, 118.0], "unit": "score", "name": "audio-beat-detection", "value": 149.8}, {"lowerIsBetter": true, "replicates": [187.0, 184.0, 187.0, 195.0, 189.0, 185.0, 187.0, 182.0, 185.0, 185.0], "unit": "score", "name": "audio-dft", "value": 186.6}, {"lowerIsBetter": true, "replicates": [75.0, 78.0, 89.0, 88.0, 81.0, 80.0, 82.0, 93.0, 77.0, 57.0], "unit": "score", "name": "audio-fft", "value": 80.0}, {"lowerIsBetter": true, "replicates": [96.0, 91.0, 128.0, 78.0, 79.0, 81.0, 113.0, 76.0, 76.0, 115.0], "unit": "score", "name": "audio-oscillator", "value": 93.3}, {"lowerIsBetter": true, "replicates": [85.0, 92.0, 85.0, 92.0, 84.0, 90.0, 85.0, 92.0, 92.0, 136.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 93.3}, {"lowerIsBetter": true, "replicates": [136.0, 147.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0], "unit": "score", "name": "imaging-darkroom", "value": 103.8}, {"lowerIsBetter": true, "replicates": [133.0, 132.0, 138.0, 87.0, 90.0, 89.0, 86.0, 85.0, 85.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 101.0}, {"lowerIsBetter": true, "replicates": [55.0, 53.0, 53.0, 53.0, 52.0, 53.0, 53.0, 62.0, 51.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 53.6}, {"lowerIsBetter": true, "replicates": [64.0, 70.0, 73.0, 76.0, 70.0, 72.0, 67.0, 68.0, 70.0, 70.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 70.0}, {"lowerIsBetter": true, "replicates": [80.0, 84.0, 84.0, 81.0, 99.0, 100.0, 100.0, 83.0, 83.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.8}, {"lowerIsBetter": true, "replicates": [125.0, 125.0, 124.0, 123.0, 135.0, 116.0, 108.0, 127.0, 126.0, 129.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.8}, {"lowerIsBetter": true, "replicates": [138.0, 122.0, 164.0, 166.0, 147.0, 131.0, 138.0, 137.0, 136.0, 132.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 141.1}, {"lowerIsBetter": true, "replicates": [70.0, 67.0, 66.0, 67.0, 67.0, 68.0, 66.0, 67.0, 67.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.0}], "name": "kraken", "value": 1462.1999999999998}]} 05:22:11 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,59]]", "result": "1462.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,59]]", "result": "7406.39"}}} 05:22:11 INFO - Return code: 0 05:22:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:22:11 INFO - # TBPL SUCCESS # 05:22:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:22:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:22:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:22:11 INFO - 2016-02-06 05:22:11.825 screenresolution[2382:21708] starting screenresolution argv=screenresolution get 05:22:11 INFO - 2016-02-06 05:22:11.827 screenresolution[2382:21708] Display 0: 1600x1200x32@60 05:22:11 INFO - 2016-02-06 05:22:11.833 screenresolution[2383:21711] starting screenresolution argv=screenresolution list 05:22:11 INFO - Available Modes on Display 0 05:22:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:22:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:22:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:22:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:22:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:22:12 INFO - Intel Iris: 05:22:12 INFO - Chipset Model: Intel Iris 05:22:12 INFO - Type: GPU 05:22:12 INFO - Bus: Built-In 05:22:12 INFO - VRAM (Dynamic, Max): 1536 MB 05:22:12 INFO - Vendor: Intel (0x8086) 05:22:12 INFO - Device ID: 0x0a2e 05:22:12 INFO - Revision ID: 0x0009 05:22:12 INFO - Displays: 05:22:12 INFO - Display: 05:22:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:22:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:22:12 INFO - Display Serial Number: 1600x1200 60 05:22:12 INFO - Main Display: Yes 05:22:12 INFO - Mirror: Off 05:22:12 INFO - Online: Yes 05:22:12 INFO - Rotation: Supported 05:22:12 INFO - Return code: 0 05:22:12 INFO - Running post-action listener: _resource_record_post_action 05:22:12 INFO - Running post-run listener: _resource_record_post_run 05:22:13 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 598016; Write bytes: 710629376; Read time: 5; Write time: 2146 05:22:13 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 386474496; Write bytes: 385837568; Read time: 29739; Write time: 757 05:22:13 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 598016; Write bytes: 300477952; Read time: 5; Write time: 1335 05:22:13 INFO - Running post-run listener: _upload_blobber_files 05:22:13 INFO - Blob upload gear active. 05:22:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:22:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:22:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:22:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:22:13 INFO - (blobuploader) - INFO - Open directory for files ... 05:22:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:22:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:22:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:22:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:22:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:22:14 INFO - (blobuploader) - INFO - Done attempting. 05:22:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:22:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:22:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:22:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:22:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:22:15 INFO - (blobuploader) - INFO - Done attempting. 05:22:15 INFO - (blobuploader) - INFO - Iteration through files over. 05:22:15 INFO - Return code: 0 05:22:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:22:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:22:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/838c19ff5da27e1d500dbd063461989d562a19e0becae4af404331b067ff11bf9a698bd9a9e7034d5b6ab7fad69239d85af2f838bca5f74fef9e017ff83a753f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f00142101583dedef407fac6fe7cf438d5d78405b0893c102a5ee6a41d1235b1c1c3213ba39d6614df795e90540c90c08c50a0c1228726ce9fa0d9b23af446c6"} 05:22:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:22:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:22:15 INFO - Contents: 05:22:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/838c19ff5da27e1d500dbd063461989d562a19e0becae4af404331b067ff11bf9a698bd9a9e7034d5b6ab7fad69239d85af2f838bca5f74fef9e017ff83a753f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f00142101583dedef407fac6fe7cf438d5d78405b0893c102a5ee6a41d1235b1c1c3213ba39d6614df795e90540c90c08c50a0c1228726ce9fa0d9b23af446c6"} 05:22:15 INFO - Running post-run listener: copy_logs_to_upload_dir 05:22:15 INFO - Copying logs to upload dir... 05:22:15 INFO - mkdir: /builds/slave/test/build/upload/logs 05:22:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.438672 ========= master_lag: 7.61 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-02-06 05:22:23.044970) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-06 05:22:23.048365) ========= 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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/838c19ff5da27e1d500dbd063461989d562a19e0becae4af404331b067ff11bf9a698bd9a9e7034d5b6ab7fad69239d85af2f838bca5f74fef9e017ff83a753f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f00142101583dedef407fac6fe7cf438d5d78405b0893c102a5ee6a41d1235b1c1c3213ba39d6614df795e90540c90c08c50a0c1228726ce9fa0d9b23af446c6"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011336 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/838c19ff5da27e1d500dbd063461989d562a19e0becae4af404331b067ff11bf9a698bd9a9e7034d5b6ab7fad69239d85af2f838bca5f74fef9e017ff83a753f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f00142101583dedef407fac6fe7cf438d5d78405b0893c102a5ee6a41d1235b1c1c3213ba39d6614df795e90540c90c08c50a0c1228726ce9fa0d9b23af446c6"}' ========= master_lag: 0.21 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-06 05:22:23.270353) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-02-06 05:22:23.270680) ========= 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.9tYlBkQmdi/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.SsZJUOcCOB/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.005286 ========= master_lag: 4.20 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-02-06 05:22:27.480120) ========= ========= Started reboot skipped (results: 3, elapsed: 8 secs) (at 2016-02-06 05:22:27.480426) ========= ========= Finished reboot skipped (results: 3, elapsed: 8 secs) (at 2016-02-06 05:22:35.743201) ========= ========= Total master_lag: 12.23 =========