builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0091 starttime: 1454763698.34 results: success (0) buildid: 20160206045248 builduid: 4c498a015649464ab97a0f01d7b013dc revision: 6826dfd0e85b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:38.336690) ========= 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:38.337177) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:38.337521) ========= 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.aatiAd2XEc/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.54hWVK2nfH/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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.005810 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:38.360834) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:38.361159) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:38.376438) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:38.376815) ========= 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.aatiAd2XEc/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.54hWVK2nfH/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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.005530 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:38.425930) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:38.426251) ========= 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:38.426687) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:38.426974) ========= 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.aatiAd2XEc/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.54hWVK2nfH/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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:38-- 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% 9.69M=0.001s 2016-02-06 05:01:38 (9.69 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.179893 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:38.622482) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:38.622796) ========= 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.aatiAd2XEc/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.54hWVK2nfH/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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.021919 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:38.673614) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-06 05:01:38.674208) ========= 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.aatiAd2XEc/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.54hWVK2nfH/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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:38,745 Setting DEBUG logging. 2016-02-06 05:01:38,745 attempt 1/10 2016-02-06 05:01:38,746 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:39,836 unpacking tar archive at: fx-team-6826dfd0e85b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.331219 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-06 05:01:40.021737) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:40.022141) ========= 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.aatiAd2XEc/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.54hWVK2nfH/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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.005327 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:40.073478) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:40.073827) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-06 05:01:40.087138) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-02-06 05:01:40.087527) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.aatiAd2XEc/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.54hWVK2nfH/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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:40 INFO - MultiFileLogger online at 20160206 05:01:40 in /builds/slave/test 05:01:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:01:40 INFO - {'append_to_log': False, 05:01:40 INFO - 'base_work_dir': '/builds/slave/test', 05:01:40 INFO - 'blob_upload_branch': 'Fx-Team', 05:01:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:01:40 INFO - 'branch': 'Fx-Team', 05:01:40 INFO - 'buildbot_json_path': 'buildprops.json', 05:01:40 INFO - 'config_files': ('talos/mac_config.py',), 05:01:40 INFO - 'default_actions': ('clobber', 05:01:40 INFO - 'read-buildbot-config', 05:01:40 INFO - 'download-and-extract', 05:01:40 INFO - 'populate-webroot', 05:01:40 INFO - 'create-virtualenv', 05:01:40 INFO - 'install', 05:01:40 INFO - 'run-tests'), 05:01:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:01:40 INFO - 'download_minidump_stackwalk': True, 05:01:40 INFO - 'download_symbols': 'ondemand', 05:01:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:01:40 INFO - 'tooltool.py': '/tools/tooltool.py', 05:01:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:01:40 INFO - '/tools/misc-python/virtualenv.py')}, 05:01:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:01:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:01:40 INFO - 'installer_path': 'installer.exe', 05:01:40 INFO - 'log_level': 'info', 05:01:40 INFO - 'log_name': 'talos', 05:01:40 INFO - 'log_to_console': True, 05:01:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:01:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:01:40 INFO - 'opt_config_files': (), 05:01:40 INFO - 'pip_index': False, 05:01:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:01:40 INFO - 'cmd': ('bash', 05:01:40 INFO - '-c', 05:01:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:01:40 INFO - 'enabled': True, 05:01:40 INFO - 'halt_on_failure': False, 05:01:40 INFO - 'name': 'check_screen_resolution'},), 05:01:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:01:40 INFO - 'cmd': ('bash', 05:01:40 INFO - '-c', 05:01:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:01:40 INFO - 'enabled': True, 05:01:40 INFO - 'halt_on_failure': False, 05:01:40 INFO - 'name': 'check_screen_resolution'},), 05:01:40 INFO - 'run_cmd_checks_enabled': True, 05:01:40 INFO - 'sps_profile': False, 05:01:40 INFO - 'sps_profile_interval': 0, 05:01:40 INFO - 'suite': 'dromaeojs-e10s', 05:01:40 INFO - 'system_bits': '32', 05:01:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:01:40 INFO - 'title': 't-yosemite-r7-0091', 05:01:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:01:40 INFO - 'use_talos_json': True, 05:01:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:01:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:01:40 INFO - 'work_dir': 'build'} 05:01:40 INFO - ##### 05:01:40 INFO - ##### Running clobber step. 05:01:40 INFO - ##### 05:01:40 INFO - Running pre-action listener: _resource_record_pre_action 05:01:40 INFO - Running main action method: clobber 05:01:40 INFO - rmtree: /builds/slave/test/build 05:01:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:01:41 INFO - Running post-action listener: _resource_record_post_action 05:01:41 INFO - ##### 05:01:41 INFO - ##### Running read-buildbot-config step. 05:01:41 INFO - ##### 05:01:41 INFO - Running pre-action listener: _resource_record_pre_action 05:01:41 INFO - Running main action method: read_buildbot_config 05:01:41 INFO - Using buildbot properties: 05:01:41 INFO - { 05:01:41 INFO - "project": "", 05:01:41 INFO - "product": "firefox", 05:01:41 INFO - "script_repo_revision": "production", 05:01:41 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:01:41 INFO - "repository": "", 05:01:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:01:41 INFO - "buildid": "20160206045248", 05:01:41 INFO - "stage_platform": "macosx64", 05:01:41 INFO - "basedir": "/builds/slave/test", 05:01:41 INFO - "buildnumber": 340, 05:01:41 INFO - "platform": "yosemite_r7", 05:01:41 INFO - "mozci_request": { 05:01:41 INFO - "priority": -1, 05:01:41 INFO - "type": "trigger_all_talos_jobs", 05:01:41 INFO - "times": 6 05:01:41 INFO - }, 05:01:41 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:01:41 INFO - "slavebuilddir": "test", 05:01:41 INFO - "branch": "fx-team", 05:01:41 INFO - "revision": "6826dfd0e85b", 05:01:41 INFO - "slavename": "t-yosemite-r7-0091", 05:01:41 INFO - "builduid": "4c498a015649464ab97a0f01d7b013dc", 05:01:41 INFO - "repo_path": "integration/fx-team" 05:01:41 INFO - } 05:01:41 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:41 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/test_packages.json. 05:01:41 INFO - Running post-action listener: _resource_record_post_action 05:01:41 INFO - ##### 05:01:41 INFO - ##### Running download-and-extract step. 05:01:41 INFO - ##### 05:01:41 INFO - Running pre-action listener: _resource_record_pre_action 05:01:41 INFO - Running main action method: download_and_extract 05:01:41 INFO - mkdir: /builds/slave/test/build/tests 05:01:41 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:41 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/test_packages.json 05:01:41 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:41 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:41 INFO - Downloaded 1277 bytes. 05:01:41 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:01:41 INFO - Using the following test package requirements: 05:01:41 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:01:41 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:41 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 05:01:41 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:41 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 05:01:41 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:41 INFO - u'jsshell-mac.zip'], 05:01:41 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:41 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 05:01:41 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:01:41 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:41 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 05:01:41 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:41 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 05:01:41 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:41 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 05:01:41 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:01:41 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:01:41 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 05:01:41 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:01:41 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:41 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:41 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:41 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:41 INFO - Downloaded 17118709 bytes. 05:01:41 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:41 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:44 INFO - Return code: 0 05:01:44 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: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 - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.common.tests.zip 05:01:44 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:44 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:45 INFO - Downloaded 17118709 bytes. 05:01:45 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:45 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:48 INFO - Return code: 0 05:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:01:48 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:48 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:48 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:48 INFO - Downloaded 10706582 bytes. 05:01:48 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:48 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:49 INFO - Return code: 0 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 - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg 05:01:49 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:49 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:50 INFO - Downloaded 111102939 bytes. 05:01:50 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:50 INFO - mkdir: /builds/slave/test/properties 05:01:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:01:50 INFO - Writing to file /builds/slave/test/properties/build_url 05:01:50 INFO - Contents: 05:01:50 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg 05:01:50 INFO - Running post-action listener: _resource_record_post_action 05:01:50 INFO - Running post-action listener: set_extra_try_arguments 05:01:50 INFO - ##### 05:01:50 INFO - ##### Running populate-webroot step. 05:01:50 INFO - ##### 05:01:50 INFO - Running pre-action listener: _resource_record_pre_action 05:01:50 INFO - Running main action method: populate_webroot 05:01:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:01:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:01:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:01:50 INFO - '--tppagecycles', 05:01:50 INFO - '7'], 05:01:50 INFO - 'tests': ['tsvgm']}}, 05:01:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:01:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:01:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:01:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:01:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:01:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:01:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:01:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:50 INFO - 'talos_options': ['--e10s'], 05:01:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:01:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:50 INFO - 'tests': ['damp', 'tps']}, 05:01:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:50 INFO - 'talos_options': ['--e10s'], 05:01:50 INFO - 'tests': ['damp', 'tps']}, 05:01:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:01:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:01:50 INFO - 'other': {'tests': ['a11yr', 05:01:50 INFO - 'ts_paint', 05:01:50 INFO - 'tpaint', 05:01:50 INFO - 'sessionrestore', 05:01:50 INFO - 'sessionrestore_no_auto_restore']}, 05:01:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:01:50 INFO - 'tests': ['a11yr', 05:01:50 INFO - 'ts_paint', 05:01:50 INFO - 'tpaint', 05:01:50 INFO - 'sessionrestore', 05:01:50 INFO - 'sessionrestore_no_auto_restore']}, 05:01:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:01:50 INFO - 'tests': ['a11yr', 05:01:50 INFO - 'ts_paint', 05:01:50 INFO - 'tpaint', 05:01:50 INFO - 'sessionrestore', 05:01:50 INFO - 'sessionrestore_no_auto_restore']}, 05:01:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:01:50 INFO - 'tests': ['a11yr', 05:01:50 INFO - 'ts_paint', 05:01:50 INFO - 'tpaint', 05:01:50 INFO - 'sessionrestore', 05:01:50 INFO - 'sessionrestore_no_auto_restore']}, 05:01:50 INFO - 'other_l64': {'tests': ['a11yr', 05:01:50 INFO - 'ts_paint', 05:01:50 INFO - 'tpaint', 05:01:50 INFO - 'sessionrestore', 05:01:50 INFO - 'sessionrestore_no_auto_restore']}, 05:01:50 INFO - 'other_nol64': {'tests': ['a11yr', 05:01:50 INFO - 'ts_paint', 05:01:50 INFO - 'tpaint', 05:01:50 INFO - 'sessionrestore', 05:01:50 INFO - 'sessionrestore_no_auto_restore']}, 05:01:50 INFO - 'svgr': {'tests': ['tsvgx', 05:01:50 INFO - 'tsvgr_opacity', 05:01:50 INFO - 'tart', 05:01:50 INFO - 'tscrollx', 05:01:50 INFO - 'cart']}, 05:01:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:01:50 INFO - 'tests': ['tsvgx', 05:01:50 INFO - 'tsvgr_opacity', 05:01:50 INFO - 'tart', 05:01:50 INFO - 'tscrollx', 05:01:50 INFO - 'cart']}, 05:01:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:50 INFO - 'tests': ['tp5o']}, 05:01:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:50 INFO - 'talos_options': ['--e10s'], 05:01:50 INFO - 'tests': ['tp5o']}, 05:01:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:50 INFO - 'talos_options': ['--xperf_path', 05:01:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:01:50 INFO - 'tests': ['tp5n']}, 05:01:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:50 INFO - 'talos_options': ['--e10s', 05:01:50 INFO - '--xperf_path', 05:01:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:01:50 INFO - 'tests': ['tp5n']}}, 05:01:50 INFO - 'talos.zip': {'path': '', 05:01:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:01:50 INFO - Running post-action listener: _resource_record_post_action 05:01:50 INFO - ##### 05:01:50 INFO - ##### Running create-virtualenv step. 05:01:50 INFO - ##### 05:01:50 INFO - Running pre-action listener: _resource_record_pre_action 05:01:50 INFO - Running main action method: create_virtualenv 05:01:50 INFO - Creating virtualenv /builds/slave/test/build/venv 05:01:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:01:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:01:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:01:50 INFO - Using real prefix '/tools/python27' 05:01:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:01:51 INFO - Installing distribute.............................................................................................................................................................................................done. 05:01:54 INFO - Installing pip.................done. 05:01:54 INFO - Return code: 0 05:01:54 INFO - Installing pip>=1.5 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', '--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 0x106675a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10667c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0dacaa330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10651ad30>, '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 0x10637ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106510b20>, '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.aatiAd2XEc/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.54hWVK2nfH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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', '--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:54 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:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aatiAd2XEc/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.54hWVK2nfH/Listeners', 05:01:54 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:54 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:54 INFO - Cleaning up... 05:01:54 INFO - Return code: 0 05:01:54 INFO - Installing psutil>=0.7.1 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', '--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 0x106675a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10667c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0dacaa330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10651ad30>, '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 0x10637ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106510b20>, '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.aatiAd2XEc/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.54hWVK2nfH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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', '--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:54 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:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aatiAd2XEc/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.54hWVK2nfH/Listeners', 05:01:54 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:54 INFO - Downloading/unpacking psutil>=0.7.1 05:01:54 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:54 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 - 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:54 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:54 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:54 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 - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:01:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:01:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:01:57 INFO - Installing collected packages: psutil 05:01:57 INFO - Running setup.py install for psutil 05:01:57 INFO - building 'psutil._psutil_osx' extension 05:01:57 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:58 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:58 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:58 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:58 INFO - building 'psutil._psutil_posix' extension 05:01:58 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:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:01:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:01:58 INFO - ^ 05:01:58 INFO - 1 warning generated. 05:01:58 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:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:01:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:01:58 INFO - Successfully installed psutil 05:01:58 INFO - Cleaning up... 05:01:58 INFO - Return code: 0 05:01:58 INFO - Installing mozsystemmonitor==0.0.0 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', '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 0x106675a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10667c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0dacaa330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10651ad30>, '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 0x10637ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106510b20>, '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.aatiAd2XEc/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.54hWVK2nfH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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', 'mozsystemmonitor==0.0.0'] 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 mozsystemmonitor==0.0.0 05:01:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aatiAd2XEc/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.54hWVK2nfH/Listeners', 05:01:58 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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 mozsystemmonitor==0.0.0 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:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:02:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:02:01 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:02:01 INFO - Installing collected packages: mozsystemmonitor 05:02:01 INFO - Running setup.py install for mozsystemmonitor 05:02:01 INFO - Successfully installed mozsystemmonitor 05:02:01 INFO - Cleaning up... 05:02:01 INFO - Return code: 0 05:02:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:01 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 0x106675a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10667c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0dacaa330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10651ad30>, '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 0x10637ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106510b20>, '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.aatiAd2XEc/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.54hWVK2nfH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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:02:01 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:02:01 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:02:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aatiAd2XEc/Render', 05:02:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:01 INFO - 'HOME': '/Users/cltbld', 05:02:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:01 INFO - 'LOGNAME': 'cltbld', 05:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:01 INFO - 'MOZ_NO_REMOTE': '1', 05:02:01 INFO - 'NO_EM_RESTART': '1', 05:02:01 INFO - 'PAGER': '/bin/cat', 05:02:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:01 INFO - 'PWD': '/builds/slave/test', 05:02:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:01 INFO - 'SHELL': '/bin/bash', 05:02:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.54hWVK2nfH/Listeners', 05:02:01 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:02:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:01 INFO - 'USER': 'cltbld', 05:02:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:01 INFO - 'XPC_FLAGS': '0x0', 05:02:01 INFO - 'XPC_SERVICE_NAME': '0', 05:02:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:01 INFO - Downloading/unpacking blobuploader==1.2.4 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:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:02:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:02:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:05 INFO - Downloading docopt-0.6.1.tar.gz 05:02:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:02:05 INFO - Installing collected packages: blobuploader, requests, docopt 05:02:05 INFO - Running setup.py install for blobuploader 05:02:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:02:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:02:05 INFO - Running setup.py install for requests 05:02:05 INFO - Running setup.py install for docopt 05:02:06 INFO - Successfully installed blobuploader requests docopt 05:02:06 INFO - Cleaning up... 05:02:06 INFO - Return code: 0 05:02:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:06 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:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:06 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:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:06 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 0x106675a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10667c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0dacaa330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10651ad30>, '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 0x10637ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106510b20>, '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.aatiAd2XEc/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.54hWVK2nfH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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:02:06 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:02:06 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:02:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aatiAd2XEc/Render', 05:02:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:06 INFO - 'HOME': '/Users/cltbld', 05:02:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:06 INFO - 'LOGNAME': 'cltbld', 05:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:06 INFO - 'MOZ_NO_REMOTE': '1', 05:02:06 INFO - 'NO_EM_RESTART': '1', 05:02:06 INFO - 'PAGER': '/bin/cat', 05:02:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:06 INFO - 'PWD': '/builds/slave/test', 05:02:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:06 INFO - 'SHELL': '/bin/bash', 05:02:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.54hWVK2nfH/Listeners', 05:02:06 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:02:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:06 INFO - 'USER': 'cltbld', 05:02:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:06 INFO - 'XPC_FLAGS': '0x0', 05:02:06 INFO - 'XPC_SERVICE_NAME': '0', 05:02:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:02:06 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-jWXGk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:02:06 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-AiM4Ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:02:06 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-qiVoqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:02:06 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-zwOnYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:02:06 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-pC4L_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:02:06 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-MayT_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:02:07 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-wmcTpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:02:07 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-4Ye7pM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:02:07 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-Vi3n8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:02:07 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-A1BbUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:02:07 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-AWHTCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:02:07 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-nSmNuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:02:07 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-X7ACug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:02:07 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-SDOjAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:07 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-dOsVw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:02:08 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-WyShE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:02:08 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-a1HFtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:02:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:02:08 INFO - Running setup.py install for manifestparser 05:02:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:02:08 INFO - Running setup.py install for mozcrash 05:02:08 INFO - Running setup.py install for mozdebug 05:02:08 INFO - Running setup.py install for mozdevice 05:02:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:02:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:02:08 INFO - Running setup.py install for mozfile 05:02:09 INFO - Running setup.py install for mozhttpd 05:02:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Running setup.py install for mozinfo 05:02:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Running setup.py install for mozInstall 05:02:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Running setup.py install for mozleak 05:02:09 INFO - Running setup.py install for mozlog 05:02:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Running setup.py install for moznetwork 05:02:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Running setup.py install for mozprocess 05:02:10 INFO - Running setup.py install for mozprofile 05:02:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:02:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:02:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:02:10 INFO - Running setup.py install for mozrunner 05:02:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:02:10 INFO - Running setup.py install for mozscreenshot 05:02:10 INFO - Running setup.py install for moztest 05:02:10 INFO - Running setup.py install for mozversion 05:02:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:02:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:02:10 INFO - Cleaning up... 05:02:10 INFO - Return code: 0 05:02:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:10 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:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:10 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:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:10 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 0x106675a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10667c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0dacaa330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10651ad30>, '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 0x10637ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106510b20>, '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.aatiAd2XEc/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.54hWVK2nfH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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:02:10 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:02:10 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:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aatiAd2XEc/Render', 05:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:10 INFO - 'HOME': '/Users/cltbld', 05:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:10 INFO - 'LOGNAME': 'cltbld', 05:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:10 INFO - 'MOZ_NO_REMOTE': '1', 05:02:10 INFO - 'NO_EM_RESTART': '1', 05:02:10 INFO - 'PAGER': '/bin/cat', 05:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:10 INFO - 'PWD': '/builds/slave/test', 05:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:10 INFO - 'SHELL': '/bin/bash', 05:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.54hWVK2nfH/Listeners', 05:02:10 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:10 INFO - 'USER': 'cltbld', 05:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:10 INFO - 'XPC_FLAGS': '0x0', 05:02:10 INFO - 'XPC_SERVICE_NAME': '0', 05:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:02:11 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-BfjV07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:02:11 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:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:02:11 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-T7MXm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:02:11 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:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:02:11 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-GOaJWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:02:11 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:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:02:11 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-ZEJl87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:02:11 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:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:02:11 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-qMXUdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:02:11 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:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:02:11 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-gdd9mW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:02:11 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:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:02:11 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-tsJsvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:02:11 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:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:02:11 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-TA6xQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:02:11 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:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:02:11 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-WcKo_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:02:12 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:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:02:12 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-xX1qwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:02:12 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:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:02:12 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-cCTEeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:02:12 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:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:02:12 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-xPuk6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:02:12 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:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:02:12 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-GJDq6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:02:12 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:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:02:12 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-R_ohkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:02:12 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:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:12 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-SmRAcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:12 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:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:02:12 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-Lx9O8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:02:12 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:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:02:12 INFO - Running setup.py (path:/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/pip-waq4Hm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:02:12 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:02:12 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:02:12 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:02:12 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:02:12 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:02:12 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:02:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:02:12 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:12 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:12 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:12 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:12 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:12 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:15 INFO - Downloading blessings-1.6.tar.gz 05:02:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:02:15 INFO - Installing collected packages: blessings 05:02:15 INFO - Running setup.py install for blessings 05:02:15 INFO - Successfully installed blessings 05:02:15 INFO - Cleaning up... 05:02:15 INFO - Return code: 0 05:02:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:02:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:02:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:02:15 INFO - Reading from file tmpfile_stdout 05:02:15 INFO - Current package versions: 05:02:15 INFO - blessings == 1.6 05:02:15 INFO - blobuploader == 1.2.4 05:02:15 INFO - docopt == 0.6.1 05:02:15 INFO - manifestparser == 1.1 05:02:15 INFO - mozInstall == 1.12 05:02:15 INFO - mozcrash == 0.16 05:02:15 INFO - mozdebug == 0.1 05:02:15 INFO - mozdevice == 0.48 05:02:15 INFO - mozfile == 1.2 05:02:15 INFO - mozhttpd == 0.7 05:02:15 INFO - mozinfo == 0.9 05:02:15 INFO - mozleak == 0.1 05:02:15 INFO - mozlog == 3.1 05:02:15 INFO - moznetwork == 0.27 05:02:15 INFO - mozprocess == 0.22 05:02:15 INFO - mozprofile == 0.28 05:02:15 INFO - mozrunner == 6.11 05:02:15 INFO - mozscreenshot == 0.1 05:02:15 INFO - mozsystemmonitor == 0.0 05:02:15 INFO - moztest == 0.7 05:02:15 INFO - mozversion == 1.4 05:02:15 INFO - psutil == 3.1.1 05:02:15 INFO - requests == 1.2.3 05:02:15 INFO - wsgiref == 0.1.2 05:02:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:15 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 0x106675a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10667c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0dacaa330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10651ad30>, '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 0x10637ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106510b20>, '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.aatiAd2XEc/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.54hWVK2nfH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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:02:15 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:02:15 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:02:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aatiAd2XEc/Render', 05:02:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:15 INFO - 'HOME': '/Users/cltbld', 05:02:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:15 INFO - 'LOGNAME': 'cltbld', 05:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:15 INFO - 'MOZ_NO_REMOTE': '1', 05:02:15 INFO - 'NO_EM_RESTART': '1', 05:02:15 INFO - 'PAGER': '/bin/cat', 05:02:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:15 INFO - 'PWD': '/builds/slave/test', 05:02:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:15 INFO - 'SHELL': '/bin/bash', 05:02:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.54hWVK2nfH/Listeners', 05:02:15 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:02:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:15 INFO - 'USER': 'cltbld', 05:02:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:15 INFO - 'XPC_FLAGS': '0x0', 05:02:15 INFO - 'XPC_SERVICE_NAME': '0', 05:02:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:16 INFO - Cleaning up... 05:02:16 INFO - Return code: 0 05:02:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:02:16 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:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:16 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:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:16 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 0x106675a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10667c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0dacaa330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10651ad30>, '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 0x10637ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106510b20>, '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.aatiAd2XEc/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.54hWVK2nfH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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:02:16 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:02:16 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:02:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aatiAd2XEc/Render', 05:02:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:16 INFO - 'HOME': '/Users/cltbld', 05:02:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:16 INFO - 'LOGNAME': 'cltbld', 05:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:16 INFO - 'MOZ_NO_REMOTE': '1', 05:02:16 INFO - 'NO_EM_RESTART': '1', 05:02:16 INFO - 'PAGER': '/bin/cat', 05:02:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:16 INFO - 'PWD': '/builds/slave/test', 05:02:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:16 INFO - 'SHELL': '/bin/bash', 05:02:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.54hWVK2nfH/Listeners', 05:02:16 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:02:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:16 INFO - 'USER': 'cltbld', 05:02:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:16 INFO - 'XPC_FLAGS': '0x0', 05:02:16 INFO - 'XPC_SERVICE_NAME': '0', 05:02:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:16 INFO - Downloading/unpacking jsonschema 05:02:16 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:16 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:16 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:16 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:16 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:16 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:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:02:18 INFO - Downloading/unpacking functools32 (from jsonschema) 05:02:18 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:18 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:18 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:18 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:18 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:18 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:19 INFO - Downloading functools32-3.2.3-2.tar.gz 05:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:02:19 INFO - warning: no files found matching '*.txt' 05:02:19 INFO - no previously-included directories found matching 'build' 05:02:19 INFO - no previously-included directories found matching 'dist' 05:02:19 INFO - no previously-included directories found matching '.git*' 05:02:19 INFO - Installing collected packages: jsonschema, functools32 05:02:19 INFO - Running setup.py install for functools32 05:02:19 INFO - warning: no files found matching '*.txt' 05:02:19 INFO - no previously-included directories found matching 'build' 05:02:19 INFO - no previously-included directories found matching 'dist' 05:02:19 INFO - no previously-included directories found matching '.git*' 05:02:19 INFO - Successfully installed jsonschema functools32 05:02:19 INFO - Cleaning up... 05:02:19 INFO - Return code: 0 05:02:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:02:19 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:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:19 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:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:19 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 0x106675a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10667c4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0dacaa330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10651ad30>, '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 0x10637ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106510b20>, '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.aatiAd2XEc/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.54hWVK2nfH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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:02:19 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:02:19 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:02:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aatiAd2XEc/Render', 05:02:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:19 INFO - 'HOME': '/Users/cltbld', 05:02:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:19 INFO - 'LOGNAME': 'cltbld', 05:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:19 INFO - 'MOZ_NO_REMOTE': '1', 05:02:19 INFO - 'NO_EM_RESTART': '1', 05:02:19 INFO - 'PAGER': '/bin/cat', 05:02:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:19 INFO - 'PWD': '/builds/slave/test', 05:02:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:19 INFO - 'SHELL': '/bin/bash', 05:02:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.54hWVK2nfH/Listeners', 05:02:19 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:02:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:19 INFO - 'USER': 'cltbld', 05:02:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:19 INFO - 'XPC_FLAGS': '0x0', 05:02:19 INFO - 'XPC_SERVICE_NAME': '0', 05:02:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:19 INFO - Downloading/unpacking flake8 05:02:19 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:19 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:19 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:19 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:19 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:19 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:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:02:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:02:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:02:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:02:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:02:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:02:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:02:23 INFO - no previously-included directories found matching 'docs/_build' 05:02:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:02:23 INFO - Running setup.py install for pep8 05:02:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:02:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:02:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:02:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:02:23 INFO - no previously-included directories found matching 'docs/_build' 05:02:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:02:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:02:23 INFO - Cleaning up... 05:02:23 INFO - Return code: 0 05:02:23 INFO - Running post-action listener: _resource_record_post_action 05:02:23 INFO - Running post-action listener: _start_resource_monitoring 05:02:23 INFO - Starting resource monitoring. 05:02:23 INFO - ##### 05:02:23 INFO - ##### Running install step. 05:02:23 INFO - ##### 05:02:23 INFO - Running pre-action listener: _resource_record_pre_action 05:02:23 INFO - Running main action method: install 05:02:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:02:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:02:24 INFO - Reading from file tmpfile_stdout 05:02:24 INFO - Detecting whether we're running mozinstall >=1.0... 05:02:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:02:24 INFO - Reading from file tmpfile_stdout 05:02:24 INFO - Output received: 05:02:24 INFO - Usage: mozinstall [options] installer 05:02:24 INFO - Options: 05:02:24 INFO - -h, --help show this help message and exit 05:02:24 INFO - -d DEST, --destination=DEST 05:02:24 INFO - Directory to install application into. [default: 05:02:24 INFO - "/builds/slave/test"] 05:02:24 INFO - --app=APP Application being installed. [default: firefox] 05:02:24 INFO - mkdir: /builds/slave/test/build/application 05:02:24 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:24 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:58 INFO - Reading from file tmpfile_stdout 05:02:58 INFO - Output received: 05:02:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:02:58 INFO - Running post-action listener: _resource_record_post_action 05:02:58 INFO - ##### 05:02:58 INFO - ##### Running run-tests step. 05:02:58 INFO - ##### 05:02:58 INFO - Running pre-action listener: _resource_record_pre_action 05:02:58 INFO - Running main action method: run_tests 05:02:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:02:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:02:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:02:58 INFO - 2016-02-06 05:02:58.144 screenresolution[8835:146096] starting screenresolution argv=screenresolution get 05:02:58 INFO - 2016-02-06 05:02:58.147 screenresolution[8835:146096] Display 0: 1600x1200x32@60 05:02:58 INFO - 2016-02-06 05:02:58.153 screenresolution[8836:146098] starting screenresolution argv=screenresolution list 05:02:58 INFO - Available Modes on Display 0 05:02:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:02:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:02:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:02:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:02:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:02:58 INFO - Intel Iris: 05:02:58 INFO - Chipset Model: Intel Iris 05:02:58 INFO - Type: GPU 05:02:58 INFO - Bus: Built-In 05:02:58 INFO - VRAM (Dynamic, Max): 1536 MB 05:02:58 INFO - Vendor: Intel (0x8086) 05:02:58 INFO - Device ID: 0x0a2e 05:02:58 INFO - Revision ID: 0x0009 05:02:58 INFO - Displays: 05:02:58 INFO - Display: 05:02:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:02:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:02:58 INFO - Display Serial Number: 1600x1200 60 05:02:58 INFO - Main Display: Yes 05:02:58 INFO - Mirror: Off 05:02:58 INFO - Online: Yes 05:02:58 INFO - Rotation: Supported 05:02:58 INFO - Return code: 0 05:02:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:02:58 INFO - Python 2.7.3 05:02:58 INFO - Return code: 0 05:02:58 INFO - grabbing minidump binary from tooltool 05:02: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:02:58 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 0x10651ad30>, '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 0x10637ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106510b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:02:58 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:58 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:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:02:58 INFO - Return code: 0 05:02:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:02:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:02:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:02:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:02:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:02:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:02:59 INFO - Return code: 0 05:02:59 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-e10s', '--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-0091', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:02:59 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-e10s --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-0091 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:02:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aatiAd2XEc/Render', 05:02:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:59 INFO - 'HOME': '/Users/cltbld', 05:02:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:59 INFO - 'LOGNAME': 'cltbld', 05:02:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:02:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:59 INFO - 'MOZ_NO_REMOTE': '1', 05:02:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:02:59 INFO - 'NO_EM_RESTART': '1', 05:02:59 INFO - 'PAGER': '/bin/cat', 05:02:59 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:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:59 INFO - 'PWD': '/builds/slave/test', 05:02:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:02:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:59 INFO - 'SHELL': '/bin/bash', 05:02:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.54hWVK2nfH/Listeners', 05:02:59 INFO - 'TMPDIR': '/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/', 05:02:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:59 INFO - 'USER': 'cltbld', 05:02:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:59 INFO - 'XPC_FLAGS': '0x0', 05:02:59 INFO - 'XPC_SERVICE_NAME': '0', 05:02:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--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-0091', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:02:59 INFO - mozversion application_buildid: 20160203064345 05:02:59 INFO - mozversion application_changeset: 6826dfd0e85b29050e45273d9bfdb27dc2bc1128 05:02:59 INFO - mozversion application_display_name: Nightly 05:02:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:02:59 INFO - mozversion application_name: Firefox 05:02:59 INFO - mozversion application_remotingname: firefox 05:02:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:02:59 INFO - mozversion application_vendor: Mozilla 05:02:59 INFO - mozversion application_version: 47.0a1 05:02:59 INFO - mozversion platform_buildid: 20160203064345 05:02:59 INFO - mozversion platform_changeset: 6826dfd0e85b29050e45273d9bfdb27dc2bc1128 05:02:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:02:59 INFO - mozversion platform_version: 47.0a1 05:02:59 INFO - using testdate: 1454763779 05:02:59 INFO - actual date: 1454763779 05:03:00 INFO - starting webserver on 'localhost:49545' 05:03:00 INFO - SUITE-START | Running 2 tests 05:03:00 INFO - TEST-START | dromaeo_css 05:03:00 INFO - operating with platform_type : mac_ 05:03:00 INFO - Initialising browser for dromaeo_css test... 05:03:00 INFO - TEST-INFO | started process 8854 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmpoO1y3o/profile http://localhost:49545/getInfo.html) 05:03:00 INFO - PROCESS | 8854 | JavaScript warning: resource://gre/modules/AddonManager.jsm, line 692: Proxy.create and Proxy.createFunction are deprecated, use new Proxy instead 05:03:03 INFO - PROCESS | 8854 | __metrics Screen width/height:1600/1200 05:03:03 INFO - PROCESS | 8854 | colorDepth:24 05:03:03 INFO - PROCESS | 8854 | Browser inner width/height: 1024/689 05:03:03 INFO - PROCESS | 8854 | __metrics 05:03:03 INFO - PROCESS | 8854 | 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:03:03 INFO - PROCESS | 8854 | 05:03:03 INFO - PROCESS | 8854 | ###!!! [Child][MessageChannel] Error: (msgtype=0x2A0045,name=PBrowser::Msg___delete__) Channel closing: too late to send/recv, messages will be lost 05:03:03 INFO - PROCESS | 8854 | 05:03:03 INFO - TEST-INFO | 8854: exit 0 05:03:03 INFO - Browser initialized. 05:03:03 INFO - Running cycle 1/1 for dromaeo_css test... 05:03:03 INFO - TEST-INFO | started process 8858 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmpoO1y3o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:03:15 INFO - PROCESS | 8858 | RSS: Main: 255856640 05:03:15 INFO - PROCESS | 8858 | 05:04:05 INFO - PROCESS | 8858 | 1454763845535 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:06:16 INFO - PROCESS | 8858 | Cycle 1(1): loaded http://localhost:49545/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49545/tests/dromaeo/cssquery-ext.html) 05:06:17 INFO - PROCESS | 8858 | RSS: Main: 269193216 05:06:17 INFO - PROCESS | 8858 | 05:09:19 INFO - PROCESS | 8858 | Cycle 1(1): loaded http://localhost:49545/tests/dromaeo/cssquery-ext.html (next: http://localhost:49545/tests/dromaeo/cssquery-jquery.html) 05:09:20 INFO - PROCESS | 8858 | RSS: Main: 281948160 05:09:20 INFO - PROCESS | 8858 | 05:12:22 INFO - PROCESS | 8858 | Cycle 1(1): loaded http://localhost:49545/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49545/tests/dromaeo/cssquery-mootools.html) 05:12:22 INFO - PROCESS | 8858 | RSS: Main: 284266496 05:12:22 INFO - PROCESS | 8858 | 05:15:24 INFO - PROCESS | 8858 | Cycle 1(1): loaded http://localhost:49545/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49545/tests/dromaeo/cssquery-prototype.html) 05:15:24 INFO - PROCESS | 8858 | RSS: Main: 285114368 05:15:24 INFO - PROCESS | 8858 | 05:18:27 INFO - PROCESS | 8858 | Cycle 1(1): loaded http://localhost:49545/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49545/tests/dromaeo/cssquery-yui.html) 05:18:28 INFO - PROCESS | 8858 | RSS: Main: 286621696 05:18:28 INFO - PROCESS | 8858 | 05:21:30 INFO - PROCESS | 8858 | Cycle 1(1): loaded http://localhost:49545/tests/dromaeo/cssquery-yui.html (next: http://localhost:49545/tests/dromaeo/cssquery-dojo.html) 05:21:30 INFO - PROCESS | 8858 | RSS: Main: 289468416 05:21:30 INFO - PROCESS | 8858 | 05:21:30 INFO - PROCESS | 8858 | __start_tp_report 05:21:30 INFO - PROCESS | 8858 | _x_x_mozilla_page_load 05:21:30 INFO - PROCESS | 8858 | _x_x_mozilla_page_load_details 05:21:30 INFO - PROCESS | 8858 | |i|pagename|runs| 05:21:30 INFO - PROCESS | 8858 | |0;dojo.html;3368.6968172864435;3542.840572174252;3567.554055743032;3582.695470884975;3592.119930616999;4969.527894849993;5543.972280138603;5849.853753656161;5858;5871.236739223906;5664.886702265952;5750.798722044729;5805.738741756621;5807.73865176067;5811.593188476808;5213.739313034352;5244.396894357141;5285.154375299952;5292.788288468476;5382.703951282678;4493.820247190125;5325.334333208349;5366.195070739401;5380.542653874406;5421.349438067417;5348.759305831247;5367.46325367464;5373.113436283028;5395;5455.781768729266;5122.92315615266;5293.444188360244;5302.284191634157;5321.068812957717;5332.386775520826;5246.344206974129;5261.605379596545;5262.97368513155;5294.285271488338;5298.867528311823;5546.223528705985;5553.7778488860395;5670.773169073233;5726.112452569817;5742.167385729046;6223.346548612376;6237.906431403532;6274.811755647293;6286.937130628727;6289.465395441393;6246.094316324107;6247.375262473716;6257.405546473078;6273.059041143865;6283.277423096357;5810.738516766755;5882.529397648178;5907.232059832238;5909.172715819789;5929.407059294079;6223.751049958041;6266.216722909637;6331.461825744771;6336.904946425807;6343;5181.300524429229;6194.287656919512;6200.503959683215;6212.192414986023;6214.689265536705;6169.660668663267;6196.47329976957;6207.286162091327;6252.656103914328;6262.436380725757;6171.074338849119;6246.812595622138;6255.437010669061;6269.811905642838;6275.901717199472;6227.937720622826;6264.968675156595;6280.528960327993;6292.716827742763;6297.4962003039645;4225.746455212697;4880.072786170616;4959;4964.776585053682;4965.726885021359;4430.8449204277695;4452.1763473757455;4457.153140903218;4492.505824359383;4494.258447356149;4974.427940786856;5874.853128671817;5934.287885453773;5965.313988891333;5996.430339117776;4579.244424669959;5260.605454590845;6146.262448506208;6171.814845554641;6255;5942.910856337158;5954.047352423591;5968.940310596839;6001.1898393717165;6002.939970600238;4545.954540454553;5407.891842163135;5417.8103766367985;5419.539339156137;5447.319085114361;6264.185655864709;6336.334684858023;6341.682915854189;6418.358164183544;6432.807015789534;6274;6279.120923070683;6284.654344011124;6286.748530058747;6308.306086330408;5660.773569057356;6129.754809807558;6229.595076320206;6408.038794180724;6416.358364163546;5555.999920014437;6146.985747351637;6269.279032911274;6296.496280297473;6306.621602703853;6193.783217587363;6265.718042688181;6275.246970363585;6281.937180628318;6316.968415157895;5172.043172013189;5984.371640977634;6135.233095863017;6167.599106058092;6187;6135.753292002456;6190.566660333733;6212.968935155295;6213.9067913980425;6217.658028808459;5984.970075149597;6043.335233124265;6065.696715164312;6079.969600151971;6095.299040610386;6079.148919151411;6128.686460748492;6139.001538246492;6217.595856269328;6224.533160012927;6095.213525956522;6117.9492029047315;6121.5439883209065;6125.937554509019;6126.366913828976 05:21:30 INFO - PROCESS | 8858 | |1;ext.html;455.683300106426;497.4031162604875;497.45280191789027;558.7346010644944;562.9332415073435;32434.513482297756;38794.61205387945;39017.80491097541;39148;39197.41203881937;33664.66335336646;41446.756597302054;42004.94987625311;42013.78993105038;42048.78975605125;9481.715548533537;9722.173615242713;9736.172425343855;9796.608135674565;9799.02009799018;9608.663696770614;9725.97877222892;9760.121589056984;9857.802843943116;9858.605655773761;9835;9853.408795472258;9904.356216845881;9915.851262231072;9923.354981926152;9787.265955053364;9853.458059806708;9865.112139907444;9867.210623150131;9867.96386379427;9662.75843103928;9747.220222382264;9777.951110244474;9779.364341317792;9794.608215671436;39566.802165988986;40324.79837600823;40662;40707.592924070676;40835.120663536916;3339.916502087443;3490.3193877193958;3507.7193824494234;3866.7486613370043;3884;23062.308130756104;28457;28915.710842891513;29385.118446446642;29929.251768705737;2360.280114565057;2451.4361696809833;2827.6606807183066;2868.6557613086356;2885.2209903326193;350099.2495037534;418401;462359;475441;481241;45504.772476137405;45874.5412545877;68769;69067.65466172637;69613;45122;45259.86850328702;45264.3210351845;45388;45633.31550026752;13458.596242112751;15504.457343992906;15693.607659808367;15698.979566328153;15776.921115394349;12386.62840114798;12597.055220858472;12600.74798504043;12692.873071269169;12754.681132971564;14307.141571505743;14647.267636618126;14747.631309217355;14785.556433307018;14872.330745116496;5876.588638795243;6202.5658203925295;6322.557420980487;6351.936480635227;6354.555181137276;917.5779141594941;1042.3172821801732;1148.936808475542;1156.4969238381332;1197.3713800254936;29603.259918502208;29684.257893552403;29945.80216791304;30511.69488305133;30603.2349191272;1068.9198310126772;1141.5547936304847;1205.8182980678807;1210.189173253922;1261.413442749126;12683.936580317039;15621.843781562266;16040.358385664676;16296.34814607426;16341.26464309109;483734;599410;609350;613980;614733;61059;61372.38627613756;62444.43888902814;62930;63324.366756331845;1446.4720377062297;1462.159258426409;1467.9412823486941;1473.3075454536352;1589.6184915620167;30883;30914.536281955337;31127.84436077805;31598.842005789826;31769;54213.728931356665;55748;55774;55938.440615593325;55993.16010259931;485306;489336;494288;494730;495013;43612;44094.11811763811;44150.89622759457;44933.550664492934;44976.77511612421;43207.35188972231;43347;43696.56303437052;43903;43905;30684.84657576787;35158.472622910165;36446.27107457889;37177.62822371742;37446.06384840292;35967.82016089903;37277.0680732973;37470.250594988494;37626;39173.21653566856;35195.82402087973;36010.819945900104;36150.27699446049;36284.27431451409;36576.63423365732;30113.798079563152;30241.487925603193;33463.49804752882;33698.83150584231;33764.15589610279;25074.62388064217;25339.366515837253;25494.74505254998;25810.61284080703;25900.186986910736 05:21:30 INFO - PROCESS | 8858 | |2;jquery.html;362.5413851477881;365.7384969746631;365.884746304914;369.80030783376947;372.2759233291248;15393.846061539381;18687.813121868778;19007.714884276746;19408.611827763452;20147.798522014775;11309.547618095266;11606.883931160686;11720.82418763717;11758.000569951542;11965.102617303715;3507.281007393491;4483.125790470843;5035.748212589374;5126.71803050832;5334.1732031535175;3733.6826369758605;5617;5658.066419040874;5891.293044834604;5899.764009439617;5417.783288668448;5624.578156638248;5658.660480371191;5776.855578610526;5840.123981402802;4909.312696222514;5356.0894647909945;5604.607677462579;5614.29821272341;5661.094224924033;5286.180642000496;5316.38861530925;5377.059014672417;5404.1083221268445;5411.891762164734;13159.605211843564;13354.33228338589;13393.531226407127;17255.568610784707;24721.752782472304;2062.5668609592003;2149.9462513437134;2178.6623073423643;2185.4099393163915;2446.7797898189247;9459.763505912339;9604.855927161097;9885.851712224323;11224.66326010221;14690.926545367312;1726.3672898829261;1783.6611043901617;1788.7227479740532;1790.7134858422721;1940;94156.58765118528;98201.0687709133;98552;99009;99814;11067.280626759235;11178.385188814531;11252.324860508395;11290;11354.3755093469;13296.468141274434;13380.531681391105;13411.195328280335;13433.798493022612;13468;7487.1389790173735;7683.769486915401;8145.51126932386;8496.470635285708;8569.871451928226;7024.473164512682;8131.430799843954;8570.4000719949;8700.521471319138;8779.12208779117;3589.7487175897436;3995.08113133975;4149.2738770715605;4338.154059958278;4460.77696115193;1581.2251996521852;1870.3453791173254;1875.990620046891;1882.7175923611567;1897.3169658922777;890.3678388344348;915.4964769376816;921.2675922641516;965.560669895196;1014.0587677348537;16528.338866445207;19159;19476.610467790564;19723.704144437845;19850.205991760457;942.3544871762728;944.8535477001076;961.7018724195522;997.4514017290461;1032.4115254304975;10445.634402795866;10956.780864382668;11362.659120226406;11409.48657310423;11455.656330310105;654804;683653;688958;697122;716845;111098.44450777695;113402.43298783619;114154;115387;116210;1029.1818004686293;1173.2725710059817;1189.2032338333338;1190.3988485814782;1214.9635510934686;18203.362882299058;18289;18362.81637183611;18490.722639160693;18509.9074504632;48308.75845620749;48361.516384835704;48393.99999999859;48566.27150592815;49218;69123;74094;74134;74283.2571674298;75137;7362.815929601803;7620.961895190489;7652.923470765221;7669.003029606116;7723.497972631761;6193.535484838565;10719.678409647722;11282.548698052315;11629.24409913353;11771.11716621274;10215.438150901771;10305.021023002802;10407.895921040996;10422.21833362516;10534.947325263325;9481.288903332415;9691.70924872255;9843.163331116935;10192.643257485952;10248.436336001592;10186.74533136648;10352.120069793851;10478.738031549272;10507.15942724565;10537.789244215226;7434.2565743427385;7558.433117516318;7603.391728661583;7657.57883316423;7658.6170691464085;6595.670216489252;9877.901220987698;10335.94832025835;10538.209634277613;10549.841752373874 05:21:30 INFO - PROCESS | 8858 | |3;mootools.html;1510.675204830961;1537.9846201537985;1547.9226038698066;1871.3450292397654;1898.5158784509947;7776.766696999085;7914.3272821810215;8547.871781923268;8739.431936924095;8758.562071896411;8303.50178989261;8503.532305723184;8587.270082043035;8614.224719775219;8619.784505387353;2465.001674321898;2483.9751602483925;2491.1281051631963;2495.4011037350997;2496.3384703053707;2439.6706444629926;2501.4371766352515;2514.459391230885;2514.911978080758;2527.0018342754693;2471.851688898663;2501.3246423465653;2504.3113143885485;2507.9466624017864;2520.269121954631;2443.767842055002;2498.4253621667026;2510.5606518859217;2519.7984161267054;2526.835755675875;2337.6610391493136;2410.288964755384;2477.677901872763;2491.489244704831;2503.9374015649573;8760.992485864079;8842.823143537158;8850.601722922484;8859.335549833788;8861.601227944759;5336.199570064483;5473.616846820724;5485.533729632986;5488.643238189505;5491.148871924858;3582.5342705448124;3656.433252845813;3682;3719.088823238324;3730.8321125549414;1052.6894566103017;1072.6674730833388;1078.877966914408;1082.8862969388258;1109.7835921995213;11864.58473953416;12004.69988250292;12014.579489717906;12020;12089.516419343216;4923.77842997066;4942.777575009133;4987.775550100254;4999.425066117443;5005.324281221989;10072.395656260647;10143.797124057477;10145.594176233015;10164.949175254076;10169.593216271414;5352.170413585901;5491.670499769947;5493.642913210629;5498.395176530578;5507.173923911445;4429.402030725875;4551.931721024187;4843.370361853007;4970.8757281068265;5001.924971125436;3912.197999410114;3931.312020396419;3946.0134966258433;3986.1828325193264;3997.120633460634;1325.2181213084327;1334.4595438847282;1342.1209108034254;1344.2539390638094;1345.6299517632729;846.551327796269;1112.777444511101;1227.0061250387214;1309.1948451702135;1320.8414990201238;8001.319887809602;8036.196380361917;8043.6782528698195;8045;8054.476459030261;514.5189248053082;527.6385675812081;528.3870709976486;535.1170568561904;536.694084371912;6093.634381937098;6147.415995480422;6151;6179.227596550431;6229.252489701174;8954.507502087317;10629.5216715248;10667.786644267071;10726.785464290671;10843.024127828534;8213.876791848126;10526.842097368546;10709.196810239264;10770.838437423445;10788.622398216025;8241.134680858544;8256.091829898707;8356.415050946496;8362.080171181044;8372.246497815226;7680.270374314431;7798.259165379507;7806.41451891099;7867.095284042409;7908.84182316339;8481.448705834102;9380.20268277204;9547;9633.566489507848;9707.757306067404;9738.318317717692;9829;9869.013098690073;9905.702828915144;9979.750506237402;5688.374278829243;6647.368499992493;7033.683484243119;7437.776866694008;7511.962440187983;8013.998250218722;8030.919690803017;8065.274125328514;8074.757757267292;8103.31121854649;7422.1835598085245;7517.624118794148;7556.055493063367;7671.38628909697;7768.262015108556;7357.889631655637;7436.256374362521;7456;7509;7601.6579253934815;6348.904766428415;7565.621718913922;7595.506292090997;7603.619819009138;7622.352100071556;6930.826729331807;6960.825979350354;6973;7006.474514411338;7011;7048.894266586108;7082.964585177041;7088.291170882871;7092.148942126978;7103.751368702277 05:21:30 INFO - PROCESS | 8858 | |4;prototype.html;252.40936209270305;304.4823799540781;323.1356122372653;336.14954165960114;337.28663875902475;6430.324815894334;6806.115205023346;6946.6179360135175;6961.7911462656075;7060.117485314336;6790.422814060798;6834.384905358516;6853.862922741542;6862.828429289258;6919.8616027679545;4804.231322988323;4933.531314525115;5045.672031317971;5049.9747501262445;5059.924101138486;4125.773082480462;4734.721625161204;4899.044686286175;4933.407991041062;5033.899322013576;4953.058918805434;4968.677035992649;4981.5018498150075;4991.675541089836;4994.325766021595;3863.7295389322617;4983.426905905812;4984.102861484931;5001.424551266339;5012.448630650625;4253.191893540248;4267.551907049746;4268.40242366069;4279.465066866642;4312.784360781949;5494.697791621459;6387.297397286295;6492.545521813475;6493.253275873288;6559.508036897252;5957.582969192158;5971.193888824996;5972.163897054415;5973.731182096816;5982.222311099573;6294.653794041325;7322.81692957675;7353.30143636359;7378.704851805921;7383.409327253807;3967.7024223183375;4610.492845786961;4805.014971930746;4817.735024573647;4828.61371090312;6104.694765261719;7450.776476705708;7658.310752032288;7828.647710853025;7873.96063019687;4836.347093142381;4871.610271178297;4873.488283730228;4879.26810978349;4886.3403440535785;4140.91718165635;4872.342233798463;4964.553190212898;4971.328870602495;4976.776045077981;5734.5125664319;5899.085641725539;5913.674747888821;5920.674362910081;5949.791757288474;7367;7414.369778568775;7425.665845036986;7434.219406962299;7437.888431673529;1720.9053502057257;1737.053305948236;1740.2342969093559;1740.3299729604194;1752.299080367863;1108.14672702019;1117.983230251547;1127.503898284752;1132.8640563132476;1144.9427528623535;6970.546914450544;6975.372216500539;6994.33553812387;7010.474214433836;7013.088298521159;7684.577348245788;7692.961450204262;7705.4220933430215;7707.421943354158;7740.032495938008;5971.7611295547695;6759.763408280785;6866.141732283465;6983.89524157138;6995;3261.9184520386707;3481.9129521762156;3611.8735844245325;3967.6429121379215;4071.328230841937;7998.880156778056;8017.118117007241;8031.759047228593;8047.798805029921;8070.959645201737;7611.20082391352;7924.762257132295;7930.6431210595665;7932.889395484637;7966.0440747110715;8008.878756974028;8019.51882887017;8022.197780221931;8042.954415925892;8044.718434854869;6373.490120790233;7399.667014984231;7485.0643669541305;7631.503952243309;7651.283794253182;7498.137714162723;7508.549487030796;7530.736424225126;7533.434992375484;7540.585267810104;6909.585424874524;7363.337299643058;7983.600819958862;8020.518768873886;8032.518048917083;4891.730954797378;4922.532359425849;4946.208606622923;4947.3073769671555;4955.603551715782;7452.292032256927;7549.7357592483995;7644.885326720215;7659.923400766144;7693.961530192537;7355.8161045974275;7398.556086634819;7401.444891633041;7419.517731347661;7420.035395398563;6308.6530240835355;7339.522931009467;7342.963285183753;7349.77950661481;7367.963160184165;6962.477814164059;7172.784816455514;7177.7487787927175;7202.459815513753;7255.600941948243;7415.777526674208;7470.887936680845;7472.701091956261;7483.925160748323;7498.775036748906;7042.260562640849;7243.420526357773;7279.563226206445;7281.199068102399;7290.5625662460425 05:21:30 INFO - PROCESS | 8858 | |5;yui.html;673.6497021548795;716.2372073741468;742.528494406052;751.6204316820005;756.326869086513;14533.709325813477;14661.853381466182;14914.701705965874;14926.552203433901;16371.836281637181;12851.35743212838;15650.452234171806;15682.607934801636;15694.921525392385;15739.527814165565;700.3661686173995;718.1203026292802;856.8072183758667;871.6121326009928;873.9694110706126;850.9872351914727;868.3530769576674;870.5516658920661;876.3821505838368;882.2765332427412;877.662100091463;930.0095398400715;930.2511478259999;931.8509038553833;932.1796818799447;906.4697152165958;907.3059109781022;908.4458480326995;925.0102390442196;925.3245131054301;667.3093348384399;668.5954997226656;679.9354061364163;682.0383259603934;692.9133858267717;13463.596092117154;13565.321733913264;13574.117682350616;13584.932075339659;13639.249841258727;12232.449539770643;12332.260064396165;12552.937235313857;12635.43140558677;13116.409761560753;9989.90010099897;10064.094231519126;10416.27086103973;10451.63419280329;10860.565577376896;3480.32133733922;3522.84147213376;3531.6998055165086;3540.6636369544854;3690.3541880170865;78677.42645147123;78693;78776;78887.60556197182;79385.80921286186;9216.447013179231;9263.120003599648;9500.809983800285;9514.809703805886;9772.12050915421;20391.898040509703;20500.589988200154;20577.868217247895;20585.69121463179;20600.484987875123;7178.959050937585;7391.926080739124;7447.925520744831;7512.737054203077;7586.24137586237;3616.6021737608844;3942.172143849766;4073.6130067643526;4085.20338533989;4158.750474971451;3444.483327500895;3455.706264967456;3475.461303497962;3516.3143187078786;3518;631.1983780598614;642.9260635027032;645.9063435801783;669.0566301514938;671.1342368344781;501.88205771643635;629.0217266696214;633.5343522510951;643.9953672271179;652.2205963873126;8292.751217463485;8519.95740021296;8572.657093716181;8574.528400937883;8664.523451210243;620.8913440147955;631.4916492223712;634.1185751804994;636.961782293064;637.6747858592151;3324.700776930088;3982.78094704794;4299.677524185636;4343.804528796211;4381.802818873159;90623;93693.5315323419;94511;94577.52711236538;95450;77757;79891.60054199692;80184;80855.59572202219;81157.59421202856;70133.94799078017;70527.64736176287;70779.29220707728;71059.65275565496;71236.93144602835;8770.166834150745;8805.735827925173;8816.426932249384;8858.379913406;8860.601272942862;47811;48373.5162648369;48388.5161148384;48417.54747357585;49685.75157124191;65482;67645.6617716928;69133.65433173004;69397;69958;11020.614278500214;11038.282511636718;11178.441077946232;11217.943910280397;11785.116116291483;11552.249103808226;11670.124740644655;11789.823152652889;11811.586594469152;12052.732564192644;9404.670836520689;11328.603498877499;11369.658910232707;11456.255343402652;12197.573084941985;10940.56237750481;11311.208215425171;11319.628548264405;11419.714507137389;11564.074874010228;9594.760130996781;9651;11543.71140721455;11560.537578496765;11886.940565297118;9242.924023741509;9269.073092690676;9302.255819534555;9577.664781732607;9652.75868103303;9667.984861589432;9685;9901;9915.454649994319;10050.597976081168 05:21:30 INFO - PROCESS | 8858 | __end_tp_report 05:21:30 INFO - PROCESS | 8858 | __start_cc_report 05:21:30 INFO - PROCESS | 8858 | _x_x_mozilla_cycle_collect,357 05:21:30 INFO - PROCESS | 8858 | __end_cc_report 05:21:30 INFO - PROCESS | 8858 | __startTimestamp1454764890660__endTimestamp 05:21:30 INFO - PROCESS | 8858 | 05:21:30 INFO - PROCESS | 8858 | ------- Summary: start ------- 05:21:30 INFO - PROCESS | 8858 | Number of tests: 6 05:21:30 INFO - PROCESS | 8858 | 05:21:30 INFO - PROCESS | 8858 | [#0] dojo.html Cycles:165 Average:5757.42 Median:6002.94 stddev:652.93 (10.9%) stddev-sans-first:627.47 05:21:30 INFO - PROCESS | 8858 | Values: 3368.7 3542.8 3567.6 3582.7 3592.1 4969.5 5544.0 5849.9 5858.0 5871.2 5664.9 5750.8 5805.7 5807.7 5811.6 5213.7 5244.4 5285.2 5292.8 5382.7 4493.8 5325.3 5366.2 5380.5 5421.3 5348.8 5367.5 5373.1 5395.0 5455.8 5122.9 5293.4 5302.3 5321.1 5332.4 5246.3 5261.6 5263.0 5294.3 5298.9 5546.2 5553.8 5670.8 5726.1 5742.2 6223.3 6237.9 6274.8 6286.9 6289.5 6246.1 6247.4 6257.4 6273.1 6283.3 5810.7 5882.5 5907.2 5909.2 5929.4 6223.8 6266.2 6331.5 6336.9 6343.0 5181.3 6194.3 6200.5 6212.2 6214.7 6169.7 6196.5 6207.3 6252.7 6262.4 6171.1 6246.8 6255.4 6269.8 6275.9 6227.9 6265.0 6280.5 6292.7 6297.5 4225.7 4880.1 4959.0 4964.8 4965.7 4430.8 4452.2 4457.2 4492.5 4494.3 4974.4 5874.9 5934.3 5965.3 5996.4 4579.2 5260.6 6146.3 6171.8 6255.0 5942.9 5954.0 5968.9 6001.2 6002.9 4546.0 5407.9 5417.8 5419.5 5447.3 6264.2 6336.3 6341.7 6418.4 6432.8 6274.0 6279.1 6284.7 6286.7 6308.3 5660.8 6129.8 6229.6 6408.0 6416.4 5556.0 6147.0 6269.3 6296.5 6306.6 6193.8 6265.7 6275.2 6281.9 6317.0 5172.0 5984.4 6135.2 6167.6 6187.0 6135.8 6190.6 6213.0 6213.9 6217.7 5985.0 6043.3 6065.7 6080.0 6095.3 6079.1 6128.7 6139.0 6217.6 6224.5 6095.2 6117.9 6121.5 6125.9 6126.4 05:21:30 INFO - PROCESS | 8858 | 05:21:30 INFO - PROCESS | 8858 | [#1] ext.html Cycles:180 Average:64508.83 Median:39382.11 stddev:135851.03 (345.0%) stddev-sans-first:136147.01 05:21:30 INFO - PROCESS | 8858 | Values: 455.7 497.4 497.5 558.7 562.9 32434.5 38794.6 39017.8 39148.0 39197.4 33664.7 41446.8 42004.9 42013.8 42048.8 9481.7 9722.2 9736.2 9796.6 9799.0 9608.7 9726.0 9760.1 9857.8 9858.6 9835.0 9853.4 9904.4 9915.9 9923.4 9787.3 9853.5 9865.1 9867.2 9868.0 9662.8 9747.2 9778.0 9779.4 9794.6 39566.8 40324.8 40662.0 40707.6 40835.1 3339.9 3490.3 3507.7 3866.7 3884.0 23062.3 28457.0 28915.7 29385.1 29929.3 2360.3 2451.4 2827.7 2868.7 2885.2 350099.2 418401.0 462359.0 475441.0 481241.0 45504.8 45874.5 68769.0 69067.7 69613.0 45122.0 45259.9 45264.3 45388.0 45633.3 13458.6 15504.5 15693.6 15699.0 15776.9 12386.6 12597.1 12600.7 12692.9 12754.7 14307.1 14647.3 14747.6 14785.6 14872.3 5876.6 6202.6 6322.6 6351.9 6354.6 917.6 1042.3 1148.9 1156.5 1197.4 29603.3 29684.3 29945.8 30511.7 30603.2 1068.9 1141.6 1205.8 1210.2 1261.4 12683.9 15621.8 16040.4 16296.3 16341.3 483734.0 599410.0 609350.0 613980.0 614733.0 61059.0 61372.4 62444.4 62930.0 63324.4 1446.5 1462.2 1467.9 1473.3 1589.6 30883.0 30914.5 31127.8 31598.8 31769.0 54213.7 55748.0 55774.0 55938.4 55993.2 485306.0 489336.0 494288.0 494730.0 495013.0 43612.0 44094.1 44150.9 44933.6 44976.8 43207.4 43347.0 43696.6 43903.0 43905.0 30684.8 35158.5 36446.3 37177.6 37446.1 35967.8 37277.1 37470.3 37626.0 39173.2 35195.8 36010.8 36150.3 36284.3 36576.6 30113.8 30241.5 33463.5 33698.8 33764.2 25074.6 25339.4 25494.7 25810.6 25900.2 05:21:30 INFO - PROCESS | 8858 | 05:21:30 INFO - PROCESS | 8858 | [#2] jquery.html Cycles:180 Average:35411.04 Median:364.14 stddev:113638.00 (31207.2%) stddev-sans-first:113926.31 05:21:30 INFO - PROCESS | 8858 | Values: 362.5 365.7 365.9 369.8 372.3 15393.8 18687.8 19007.7 19408.6 20147.8 11309.5 11606.9 11720.8 11758.0 11965.1 3507.3 4483.1 5035.7 5126.7 5334.2 3733.7 5617.0 5658.1 5891.3 5899.8 5417.8 5624.6 5658.7 5776.9 5840.1 4909.3 5356.1 5604.6 5614.3 5661.1 5286.2 5316.4 5377.1 5404.1 5411.9 13159.6 13354.3 13393.5 17255.6 24721.8 2062.6 2149.9 2178.7 2185.4 2446.8 9459.8 9604.9 9885.9 11224.7 14690.9 1726.4 1783.7 1788.7 1790.7 1940.0 94156.6 98201.1 98552.0 99009.0 99814.0 11067.3 11178.4 11252.3 11290.0 11354.4 13296.5 13380.5 13411.2 13433.8 13468.0 7487.1 7683.8 8145.5 8496.5 8569.9 7024.5 8131.4 8570.4 8700.5 8779.1 3589.7 3995.1 4149.3 4338.2 4460.8 1581.2 1870.3 1876.0 1882.7 1897.3 890.4 915.5 921.3 965.6 1014.1 16528.3 19159.0 19476.6 19723.7 19850.2 942.4 944.9 961.7 997.5 1032.4 10445.6 10956.8 11362.7 11409.5 11455.7 654804.0 683653.0 688958.0 697122.0 716845.0 111098.4 113402.4 114154.0 115387.0 116210.0 1029.2 1173.3 1189.2 1190.4 1215.0 18203.4 18289.0 18362.8 18490.7 18509.9 48308.8 48361.5 48394.0 48566.3 49218.0 69123.0 74094.0 74134.0 74283.3 75137.0 7362.8 7621.0 7652.9 7669.0 7723.5 6193.5 10719.7 11282.5 11629.2 11771.1 10215.4 10305.0 10407.9 10422.2 10534.9 9481.3 9691.7 9843.2 10192.6 10248.4 10186.7 10352.1 10478.7 10507.2 10537.8 7434.3 7558.4 7603.4 7657.6 7658.6 6595.7 9877.9 10335.9 10538.2 10549.8 05:21:30 INFO - PROCESS | 8858 | 05:21:30 INFO - PROCESS | 8858 | [#3] mootools.html Cycles:180 Average:5995.49 Median:5487.09 stddev:3160.50 (57.6%) stddev-sans-first:3151.39 05:21:30 INFO - PROCESS | 8858 | Values: 1510.7 1538.0 1547.9 1871.3 1898.5 7776.8 7914.3 8547.9 8739.4 8758.6 8303.5 8503.5 8587.3 8614.2 8619.8 2465.0 2484.0 2491.1 2495.4 2496.3 2439.7 2501.4 2514.5 2514.9 2527.0 2471.9 2501.3 2504.3 2507.9 2520.3 2443.8 2498.4 2510.6 2519.8 2526.8 2337.7 2410.3 2477.7 2491.5 2503.9 8761.0 8842.8 8850.6 8859.3 8861.6 5336.2 5473.6 5485.5 5488.6 5491.1 3582.5 3656.4 3682.0 3719.1 3730.8 1052.7 1072.7 1078.9 1082.9 1109.8 11864.6 12004.7 12014.6 12020.0 12089.5 4923.8 4942.8 4987.8 4999.4 5005.3 10072.4 10143.8 10145.6 10164.9 10169.6 5352.2 5491.7 5493.6 5498.4 5507.2 4429.4 4551.9 4843.4 4970.9 5001.9 3912.2 3931.3 3946.0 3986.2 3997.1 1325.2 1334.5 1342.1 1344.3 1345.6 846.6 1112.8 1227.0 1309.2 1320.8 8001.3 8036.2 8043.7 8045.0 8054.5 514.5 527.6 528.4 535.1 536.7 6093.6 6147.4 6151.0 6179.2 6229.3 8954.5 10629.5 10667.8 10726.8 10843.0 8213.9 10526.8 10709.2 10770.8 10788.6 8241.1 8256.1 8356.4 8362.1 8372.2 7680.3 7798.3 7806.4 7867.1 7908.8 8481.4 9380.2 9547.0 9633.6 9707.8 9738.3 9829.0 9869.0 9905.7 9979.8 5688.4 6647.4 7033.7 7437.8 7512.0 8014.0 8030.9 8065.3 8074.8 8103.3 7422.2 7517.6 7556.1 7671.4 7768.3 7357.9 7436.3 7456.0 7509.0 7601.7 6348.9 7565.6 7595.5 7603.6 7622.4 6930.8 6960.8 6973.0 7006.5 7011.0 7048.9 7083.0 7088.3 7092.1 7103.8 05:21:30 INFO - PROCESS | 8858 | 05:21:30 INFO - PROCESS | 8858 | [#4] prototype.html Cycles:180 Average:5983.48 Median:6858.35 stddev:1952.18 (28.5%) stddev-sans-first:1909.67 05:21:30 INFO - PROCESS | 8858 | Values: 252.4 304.5 323.1 336.1 337.3 6430.3 6806.1 6946.6 6961.8 7060.1 6790.4 6834.4 6853.9 6862.8 6919.9 4804.2 4933.5 5045.7 5050.0 5059.9 4125.8 4734.7 4899.0 4933.4 5033.9 4953.1 4968.7 4981.5 4991.7 4994.3 3863.7 4983.4 4984.1 5001.4 5012.4 4253.2 4267.6 4268.4 4279.5 4312.8 5494.7 6387.3 6492.5 6493.3 6559.5 5957.6 5971.2 5972.2 5973.7 5982.2 6294.7 7322.8 7353.3 7378.7 7383.4 3967.7 4610.5 4805.0 4817.7 4828.6 6104.7 7450.8 7658.3 7828.6 7874.0 4836.3 4871.6 4873.5 4879.3 4886.3 4140.9 4872.3 4964.6 4971.3 4976.8 5734.5 5899.1 5913.7 5920.7 5949.8 7367.0 7414.4 7425.7 7434.2 7437.9 1720.9 1737.1 1740.2 1740.3 1752.3 1108.1 1118.0 1127.5 1132.9 1144.9 6970.5 6975.4 6994.3 7010.5 7013.1 7684.6 7693.0 7705.4 7707.4 7740.0 5971.8 6759.8 6866.1 6983.9 6995.0 3261.9 3481.9 3611.9 3967.6 4071.3 7998.9 8017.1 8031.8 8047.8 8071.0 7611.2 7924.8 7930.6 7932.9 7966.0 8008.9 8019.5 8022.2 8043.0 8044.7 6373.5 7399.7 7485.1 7631.5 7651.3 7498.1 7508.5 7530.7 7533.4 7540.6 6909.6 7363.3 7983.6 8020.5 8032.5 4891.7 4922.5 4946.2 4947.3 4955.6 7452.3 7549.7 7644.9 7659.9 7694.0 7355.8 7398.6 7401.4 7419.5 7420.0 6308.7 7339.5 7343.0 7349.8 7368.0 6962.5 7172.8 7177.7 7202.5 7255.6 7415.8 7470.9 7472.7 7483.9 7498.8 7042.3 7243.4 7279.6 7281.2 7290.6 05:21:30 INFO - PROCESS | 8858 | 05:21:30 INFO - PROCESS | 8858 | [#5] yui.html Cycles:180 Average:18310.04 Median:33074.65 stddev:25830.97 (78.1%) stddev-sans-first:25869.49 05:21:30 INFO - PROCESS | 8858 | Values: 673.6 716.2 742.5 751.6 756.3 14533.7 14661.9 14914.7 14926.6 16371.8 12851.4 15650.5 15682.6 15694.9 15739.5 700.4 718.1 856.8 871.6 874.0 851.0 868.4 870.6 876.4 882.3 877.7 930.0 930.3 931.9 932.2 906.5 907.3 908.4 925.0 925.3 667.3 668.6 679.9 682.0 692.9 13463.6 13565.3 13574.1 13584.9 13639.2 12232.4 12332.3 12552.9 12635.4 13116.4 9989.9 10064.1 10416.3 10451.6 10860.6 3480.3 3522.8 3531.7 3540.7 3690.4 78677.4 78693.0 78776.0 78887.6 79385.8 9216.4 9263.1 9500.8 9514.8 9772.1 20391.9 20500.6 20577.9 20585.7 20600.5 7179.0 7391.9 7447.9 7512.7 7586.2 3616.6 3942.2 4073.6 4085.2 4158.8 3444.5 3455.7 3475.5 3516.3 3518.0 631.2 642.9 645.9 669.1 671.1 501.9 629.0 633.5 644.0 652.2 8292.8 8520.0 8572.7 8574.5 8664.5 620.9 631.5 634.1 637.0 637.7 3324.7 3982.8 4299.7 4343.8 4381.8 90623.0 93693.5 94511.0 94577.5 95450.0 77757.0 79891.6 80184.0 80855.6 81157.6 70133.9 70527.6 70779.3 71059.7 71236.9 8770.2 8805.7 8816.4 8858.4 8860.6 47811.0 48373.5 48388.5 48417.5 49685.8 65482.0 67645.7 69133.7 69397.0 69958.0 11020.6 11038.3 11178.4 11217.9 11785.1 11552.2 11670.1 11789.8 11811.6 12052.7 9404.7 11328.6 11369.7 11456.3 12197.6 10940.6 11311.2 11319.6 11419.7 11564.1 9594.8 9651.0 11543.7 11560.5 11886.9 9242.9 9269.1 9302.3 9577.7 9652.8 9668.0 9685.0 9901.0 9915.5 10050.6 05:21:30 INFO - PROCESS | 8858 | -------- Summary: end -------- 05:21:30 INFO - PROCESS | 8858 | 05:21:31 INFO - TEST-INFO | 8858: exit 0 05:21:31 INFO - TEST-OK | dromaeo_css | took 1110777ms 05:21:31 INFO - TEST-START | kraken 05:21:31 INFO - operating with platform_type : mac_ 05:21:31 INFO - Initialising browser for kraken test... 05:21:31 INFO - TEST-INFO | started process 8862 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmp4ciq0U/profile http://localhost:49545/getInfo.html) 05:21:31 INFO - PROCESS | 8862 | JavaScript warning: resource://gre/modules/AddonManager.jsm, line 692: Proxy.create and Proxy.createFunction are deprecated, use new Proxy instead 05:21:34 INFO - PROCESS | 8862 | __metrics Screen width/height:1600/1200 05:21:34 INFO - PROCESS | 8862 | colorDepth:24 05:21:34 INFO - PROCESS | 8862 | Browser inner width/height: 1024/689 05:21:34 INFO - PROCESS | 8862 | __metrics 05:21:34 INFO - PROCESS | 8862 | 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:34 INFO - TEST-INFO | 8862: exit 0 05:21:34 INFO - Browser initialized. 05:21:34 INFO - Running cycle 1/1 for kraken test... 05:21:34 INFO - TEST-INFO | started process 8865 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/T/tmp4ciq0U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:46 INFO - PROCESS | 8865 | RSS: Main: 255881216 05:21:46 INFO - PROCESS | 8865 | 05:21:49 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49545/tests/kraken/driver.html?testName=audio-beat-detection) 05:21:50 INFO - PROCESS | 8865 | RSS: Main: 268464128 05:21:50 INFO - PROCESS | 8865 | 05:21:53 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49545/tests/kraken/driver.html?testName=audio-dft) 05:21:53 INFO - PROCESS | 8865 | RSS: Main: 278495232 05:21:53 INFO - PROCESS | 8865 | 05:21:57 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49545/tests/kraken/driver.html?testName=audio-fft) 05:21:57 INFO - PROCESS | 8865 | RSS: Main: 280870912 05:21:57 INFO - PROCESS | 8865 | 05:21:59 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49545/tests/kraken/driver.html?testName=audio-oscillator) 05:22:00 INFO - PROCESS | 8865 | RSS: Main: 281579520 05:22:00 INFO - PROCESS | 8865 | 05:22:02 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49545/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:22:02 INFO - PROCESS | 8865 | RSS: Main: 284192768 05:22:02 INFO - PROCESS | 8865 | 05:22:05 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49545/tests/kraken/driver.html?testName=imaging-darkroom) 05:22:06 INFO - PROCESS | 8865 | RSS: Main: 283021312 05:22:06 INFO - PROCESS | 8865 | 05:22:09 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49545/tests/kraken/driver.html?testName=imaging-desaturate) 05:22:09 INFO - PROCESS | 8865 | RSS: Main: 282820608 05:22:09 INFO - PROCESS | 8865 | 05:22:12 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49545/tests/kraken/driver.html?testName=json-parse-financial) 05:22:13 INFO - PROCESS | 8865 | RSS: Main: 283619328 05:22:13 INFO - PROCESS | 8865 | 05:22:14 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49545/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:22:15 INFO - PROCESS | 8865 | RSS: Main: 283643904 05:22:15 INFO - PROCESS | 8865 | 05:22:17 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49545/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:22:17 INFO - PROCESS | 8865 | RSS: Main: 284114944 05:22:17 INFO - PROCESS | 8865 | 05:22:19 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49545/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:22:20 INFO - PROCESS | 8865 | RSS: Main: 284360704 05:22:20 INFO - PROCESS | 8865 | 05:22:23 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49545/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:22:23 INFO - PROCESS | 8865 | RSS: Main: 283963392 05:22:23 INFO - PROCESS | 8865 | 05:22:26 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49545/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:22:26 INFO - PROCESS | 8865 | RSS: Main: 284299264 05:22:26 INFO - PROCESS | 8865 | 05:22:28 INFO - PROCESS | 8865 | Cycle 1(1): loaded http://localhost:49545/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49545/tests/kraken/driver.html?testName=ai-astar) 05:22:28 INFO - PROCESS | 8865 | RSS: Main: 283918336 05:22:28 INFO - PROCESS | 8865 | 05:22:28 INFO - PROCESS | 8865 | __start_tp_report 05:22:28 INFO - PROCESS | 8865 | _x_x_mozilla_page_load 05:22:28 INFO - PROCESS | 8865 | _x_x_mozilla_page_load_details 05:22:28 INFO - PROCESS | 8865 | |i|pagename|runs| 05:22:28 INFO - PROCESS | 8865 | |0;ai-astar;169;142;172;156;118;124;122;160;115;114 05:22:28 INFO - PROCESS | 8865 | |1;audio-beat-detection;153;125;149;163;157;147;141;153;190;123 05:22:28 INFO - PROCESS | 8865 | |2;audio-dft;188;194;209;219;199;195;198;218;276;210 05:22:28 INFO - PROCESS | 8865 | |3;audio-fft;73;73;70;126;78;81;83;88;77;57 05:22:28 INFO - PROCESS | 8865 | |4;audio-oscillator;81;82;124;78;81;83;116;80;82;118 05:22:28 INFO - PROCESS | 8865 | |5;imaging-gaussian-blur;88;108;88;92;88;93;100;140;85;138 05:22:28 INFO - PROCESS | 8865 | |6;imaging-darkroom;121;153;97;96;97;95;97;96;97;107 05:22:28 INFO - PROCESS | 8865 | |7;imaging-desaturate;92;98;93;92;92;92;90;95;92;93 05:22:28 INFO - PROCESS | 8865 | |8;json-parse-financial;57;57;54;55;54;55;53;60;54;53 05:22:28 INFO - PROCESS | 8865 | |9;json-stringify-tinderbox;66;68;76;66;76;72;65;68;67;70 05:22:28 INFO - PROCESS | 8865 | |10;stanford-crypto-aes;85;84;90;110;112;81;83;83;85;85 05:22:28 INFO - PROCESS | 8865 | |11;stanford-crypto-ccm;137;133;129;127;139;136;142;124;135;121 05:22:28 INFO - PROCESS | 8865 | |12;stanford-crypto-pbkdf2;126;179;165;140;139;143;137;137;141;143 05:22:28 INFO - PROCESS | 8865 | |13;stanford-crypto-sha256-iterative;68;68;60;64;94;96;93;66;68;69 05:22:28 INFO - PROCESS | 8865 | __end_tp_report 05:22:28 INFO - PROCESS | 8865 | __start_cc_report 05:22:28 INFO - PROCESS | 8865 | _x_x_mozilla_cycle_collect,880 05:22:28 INFO - PROCESS | 8865 | __end_cc_report 05:22:28 INFO - PROCESS | 8865 | __startTimestamp1454764948988__endTimestamp 05:22:28 INFO - PROCESS | 8865 | 05:22:28 INFO - PROCESS | 8865 | ------- Summary: start ------- 05:22:28 INFO - PROCESS | 8865 | Number of tests: 14 05:22:28 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#0] ai-astar Cycles:10 Average:139.20 Median:149.00 stddev:23.29 (15.6%) stddev-sans-first:22.07 05:22:29 INFO - PROCESS | 8865 | Values: 169.0 142.0 172.0 156.0 118.0 124.0 122.0 160.0 115.0 114.0 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#1] audio-beat-detection Cycles:10 Average:150.10 Median:153.00 stddev:19.09 (12.5%) stddev-sans-first:20.22 05:22:29 INFO - PROCESS | 8865 | Values: 153.0 125.0 149.0 163.0 157.0 147.0 141.0 153.0 190.0 123.0 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#2] audio-dft Cycles:10 Average:210.60 Median:209.50 stddev:25.23 (12.0%) stddev-sans-first:25.40 05:22:29 INFO - PROCESS | 8865 | Values: 188.0 194.0 209.0 219.0 199.0 195.0 198.0 218.0 276.0 210.0 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#3] audio-fft Cycles:10 Average:80.60 Median:77.50 stddev:18.03 (23.3%) stddev-sans-first:18.92 05:22:29 INFO - PROCESS | 8865 | Values: 73.0 73.0 70.0 126.0 78.0 81.0 83.0 88.0 77.0 57.0 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:81.00 stddev:18.67 (23.0%) stddev-sans-first:19.33 05:22:29 INFO - PROCESS | 8865 | Values: 81.0 82.0 124.0 78.0 81.0 83.0 116.0 80.0 82.0 118.0 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#5] imaging-gaussian-blur Cycles:10 Average:102.00 Median:88.00 stddev:20.65 (23.5%) stddev-sans-first:21.27 05:22:29 INFO - PROCESS | 8865 | Values: 88.0 108.0 88.0 92.0 88.0 93.0 100.0 140.0 85.0 138.0 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#6] imaging-darkroom Cycles:10 Average:105.60 Median:96.50 stddev:18.49 (19.2%) stddev-sans-first:18.76 05:22:29 INFO - PROCESS | 8865 | Values: 121.0 153.0 97.0 96.0 97.0 95.0 97.0 96.0 97.0 107.0 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#7] imaging-desaturate Cycles:10 Average:92.90 Median:92.50 stddev:2.18 (2.4%) stddev-sans-first:2.29 05:22:29 INFO - PROCESS | 8865 | Values: 92.0 98.0 93.0 92.0 92.0 92.0 90.0 95.0 92.0 93.0 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.00 stddev:2.20 (4.0%) stddev-sans-first:2.24 05:22:29 INFO - PROCESS | 8865 | Values: 57.0 57.0 54.0 55.0 54.0 55.0 53.0 60.0 54.0 53.0 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.00 stddev:4.03 (5.8%) stddev-sans-first:4.09 05:22:29 INFO - PROCESS | 8865 | Values: 66.0 68.0 76.0 66.0 76.0 72.0 65.0 68.0 67.0 70.0 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:84.50 stddev:11.42 (13.5%) stddev-sans-first:11.98 05:22:29 INFO - PROCESS | 8865 | Values: 85.0 84.0 90.0 110.0 112.0 81.0 83.0 83.0 85.0 85.0 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#11] stanford-crypto-ccm Cycles:10 Average:132.30 Median:135.50 stddev:6.82 (5.0%) stddev-sans-first:7.01 05:22:29 INFO - PROCESS | 8865 | Values: 137.0 133.0 129.0 127.0 139.0 136.0 142.0 124.0 135.0 121.0 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.00 Median:142.00 stddev:15.38 (10.8%) stddev-sans-first:14.70 05:22:29 INFO - PROCESS | 8865 | Values: 126.0 179.0 165.0 140.0 139.0 143.0 137.0 137.0 141.0 143.0 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - PROCESS | 8865 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:68.50 stddev:13.88 (20.3%) stddev-sans-first:14.52 05:22:29 INFO - PROCESS | 8865 | Values: 68.0 68.0 60.0 64.0 94.0 96.0 93.0 66.0 68.0 69.0 05:22:29 INFO - PROCESS | 8865 | -------- Summary: end -------- 05:22:29 INFO - PROCESS | 8865 | 05:22:29 INFO - TEST-INFO | 8865: exit 0 05:22:29 INFO - TEST-OK | kraken | took 58506ms 05:22:29 INFO - SUITE-END | took 1169s 05:22:30 INFO - Completed test suite (00:19:29) 05:22:30 INFO - Working with test: dromaeo_css 05:22:30 INFO - Generating results file: dromaeo_css 05:22:30 INFO - Working with test: kraken 05:22:30 INFO - Generating results file: kraken 05:22:30 INFO - javascript benchmark 05:22:30 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:22:30 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,61]] 05:22:30 INFO - process_Request line: dromaeo_css 7482.77 graph.html#tests=[[72,64,61]] 05:22:30 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:22:30 INFO - process_Request line: kraken 1539.80 graph.html#tests=[[232,64,61]] 05:22:30 INFO - RETURN: dromaeo_css: 7482.77 05:22:30 INFO - RETURN: kraken: 1539.80 05:22:30 INFO - javascript benchmark 05:22:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3368.6968172864435, 3542.840572174252, 3567.554055743032, 3582.695470884975, 3592.119930616999, 4969.527894849993, 5543.972280138603, 5849.853753656161, 5858.0, 5871.236739223906, 5664.886702265952, 5750.798722044729, 5805.738741756621, 5807.73865176067, 5811.593188476808, 5213.739313034352, 5244.396894357141, 5285.154375299952, 5292.788288468476, 5382.703951282678, 4493.820247190125, 5325.334333208349, 5366.195070739401, 5380.542653874406, 5421.349438067417, 5348.759305831247, 5367.46325367464, 5373.113436283028, 5395.0, 5455.781768729266, 5122.92315615266, 5293.444188360244, 5302.284191634157, 5321.068812957717, 5332.386775520826, 5246.344206974129, 5261.605379596545, 5262.97368513155, 5294.285271488338, 5298.867528311823, 5546.223528705985, 5553.7778488860395, 5670.773169073233, 5726.112452569817, 5742.167385729046, 6223.346548612376, 6237.906431403532, 6274.811755647293, 6286.937130628727, 6289.465395441393, 6246.094316324107, 6247.375262473716, 6257.405546473078, 6273.059041143865, 6283.277423096357, 5810.738516766755, 5882.529397648178, 5907.232059832238, 5909.172715819789, 5929.407059294079, 6223.751049958041, 6266.216722909637, 6331.461825744771, 6336.904946425807, 6343.0, 5181.300524429229, 6194.287656919512, 6200.503959683215, 6212.192414986023, 6214.689265536705, 6169.660668663267, 6196.47329976957, 6207.286162091327, 6252.656103914328, 6262.436380725757, 6171.074338849119, 6246.812595622138, 6255.437010669061, 6269.811905642838, 6275.901717199472, 6227.937720622826, 6264.968675156595, 6280.528960327993, 6292.716827742763, 6297.4962003039645, 4225.746455212697, 4880.072786170616, 4959.0, 4964.776585053682, 4965.726885021359, 4430.8449204277695, 4452.1763473757455, 4457.153140903218, 4492.505824359383, 4494.258447356149, 4974.427940786856, 5874.853128671817, 5934.287885453773, 5965.313988891333, 5996.430339117776, 4579.244424669959, 5260.605454590845, 6146.262448506208, 6171.814845554641, 6255.0, 5942.910856337158, 5954.047352423591, 5968.940310596839, 6001.1898393717165, 6002.939970600238, 4545.954540454553, 5407.891842163135, 5417.8103766367985, 5419.539339156137, 5447.319085114361, 6264.185655864709, 6336.334684858023, 6341.682915854189, 6418.358164183544, 6432.807015789534, 6274.0, 6279.120923070683, 6284.654344011124, 6286.748530058747, 6308.306086330408, 5660.773569057356, 6129.754809807558, 6229.595076320206, 6408.038794180724, 6416.358364163546, 5555.999920014437, 6146.985747351637, 6269.279032911274, 6296.496280297473, 6306.621602703853, 6193.783217587363, 6265.718042688181, 6275.246970363585, 6281.937180628318, 6316.968415157895, 5172.043172013189, 5984.371640977634, 6135.233095863017, 6167.599106058092, 6187.0, 6135.753292002456, 6190.566660333733, 6212.968935155295, 6213.9067913980425, 6217.658028808459, 5984.970075149597, 6043.335233124265, 6065.696715164312, 6079.969600151971, 6095.299040610386, 6079.148919151411, 6128.686460748492, 6139.001538246492, 6217.595856269328, 6224.533160012927, 6095.213525956522, 6117.9492029047315, 6121.5439883209065, 6125.937554509019, 6126.366913828976], "unit": "score", "name": "dojo.html", "value": 5719.216605050822}, {"lowerIsBetter": false, "replicates": [455.683300106426, 497.4031162604875, 497.45280191789027, 558.7346010644944, 562.9332415073435, 32434.513482297756, 38794.61205387945, 39017.80491097541, 39148.0, 39197.41203881937, 33664.66335336646, 41446.756597302054, 42004.94987625311, 42013.78993105038, 42048.78975605125, 9481.715548533537, 9722.173615242713, 9736.172425343855, 9796.608135674565, 9799.02009799018, 9608.663696770614, 9725.97877222892, 9760.121589056984, 9857.802843943116, 9858.605655773761, 9835.0, 9853.408795472258, 9904.356216845881, 9915.851262231072, 9923.354981926152, 9787.265955053364, 9853.458059806708, 9865.112139907444, 9867.210623150131, 9867.96386379427, 9662.75843103928, 9747.220222382264, 9777.951110244474, 9779.364341317792, 9794.608215671436, 39566.802165988986, 40324.79837600823, 40662.0, 40707.592924070676, 40835.120663536916, 3339.916502087443, 3490.3193877193958, 3507.7193824494234, 3866.7486613370043, 3884.0, 23062.308130756104, 28457.0, 28915.710842891513, 29385.118446446642, 29929.251768705737, 2360.280114565057, 2451.4361696809833, 2827.6606807183066, 2868.6557613086356, 2885.2209903326193, 350099.2495037534, 418401.0, 462359.0, 475441.0, 481241.0, 45504.772476137405, 45874.5412545877, 68769.0, 69067.65466172637, 69613.0, 45122.0, 45259.86850328702, 45264.3210351845, 45388.0, 45633.31550026752, 13458.596242112751, 15504.457343992906, 15693.607659808367, 15698.979566328153, 15776.921115394349, 12386.62840114798, 12597.055220858472, 12600.74798504043, 12692.873071269169, 12754.681132971564, 14307.141571505743, 14647.267636618126, 14747.631309217355, 14785.556433307018, 14872.330745116496, 5876.588638795243, 6202.5658203925295, 6322.557420980487, 6351.936480635227, 6354.555181137276, 917.5779141594941, 1042.3172821801732, 1148.936808475542, 1156.4969238381332, 1197.3713800254936, 29603.259918502208, 29684.257893552403, 29945.80216791304, 30511.69488305133, 30603.2349191272, 1068.9198310126772, 1141.5547936304847, 1205.8182980678807, 1210.189173253922, 1261.413442749126, 12683.936580317039, 15621.843781562266, 16040.358385664676, 16296.34814607426, 16341.26464309109, 483734.0, 599410.0, 609350.0, 613980.0, 614733.0, 61059.0, 61372.38627613756, 62444.43888902814, 62930.0, 63324.366756331845, 1446.4720377062297, 1462.159258426409, 1467.9412823486941, 1473.3075454536352, 1589.6184915620167, 30883.0, 30914.536281955337, 31127.84436077805, 31598.842005789826, 31769.0, 54213.728931356665, 55748.0, 55774.0, 55938.440615593325, 55993.16010259931, 485306.0, 489336.0, 494288.0, 494730.0, 495013.0, 43612.0, 44094.11811763811, 44150.89622759457, 44933.550664492934, 44976.77511612421, 43207.35188972231, 43347.0, 43696.56303437052, 43903.0, 43905.0, 30684.84657576787, 35158.472622910165, 36446.27107457889, 37177.62822371742, 37446.06384840292, 35967.82016089903, 37277.0680732973, 37470.250594988494, 37626.0, 39173.21653566856, 35195.82402087973, 36010.819945900104, 36150.27699446049, 36284.27431451409, 36576.63423365732, 30113.798079563152, 30241.487925603193, 33463.49804752882, 33698.83150584231, 33764.15589610279, 25074.62388064217, 25339.366515837253, 25494.74505254998, 25810.61284080703, 25900.186986910736], "unit": "score", "name": "ext.html", "value": 19606.70308315587}, {"lowerIsBetter": false, "replicates": [362.5413851477881, 365.7384969746631, 365.884746304914, 369.80030783376947, 372.2759233291248, 15393.846061539381, 18687.813121868778, 19007.714884276746, 19408.611827763452, 20147.798522014775, 11309.547618095266, 11606.883931160686, 11720.82418763717, 11758.000569951542, 11965.102617303715, 3507.281007393491, 4483.125790470843, 5035.748212589374, 5126.71803050832, 5334.1732031535175, 3733.6826369758605, 5617.0, 5658.066419040874, 5891.293044834604, 5899.764009439617, 5417.783288668448, 5624.578156638248, 5658.660480371191, 5776.855578610526, 5840.123981402802, 4909.312696222514, 5356.0894647909945, 5604.607677462579, 5614.29821272341, 5661.094224924033, 5286.180642000496, 5316.38861530925, 5377.059014672417, 5404.1083221268445, 5411.891762164734, 13159.605211843564, 13354.33228338589, 13393.531226407127, 17255.568610784707, 24721.752782472304, 2062.5668609592003, 2149.9462513437134, 2178.6623073423643, 2185.4099393163915, 2446.7797898189247, 9459.763505912339, 9604.855927161097, 9885.851712224323, 11224.66326010221, 14690.926545367312, 1726.3672898829261, 1783.6611043901617, 1788.7227479740532, 1790.7134858422721, 1940.0, 94156.58765118528, 98201.0687709133, 98552.0, 99009.0, 99814.0, 11067.280626759235, 11178.385188814531, 11252.324860508395, 11290.0, 11354.3755093469, 13296.468141274434, 13380.531681391105, 13411.195328280335, 13433.798493022612, 13468.0, 7487.1389790173735, 7683.769486915401, 8145.51126932386, 8496.470635285708, 8569.871451928226, 7024.473164512682, 8131.430799843954, 8570.4000719949, 8700.521471319138, 8779.12208779117, 3589.7487175897436, 3995.08113133975, 4149.2738770715605, 4338.154059958278, 4460.77696115193, 1581.2251996521852, 1870.3453791173254, 1875.990620046891, 1882.7175923611567, 1897.3169658922777, 890.3678388344348, 915.4964769376816, 921.2675922641516, 965.560669895196, 1014.0587677348537, 16528.338866445207, 19159.0, 19476.610467790564, 19723.704144437845, 19850.205991760457, 942.3544871762728, 944.8535477001076, 961.7018724195522, 997.4514017290461, 1032.4115254304975, 10445.634402795866, 10956.780864382668, 11362.659120226406, 11409.48657310423, 11455.656330310105, 654804.0, 683653.0, 688958.0, 697122.0, 716845.0, 111098.44450777695, 113402.43298783619, 114154.0, 115387.0, 116210.0, 1029.1818004686293, 1173.2725710059817, 1189.2032338333338, 1190.3988485814782, 1214.9635510934686, 18203.362882299058, 18289.0, 18362.81637183611, 18490.722639160693, 18509.9074504632, 48308.75845620749, 48361.516384835704, 48393.99999999859, 48566.27150592815, 49218.0, 69123.0, 74094.0, 74134.0, 74283.2571674298, 75137.0, 7362.815929601803, 7620.961895190489, 7652.923470765221, 7669.003029606116, 7723.497972631761, 6193.535484838565, 10719.678409647722, 11282.548698052315, 11629.24409913353, 11771.11716621274, 10215.438150901771, 10305.021023002802, 10407.895921040996, 10422.21833362516, 10534.947325263325, 9481.288903332415, 9691.70924872255, 9843.163331116935, 10192.643257485952, 10248.436336001592, 10186.74533136648, 10352.120069793851, 10478.738031549272, 10507.15942724565, 10537.789244215226, 7434.2565743427385, 7558.433117516318, 7603.391728661583, 7657.57883316423, 7658.6170691464085, 6595.670216489252, 9877.901220987698, 10335.94832025835, 10538.209634277613, 10549.841752373874], "unit": "score", "name": "jquery.html", "value": 8848.585662558093}, {"lowerIsBetter": false, "replicates": [1510.675204830961, 1537.9846201537985, 1547.9226038698066, 1871.3450292397654, 1898.5158784509947, 7776.766696999085, 7914.3272821810215, 8547.871781923268, 8739.431936924095, 8758.562071896411, 8303.50178989261, 8503.532305723184, 8587.270082043035, 8614.224719775219, 8619.784505387353, 2465.001674321898, 2483.9751602483925, 2491.1281051631963, 2495.4011037350997, 2496.3384703053707, 2439.6706444629926, 2501.4371766352515, 2514.459391230885, 2514.911978080758, 2527.0018342754693, 2471.851688898663, 2501.3246423465653, 2504.3113143885485, 2507.9466624017864, 2520.269121954631, 2443.767842055002, 2498.4253621667026, 2510.5606518859217, 2519.7984161267054, 2526.835755675875, 2337.6610391493136, 2410.288964755384, 2477.677901872763, 2491.489244704831, 2503.9374015649573, 8760.992485864079, 8842.823143537158, 8850.601722922484, 8859.335549833788, 8861.601227944759, 5336.199570064483, 5473.616846820724, 5485.533729632986, 5488.643238189505, 5491.148871924858, 3582.5342705448124, 3656.433252845813, 3682.0, 3719.088823238324, 3730.8321125549414, 1052.6894566103017, 1072.6674730833388, 1078.877966914408, 1082.8862969388258, 1109.7835921995213, 11864.58473953416, 12004.69988250292, 12014.579489717906, 12020.0, 12089.516419343216, 4923.77842997066, 4942.777575009133, 4987.775550100254, 4999.425066117443, 5005.324281221989, 10072.395656260647, 10143.797124057477, 10145.594176233015, 10164.949175254076, 10169.593216271414, 5352.170413585901, 5491.670499769947, 5493.642913210629, 5498.395176530578, 5507.173923911445, 4429.402030725875, 4551.931721024187, 4843.370361853007, 4970.8757281068265, 5001.924971125436, 3912.197999410114, 3931.312020396419, 3946.0134966258433, 3986.1828325193264, 3997.120633460634, 1325.2181213084327, 1334.4595438847282, 1342.1209108034254, 1344.2539390638094, 1345.6299517632729, 846.551327796269, 1112.777444511101, 1227.0061250387214, 1309.1948451702135, 1320.8414990201238, 8001.319887809602, 8036.196380361917, 8043.6782528698195, 8045.0, 8054.476459030261, 514.5189248053082, 527.6385675812081, 528.3870709976486, 535.1170568561904, 536.694084371912, 6093.634381937098, 6147.415995480422, 6151.0, 6179.227596550431, 6229.252489701174, 8954.507502087317, 10629.5216715248, 10667.786644267071, 10726.785464290671, 10843.024127828534, 8213.876791848126, 10526.842097368546, 10709.196810239264, 10770.838437423445, 10788.622398216025, 8241.134680858544, 8256.091829898707, 8356.415050946496, 8362.080171181044, 8372.246497815226, 7680.270374314431, 7798.259165379507, 7806.41451891099, 7867.095284042409, 7908.84182316339, 8481.448705834102, 9380.20268277204, 9547.0, 9633.566489507848, 9707.757306067404, 9738.318317717692, 9829.0, 9869.013098690073, 9905.702828915144, 9979.750506237402, 5688.374278829243, 6647.368499992493, 7033.683484243119, 7437.776866694008, 7511.962440187983, 8013.998250218722, 8030.919690803017, 8065.274125328514, 8074.757757267292, 8103.31121854649, 7422.1835598085245, 7517.624118794148, 7556.055493063367, 7671.38628909697, 7768.262015108556, 7357.889631655637, 7436.256374362521, 7456.0, 7509.0, 7601.6579253934815, 6348.904766428415, 7565.621718913922, 7595.506292090997, 7603.619819009138, 7622.352100071556, 6930.826729331807, 6960.825979350354, 6973.0, 7006.474514411338, 7011.0, 7048.894266586108, 7082.964585177041, 7088.291170882871, 7092.148942126978, 7103.751368702277], "unit": "score", "name": "mootools.html", "value": 4822.711447703257}, {"lowerIsBetter": false, "replicates": [252.40936209270305, 304.4823799540781, 323.1356122372653, 336.14954165960114, 337.28663875902475, 6430.324815894334, 6806.115205023346, 6946.6179360135175, 6961.7911462656075, 7060.117485314336, 6790.422814060798, 6834.384905358516, 6853.862922741542, 6862.828429289258, 6919.8616027679545, 4804.231322988323, 4933.531314525115, 5045.672031317971, 5049.9747501262445, 5059.924101138486, 4125.773082480462, 4734.721625161204, 4899.044686286175, 4933.407991041062, 5033.899322013576, 4953.058918805434, 4968.677035992649, 4981.5018498150075, 4991.675541089836, 4994.325766021595, 3863.7295389322617, 4983.426905905812, 4984.102861484931, 5001.424551266339, 5012.448630650625, 4253.191893540248, 4267.551907049746, 4268.40242366069, 4279.465066866642, 4312.784360781949, 5494.697791621459, 6387.297397286295, 6492.545521813475, 6493.253275873288, 6559.508036897252, 5957.582969192158, 5971.193888824996, 5972.163897054415, 5973.731182096816, 5982.222311099573, 6294.653794041325, 7322.81692957675, 7353.30143636359, 7378.704851805921, 7383.409327253807, 3967.7024223183375, 4610.492845786961, 4805.014971930746, 4817.735024573647, 4828.61371090312, 6104.694765261719, 7450.776476705708, 7658.310752032288, 7828.647710853025, 7873.96063019687, 4836.347093142381, 4871.610271178297, 4873.488283730228, 4879.26810978349, 4886.3403440535785, 4140.91718165635, 4872.342233798463, 4964.553190212898, 4971.328870602495, 4976.776045077981, 5734.5125664319, 5899.085641725539, 5913.674747888821, 5920.674362910081, 5949.791757288474, 7367.0, 7414.369778568775, 7425.665845036986, 7434.219406962299, 7437.888431673529, 1720.9053502057257, 1737.053305948236, 1740.2342969093559, 1740.3299729604194, 1752.299080367863, 1108.14672702019, 1117.983230251547, 1127.503898284752, 1132.8640563132476, 1144.9427528623535, 6970.546914450544, 6975.372216500539, 6994.33553812387, 7010.474214433836, 7013.088298521159, 7684.577348245788, 7692.961450204262, 7705.4220933430215, 7707.421943354158, 7740.032495938008, 5971.7611295547695, 6759.763408280785, 6866.141732283465, 6983.89524157138, 6995.0, 3261.9184520386707, 3481.9129521762156, 3611.8735844245325, 3967.6429121379215, 4071.328230841937, 7998.880156778056, 8017.118117007241, 8031.759047228593, 8047.798805029921, 8070.959645201737, 7611.20082391352, 7924.762257132295, 7930.6431210595665, 7932.889395484637, 7966.0440747110715, 8008.878756974028, 8019.51882887017, 8022.197780221931, 8042.954415925892, 8044.718434854869, 6373.490120790233, 7399.667014984231, 7485.0643669541305, 7631.503952243309, 7651.283794253182, 7498.137714162723, 7508.549487030796, 7530.736424225126, 7533.434992375484, 7540.585267810104, 6909.585424874524, 7363.337299643058, 7983.600819958862, 8020.518768873886, 8032.518048917083, 4891.730954797378, 4922.532359425849, 4946.208606622923, 4947.3073769671555, 4955.603551715782, 7452.292032256927, 7549.7357592483995, 7644.885326720215, 7659.923400766144, 7693.961530192537, 7355.8161045974275, 7398.556086634819, 7401.444891633041, 7419.517731347661, 7420.035395398563, 6308.6530240835355, 7339.522931009467, 7342.963285183753, 7349.77950661481, 7367.963160184165, 6962.477814164059, 7172.784816455514, 7177.7487787927175, 7202.459815513753, 7255.600941948243, 7415.777526674208, 7470.887936680845, 7472.701091956261, 7483.925160748323, 7498.775036748906, 7042.260562640849, 7243.420526357773, 7279.563226206445, 7281.199068102399, 7290.5625662460425], "unit": "score", "name": "prototype.html", "value": 5323.016930880791}, {"lowerIsBetter": false, "replicates": [673.6497021548795, 716.2372073741468, 742.528494406052, 751.6204316820005, 756.326869086513, 14533.709325813477, 14661.853381466182, 14914.701705965874, 14926.552203433901, 16371.836281637181, 12851.35743212838, 15650.452234171806, 15682.607934801636, 15694.921525392385, 15739.527814165565, 700.3661686173995, 718.1203026292802, 856.8072183758667, 871.6121326009928, 873.9694110706126, 850.9872351914727, 868.3530769576674, 870.5516658920661, 876.3821505838368, 882.2765332427412, 877.662100091463, 930.0095398400715, 930.2511478259999, 931.8509038553833, 932.1796818799447, 906.4697152165958, 907.3059109781022, 908.4458480326995, 925.0102390442196, 925.3245131054301, 667.3093348384399, 668.5954997226656, 679.9354061364163, 682.0383259603934, 692.9133858267717, 13463.596092117154, 13565.321733913264, 13574.117682350616, 13584.932075339659, 13639.249841258727, 12232.449539770643, 12332.260064396165, 12552.937235313857, 12635.43140558677, 13116.409761560753, 9989.90010099897, 10064.094231519126, 10416.27086103973, 10451.63419280329, 10860.565577376896, 3480.32133733922, 3522.84147213376, 3531.6998055165086, 3540.6636369544854, 3690.3541880170865, 78677.42645147123, 78693.0, 78776.0, 78887.60556197182, 79385.80921286186, 9216.447013179231, 9263.120003599648, 9500.809983800285, 9514.809703805886, 9772.12050915421, 20391.898040509703, 20500.589988200154, 20577.868217247895, 20585.69121463179, 20600.484987875123, 7178.959050937585, 7391.926080739124, 7447.925520744831, 7512.737054203077, 7586.24137586237, 3616.6021737608844, 3942.172143849766, 4073.6130067643526, 4085.20338533989, 4158.750474971451, 3444.483327500895, 3455.706264967456, 3475.461303497962, 3516.3143187078786, 3518.0, 631.1983780598614, 642.9260635027032, 645.9063435801783, 669.0566301514938, 671.1342368344781, 501.88205771643635, 629.0217266696214, 633.5343522510951, 643.9953672271179, 652.2205963873126, 8292.751217463485, 8519.95740021296, 8572.657093716181, 8574.528400937883, 8664.523451210243, 620.8913440147955, 631.4916492223712, 634.1185751804994, 636.961782293064, 637.6747858592151, 3324.700776930088, 3982.78094704794, 4299.677524185636, 4343.804528796211, 4381.802818873159, 90623.0, 93693.5315323419, 94511.0, 94577.52711236538, 95450.0, 77757.0, 79891.60054199692, 80184.0, 80855.59572202219, 81157.59421202856, 70133.94799078017, 70527.64736176287, 70779.29220707728, 71059.65275565496, 71236.93144602835, 8770.166834150745, 8805.735827925173, 8816.426932249384, 8858.379913406, 8860.601272942862, 47811.0, 48373.5162648369, 48388.5161148384, 48417.54747357585, 49685.75157124191, 65482.0, 67645.6617716928, 69133.65433173004, 69397.0, 69958.0, 11020.614278500214, 11038.282511636718, 11178.441077946232, 11217.943910280397, 11785.116116291483, 11552.249103808226, 11670.124740644655, 11789.823152652889, 11811.586594469152, 12052.732564192644, 9404.670836520689, 11328.603498877499, 11369.658910232707, 11456.255343402652, 12197.573084941985, 10940.56237750481, 11311.208215425171, 11319.628548264405, 11419.714507137389, 11564.074874010228, 9594.760130996781, 9651.0, 11543.71140721455, 11560.537578496765, 11886.940565297118, 9242.924023741509, 9269.073092690676, 9302.255819534555, 9577.664781732607, 9652.75868103303, 9667.984861589432, 9685.0, 9901.0, 9915.454649994319, 10050.597976081168], "unit": "score", "name": "yui.html", "value": 6890.912976391104}], "name": "dromaeo_css", "value": 7482.770219709377}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [169.0, 142.0, 172.0, 156.0, 118.0, 124.0, 122.0, 160.0, 115.0, 114.0], "unit": "score", "name": "ai-astar", "value": 139.2}, {"lowerIsBetter": true, "replicates": [153.0, 125.0, 149.0, 163.0, 157.0, 147.0, 141.0, 153.0, 190.0, 123.0], "unit": "score", "name": "audio-beat-detection", "value": 150.1}, {"lowerIsBetter": true, "replicates": [188.0, 194.0, 209.0, 219.0, 199.0, 195.0, 198.0, 218.0, 276.0, 210.0], "unit": "score", "name": "audio-dft", "value": 210.6}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 70.0, 126.0, 78.0, 81.0, 83.0, 88.0, 77.0, 57.0], "unit": "score", "name": "audio-fft", "value": 80.6}, {"lowerIsBetter": true, "replicates": [81.0, 82.0, 124.0, 78.0, 81.0, 83.0, 116.0, 80.0, 82.0, 118.0], "unit": "score", "name": "audio-oscillator", "value": 92.5}, {"lowerIsBetter": true, "replicates": [88.0, 108.0, 88.0, 92.0, 88.0, 93.0, 100.0, 140.0, 85.0, 138.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 102.0}, {"lowerIsBetter": true, "replicates": [121.0, 153.0, 97.0, 96.0, 97.0, 95.0, 97.0, 96.0, 97.0, 107.0], "unit": "score", "name": "imaging-darkroom", "value": 105.6}, {"lowerIsBetter": true, "replicates": [92.0, 98.0, 93.0, 92.0, 92.0, 92.0, 90.0, 95.0, 92.0, 93.0], "unit": "score", "name": "imaging-desaturate", "value": 92.9}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 54.0, 55.0, 54.0, 55.0, 53.0, 60.0, 54.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 55.2}, {"lowerIsBetter": true, "replicates": [66.0, 68.0, 76.0, 66.0, 76.0, 72.0, 65.0, 68.0, 67.0, 70.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.4}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 90.0, 110.0, 112.0, 81.0, 83.0, 83.0, 85.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 89.8}, {"lowerIsBetter": true, "replicates": [137.0, 133.0, 129.0, 127.0, 139.0, 136.0, 142.0, 124.0, 135.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 132.3}, {"lowerIsBetter": true, "replicates": [126.0, 179.0, 165.0, 140.0, 139.0, 143.0, 137.0, 137.0, 141.0, 143.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 145.0}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 60.0, 64.0, 94.0, 96.0, 93.0, 66.0, 68.0, 69.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 74.6}], "name": "kraken", "value": 1539.8}]} 05:22:30 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,61]]", "result": "1539.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,61]]", "result": "7482.77"}}} 05:22:30 INFO - Return code: 0 05:22:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:22:30 INFO - # TBPL SUCCESS # 05:22:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:22:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:22:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:22:30 INFO - 2016-02-06 05:22:30.704 screenresolution[8870:148629] starting screenresolution argv=screenresolution get 05:22:30 INFO - 2016-02-06 05:22:30.707 screenresolution[8870:148629] Display 0: 1600x1200x32@60 05:22:30 INFO - 2016-02-06 05:22:30.713 screenresolution[8871:148632] starting screenresolution argv=screenresolution list 05:22:30 INFO - Available Modes on Display 0 05:22:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:22:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:22:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:22:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:22:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:22:30 INFO - Intel Iris: 05:22:30 INFO - Chipset Model: Intel Iris 05:22:30 INFO - Type: GPU 05:22:30 INFO - Bus: Built-In 05:22:30 INFO - VRAM (Dynamic, Max): 1536 MB 05:22:30 INFO - Vendor: Intel (0x8086) 05:22:30 INFO - Device ID: 0x0a2e 05:22:30 INFO - Revision ID: 0x0009 05:22:30 INFO - Displays: 05:22:30 INFO - Display: 05:22:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:22:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:22:30 INFO - Display Serial Number: 1600x1200 60 05:22:30 INFO - Main Display: Yes 05:22:30 INFO - Mirror: Off 05:22:30 INFO - Online: Yes 05:22:30 INFO - Rotation: Supported 05:22:30 INFO - Return code: 0 05:22:30 INFO - Running post-action listener: _resource_record_post_action 05:22:30 INFO - Running post-run listener: _resource_record_post_run 05:22:32 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 4096; Write bytes: 708397056; Read time: 0; Write time: 1940 05:22:32 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 399057408; Write bytes: 392910848; Read time: 30163; Write time: 702 05:22:32 INFO - run-tests - Wall time: 1173s; CPU: 54.0%; Read bytes: 4096; Write bytes: 301854720; Read time: 0; Write time: 1197 05:22:32 INFO - Running post-run listener: _upload_blobber_files 05:22:32 INFO - Blob upload gear active. 05:22:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:22:32 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:32 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:32 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:32 INFO - (blobuploader) - INFO - Open directory for files ... 05:22:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:22:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:22:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:22:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:22:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:22:33 INFO - (blobuploader) - INFO - Done attempting. 05:22:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:22:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:22:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:22:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:22:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:22:34 INFO - (blobuploader) - INFO - Done attempting. 05:22:34 INFO - (blobuploader) - INFO - Iteration through files over. 05:22:34 INFO - Return code: 0 05:22:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:22:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:22:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/407d24c47bce1cbf37d27589e97fa6adddfd97337b624236972a0d3cac203535023e3a1f0157bc67654c49a243bc608fac163ab299350dff6c79e1dc30509b13", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a39ea41c4968c30af549005a3f980140a8f076a6e11bea6f2b18abc845c16fdeac89e222149300ffa752841015fc0e7e1cf7d4cd2a0c9910d95e86b47db8c8a9"} 05:22:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:22:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:22:34 INFO - Contents: 05:22:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/407d24c47bce1cbf37d27589e97fa6adddfd97337b624236972a0d3cac203535023e3a1f0157bc67654c49a243bc608fac163ab299350dff6c79e1dc30509b13", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a39ea41c4968c30af549005a3f980140a8f076a6e11bea6f2b18abc845c16fdeac89e222149300ffa752841015fc0e7e1cf7d4cd2a0c9910d95e86b47db8c8a9"} 05:22:34 INFO - Running post-run listener: copy_logs_to_upload_dir 05:22:34 INFO - Copying logs to upload dir... 05:22:34 INFO - mkdir: /builds/slave/test/build/upload/logs 05:22:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.297641 ========= master_lag: 11.72 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-02-06 05:22:46.109442) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-02-06 05:22:46.113336) ========= 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.aatiAd2XEc/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.54hWVK2nfH/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/407d24c47bce1cbf37d27589e97fa6adddfd97337b624236972a0d3cac203535023e3a1f0157bc67654c49a243bc608fac163ab299350dff6c79e1dc30509b13", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a39ea41c4968c30af549005a3f980140a8f076a6e11bea6f2b18abc845c16fdeac89e222149300ffa752841015fc0e7e1cf7d4cd2a0c9910d95e86b47db8c8a9"} 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.011323 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/407d24c47bce1cbf37d27589e97fa6adddfd97337b624236972a0d3cac203535023e3a1f0157bc67654c49a243bc608fac163ab299350dff6c79e1dc30509b13", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a39ea41c4968c30af549005a3f980140a8f076a6e11bea6f2b18abc845c16fdeac89e222149300ffa752841015fc0e7e1cf7d4cd2a0c9910d95e86b47db8c8a9"}' ========= master_lag: 3.97 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-02-06 05:22:50.093251) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:22:50.093695) ========= 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.aatiAd2XEc/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.54hWVK2nfH/Listeners TMPDIR=/var/folders/gq/h8xl864x5nj_24yktmm7rk9000000w/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.005399 ========= master_lag: 0.38 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:22:50.477483) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-06 05:22:50.477808) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-06 05:22:50.516561) ========= ========= Total master_lag: 16.24 =========