builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0093 starttime: 1454763721.69 results: success (0) buildid: 20160206045251 builduid: a6c13d4e19b340b998ef14a27a7425ff revision: 6826dfd0e85b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-06 05:02:01.691269) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-06 05:02:01.691723) ========= ========= Started set props: basedir (results: 0, elapsed: 4 secs) (at 2016-02-06 05:02:01.692015) ========= 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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.005761 basedir: '/builds/slave/test' ========= master_lag: 4.40 ========= ========= Finished set props: basedir (results: 0, elapsed: 4 secs) (at 2016-02-06 05:02:06.100139) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 mins, 9 secs) (at 2016-02-06 05:02:06.100473) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 mins, 9 secs) (at 2016-02-06 05:04:15.621647) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2016-02-06 05:04:15.621950) ========= 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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.005898 ========= master_lag: 2.33 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2016-02-06 05:04:17.953818) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-06 05:04:17.954132) ========= 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:04:17.954489) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 17 secs) (at 2016-02-06 05:04:17.954811) ========= 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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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:04:18-- 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% 6.82M=0.002s 2016-02-06 05:04:19 (6.82 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.679614 ========= master_lag: 16.72 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 17 secs) (at 2016-02-06 05:04:35.351221) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 20 secs) (at 2016-02-06 05:04:35.354534) ========= 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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.018904 ========= master_lag: 20.13 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 20 secs) (at 2016-02-06 05:04:55.508370) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2016-02-06 05:04:55.508694) ========= 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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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:04:56,007 Setting DEBUG logging. 2016-02-06 05:04:56,007 attempt 1/10 2016-02-06 05:04:56,007 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:04:57,680 unpacking tar archive at: fx-team-6826dfd0e85b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.912139 ========= master_lag: 9.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2016-02-06 05:05:06.567151) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 3 secs) (at 2016-02-06 05:05:06.570481) ========= 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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.005288 script_repo_revision: '6826dfd0e85b' ========= master_lag: 3.22 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 3 secs) (at 2016-02-06 05:05:09.797636) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 10 secs) (at 2016-02-06 05:05:09.797948) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 10 secs) (at 2016-02-06 05:05:20.196402) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-02-06 05:05:20.197142) ========= /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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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:05:20 INFO - MultiFileLogger online at 20160206 05:05:20 in /builds/slave/test 05:05:20 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:05:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:05:20 INFO - {'append_to_log': False, 05:05:20 INFO - 'base_work_dir': '/builds/slave/test', 05:05:20 INFO - 'blob_upload_branch': 'Fx-Team', 05:05:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:05:20 INFO - 'branch': 'Fx-Team', 05:05:20 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:20 INFO - 'config_files': ('talos/mac_config.py',), 05:05:20 INFO - 'default_actions': ('clobber', 05:05:20 INFO - 'read-buildbot-config', 05:05:20 INFO - 'download-and-extract', 05:05:20 INFO - 'populate-webroot', 05:05:20 INFO - 'create-virtualenv', 05:05:20 INFO - 'install', 05:05:20 INFO - 'run-tests'), 05:05:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:20 INFO - 'download_minidump_stackwalk': True, 05:05:20 INFO - 'download_symbols': 'ondemand', 05:05:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:20 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:20 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:20 INFO - 'installer_path': 'installer.exe', 05:05:20 INFO - 'log_level': 'info', 05:05:20 INFO - 'log_name': 'talos', 05:05:20 INFO - 'log_to_console': True, 05:05:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:05:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:05:20 INFO - 'opt_config_files': (), 05:05:20 INFO - 'pip_index': False, 05:05:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:20 INFO - 'cmd': ('bash', 05:05:20 INFO - '-c', 05:05:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:20 INFO - 'enabled': True, 05:05:20 INFO - 'halt_on_failure': False, 05:05:20 INFO - 'name': 'check_screen_resolution'},), 05:05:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:20 INFO - 'cmd': ('bash', 05:05:20 INFO - '-c', 05:05:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:20 INFO - 'enabled': True, 05:05:20 INFO - 'halt_on_failure': False, 05:05:20 INFO - 'name': 'check_screen_resolution'},), 05:05:20 INFO - 'run_cmd_checks_enabled': True, 05:05:20 INFO - 'sps_profile': False, 05:05:20 INFO - 'sps_profile_interval': 0, 05:05:20 INFO - 'suite': 'dromaeojs-e10s', 05:05:20 INFO - 'system_bits': '32', 05:05:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:20 INFO - 'title': 't-yosemite-r7-0093', 05:05:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:20 INFO - 'use_talos_json': True, 05:05:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:05:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:20 INFO - 'work_dir': 'build'} 05:05:20 INFO - ##### 05:05:20 INFO - ##### Running clobber step. 05:05:20 INFO - ##### 05:05:20 INFO - Running pre-action listener: _resource_record_pre_action 05:05:20 INFO - Running main action method: clobber 05:05:20 INFO - rmtree: /builds/slave/test/build 05:05:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:05:22 INFO - Running post-action listener: _resource_record_post_action 05:05:22 INFO - ##### 05:05:22 INFO - ##### Running read-buildbot-config step. 05:05:22 INFO - ##### 05:05:22 INFO - Running pre-action listener: _resource_record_pre_action 05:05:22 INFO - Running main action method: read_buildbot_config 05:05:22 INFO - Using buildbot properties: 05:05:22 INFO - { 05:05:22 INFO - "project": "", 05:05:22 INFO - "product": "firefox", 05:05:22 INFO - "script_repo_revision": "production", 05:05:22 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:05:22 INFO - "repository": "", 05:05:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:05:22 INFO - "buildid": "20160206045251", 05:05:22 INFO - "stage_platform": "macosx64", 05:05:22 INFO - "basedir": "/builds/slave/test", 05:05:22 INFO - "buildnumber": 393, 05:05:22 INFO - "platform": "yosemite_r7", 05:05:22 INFO - "mozci_request": { 05:05:22 INFO - "priority": -1, 05:05:22 INFO - "type": "trigger_all_talos_jobs", 05:05:22 INFO - "times": 6 05:05:22 INFO - }, 05:05:22 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:05:22 INFO - "slavebuilddir": "test", 05:05:22 INFO - "branch": "fx-team", 05:05:22 INFO - "revision": "6826dfd0e85b", 05:05:22 INFO - "slavename": "t-yosemite-r7-0093", 05:05:22 INFO - "builduid": "a6c13d4e19b340b998ef14a27a7425ff", 05:05:22 INFO - "repo_path": "integration/fx-team" 05:05:22 INFO - } 05:05:22 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg. 05:05:22 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/test_packages.json. 05:05:22 INFO - Running post-action listener: _resource_record_post_action 05:05:22 INFO - ##### 05:05:22 INFO - ##### Running download-and-extract step. 05:05:22 INFO - ##### 05:05:22 INFO - Running pre-action listener: _resource_record_pre_action 05:05:22 INFO - Running main action method: download_and_extract 05:05:22 INFO - mkdir: /builds/slave/test/build/tests 05:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:22 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/test_packages.json 05:05:22 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:05:22 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:05:22 INFO - Downloaded 1277 bytes. 05:05:22 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:05:22 INFO - Using the following test package requirements: 05:05:22 INFO - {u'common': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:05:22 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:05:22 INFO - u'firefox-47.0a1.en-US.mac.cppunittest.tests.zip'], 05:05:22 INFO - u'gtest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:05:22 INFO - u'firefox-47.0a1.en-US.mac.gtest.tests.zip'], 05:05:22 INFO - u'jittest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:05:22 INFO - u'jsshell-mac.zip'], 05:05:22 INFO - u'mochitest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:05:22 INFO - u'firefox-47.0a1.en-US.mac.mochitest.tests.zip'], 05:05:22 INFO - u'mozbase': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:05:22 INFO - u'reftest': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:05:22 INFO - u'firefox-47.0a1.en-US.mac.reftest.tests.zip'], 05:05:22 INFO - u'talos': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:05:22 INFO - u'firefox-47.0a1.en-US.mac.talos.tests.zip'], 05:05:22 INFO - u'web-platform': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:05:22 INFO - u'firefox-47.0a1.en-US.mac.web-platform.tests.zip'], 05:05:22 INFO - u'webapprt': [u'firefox-47.0a1.en-US.mac.common.tests.zip'], 05:05:22 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.mac.common.tests.zip', 05:05:22 INFO - u'firefox-47.0a1.en-US.mac.xpcshell.tests.zip']} 05:05:22 INFO - Downloading packages: [u'firefox-47.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:22 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.common.tests.zip 05:05:22 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:05:22 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:05:23 INFO - Downloaded 17118709 bytes. 05:05:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:05:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:05:26 INFO - Return code: 0 05:05:26 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:05:26 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:05:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.common.tests.zip 05:05:26 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:05:26 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:05:26 INFO - Downloaded 17118709 bytes. 05:05:26 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:05:26 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:05:30 INFO - Return code: 0 05:05:30 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:05:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.talos.tests.zip 05:05:30 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:05:30 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:05:30 INFO - Downloaded 10706582 bytes. 05:05:30 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:05:30 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:05:30 INFO - Return code: 0 05:05:30 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:05:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg 05:05:30 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:05:30 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:05:33 INFO - Downloaded 111102939 bytes. 05:05:33 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:05:33 INFO - mkdir: /builds/slave/test/properties 05:05:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:05:33 INFO - Writing to file /builds/slave/test/properties/build_url 05:05:33 INFO - Contents: 05:05:33 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-macosx64/1454510625/firefox-47.0a1.en-US.mac.dmg 05:05:34 INFO - Running post-action listener: _resource_record_post_action 05:05:34 INFO - Running post-action listener: set_extra_try_arguments 05:05:34 INFO - ##### 05:05:34 INFO - ##### Running populate-webroot step. 05:05:34 INFO - ##### 05:05:34 INFO - Running pre-action listener: _resource_record_pre_action 05:05:34 INFO - Running main action method: populate_webroot 05:05:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:05:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:05:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:05:34 INFO - '--tppagecycles', 05:05:34 INFO - '7'], 05:05:34 INFO - 'tests': ['tsvgm']}}, 05:05:34 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:05:34 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:05:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:05:34 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:05:34 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:05:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:05:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:34 INFO - 'talos_options': ['--e10s'], 05:05:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:34 INFO - 'tests': ['damp', 'tps']}, 05:05:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:34 INFO - 'talos_options': ['--e10s'], 05:05:34 INFO - 'tests': ['damp', 'tps']}, 05:05:34 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:05:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:05:34 INFO - 'other': {'tests': ['a11yr', 05:05:34 INFO - 'ts_paint', 05:05:34 INFO - 'tpaint', 05:05:34 INFO - 'sessionrestore', 05:05:34 INFO - 'sessionrestore_no_auto_restore']}, 05:05:34 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:05:34 INFO - 'tests': ['a11yr', 05:05:34 INFO - 'ts_paint', 05:05:34 INFO - 'tpaint', 05:05:34 INFO - 'sessionrestore', 05:05:34 INFO - 'sessionrestore_no_auto_restore']}, 05:05:34 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:05:34 INFO - 'tests': ['a11yr', 05:05:34 INFO - 'ts_paint', 05:05:34 INFO - 'tpaint', 05:05:34 INFO - 'sessionrestore', 05:05:34 INFO - 'sessionrestore_no_auto_restore']}, 05:05:34 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:05:34 INFO - 'tests': ['a11yr', 05:05:34 INFO - 'ts_paint', 05:05:34 INFO - 'tpaint', 05:05:34 INFO - 'sessionrestore', 05:05:34 INFO - 'sessionrestore_no_auto_restore']}, 05:05:34 INFO - 'other_l64': {'tests': ['a11yr', 05:05:34 INFO - 'ts_paint', 05:05:34 INFO - 'tpaint', 05:05:34 INFO - 'sessionrestore', 05:05:34 INFO - 'sessionrestore_no_auto_restore']}, 05:05:34 INFO - 'other_nol64': {'tests': ['a11yr', 05:05:34 INFO - 'ts_paint', 05:05:34 INFO - 'tpaint', 05:05:34 INFO - 'sessionrestore', 05:05:34 INFO - 'sessionrestore_no_auto_restore']}, 05:05:34 INFO - 'svgr': {'tests': ['tsvgx', 05:05:34 INFO - 'tsvgr_opacity', 05:05:34 INFO - 'tart', 05:05:34 INFO - 'tscrollx', 05:05:34 INFO - 'cart']}, 05:05:34 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:05:34 INFO - 'tests': ['tsvgx', 05:05:34 INFO - 'tsvgr_opacity', 05:05:34 INFO - 'tart', 05:05:34 INFO - 'tscrollx', 05:05:34 INFO - 'cart']}, 05:05:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:34 INFO - 'tests': ['tp5o']}, 05:05:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:34 INFO - 'talos_options': ['--e10s'], 05:05:34 INFO - 'tests': ['tp5o']}, 05:05:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:34 INFO - 'talos_options': ['--xperf_path', 05:05:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:34 INFO - 'tests': ['tp5n']}, 05:05:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:34 INFO - 'talos_options': ['--e10s', 05:05:34 INFO - '--xperf_path', 05:05:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:34 INFO - 'tests': ['tp5n']}}, 05:05:34 INFO - 'talos.zip': {'path': '', 05:05:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:05:34 INFO - Running post-action listener: _resource_record_post_action 05:05:34 INFO - ##### 05:05:34 INFO - ##### Running create-virtualenv step. 05:05:34 INFO - ##### 05:05:34 INFO - Running pre-action listener: _resource_record_pre_action 05:05:34 INFO - Running main action method: create_virtualenv 05:05:34 INFO - Creating virtualenv /builds/slave/test/build/venv 05:05:34 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:05:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:05:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:05:34 INFO - Using real prefix '/tools/python27' 05:05:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:05:35 INFO - Installing distribute.............................................................................................................................................................................................done. 05:05:38 INFO - Installing pip.................done. 05:05:38 INFO - Return code: 0 05:05:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:05:38 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:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:38 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:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:38 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 0x10edbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae92492860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec63d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec59b20>, '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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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:05:38 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:05:38 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:05:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CiQ3QfSV8i/Render', 05:05:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:38 INFO - 'HOME': '/Users/cltbld', 05:05:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:38 INFO - 'LOGNAME': 'cltbld', 05:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:38 INFO - 'MOZ_NO_REMOTE': '1', 05:05:38 INFO - 'NO_EM_RESTART': '1', 05:05:38 INFO - 'PAGER': '/bin/cat', 05:05:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:38 INFO - 'PWD': '/builds/slave/test', 05:05:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:38 INFO - 'SHELL': '/bin/bash', 05:05:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwnJTBPoga/Listeners', 05:05:38 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:05:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:38 INFO - 'USER': 'cltbld', 05:05:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:38 INFO - 'XPC_FLAGS': '0x0', 05:05:38 INFO - 'XPC_SERVICE_NAME': '0', 05:05:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:38 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:05:38 INFO - Cleaning up... 05:05:38 INFO - Return code: 0 05:05:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:05:38 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:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:38 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:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:38 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 0x10edbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae92492860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec63d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec59b20>, '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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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:05:38 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:05:38 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:05:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CiQ3QfSV8i/Render', 05:05:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:38 INFO - 'HOME': '/Users/cltbld', 05:05:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:38 INFO - 'LOGNAME': 'cltbld', 05:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:38 INFO - 'MOZ_NO_REMOTE': '1', 05:05:38 INFO - 'NO_EM_RESTART': '1', 05:05:38 INFO - 'PAGER': '/bin/cat', 05:05:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:38 INFO - 'PWD': '/builds/slave/test', 05:05:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:38 INFO - 'SHELL': '/bin/bash', 05:05:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwnJTBPoga/Listeners', 05:05:38 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:05:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:38 INFO - 'USER': 'cltbld', 05:05:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:38 INFO - 'XPC_FLAGS': '0x0', 05:05:38 INFO - 'XPC_SERVICE_NAME': '0', 05:05:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:38 INFO - Downloading/unpacking psutil>=0.7.1 05:05:38 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:05:38 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:05:38 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:05:38 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:05:38 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:05:38 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:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:05:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:42 INFO - Installing collected packages: psutil 05:05:42 INFO - Running setup.py install for psutil 05:05:42 INFO - building 'psutil._psutil_osx' extension 05:05:42 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:05:42 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:05:42 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:05:42 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:05:42 INFO - building 'psutil._psutil_posix' extension 05:05:42 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:05:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:05:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:05:42 INFO - ^ 05:05:42 INFO - 1 warning generated. 05:05:42 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:05:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:42 INFO - Successfully installed psutil 05:05:42 INFO - Cleaning up... 05:05:42 INFO - Return code: 0 05:05:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:42 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 0x10edbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae92492860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec63d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec59b20>, '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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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:05:42 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:05:42 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:05:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CiQ3QfSV8i/Render', 05:05:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:42 INFO - 'HOME': '/Users/cltbld', 05:05:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:42 INFO - 'LOGNAME': 'cltbld', 05:05:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:42 INFO - 'MOZ_NO_REMOTE': '1', 05:05:42 INFO - 'NO_EM_RESTART': '1', 05:05:42 INFO - 'PAGER': '/bin/cat', 05:05:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:42 INFO - 'PWD': '/builds/slave/test', 05:05:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:42 INFO - 'SHELL': '/bin/bash', 05:05:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwnJTBPoga/Listeners', 05:05:42 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:05:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:42 INFO - 'USER': 'cltbld', 05:05:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:42 INFO - 'XPC_FLAGS': '0x0', 05:05:42 INFO - 'XPC_SERVICE_NAME': '0', 05:05:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:43 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:05:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:05:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:05:45 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:05:45 INFO - Installing collected packages: mozsystemmonitor 05:05:45 INFO - Running setup.py install for mozsystemmonitor 05:05:45 INFO - Successfully installed mozsystemmonitor 05:05:45 INFO - Cleaning up... 05:05:45 INFO - Return code: 0 05:05:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:45 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 0x10edbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae92492860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec63d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec59b20>, '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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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:05:45 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:05:45 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:05:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CiQ3QfSV8i/Render', 05:05:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:45 INFO - 'HOME': '/Users/cltbld', 05:05:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:45 INFO - 'LOGNAME': 'cltbld', 05:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:45 INFO - 'MOZ_NO_REMOTE': '1', 05:05:45 INFO - 'NO_EM_RESTART': '1', 05:05:45 INFO - 'PAGER': '/bin/cat', 05:05:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:45 INFO - 'PWD': '/builds/slave/test', 05:05:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:45 INFO - 'SHELL': '/bin/bash', 05:05:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwnJTBPoga/Listeners', 05:05:45 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:05:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:45 INFO - 'USER': 'cltbld', 05:05:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:45 INFO - 'XPC_FLAGS': '0x0', 05:05:45 INFO - 'XPC_SERVICE_NAME': '0', 05:05:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:45 INFO - Downloading/unpacking blobuploader==1.2.4 05:05:45 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:05:45 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:05:45 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:05:45 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:05:45 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:05:45 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:05:48 INFO - Downloading blobuploader-1.2.4.tar.gz 05:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:05:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:05:48 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:05:48 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:05:48 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:05:48 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:05:48 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:05:48 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:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:05:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:05:48 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:05:48 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:05:48 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:05:48 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:05:48 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:05:48 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:05:49 INFO - Downloading docopt-0.6.1.tar.gz 05:05:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:05:49 INFO - Installing collected packages: blobuploader, requests, docopt 05:05:49 INFO - Running setup.py install for blobuploader 05:05:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:05:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:05:49 INFO - Running setup.py install for requests 05:05:50 INFO - Running setup.py install for docopt 05:05:50 INFO - Successfully installed blobuploader requests docopt 05:05:50 INFO - Cleaning up... 05:05:50 INFO - Return code: 0 05:05:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:50 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:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:50 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:05:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:50 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 0x10edbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae92492860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec63d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec59b20>, '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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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:05:50 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:05:50 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:05:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CiQ3QfSV8i/Render', 05:05:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:50 INFO - 'HOME': '/Users/cltbld', 05:05:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:50 INFO - 'LOGNAME': 'cltbld', 05:05:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:50 INFO - 'MOZ_NO_REMOTE': '1', 05:05:50 INFO - 'NO_EM_RESTART': '1', 05:05:50 INFO - 'PAGER': '/bin/cat', 05:05:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:50 INFO - 'PWD': '/builds/slave/test', 05:05:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:50 INFO - 'SHELL': '/bin/bash', 05:05:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwnJTBPoga/Listeners', 05:05:50 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:05:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:50 INFO - 'USER': 'cltbld', 05:05:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:50 INFO - 'XPC_FLAGS': '0x0', 05:05:50 INFO - 'XPC_SERVICE_NAME': '0', 05:05:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:50 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-axJ77V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:50 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-tTboub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:50 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-bTVWyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:50 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-lJNMab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-RiNfwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-lxzI8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-zu3eti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-jhwoY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-yk733g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-FgA4GU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-61kRxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-GZvlel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-iJ1wLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-EreOt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:52 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-7KnBmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:52 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-YNP5I9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:52 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-4Wr_2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:05:52 INFO - Running setup.py install for manifestparser 05:05:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:05:52 INFO - Running setup.py install for mozcrash 05:05:52 INFO - Running setup.py install for mozdebug 05:05:52 INFO - Running setup.py install for mozdevice 05:05:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:05:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:05:52 INFO - Running setup.py install for mozfile 05:05:53 INFO - Running setup.py install for mozhttpd 05:05:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Running setup.py install for mozinfo 05:05:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Running setup.py install for mozInstall 05:05:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Running setup.py install for mozleak 05:05:53 INFO - Running setup.py install for mozlog 05:05:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Running setup.py install for moznetwork 05:05:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:05:53 INFO - Running setup.py install for mozprocess 05:05:54 INFO - Running setup.py install for mozprofile 05:05:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:05:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:05:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:05:54 INFO - Running setup.py install for mozrunner 05:05:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:05:54 INFO - Running setup.py install for mozscreenshot 05:05:54 INFO - Running setup.py install for moztest 05:05:54 INFO - Running setup.py install for mozversion 05:05:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:05:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:05:54 INFO - Cleaning up... 05:05:54 INFO - Return code: 0 05:05:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05: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:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:54 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 0x10edbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae92492860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec63d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec59b20>, '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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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:05:54 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:05:54 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:05:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CiQ3QfSV8i/Render', 05:05:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:54 INFO - 'HOME': '/Users/cltbld', 05:05:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:54 INFO - 'LOGNAME': 'cltbld', 05:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:54 INFO - 'MOZ_NO_REMOTE': '1', 05:05:54 INFO - 'NO_EM_RESTART': '1', 05:05:54 INFO - 'PAGER': '/bin/cat', 05:05:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:54 INFO - 'PWD': '/builds/slave/test', 05:05:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:54 INFO - 'SHELL': '/bin/bash', 05:05:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwnJTBPoga/Listeners', 05:05:54 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:05:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:54 INFO - 'USER': 'cltbld', 05:05:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:54 INFO - 'XPC_FLAGS': '0x0', 05:05:54 INFO - 'XPC_SERVICE_NAME': '0', 05:05:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-29w3Cz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-K8gCup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-sW2Vpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-JWr5XC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-9lDGqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-l9nCaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-IsrGYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:55 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:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-3g6BiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:56 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:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-mYIrVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:56 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:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-buvXqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:56 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:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-OtpLIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:56 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:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-RHLht0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:56 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:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Pebp2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:56 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:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-qTxtme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:56 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:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-PyFsJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:56 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:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-P61Khz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:56 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:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-p2xFcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:56 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:05:56 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:05:56 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:05:56 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:05:56 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:05:56 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:05:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:59 INFO - Downloading blessings-1.6.tar.gz 05:05:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:05:59 INFO - Installing collected packages: blessings 05:05:59 INFO - Running setup.py install for blessings 05:05:59 INFO - Successfully installed blessings 05:05:59 INFO - Cleaning up... 05:05:59 INFO - Return code: 0 05:05:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:05:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:59 INFO - Reading from file tmpfile_stdout 05:05:59 INFO - Current package versions: 05:05:59 INFO - blessings == 1.6 05:05:59 INFO - blobuploader == 1.2.4 05:05:59 INFO - docopt == 0.6.1 05:05:59 INFO - manifestparser == 1.1 05:05:59 INFO - mozInstall == 1.12 05:05:59 INFO - mozcrash == 0.16 05:05:59 INFO - mozdebug == 0.1 05:05:59 INFO - mozdevice == 0.48 05:05:59 INFO - mozfile == 1.2 05:05:59 INFO - mozhttpd == 0.7 05:05:59 INFO - mozinfo == 0.9 05:05:59 INFO - mozleak == 0.1 05:05:59 INFO - mozlog == 3.1 05:05:59 INFO - moznetwork == 0.27 05:05:59 INFO - mozprocess == 0.22 05:05:59 INFO - mozprofile == 0.28 05:05:59 INFO - mozrunner == 6.11 05:05:59 INFO - mozscreenshot == 0.1 05:05:59 INFO - mozsystemmonitor == 0.0 05:05:59 INFO - moztest == 0.7 05:05:59 INFO - mozversion == 1.4 05:05:59 INFO - psutil == 3.1.1 05:05:59 INFO - requests == 1.2.3 05:05:59 INFO - wsgiref == 0.1.2 05:05:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:59 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 0x10edbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae92492860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec63d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec59b20>, '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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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:05:59 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:05:59 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:05:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CiQ3QfSV8i/Render', 05:05:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:59 INFO - 'HOME': '/Users/cltbld', 05:05:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:59 INFO - 'LOGNAME': 'cltbld', 05:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:59 INFO - 'MOZ_NO_REMOTE': '1', 05:05:59 INFO - 'NO_EM_RESTART': '1', 05:05:59 INFO - 'PAGER': '/bin/cat', 05:05:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:59 INFO - 'PWD': '/builds/slave/test', 05:05:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:59 INFO - 'SHELL': '/bin/bash', 05:05:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwnJTBPoga/Listeners', 05:05:59 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:05:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:59 INFO - 'USER': 'cltbld', 05:05:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:59 INFO - 'XPC_FLAGS': '0x0', 05:05:59 INFO - 'XPC_SERVICE_NAME': '0', 05:05:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:00 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:06:00 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:06:00 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:06:00 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:06:00 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:06:00 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:06:00 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:06:00 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:06:00 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:06:00 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:06:00 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:06:00 INFO - Cleaning up... 05:06:00 INFO - Return code: 0 05:06:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:00 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 0x10edbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae92492860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec63d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec59b20>, '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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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:06:00 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:06:00 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:06:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CiQ3QfSV8i/Render', 05:06:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:00 INFO - 'HOME': '/Users/cltbld', 05:06:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:00 INFO - 'LOGNAME': 'cltbld', 05:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:00 INFO - 'MOZ_NO_REMOTE': '1', 05:06:00 INFO - 'NO_EM_RESTART': '1', 05:06:00 INFO - 'PAGER': '/bin/cat', 05:06:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:00 INFO - 'PWD': '/builds/slave/test', 05:06:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:00 INFO - 'SHELL': '/bin/bash', 05:06:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwnJTBPoga/Listeners', 05:06:00 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:06:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:00 INFO - 'USER': 'cltbld', 05:06:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:00 INFO - 'XPC_FLAGS': '0x0', 05:06:00 INFO - 'XPC_SERVICE_NAME': '0', 05:06:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:00 INFO - Downloading/unpacking jsonschema 05:06:00 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:06:00 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:06:00 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:06:00 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:06:00 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:06:00 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:06:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:06:02 INFO - Downloading/unpacking functools32 (from jsonschema) 05:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:03 INFO - Downloading functools32-3.2.3-2.tar.gz 05:06:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:06:03 INFO - warning: no files found matching '*.txt' 05:06:03 INFO - no previously-included directories found matching 'build' 05:06:03 INFO - no previously-included directories found matching 'dist' 05:06:03 INFO - no previously-included directories found matching '.git*' 05:06:03 INFO - Installing collected packages: jsonschema, functools32 05:06:03 INFO - Running setup.py install for functools32 05:06:03 INFO - warning: no files found matching '*.txt' 05:06:03 INFO - no previously-included directories found matching 'build' 05:06:03 INFO - no previously-included directories found matching 'dist' 05:06:03 INFO - no previously-included directories found matching '.git*' 05:06:03 INFO - Successfully installed jsonschema functools32 05:06:03 INFO - Cleaning up... 05:06:03 INFO - Return code: 0 05:06:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:06:03 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:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:03 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:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:03 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 0x10edbea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edc54c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae92492860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec63d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec59b20>, '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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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:06:03 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:06:03 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:06:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CiQ3QfSV8i/Render', 05:06:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:03 INFO - 'HOME': '/Users/cltbld', 05:06:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:03 INFO - 'LOGNAME': 'cltbld', 05:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:03 INFO - 'MOZ_NO_REMOTE': '1', 05:06:03 INFO - 'NO_EM_RESTART': '1', 05:06:03 INFO - 'PAGER': '/bin/cat', 05:06:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:03 INFO - 'PWD': '/builds/slave/test', 05:06:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:03 INFO - 'SHELL': '/bin/bash', 05:06:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwnJTBPoga/Listeners', 05:06:03 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:06:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:03 INFO - 'USER': 'cltbld', 05:06:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:03 INFO - 'XPC_FLAGS': '0x0', 05:06:03 INFO - 'XPC_SERVICE_NAME': '0', 05:06:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:03 INFO - Downloading/unpacking flake8 05:06:03 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:06:03 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:06:03 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:06:03 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:06:03 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:06:03 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:06:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:06:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:06:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:06:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:06:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:06:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:06:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:06:07 INFO - no previously-included directories found matching 'docs/_build' 05:06:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:06:07 INFO - Running setup.py install for pep8 05:06:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:06:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:06:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:06:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:06:07 INFO - no previously-included directories found matching 'docs/_build' 05:06:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:06:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:06:07 INFO - Cleaning up... 05:06:07 INFO - Return code: 0 05:06:07 INFO - Running post-action listener: _resource_record_post_action 05:06:07 INFO - Running post-action listener: _start_resource_monitoring 05:06:07 INFO - Starting resource monitoring. 05:06:07 INFO - ##### 05:06:07 INFO - ##### Running install step. 05:06:07 INFO - ##### 05:06:07 INFO - Running pre-action listener: _resource_record_pre_action 05:06:07 INFO - Running main action method: install 05:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:08 INFO - Reading from file tmpfile_stdout 05:06:08 INFO - Detecting whether we're running mozinstall >=1.0... 05:06:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:06:08 INFO - Reading from file tmpfile_stdout 05:06:08 INFO - Output received: 05:06:08 INFO - Usage: mozinstall [options] installer 05:06:08 INFO - Options: 05:06:08 INFO - -h, --help show this help message and exit 05:06:08 INFO - -d DEST, --destination=DEST 05:06:08 INFO - Directory to install application into. [default: 05:06:08 INFO - "/builds/slave/test"] 05:06:08 INFO - --app=APP Application being installed. [default: firefox] 05:06:08 INFO - mkdir: /builds/slave/test/build/application 05:06:08 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:06:08 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:06:42 INFO - Reading from file tmpfile_stdout 05:06:42 INFO - Output received: 05:06:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:06:42 INFO - Running post-action listener: _resource_record_post_action 05:06:42 INFO - ##### 05:06:42 INFO - ##### Running run-tests step. 05:06:42 INFO - ##### 05:06:42 INFO - Running pre-action listener: _resource_record_pre_action 05:06:42 INFO - Running main action method: run_tests 05:06:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:06:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:06:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:06:42 INFO - 2016-02-06 05:06:42.135 screenresolution[2422:68477] starting screenresolution argv=screenresolution get 05:06:42 INFO - 2016-02-06 05:06:42.138 screenresolution[2422:68477] Display 0: 1600x1200x32@60 05:06:42 INFO - 2016-02-06 05:06:42.144 screenresolution[2423:68479] starting screenresolution argv=screenresolution list 05:06:42 INFO - Available Modes on Display 0 05:06:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:06:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:06:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:06:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:06:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:06:42 INFO - Intel Iris: 05:06:42 INFO - Chipset Model: Intel Iris 05:06:42 INFO - Type: GPU 05:06:42 INFO - Bus: Built-In 05:06:42 INFO - VRAM (Dynamic, Max): 1536 MB 05:06:42 INFO - Vendor: Intel (0x8086) 05:06:42 INFO - Device ID: 0x0a2e 05:06:42 INFO - Revision ID: 0x0009 05:06:42 INFO - Displays: 05:06:42 INFO - Display: 05:06:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:06:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:06:42 INFO - Display Serial Number: 1600x1200 60 05:06:42 INFO - Main Display: Yes 05:06:42 INFO - Mirror: Off 05:06:42 INFO - Online: Yes 05:06:42 INFO - Rotation: Supported 05:06:42 INFO - Return code: 0 05:06:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:42 INFO - Python 2.7.3 05:06:42 INFO - Return code: 0 05:06:42 INFO - grabbing minidump binary from tooltool 05:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:06:42 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 0x10ec63d30>, '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 0x10eac6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec59b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:42 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:06:42 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:06:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:42 INFO - Return code: 0 05:06:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:06:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:06:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:06:43 INFO - Return code: 0 05:06:43 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-0093', '--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:06:43 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-0093 --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:06:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CiQ3QfSV8i/Render', 05:06:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:43 INFO - 'HOME': '/Users/cltbld', 05:06:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:43 INFO - 'LOGNAME': 'cltbld', 05:06:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:43 INFO - 'MOZ_NO_REMOTE': '1', 05:06:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:43 INFO - 'NO_EM_RESTART': '1', 05:06:43 INFO - 'PAGER': '/bin/cat', 05:06:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:43 INFO - 'PWD': '/builds/slave/test', 05:06:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:43 INFO - 'SHELL': '/bin/bash', 05:06:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QwnJTBPoga/Listeners', 05:06:43 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:06:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:43 INFO - 'USER': 'cltbld', 05:06:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:43 INFO - 'XPC_FLAGS': '0x0', 05:06:43 INFO - 'XPC_SERVICE_NAME': '0', 05:06:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:43 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-0093', '--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:06:43 INFO - mozversion application_buildid: 20160203064345 05:06:43 INFO - mozversion application_changeset: 6826dfd0e85b29050e45273d9bfdb27dc2bc1128 05:06:43 INFO - mozversion application_display_name: Nightly 05:06:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:43 INFO - mozversion application_name: Firefox 05:06:43 INFO - mozversion application_remotingname: firefox 05:06:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:06:43 INFO - mozversion application_vendor: Mozilla 05:06:43 INFO - mozversion application_version: 47.0a1 05:06:43 INFO - mozversion platform_buildid: 20160203064345 05:06:43 INFO - mozversion platform_changeset: 6826dfd0e85b29050e45273d9bfdb27dc2bc1128 05:06:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:06:43 INFO - mozversion platform_version: 47.0a1 05:06:43 INFO - using testdate: 1454764003 05:06:43 INFO - actual date: 1454764003 05:06:44 INFO - starting webserver on 'localhost:50602' 05:06:44 INFO - SUITE-START | Running 2 tests 05:06:44 INFO - TEST-START | dromaeo_css 05:06:44 INFO - operating with platform_type : mac_ 05:06:44 INFO - Initialising browser for dromaeo_css test... 05:06:44 INFO - TEST-INFO | started process 2441 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpTlCb1d/profile http://localhost:50602/getInfo.html) 05:06:44 INFO - PROCESS | 2441 | JavaScript warning: resource://gre/modules/AddonManager.jsm, line 692: Proxy.create and Proxy.createFunction are deprecated, use new Proxy instead 05:06:47 INFO - PROCESS | 2441 | __metrics Screen width/height:1600/1200 05:06:47 INFO - PROCESS | 2441 | colorDepth:24 05:06:47 INFO - PROCESS | 2441 | Browser inner width/height: 1024/689 05:06:47 INFO - PROCESS | 2441 | __metrics 05:06:47 INFO - PROCESS | 2441 | 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:06:47 INFO - TEST-INFO | 2441: exit 0 05:06:47 INFO - Browser initialized. 05:06:47 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:47 INFO - TEST-INFO | started process 2445 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpTlCb1d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:59 INFO - PROCESS | 2445 | RSS: Main: 255635456 05:06:59 INFO - PROCESS | 2445 | 05:07:49 INFO - PROCESS | 2445 | 1454764069368 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:10:00 INFO - PROCESS | 2445 | Cycle 1(1): loaded http://localhost:50602/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50602/tests/dromaeo/cssquery-ext.html) 05:10:01 INFO - PROCESS | 2445 | RSS: Main: 269074432 05:10:01 INFO - PROCESS | 2445 | 05:13:03 INFO - PROCESS | 2445 | Cycle 1(1): loaded http://localhost:50602/tests/dromaeo/cssquery-ext.html (next: http://localhost:50602/tests/dromaeo/cssquery-jquery.html) 05:13:04 INFO - PROCESS | 2445 | RSS: Main: 280506368 05:13:04 INFO - PROCESS | 2445 | 05:16:06 INFO - PROCESS | 2445 | Cycle 1(1): loaded http://localhost:50602/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50602/tests/dromaeo/cssquery-mootools.html) 05:16:06 INFO - PROCESS | 2445 | RSS: Main: 282525696 05:16:06 INFO - PROCESS | 2445 | 05:19:08 INFO - PROCESS | 2445 | Cycle 1(1): loaded http://localhost:50602/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50602/tests/dromaeo/cssquery-prototype.html) 05:19:08 INFO - PROCESS | 2445 | RSS: Main: 285413376 05:19:08 INFO - PROCESS | 2445 | 05:22:11 INFO - PROCESS | 2445 | Cycle 1(1): loaded http://localhost:50602/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50602/tests/dromaeo/cssquery-yui.html) 05:22:12 INFO - PROCESS | 2445 | RSS: Main: 287965184 05:22:12 INFO - PROCESS | 2445 | 05:25:14 INFO - PROCESS | 2445 | Cycle 1(1): loaded http://localhost:50602/tests/dromaeo/cssquery-yui.html (next: http://localhost:50602/tests/dromaeo/cssquery-dojo.html) 05:25:14 INFO - PROCESS | 2445 | RSS: Main: 289579008 05:25:14 INFO - PROCESS | 2445 | 05:25:14 INFO - PROCESS | 2445 | __start_tp_report 05:25:14 INFO - PROCESS | 2445 | _x_x_mozilla_page_load 05:25:14 INFO - PROCESS | 2445 | _x_x_mozilla_page_load_details 05:25:14 INFO - PROCESS | 2445 | |i|pagename|runs| 05:25:14 INFO - PROCESS | 2445 | |0;dojo.html;3516.841742121604;3540.7875527468336;3581.3911635022046;3594.71242300616;3602.297551977364;5876.823695289143;5879.617824841383;5914.024186009303;5925.170476133339;5925.85185370366;4850.34520339754;5535.944640553594;5748.396418376073;5879.11813228016;5898.115282707586;5241.056609810233;5282.788688452477;5305.602079844008;5341.545968592674;5351.785928562853;5317.574594032468;5358.517733403993;5366.624336296461;5367.919481207785;5368.62419630624;5288.788448462076;5292.126799078147;5294.49702278283;5341.839744807662;5385.838424847261;5331.2269720890445;5351.464853514637;5372.355317361902;5378.085725426687;5394.406615272317;4833.347498087748;5108.259302401131;5193.402758682762;5297.47025297471;5307.761150748225;4880.536349046835;5679.119736440858;5704.372519022904;5715.371309155989;5723.599348045638;5226.686398816045;5298.940211957624;5700.8859822803315;6263.31103578606;6289;5138.58891288696;5749.482546570787;6188.690565471754;6267.1539342189135;6268.905966410507;5890.175375447441;5891.086881533326;5909.704514774244;5913.763449461973;5935.73289201987;6303.432691057735;6344.175257216532;6348.968255158695;6373;6375.139356186948;6089.543284253698;6145.125736650275;6173.290071641729;6215.192025036717;6221.035739460391;6156.076588511759;6157.384261573807;6201.379862013762;6208.379162083756;6237.719302631393;6116.78591249313;6121.270068921596;6180.289266734293;6191.690415479208;6199.380061993855;6126.334466363025;6164.907526387108;6174.629522228681;6197.4732147767945;6225.003999360171;4942.777575009133;4963.726995015309;4975.203967365204;4977.004599080198;4980.626453016038;3857.479240302523;4351.325544540601;4486.506484286726;4490.079533695584;4492.528284530143;4543.840965566255;4995.525425084611;5603.6077474577205;5906.5570082244;6039.4262545058145;6226.128342032119;6248.4064013918605;6263.937360626426;6271.843203919939;6275.215598050244;5283.709395983181;5703.144528320785;5821.79623713168;5954.702264886739;5956.404359564009;4529.107496470992;4551.8179272829375;4629.837955671535;5416.160495123262;5435.728213589384;5399.514043736004;6355.539153592218;6377.74489020434;6404.903926441014;6430.967845160744;6052.576319657582;6192.907106393498;6226.097215903759;6247.468965138014;6270.874582508415;6372.617642941438;6376.202974628171;6416.839579010562;6426.871462570816;6438.645874476949;6282.434580887742;6284.402981716729;6287.245530536365;6294.402031806971;6304;5177.611679124006;6150.077488376779;6180.6291622502795;6263.843403914939;6267.811965641038;6118.5411094169;6164.229471316085;6181;6186.0102383618405;6189.381061893955;5779.422057794355;5901.026330655565;6166.974882487576;6185.597936134317;6201.162843016135;5894.322152952465;5938.435848594377;5986.850328741816;6011.819645410645;6018.789342372996;6163.691815409122;6163.9383606163365;6173.104899789595;6183.598066125687;6204.7828326008375;4604.240300350539;5921.970390148194;6119.2350956130485;6119.479844213113;6132.754689812357 05:25:14 INFO - PROCESS | 2445 | |1;ext.html;462.870396289039;488.54809301396244;498.713239887065;569.6041251330327;570.586324914437;26657.600135997975;38280.61719382805;38290.276937537776;38402.8079859601;38483;41445.58554414455;41575;41668.79165604175;41685;41782.37326440106;9595.712128636153;9845.212383009342;9848.113669769718;9854.260930430211;9903.702888913343;9624.663136790214;9764.755881102994;9785.951070244639;9808.362456440344;9820.950895245514;9864.901350986505;9941.751456213617;9948.104670579647;9951.104400603981;9961.601535938555;9812.852807207862;9925.90074099257;9940.502974851228;9942.850857237147;9943.005699430036;9787.706368808877;9907.306488545804;9921.355111917774;9927.255455840841;9936.90063099367;39860;40036.19927601461;40128.79935600333;40279.39580906289;40309;2863.1839925621307;3133.8589763460473;3263.461528847737;3374.7637665363436;3629.056445324208;27369.04208352698;27421;27440.17679469619;27669.723302766917;27684.723152768416;2850.558163484663;2855.214815925637;2857.3428111534463;2882.6396700412447;2947.572601972702;283248;336231;336855;337029.99999999756;337463;50072.74963625159;57415.85168296611;61014.38985610087;64473.03290450647;64488;68408.6579567099;68943.3105668947;69066.65466672635;69092.654536727;69368;16246.75629865553;16469.91765041191;16472.42346517866;16480.505584832474;16502.66994660124;12373.319467429377;12401.13192076546;12492.875071249171;12568.81146782799;12573.37133143339;17611.55971100733;19362;19710.507237319183;20673.689894651594;21147.577048458945;7968.521888686697;8142.430029897969;8165.714200002972;8172.7956801080445;8237.917620823715;971.7376308396834;976.8339382305026;988.5008070924281;1022.7238645565656;1129.2998341028615;29449.55825662617;29681.703182967893;29900.10299691013;30212.09363719048;30505.389892202475;1095.4905968724581;1115.6206889657556;1145.7225193908732;1258.0250306012913;1265.2092442223611;15043.323050462537;15197.772033419507;15200.315985780666;15523.379064837505;15579.298931548108;517719.4114029406;525134;525711;528192;533696;58097.70951145302;58406.831863362495;58411;58441.707791461624;58550.82898342009;1377.497213517079;1392.576916165864;1408.3873515020998;1424.9715005699827;1426.6789972256158;29474;29579.556306654988;29970.70029299679;30491.28869833034;31853;49068;49127.75436122796;49133.01733965372;49387.50612493829;49451.258231125845;357205;359593;364446;364478;369944;33514.65941362318;33806.985790426326;34810.82594587011;34851.825740871136;34936;43722.56277437185;44074.33888491739;44324.55675443333;44408;44941.87645308763;35679.464808027384;36403.817980909924;36713;37053.62946370609;37284.88145355644;36131.27737445289;36211.094722632144;36529.26941461209;36566.902992910254;36694;29932.401351973273;35440;35825.46261806127;36470.8176459116;36524.086897827765;30159.698403016566;32469.35061298808;32508.349833002736;32568;32655.836720816245;24387.512809617692;24618.876905615358;24628;25031.624525632495;25122.12072577451 05:25:14 INFO - PROCESS | 2445 | |2;jquery.html;281.7703571589153;320.8187374133614;368.3094198378041;369.7485709717392;372.78564825225504;15256.38974441021;16222.756658650129;19056.809431905676;19169;20295.39113826586;18660.720089198672;19134;19398.321058762947;19512.31706890259;19948.50128746782;5648.124540696199;5873.500752436027;5975.223220981266;5975.641461512301;5996.190514280582;3929;4097.549269580344;5558.916616250759;5785.508231800281;6024;5967.462928336449;5998.790042348519;6011.57918945674;6026.035834266519;6351.079093531435;6424.935750642504;6490.643014634194;6495.902561461582;6518.217813862343;6529.314421985718;5488.807891723771;5711.742971566289;5719.113537401709;5731.083026715747;5732.541396688255;12535.24788512692;14526.273686315642;15419.845801541953;23929;24040.9181586829;2131.6695912133646;2205.860434614132;2317.895694693743;2722.8502432366213;2776.9166924992282;9516.381435206758;9660.420374777535;10010.749731256705;12057.758844823054;13816.93091534536;1671.7659527666137;1709.5384246253566;1725.2667616263163;1767.2135899524587;1777.4756446848212;82515.17484825135;85395;86024;86380.56809715975;87628.12371876264;10887.965643263877;10989.670309890716;10995.34027958325;11054.336739795637;11146.498407571678;13283.535076272285;13308.26804525741;13320.93339533296;13330.66673333155;13433.261170635513;8367.8326433471;8374.330053595697;8454.070052294242;8475.788105297293;8486.363522735819;8448.112948140357;8527.062023177445;8549.316054715608;8572.657093716181;8592.183742544448;3708.2768860072565;4285.46431696038;4342.5874541918465;4358.629516491071;4388.648908087345;1747.8689098317677;1798.8291112344568;1846.292179733741;1851.333519932823;1870.9064546772606;400.2395448647535;405.02591268000504;894.369469523984;895.54327293081;938.5213541094091;10006.849897251546;10141.797164056678;11264.718382040515;11630.244034137922;11694.76610467786;435.9411479450297;453.98184072637383;461.3656222693796;462.26961400986363;474.122872685529;6020.578559500793;6056.091586262096;6150.569460137837;6156.8768624627255;6188.659623720649;262204;551492;562994;564810;694024;83574.5821270902;114618.4269078666;115784.42107789408;116910;118390;1142.6000899685043;1163.2962057954985;1215.4663106612386;1217.1662411248324;1223.8409006829233;18384;18450.35423760162;18606.16272267724;18966.336178233694;19006.23975040983;49403.7529812363;49916.50083499119;50616.74691626518;51778;51790.48209518007;67972.660136699;69939.20243190216;70016;70495.29504705092;71415;7031.824204394727;7606.619669016638;7807.453478256468;7822.413319001212;7825.530468171928;9381;10090.69727908164;11224.607138750105;11503.827442588201;11998.820017699567;8697.782555436164;10025.649102281384;10047.648332308334;10101.343412678152;10222.846657299997;9660.32377733552;9964.402135871871;10000.149987251165;10230.335028223142;10283.22875784334;9791;10194.643187488402;10244.231682623982;10445.582176712845;10476.528556214836;7622.618869056636;7792.37660987131;7815.804604884924;7853.685852565834;7866.331361834308;8698.391112622056;10581.576736930436;10596.735081623021;10675.786484270426;10810.459477026274 05:25:14 INFO - PROCESS | 2445 | |3;mootools.html;1512.848715128487;1519.490970524874;1734.193599976011;1753.4476639858435;1873.9156737946792;7855.528668279907;7944.324732397738;8671.826563468727;8838;8841.469511829288;6652.600843949354;7881.645325960331;8350.290225330838;8463.238308552229;8582.184692454222;2450.3751543356466;2482.453860150764;2498.8750562471896;2499.6250562415694;2500.2624225853324;2231.3975226688785;2428.7571242875747;2495.8252922295446;2496.488219914922;2529.165375426114;2455.545724041049;2479.5412848622973;2509.372656835791;2517.345490172559;2519.685039370079;2189.4416923684516;2457.9262622121278;2465;2502.8248022638422;2503.111395454606;2377.3581133093967;2378.1557547070715;2381.1189859752003;2383.427977285439;2475.2697954103583;7863.410244231705;8765.474071555727;8804.119588041209;8822;8834.602442890022;5382.111951527993;5459.07195776715;5478.548184731049;5489.780408783644;5503.009458297505;3657.195417008254;3734.290484807878;3782.092297848524;3800.847966081353;3803.4294855771577;1054.8660190295386;1072.3405105859918;1076.6447072466067;1105.9889401106047;1108.7893300273004;10075.143612792921;11664.766704665859;11965.341906195155;11973;11973.521059157623;4898.461169271377;4909.410870695539;4974.850754477371;4987.750612469435;4992.151334273182;10186.847197292047;10204.23468239885;10213.846792298122;10226.437545935045;10241.975802419847;5454.91817622736;5491.588130890199;5501.614886957875;5502.147167189092;5505.779768809283;4366.541513141138;4522.276435770261;4803.087413391444;4938.975305123451;4988.251762235694;3928.744631598937;3968.1071758854605;3996.18078293949;4006.258842114218;4020.155767288902;1303.387407918277;1321.709223970725;1325.7017171136417;1326.2109045118139;1331.9134256273312;1011.3780025284398;1108.2630051015983;1280.0847394113255;1312.6587087357166;1335.8129861819352;7850.725224617111;7883.921160788319;7949;8036.437449378487;8100.594970251464;504.44511037858064;506.5441103007249;511.71088335090553;512.0552580488992;524.538406202547;6070.119832624245;6197.349278325801;6203.441690247809;6235.095911092957;6245.531585131133;10499.422531760674;10671.3063650864;10748.462576871125;10751.731206719895;10857.131429485622;10166.034226748447;10403.375797452178;10563.419011954416;10604.628837990787;10622.415767132881;8028.678852845821;8054.275115239657;8126.918730812735;8182.345412366996;8265.338772898274;7952.244536768998;7982.920170798217;8004.879926800986;8019.799505012421;8042.557659328793;9334;9409.200217981548;9533.856992145262;9562.474063926273;9582.425054496753;9107;9483.66807161746;9523.238140948846;9943.204543636637;9960.850587241343;6019.247594050744;6743.5953842769595;6868.347506986828;6923.169219693669;7210.675519601736;8047.798805029921;8106.8783968241705;8143.18568143181;8173.6321865515;8187.754367368989;7508.136564295176;7654.923450765644;7695.307422332017;7711.575863327571;7791;6409.583377080475;7462.4776265660885;7598.734044308423;7642.04474440695;7746.883796742941;7704.884426733715;7757.806054848674;7762.883556746766;7764.844703105793;7779.961100194463;6997.090378250795;7012.6844292008;7031.648417579203;7044.189918159477;7116.25279345682;7124.180719217356;7151.82120446993;7191.928080719335;7199.784006479814;7204.4956853019785 05:25:14 INFO - PROCESS | 2445 | |4;prototype.html;305.63171378488926;321.6976042520031;325.72638983254063;332.3353293413173;335.4682827718067;6541.280459149501;6687.832804179898;6742.426893714034;6767.492438067146;6820.420264277536;5112.846614601559;6677.933220667792;6702.296258892819;6719.294474080224;6760.112935062334;4993.825216117437;5014.147594908856;5031.094403007466;5042.067217564762;5052.292679024939;4525.660575456837;4625.820415793968;5032.094223039851;5033.697978121306;5060.089183946888;4724.149653062447;4884.731339776311;4892.290617860408;4896.412430508344;4907.2884431757375;4878.000009997942;4883.804647814083;4887.095887260868;4900.436449808264;4916.852494425173;3931.783751893645;4014.417909403149;4020.6783457323345;4142.171565686875;4148.149629325981;5352.464753524655;6395.744170233232;6429.807105786834;6476.417122459001;6624.966875165594;5721.256236689245;5783.161441590946;5783.913241301384;5802.883942321172;5812.505936995361;6906.205786334585;7196.820079498002;7211.92788072123;7213.4589905757275;7234.240404757477;4622.0293738314995;4623.583877451012;4625.8149674013;4640.628749700016;4646.744429056401;7519.473636845423;7583.81040473987;7600.1639819619795;7626.580538070404;7661.892979430645;4273.594008569181;4564.38331687122;4667.276572131325;4686.133065382915;4697.436307643105;4706.905861882743;4753.120672675566;4776.23580227162;4777.880552986203;4780.350778981246;5729.713514324267;5735.397783232784;5739.2252045973255;5739.827805165852;5766.798162064308;7239;7241.42068634508;7256.528325658815;7273.236310187354;7293.744718934812;1580.6996670632543;1628.8452597003266;1651.6036151323835;1657.1714142928536;1658.5439004273921;1048.905598496139;1055.9736006599896;1064.5422468338534;1065.3128731967838;1068.033429746081;6784.050232967388;6797.388235058868;6812.250652428229;6825.35159159879;6830.658467076626;7476.813079673052;7485.588292643957;7495.925040749523;7498.812529686801;7499.887501687479;5660.26416565852;6567.67161641916;6723.193216814014;6783.118194634764;6811.591304521745;3173.381190667844;3407.4207384744655;3852.4413959975645;3923.9803800980812;4005.599440055971;7788.493747906368;7947.483413578099;7999;8007.319377852831;8033.558154301452;7853.175416581291;7855.567943763033;7865;7881.802954926058;7882.5270483771155;6141.785037523665;6231.052376600149;7833.216678332121;7968;8001.759947201594;7574.886376704244;7575.734849280248;7591.316781489692;7609.086909570887;7621.847563048819;6084.60450070744;6699;6870.866307059279;7188.137423509213;7436.219196984239;7969.203079691984;7973.401994850294;7973.401994850526;7982.281594656509;7984.321332686554;4203.033302340417;4709.576138147583;4719.433667959866;4736.336912832137;4741.786619602057;7090.149182098181;7302.525335853153;7528.284812942762;7549.622518874145;7556;7258.927410725825;7267.418606511572;7278.454115941219;7287.101400750573;7302.6713797878165;7273.272672732684;7289.380402665833;7294.890576641248;7300.488965772345;7301.503191845765;6817.386435220854;7070.363667269765;7080.929190708233;7128.572285662877;7155.248698886542;7291.52605080668;7305.41556675454;7312.780616581511;7322.707091716272;7328.461023185191;6911.550749201286;7148.821279468055;7157.7136914522835;7165.104361954755;7172.426205903619 05:25:14 INFO - PROCESS | 2445 | |5;yui.html;681.1927865479406;738.4941315199086;740.0786021403354;752.300360664582;755.2145768400864;14540.491082812103;14756.262186890654;15001.699966000688;15007.39970401184;15037.248137593104;13945.58163255104;13997.370118344674;14014.08908420952;14014.719705605881;14029.719405611882;686.4028295382999;698.4517154034085;828.6809578312333;853.6030745703247;869.3219288954625;854.2695994924344;869.4348673362333;874.2175752701329;876.5441970175505;879.248242752447;914.1270087066869;915.8305713443034;917.8164367126568;919.1497864475333;923.7829110159107;874.9868751968726;903.8779764731743;912.0423555266976;916.1113719691888;916.7524768312552;670.1522573943964;674.6727836999052;676.651524464901;677.478341676906;678.6335378895391;14533.491327803582;14650.633734156625;14666.63333416673;14718.190499522521;14763.77854332186;12801.679958001125;12925.418356173996;12927.288999105045;13263.27052012139;13380.929525637926;10055.648052318134;10195.49022548877;10256.43589602579;10387.013233742782;10655.84016239757;3580.4092324766384;3615.3853844846176;3619.6199399063244;3633.94549081764;3747.962520374816;77363.22636773616;77371;78854.60572697157;78867.21132788656;78935.60532197302;9780.902190978142;9790.265730070274;9932;9995.400275983464;10041.799164016678;18833.246670133314;21776.237831675815;21823.127074916825;21826.563468730856;21834;5830.650160990354;7577.848443031108;7620.390368770485;7750.186230445722;7802.726904558313;4041.2725709372594;4046.979765101156;4063.146739184745;4078.5513593504693;4083.3466645336603;3509.175343794206;3545.308664810389;3552.875649352312;3555.7510974231554;3558.1638314995957;649.9122618446544;675.8242856857253;695.0720787748312;704.7850405626333;705.9082319298458;568.3180183779481;602.2772672792667;608.1971797227617;623.5603899251017;628.9465395441439;8336.458130221516;8547.27348175412;8574.399792014623;8702.564871756387;8826.293896488265;535.3388565122071;567.6594043573823;616.6300219868043;619.7799781077742;624.5877720704314;3793.6016718244737;4006.9198616027516;4017.91964160721;4062.918741625151;4120.62914337711;90174.54912725394;90966;91085;91421.88601512948;91782.54108729414;77529;77646.61176694193;79140.60429697814;79172;81287.18712812914;58554;66175.33824661787;67075.99386009213;68381.97427038598;69711.77161712892;7956.2043795619975;8078.636461359135;8091.635876385694;8234.423590348619;8307.376946728898;49111.75444122757;49672.26147084979;50149.74625634388;50385.74807125941;51203.48796512136;65017.34982650302;68970.96543551951;69332.3066769346;70094.29905701082;70322.29677703157;10202.795944081225;10216.744581385525;10290.639827606297;10456.163506919276;10563.267911281302;9538.523073846418;10614.522346494581;10969.945150274198;11002.449877506253;11041.613543525938;7420.962895185489;10179.083882450615;10256.692299231036;10455.895441045492;10460.372377657364;9064.728058158265;10282.794344113225;10450.903938287374;10548.261621686408;11122.221444499131;7829.843403132019;10031.899681003097;10111.393316400745;10173.593056277667;10426.27016108865;8472.406931514735;8486;8543.743687689379;8905.777355565904;8988;8905.73282801517;9104.317176211678;9109.681161159593;9138.08619138081;9192.540372981457 05:25:14 INFO - PROCESS | 2445 | __end_tp_report 05:25:14 INFO - PROCESS | 2445 | __start_cc_report 05:25:14 INFO - PROCESS | 2445 | _x_x_mozilla_cycle_collect,353 05:25:14 INFO - PROCESS | 2445 | __end_cc_report 05:25:14 INFO - PROCESS | 2445 | __startTimestamp1454765114429__endTimestamp 05:25:14 INFO - PROCESS | 2445 | 05:25:14 INFO - PROCESS | 2445 | ------- Summary: start ------- 05:25:14 INFO - PROCESS | 2445 | Number of tests: 6 05:25:14 INFO - PROCESS | 2445 | 05:25:14 INFO - PROCESS | 2445 | [#0] dojo.html Cycles:165 Average:5715.10 Median:5925.85 stddev:662.87 (11.2%) stddev-sans-first:642.08 05:25:14 INFO - PROCESS | 2445 | Values: 3516.8 3540.8 3581.4 3594.7 3602.3 5876.8 5879.6 5914.0 5925.2 5925.9 4850.3 5535.9 5748.4 5879.1 5898.1 5241.1 5282.8 5305.6 5341.5 5351.8 5317.6 5358.5 5366.6 5367.9 5368.6 5288.8 5292.1 5294.5 5341.8 5385.8 5331.2 5351.5 5372.4 5378.1 5394.4 4833.3 5108.3 5193.4 5297.5 5307.8 4880.5 5679.1 5704.4 5715.4 5723.6 5226.7 5298.9 5700.9 6263.3 6289.0 5138.6 5749.5 6188.7 6267.2 6268.9 5890.2 5891.1 5909.7 5913.8 5935.7 6303.4 6344.2 6349.0 6373.0 6375.1 6089.5 6145.1 6173.3 6215.2 6221.0 6156.1 6157.4 6201.4 6208.4 6237.7 6116.8 6121.3 6180.3 6191.7 6199.4 6126.3 6164.9 6174.6 6197.5 6225.0 4942.8 4963.7 4975.2 4977.0 4980.6 3857.5 4351.3 4486.5 4490.1 4492.5 4543.8 4995.5 5603.6 5906.6 6039.4 6226.1 6248.4 6263.9 6271.8 6275.2 5283.7 5703.1 5821.8 5954.7 5956.4 4529.1 4551.8 4629.8 5416.2 5435.7 5399.5 6355.5 6377.7 6404.9 6431.0 6052.6 6192.9 6226.1 6247.5 6270.9 6372.6 6376.2 6416.8 6426.9 6438.6 6282.4 6284.4 6287.2 6294.4 6304.0 5177.6 6150.1 6180.6 6263.8 6267.8 6118.5 6164.2 6181.0 6186.0 6189.4 5779.4 5901.0 6167.0 6185.6 6201.2 5894.3 5938.4 5986.9 6011.8 6018.8 6163.7 6163.9 6173.1 6183.6 6204.8 4604.2 5922.0 6119.2 6119.5 6132.8 05:25:14 INFO - PROCESS | 2445 | 05:25:14 INFO - PROCESS | 2445 | [#1] ext.html Cycles:180 Average:56444.90 Median:36526.68 stddev:109916.88 (300.9%) stddev-sans-first:110144.86 05:25:14 INFO - PROCESS | 2445 | Values: 462.9 488.5 498.7 569.6 570.6 26657.6 38280.6 38290.3 38402.8 38483.0 41445.6 41575.0 41668.8 41685.0 41782.4 9595.7 9845.2 9848.1 9854.3 9903.7 9624.7 9764.8 9786.0 9808.4 9821.0 9864.9 9941.8 9948.1 9951.1 9961.6 9812.9 9925.9 9940.5 9942.9 9943.0 9787.7 9907.3 9921.4 9927.3 9936.9 39860.0 40036.2 40128.8 40279.4 40309.0 2863.2 3133.9 3263.5 3374.8 3629.1 27369.0 27421.0 27440.2 27669.7 27684.7 2850.6 2855.2 2857.3 2882.6 2947.6 283248.0 336231.0 336855.0 337030.0 337463.0 50072.7 57415.9 61014.4 64473.0 64488.0 68408.7 68943.3 69066.7 69092.7 69368.0 16246.8 16469.9 16472.4 16480.5 16502.7 12373.3 12401.1 12492.9 12568.8 12573.4 17611.6 19362.0 19710.5 20673.7 21147.6 7968.5 8142.4 8165.7 8172.8 8237.9 971.7 976.8 988.5 1022.7 1129.3 29449.6 29681.7 29900.1 30212.1 30505.4 1095.5 1115.6 1145.7 1258.0 1265.2 15043.3 15197.8 15200.3 15523.4 15579.3 517719.4 525134.0 525711.0 528192.0 533696.0 58097.7 58406.8 58411.0 58441.7 58550.8 1377.5 1392.6 1408.4 1425.0 1426.7 29474.0 29579.6 29970.7 30491.3 31853.0 49068.0 49127.8 49133.0 49387.5 49451.3 357205.0 359593.0 364446.0 364478.0 369944.0 33514.7 33807.0 34810.8 34851.8 34936.0 43722.6 44074.3 44324.6 44408.0 44941.9 35679.5 36403.8 36713.0 37053.6 37284.9 36131.3 36211.1 36529.3 36566.9 36694.0 29932.4 35440.0 35825.5 36470.8 36524.1 30159.7 32469.4 32508.3 32568.0 32655.8 24387.5 24618.9 24628.0 25031.6 25122.1 05:25:14 INFO - PROCESS | 2445 | 05:25:14 INFO - PROCESS | 2445 | [#2] jquery.html Cycles:180 Average:30428.52 Median:2251.29 stddev:90743.60 (4030.7%) stddev-sans-first:90969.92 05:25:14 INFO - PROCESS | 2445 | Values: 281.8 320.8 368.3 369.7 372.8 15256.4 16222.8 19056.8 19169.0 20295.4 18660.7 19134.0 19398.3 19512.3 19948.5 5648.1 5873.5 5975.2 5975.6 5996.2 3929.0 4097.5 5558.9 5785.5 6024.0 5967.5 5998.8 6011.6 6026.0 6351.1 6424.9 6490.6 6495.9 6518.2 6529.3 5488.8 5711.7 5719.1 5731.1 5732.5 12535.2 14526.3 15419.8 23929.0 24040.9 2131.7 2205.9 2317.9 2722.9 2776.9 9516.4 9660.4 10010.7 12057.8 13816.9 1671.8 1709.5 1725.3 1767.2 1777.5 82515.2 85395.0 86024.0 86380.6 87628.1 10888.0 10989.7 10995.3 11054.3 11146.5 13283.5 13308.3 13320.9 13330.7 13433.3 8367.8 8374.3 8454.1 8475.8 8486.4 8448.1 8527.1 8549.3 8572.7 8592.2 3708.3 4285.5 4342.6 4358.6 4388.6 1747.9 1798.8 1846.3 1851.3 1870.9 400.2 405.0 894.4 895.5 938.5 10006.8 10141.8 11264.7 11630.2 11694.8 435.9 454.0 461.4 462.3 474.1 6020.6 6056.1 6150.6 6156.9 6188.7 262204.0 551492.0 562994.0 564810.0 694024.0 83574.6 114618.4 115784.4 116910.0 118390.0 1142.6 1163.3 1215.5 1217.2 1223.8 18384.0 18450.4 18606.2 18966.3 19006.2 49403.8 49916.5 50616.7 51778.0 51790.5 67972.7 69939.2 70016.0 70495.3 71415.0 7031.8 7606.6 7807.5 7822.4 7825.5 9381.0 10090.7 11224.6 11503.8 11998.8 8697.8 10025.6 10047.6 10101.3 10222.8 9660.3 9964.4 10000.1 10230.3 10283.2 9791.0 10194.6 10244.2 10445.6 10476.5 7622.6 7792.4 7815.8 7853.7 7866.3 8698.4 10581.6 10596.7 10675.8 10810.5 05:25:14 INFO - PROCESS | 2445 | 05:25:14 INFO - PROCESS | 2445 | [#3] mootools.html Cycles:180 Average:5990.17 Median:5418.52 stddev:3150.92 (58.2%) stddev-sans-first:3141.79 05:25:14 INFO - PROCESS | 2445 | Values: 1512.8 1519.5 1734.2 1753.4 1873.9 7855.5 7944.3 8671.8 8838.0 8841.5 6652.6 7881.6 8350.3 8463.2 8582.2 2450.4 2482.5 2498.9 2499.6 2500.3 2231.4 2428.8 2495.8 2496.5 2529.2 2455.5 2479.5 2509.4 2517.3 2519.7 2189.4 2457.9 2465.0 2502.8 2503.1 2377.4 2378.2 2381.1 2383.4 2475.3 7863.4 8765.5 8804.1 8822.0 8834.6 5382.1 5459.1 5478.5 5489.8 5503.0 3657.2 3734.3 3782.1 3800.8 3803.4 1054.9 1072.3 1076.6 1106.0 1108.8 10075.1 11664.8 11965.3 11973.0 11973.5 4898.5 4909.4 4974.9 4987.8 4992.2 10186.8 10204.2 10213.8 10226.4 10242.0 5454.9 5491.6 5501.6 5502.1 5505.8 4366.5 4522.3 4803.1 4939.0 4988.3 3928.7 3968.1 3996.2 4006.3 4020.2 1303.4 1321.7 1325.7 1326.2 1331.9 1011.4 1108.3 1280.1 1312.7 1335.8 7850.7 7883.9 7949.0 8036.4 8100.6 504.4 506.5 511.7 512.1 524.5 6070.1 6197.3 6203.4 6235.1 6245.5 10499.4 10671.3 10748.5 10751.7 10857.1 10166.0 10403.4 10563.4 10604.6 10622.4 8028.7 8054.3 8126.9 8182.3 8265.3 7952.2 7982.9 8004.9 8019.8 8042.6 9334.0 9409.2 9533.9 9562.5 9582.4 9107.0 9483.7 9523.2 9943.2 9960.9 6019.2 6743.6 6868.3 6923.2 7210.7 8047.8 8106.9 8143.2 8173.6 8187.8 7508.1 7654.9 7695.3 7711.6 7791.0 6409.6 7462.5 7598.7 7642.0 7746.9 7704.9 7757.8 7762.9 7764.8 7780.0 6997.1 7012.7 7031.6 7044.2 7116.3 7124.2 7151.8 7191.9 7199.8 7204.5 05:25:14 INFO - PROCESS | 2445 | 05:25:14 INFO - PROCESS | 2445 | [#4] prototype.html Cycles:180 Average:5878.14 Median:6710.80 stddev:1930.10 (28.8%) stddev-sans-first:1889.65 05:25:14 INFO - PROCESS | 2445 | Values: 305.6 321.7 325.7 332.3 335.5 6541.3 6687.8 6742.4 6767.5 6820.4 5112.8 6677.9 6702.3 6719.3 6760.1 4993.8 5014.1 5031.1 5042.1 5052.3 4525.7 4625.8 5032.1 5033.7 5060.1 4724.1 4884.7 4892.3 4896.4 4907.3 4878.0 4883.8 4887.1 4900.4 4916.9 3931.8 4014.4 4020.7 4142.2 4148.1 5352.5 6395.7 6429.8 6476.4 6625.0 5721.3 5783.2 5783.9 5802.9 5812.5 6906.2 7196.8 7211.9 7213.5 7234.2 4622.0 4623.6 4625.8 4640.6 4646.7 7519.5 7583.8 7600.2 7626.6 7661.9 4273.6 4564.4 4667.3 4686.1 4697.4 4706.9 4753.1 4776.2 4777.9 4780.4 5729.7 5735.4 5739.2 5739.8 5766.8 7239.0 7241.4 7256.5 7273.2 7293.7 1580.7 1628.8 1651.6 1657.2 1658.5 1048.9 1056.0 1064.5 1065.3 1068.0 6784.1 6797.4 6812.3 6825.4 6830.7 7476.8 7485.6 7495.9 7498.8 7499.9 5660.3 6567.7 6723.2 6783.1 6811.6 3173.4 3407.4 3852.4 3924.0 4005.6 7788.5 7947.5 7999.0 8007.3 8033.6 7853.2 7855.6 7865.0 7881.8 7882.5 6141.8 6231.1 7833.2 7968.0 8001.8 7574.9 7575.7 7591.3 7609.1 7621.8 6084.6 6699.0 6870.9 7188.1 7436.2 7969.2 7973.4 7973.4 7982.3 7984.3 4203.0 4709.6 4719.4 4736.3 4741.8 7090.1 7302.5 7528.3 7549.6 7556.0 7258.9 7267.4 7278.5 7287.1 7302.7 7273.3 7289.4 7294.9 7300.5 7301.5 6817.4 7070.4 7080.9 7128.6 7155.2 7291.5 7305.4 7312.8 7322.7 7328.5 6911.6 7148.8 7157.7 7165.1 7172.4 05:25:14 INFO - PROCESS | 2445 | 05:25:14 INFO - PROCESS | 2445 | [#5] yui.html Cycles:180 Average:17937.90 Median:65596.34 stddev:25390.81 (38.7%) stddev-sans-first:25428.98 05:25:14 INFO - PROCESS | 2445 | Values: 681.2 738.5 740.1 752.3 755.2 14540.5 14756.3 15001.7 15007.4 15037.2 13945.6 13997.4 14014.1 14014.7 14029.7 686.4 698.5 828.7 853.6 869.3 854.3 869.4 874.2 876.5 879.2 914.1 915.8 917.8 919.1 923.8 875.0 903.9 912.0 916.1 916.8 670.2 674.7 676.7 677.5 678.6 14533.5 14650.6 14666.6 14718.2 14763.8 12801.7 12925.4 12927.3 13263.3 13380.9 10055.6 10195.5 10256.4 10387.0 10655.8 3580.4 3615.4 3619.6 3633.9 3748.0 77363.2 77371.0 78854.6 78867.2 78935.6 9780.9 9790.3 9932.0 9995.4 10041.8 18833.2 21776.2 21823.1 21826.6 21834.0 5830.7 7577.8 7620.4 7750.2 7802.7 4041.3 4047.0 4063.1 4078.6 4083.3 3509.2 3545.3 3552.9 3555.8 3558.2 649.9 675.8 695.1 704.8 705.9 568.3 602.3 608.2 623.6 628.9 8336.5 8547.3 8574.4 8702.6 8826.3 535.3 567.7 616.6 619.8 624.6 3793.6 4006.9 4017.9 4062.9 4120.6 90174.5 90966.0 91085.0 91421.9 91782.5 77529.0 77646.6 79140.6 79172.0 81287.2 58554.0 66175.3 67076.0 68382.0 69711.8 7956.2 8078.6 8091.6 8234.4 8307.4 49111.8 49672.3 50149.7 50385.7 51203.5 65017.3 68971.0 69332.3 70094.3 70322.3 10202.8 10216.7 10290.6 10456.2 10563.3 9538.5 10614.5 10969.9 11002.4 11041.6 7421.0 10179.1 10256.7 10455.9 10460.4 9064.7 10282.8 10450.9 10548.3 11122.2 7829.8 10031.9 10111.4 10173.6 10426.3 8472.4 8486.0 8543.7 8905.8 8988.0 8905.7 9104.3 9109.7 9138.1 9192.5 05:25:14 INFO - PROCESS | 2445 | -------- Summary: end -------- 05:25:14 INFO - PROCESS | 2445 | 05:25:14 INFO - TEST-INFO | 2445: exit 0 05:25:14 INFO - TEST-OK | dromaeo_css | took 1110627ms 05:25:14 INFO - TEST-START | kraken 05:25:14 INFO - operating with platform_type : mac_ 05:25:14 INFO - Initialising browser for kraken test... 05:25:14 INFO - TEST-INFO | started process 2449 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpmOjMrG/profile http://localhost:50602/getInfo.html) 05:25:15 INFO - PROCESS | 2449 | JavaScript warning: resource://gre/modules/AddonManager.jsm, line 692: Proxy.create and Proxy.createFunction are deprecated, use new Proxy instead 05:25:17 INFO - PROCESS | 2449 | __metrics Screen width/height:1600/1200 05:25:17 INFO - PROCESS | 2449 | colorDepth:24 05:25:17 INFO - PROCESS | 2449 | Browser inner width/height: 1024/689 05:25:17 INFO - PROCESS | 2449 | __metrics 05:25:17 INFO - PROCESS | 2449 | 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:25:18 INFO - PROCESS | 2449 | 05:25:18 INFO - PROCESS | 2449 | ###!!! [Child][MessageChannel] Error: (msgtype=0x2A0045,name=PBrowser::Msg___delete__) Channel closing: too late to send/recv, messages will be lost 05:25:18 INFO - PROCESS | 2449 | 05:25:18 INFO - TEST-INFO | 2449: exit 0 05:25:18 INFO - Browser initialized. 05:25:18 INFO - Running cycle 1/1 for kraken test... 05:25:18 INFO - TEST-INFO | started process 2452 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpmOjMrG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:30 INFO - PROCESS | 2452 | RSS: Main: 254185472 05:25:30 INFO - PROCESS | 2452 | 05:25:33 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50602/tests/kraken/driver.html?testName=audio-beat-detection) 05:25:34 INFO - PROCESS | 2452 | RSS: Main: 268820480 05:25:34 INFO - PROCESS | 2452 | 05:25:36 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50602/tests/kraken/driver.html?testName=audio-dft) 05:25:37 INFO - PROCESS | 2452 | RSS: Main: 276406272 05:25:37 INFO - PROCESS | 2452 | 05:25:40 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50602/tests/kraken/driver.html?testName=audio-fft) 05:25:41 INFO - PROCESS | 2452 | RSS: Main: 280621056 05:25:41 INFO - PROCESS | 2452 | 05:25:43 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50602/tests/kraken/driver.html?testName=audio-oscillator) 05:25:43 INFO - PROCESS | 2452 | RSS: Main: 282128384 05:25:43 INFO - PROCESS | 2452 | 05:25:45 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50602/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:25:46 INFO - PROCESS | 2452 | RSS: Main: 284250112 05:25:46 INFO - PROCESS | 2452 | 05:25:49 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50602/tests/kraken/driver.html?testName=imaging-darkroom) 05:25:49 INFO - PROCESS | 2452 | RSS: Main: 283140096 05:25:49 INFO - PROCESS | 2452 | 05:25:52 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50602/tests/kraken/driver.html?testName=imaging-desaturate) 05:25:53 INFO - PROCESS | 2452 | RSS: Main: 283340800 05:25:53 INFO - PROCESS | 2452 | 05:25:56 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50602/tests/kraken/driver.html?testName=json-parse-financial) 05:25:57 INFO - PROCESS | 2452 | RSS: Main: 284119040 05:25:57 INFO - PROCESS | 2452 | 05:25:58 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50602/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:25:59 INFO - PROCESS | 2452 | RSS: Main: 283971584 05:25:59 INFO - PROCESS | 2452 | 05:26:01 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50602/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:26:01 INFO - PROCESS | 2452 | RSS: Main: 282423296 05:26:01 INFO - PROCESS | 2452 | 05:26:04 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50602/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:26:04 INFO - PROCESS | 2452 | RSS: Main: 284753920 05:26:04 INFO - PROCESS | 2452 | 05:26:07 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50602/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:26:07 INFO - PROCESS | 2452 | RSS: Main: 284188672 05:26:07 INFO - PROCESS | 2452 | 05:26:10 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50602/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:26:10 INFO - PROCESS | 2452 | RSS: Main: 284807168 05:26:10 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | Cycle 1(1): loaded http://localhost:50602/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50602/tests/kraken/driver.html?testName=ai-astar) 05:26:12 INFO - PROCESS | 2452 | RSS: Main: 285057024 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | __start_tp_report 05:26:12 INFO - PROCESS | 2452 | _x_x_mozilla_page_load 05:26:12 INFO - PROCESS | 2452 | _x_x_mozilla_page_load_details 05:26:12 INFO - PROCESS | 2452 | |i|pagename|runs| 05:26:12 INFO - PROCESS | 2452 | |0;ai-astar;113;114;168;167;129;128;113;116;116;115 05:26:12 INFO - PROCESS | 2452 | |1;audio-beat-detection;146;124;143;150;123;141;122;153;145;133 05:26:12 INFO - PROCESS | 2452 | |2;audio-dft;202;195;195;208;225;193;196;199;242;282 05:26:12 INFO - PROCESS | 2452 | |3;audio-fft;72;74;73;108;55;56;58;57;121;74 05:26:12 INFO - PROCESS | 2452 | |4;audio-oscillator;84;80;125;81;80;89;121;83;87;126 05:26:12 INFO - PROCESS | 2452 | |5;imaging-gaussian-blur;75;80;75;80;117;138;121;92;86;92 05:26:12 INFO - PROCESS | 2452 | |6;imaging-darkroom;95;97;149;123;95;126;146;99;143;150 05:26:12 INFO - PROCESS | 2452 | |7;imaging-desaturate;148;128;90;107;78;124;150;95;146;145 05:26:12 INFO - PROCESS | 2452 | |8;json-parse-financial;56;55;60;82;50;55;62;53;49;76 05:26:12 INFO - PROCESS | 2452 | |9;json-stringify-tinderbox;65;67;72;65;72;71;58;75;101;95 05:26:12 INFO - PROCESS | 2452 | |10;stanford-crypto-aes;85;88;84;88;85;87;87;84;86;85 05:26:12 INFO - PROCESS | 2452 | |11;stanford-crypto-ccm;125;123;134;155;119;115;135;113;118;121 05:26:12 INFO - PROCESS | 2452 | |12;stanford-crypto-pbkdf2;132;147;144;140;139;129;139;139;148;168 05:26:12 INFO - PROCESS | 2452 | |13;stanford-crypto-sha256-iterative;70;69;69;67;72;73;91;95;97;71 05:26:12 INFO - PROCESS | 2452 | __end_tp_report 05:26:12 INFO - PROCESS | 2452 | __start_cc_report 05:26:12 INFO - PROCESS | 2452 | _x_x_mozilla_cycle_collect,889 05:26:12 INFO - PROCESS | 2452 | __end_cc_report 05:26:12 INFO - PROCESS | 2452 | __startTimestamp1454765172927__endTimestamp 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | ------- Summary: start ------- 05:26:12 INFO - PROCESS | 2452 | Number of tests: 14 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#0] ai-astar Cycles:10 Average:127.90 Median:122.00 stddev:21.67 (17.8%) stddev-sans-first:22.30 05:26:12 INFO - PROCESS | 2452 | Values: 113.0 114.0 168.0 167.0 129.0 128.0 113.0 116.0 116.0 115.0 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:144.00 stddev:11.63 (8.1%) stddev-sans-first:11.97 05:26:12 INFO - PROCESS | 2452 | Values: 146.0 124.0 143.0 150.0 123.0 141.0 122.0 153.0 145.0 133.0 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#2] audio-dft Cycles:10 Average:213.70 Median:205.00 stddev:28.71 (14.0%) stddev-sans-first:30.14 05:26:12 INFO - PROCESS | 2452 | Values: 202.0 195.0 195.0 208.0 225.0 193.0 196.0 199.0 242.0 282.0 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#3] audio-fft Cycles:10 Average:74.80 Median:65.00 stddev:22.59 (34.8%) stddev-sans-first:23.94 05:26:12 INFO - PROCESS | 2452 | Values: 72.0 74.0 73.0 108.0 55.0 56.0 58.0 57.0 121.0 74.0 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:82.00 stddev:19.84 (24.2%) stddev-sans-first:20.60 05:26:12 INFO - PROCESS | 2452 | Values: 84.0 80.0 125.0 81.0 80.0 89.0 121.0 83.0 87.0 126.0 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#5] imaging-gaussian-blur Cycles:10 Average:95.60 Median:80.00 stddev:22.00 (27.5%) stddev-sans-first:22.03 05:26:12 INFO - PROCESS | 2452 | Values: 75.0 80.0 75.0 80.0 117.0 138.0 121.0 92.0 86.0 92.0 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#6] imaging-darkroom Cycles:10 Average:122.30 Median:122.50 stddev:23.94 (19.5%) stddev-sans-first:23.26 05:26:12 INFO - PROCESS | 2452 | Values: 95.0 97.0 149.0 123.0 95.0 126.0 146.0 99.0 143.0 150.0 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#7] imaging-desaturate Cycles:10 Average:121.10 Median:149.00 stddev:26.90 (18.1%) stddev-sans-first:26.71 05:26:12 INFO - PROCESS | 2452 | Values: 148.0 128.0 90.0 107.0 78.0 124.0 150.0 95.0 146.0 145.0 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:58.00 stddev:10.95 (18.9%) stddev-sans-first:11.53 05:26:12 INFO - PROCESS | 2452 | Values: 56.0 55.0 60.0 82.0 50.0 55.0 62.0 53.0 49.0 76.0 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#9] json-stringify-tinderbox Cycles:10 Average:74.10 Median:71.50 stddev:13.56 (19.0%) stddev-sans-first:13.98 05:26:12 INFO - PROCESS | 2452 | Values: 65.0 67.0 72.0 65.0 72.0 71.0 58.0 75.0 101.0 95.0 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:86.50 stddev:1.52 (1.8%) stddev-sans-first:1.58 05:26:12 INFO - PROCESS | 2452 | Values: 85.0 88.0 84.0 88.0 85.0 87.0 87.0 84.0 86.0 85.0 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#11] stanford-crypto-ccm Cycles:10 Average:125.80 Median:124.00 stddev:12.58 (10.1%) stddev-sans-first:13.34 05:26:12 INFO - PROCESS | 2452 | Values: 125.0 123.0 134.0 155.0 119.0 115.0 135.0 113.0 118.0 121.0 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.50 Median:142.00 stddev:10.74 (7.6%) stddev-sans-first:10.70 05:26:12 INFO - PROCESS | 2452 | Values: 132.0 147.0 144.0 140.0 139.0 129.0 139.0 139.0 148.0 168.0 05:26:12 INFO - PROCESS | 2452 | 05:26:12 INFO - PROCESS | 2452 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.40 Median:72.50 stddev:11.89 (16.4%) stddev-sans-first:12.31 05:26:12 INFO - PROCESS | 2452 | Values: 70.0 69.0 69.0 67.0 72.0 73.0 91.0 95.0 97.0 71.0 05:26:12 INFO - PROCESS | 2452 | -------- Summary: end -------- 05:26:12 INFO - PROCESS | 2452 | 05:26:13 INFO - TEST-INFO | 2452: exit 0 05:26:13 INFO - TEST-OK | kraken | took 58715ms 05:26:13 INFO - SUITE-END | took 1169s 05:26:14 INFO - Completed test suite (00:19:29) 05:26:14 INFO - Working with test: dromaeo_css 05:26:14 INFO - Generating results file: dromaeo_css 05:26:14 INFO - Working with test: kraken 05:26:14 INFO - Generating results file: kraken 05:26:14 INFO - javascript benchmark 05:26:14 INFO - Posting result 0 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:26:14 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,61]] 05:26:14 INFO - process_Request line: dromaeo_css 7364.17 graph.html#tests=[[72,64,61]] 05:26:14 INFO - Posting result 1 of 2 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:26:14 INFO - process_Request line: kraken 1554.50 graph.html#tests=[[232,64,61]] 05:26:14 INFO - RETURN: dromaeo_css: 7364.17 05:26:14 INFO - RETURN: kraken: 1554.50 05:26:14 INFO - javascript benchmark 05:26:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3516.841742121604, 3540.7875527468336, 3581.3911635022046, 3594.71242300616, 3602.297551977364, 5876.823695289143, 5879.617824841383, 5914.024186009303, 5925.170476133339, 5925.85185370366, 4850.34520339754, 5535.944640553594, 5748.396418376073, 5879.11813228016, 5898.115282707586, 5241.056609810233, 5282.788688452477, 5305.602079844008, 5341.545968592674, 5351.785928562853, 5317.574594032468, 5358.517733403993, 5366.624336296461, 5367.919481207785, 5368.62419630624, 5288.788448462076, 5292.126799078147, 5294.49702278283, 5341.839744807662, 5385.838424847261, 5331.2269720890445, 5351.464853514637, 5372.355317361902, 5378.085725426687, 5394.406615272317, 4833.347498087748, 5108.259302401131, 5193.402758682762, 5297.47025297471, 5307.761150748225, 4880.536349046835, 5679.119736440858, 5704.372519022904, 5715.371309155989, 5723.599348045638, 5226.686398816045, 5298.940211957624, 5700.8859822803315, 6263.31103578606, 6289.0, 5138.58891288696, 5749.482546570787, 6188.690565471754, 6267.1539342189135, 6268.905966410507, 5890.175375447441, 5891.086881533326, 5909.704514774244, 5913.763449461973, 5935.73289201987, 6303.432691057735, 6344.175257216532, 6348.968255158695, 6373.0, 6375.139356186948, 6089.543284253698, 6145.125736650275, 6173.290071641729, 6215.192025036717, 6221.035739460391, 6156.076588511759, 6157.384261573807, 6201.379862013762, 6208.379162083756, 6237.719302631393, 6116.78591249313, 6121.270068921596, 6180.289266734293, 6191.690415479208, 6199.380061993855, 6126.334466363025, 6164.907526387108, 6174.629522228681, 6197.4732147767945, 6225.003999360171, 4942.777575009133, 4963.726995015309, 4975.203967365204, 4977.004599080198, 4980.626453016038, 3857.479240302523, 4351.325544540601, 4486.506484286726, 4490.079533695584, 4492.528284530143, 4543.840965566255, 4995.525425084611, 5603.6077474577205, 5906.5570082244, 6039.4262545058145, 6226.128342032119, 6248.4064013918605, 6263.937360626426, 6271.843203919939, 6275.215598050244, 5283.709395983181, 5703.144528320785, 5821.79623713168, 5954.702264886739, 5956.404359564009, 4529.107496470992, 4551.8179272829375, 4629.837955671535, 5416.160495123262, 5435.728213589384, 5399.514043736004, 6355.539153592218, 6377.74489020434, 6404.903926441014, 6430.967845160744, 6052.576319657582, 6192.907106393498, 6226.097215903759, 6247.468965138014, 6270.874582508415, 6372.617642941438, 6376.202974628171, 6416.839579010562, 6426.871462570816, 6438.645874476949, 6282.434580887742, 6284.402981716729, 6287.245530536365, 6294.402031806971, 6304.0, 5177.611679124006, 6150.077488376779, 6180.6291622502795, 6263.843403914939, 6267.811965641038, 6118.5411094169, 6164.229471316085, 6181.0, 6186.0102383618405, 6189.381061893955, 5779.422057794355, 5901.026330655565, 6166.974882487576, 6185.597936134317, 6201.162843016135, 5894.322152952465, 5938.435848594377, 5986.850328741816, 6011.819645410645, 6018.789342372996, 6163.691815409122, 6163.9383606163365, 6173.104899789595, 6183.598066125687, 6204.7828326008375, 4604.240300350539, 5921.970390148194, 6119.2350956130485, 6119.479844213113, 6132.754689812357], "unit": "score", "name": "dojo.html", "value": 5677.208441088257}, {"lowerIsBetter": false, "replicates": [462.870396289039, 488.54809301396244, 498.713239887065, 569.6041251330327, 570.586324914437, 26657.600135997975, 38280.61719382805, 38290.276937537776, 38402.8079859601, 38483.0, 41445.58554414455, 41575.0, 41668.79165604175, 41685.0, 41782.37326440106, 9595.712128636153, 9845.212383009342, 9848.113669769718, 9854.260930430211, 9903.702888913343, 9624.663136790214, 9764.755881102994, 9785.951070244639, 9808.362456440344, 9820.950895245514, 9864.901350986505, 9941.751456213617, 9948.104670579647, 9951.104400603981, 9961.601535938555, 9812.852807207862, 9925.90074099257, 9940.502974851228, 9942.850857237147, 9943.005699430036, 9787.706368808877, 9907.306488545804, 9921.355111917774, 9927.255455840841, 9936.90063099367, 39860.0, 40036.19927601461, 40128.79935600333, 40279.39580906289, 40309.0, 2863.1839925621307, 3133.8589763460473, 3263.461528847737, 3374.7637665363436, 3629.056445324208, 27369.04208352698, 27421.0, 27440.17679469619, 27669.723302766917, 27684.723152768416, 2850.558163484663, 2855.214815925637, 2857.3428111534463, 2882.6396700412447, 2947.572601972702, 283248.0, 336231.0, 336855.0, 337029.99999999756, 337463.0, 50072.74963625159, 57415.85168296611, 61014.38985610087, 64473.03290450647, 64488.0, 68408.6579567099, 68943.3105668947, 69066.65466672635, 69092.654536727, 69368.0, 16246.75629865553, 16469.91765041191, 16472.42346517866, 16480.505584832474, 16502.66994660124, 12373.319467429377, 12401.13192076546, 12492.875071249171, 12568.81146782799, 12573.37133143339, 17611.55971100733, 19362.0, 19710.507237319183, 20673.689894651594, 21147.577048458945, 7968.521888686697, 8142.430029897969, 8165.714200002972, 8172.7956801080445, 8237.917620823715, 971.7376308396834, 976.8339382305026, 988.5008070924281, 1022.7238645565656, 1129.2998341028615, 29449.55825662617, 29681.703182967893, 29900.10299691013, 30212.09363719048, 30505.389892202475, 1095.4905968724581, 1115.6206889657556, 1145.7225193908732, 1258.0250306012913, 1265.2092442223611, 15043.323050462537, 15197.772033419507, 15200.315985780666, 15523.379064837505, 15579.298931548108, 517719.4114029406, 525134.0, 525711.0, 528192.0, 533696.0, 58097.70951145302, 58406.831863362495, 58411.0, 58441.707791461624, 58550.82898342009, 1377.497213517079, 1392.576916165864, 1408.3873515020998, 1424.9715005699827, 1426.6789972256158, 29474.0, 29579.556306654988, 29970.70029299679, 30491.28869833034, 31853.0, 49068.0, 49127.75436122796, 49133.01733965372, 49387.50612493829, 49451.258231125845, 357205.0, 359593.0, 364446.0, 364478.0, 369944.0, 33514.65941362318, 33806.985790426326, 34810.82594587011, 34851.825740871136, 34936.0, 43722.56277437185, 44074.33888491739, 44324.55675443333, 44408.0, 44941.87645308763, 35679.464808027384, 36403.817980909924, 36713.0, 37053.62946370609, 37284.88145355644, 36131.27737445289, 36211.094722632144, 36529.26941461209, 36566.902992910254, 36694.0, 29932.401351973273, 35440.0, 35825.46261806127, 36470.8176459116, 36524.086897827765, 30159.698403016566, 32469.35061298808, 32508.349833002736, 32568.0, 32655.836720816245, 24387.512809617692, 24618.876905615358, 24628.0, 25031.624525632495, 25122.12072577451], "unit": "score", "name": "ext.html", "value": 19421.673219674172}, {"lowerIsBetter": false, "replicates": [281.7703571589153, 320.8187374133614, 368.3094198378041, 369.7485709717392, 372.78564825225504, 15256.38974441021, 16222.756658650129, 19056.809431905676, 19169.0, 20295.39113826586, 18660.720089198672, 19134.0, 19398.321058762947, 19512.31706890259, 19948.50128746782, 5648.124540696199, 5873.500752436027, 5975.223220981266, 5975.641461512301, 5996.190514280582, 3929.0, 4097.549269580344, 5558.916616250759, 5785.508231800281, 6024.0, 5967.462928336449, 5998.790042348519, 6011.57918945674, 6026.035834266519, 6351.079093531435, 6424.935750642504, 6490.643014634194, 6495.902561461582, 6518.217813862343, 6529.314421985718, 5488.807891723771, 5711.742971566289, 5719.113537401709, 5731.083026715747, 5732.541396688255, 12535.24788512692, 14526.273686315642, 15419.845801541953, 23929.0, 24040.9181586829, 2131.6695912133646, 2205.860434614132, 2317.895694693743, 2722.8502432366213, 2776.9166924992282, 9516.381435206758, 9660.420374777535, 10010.749731256705, 12057.758844823054, 13816.93091534536, 1671.7659527666137, 1709.5384246253566, 1725.2667616263163, 1767.2135899524587, 1777.4756446848212, 82515.17484825135, 85395.0, 86024.0, 86380.56809715975, 87628.12371876264, 10887.965643263877, 10989.670309890716, 10995.34027958325, 11054.336739795637, 11146.498407571678, 13283.535076272285, 13308.26804525741, 13320.93339533296, 13330.66673333155, 13433.261170635513, 8367.8326433471, 8374.330053595697, 8454.070052294242, 8475.788105297293, 8486.363522735819, 8448.112948140357, 8527.062023177445, 8549.316054715608, 8572.657093716181, 8592.183742544448, 3708.2768860072565, 4285.46431696038, 4342.5874541918465, 4358.629516491071, 4388.648908087345, 1747.8689098317677, 1798.8291112344568, 1846.292179733741, 1851.333519932823, 1870.9064546772606, 400.2395448647535, 405.02591268000504, 894.369469523984, 895.54327293081, 938.5213541094091, 10006.849897251546, 10141.797164056678, 11264.718382040515, 11630.244034137922, 11694.76610467786, 435.9411479450297, 453.98184072637383, 461.3656222693796, 462.26961400986363, 474.122872685529, 6020.578559500793, 6056.091586262096, 6150.569460137837, 6156.8768624627255, 6188.659623720649, 262204.0, 551492.0, 562994.0, 564810.0, 694024.0, 83574.5821270902, 114618.4269078666, 115784.42107789408, 116910.0, 118390.0, 1142.6000899685043, 1163.2962057954985, 1215.4663106612386, 1217.1662411248324, 1223.8409006829233, 18384.0, 18450.35423760162, 18606.16272267724, 18966.336178233694, 19006.23975040983, 49403.7529812363, 49916.50083499119, 50616.74691626518, 51778.0, 51790.48209518007, 67972.660136699, 69939.20243190216, 70016.0, 70495.29504705092, 71415.0, 7031.824204394727, 7606.619669016638, 7807.453478256468, 7822.413319001212, 7825.530468171928, 9381.0, 10090.69727908164, 11224.607138750105, 11503.827442588201, 11998.820017699567, 8697.782555436164, 10025.649102281384, 10047.648332308334, 10101.343412678152, 10222.846657299997, 9660.32377733552, 9964.402135871871, 10000.149987251165, 10230.335028223142, 10283.22875784334, 9791.0, 10194.643187488402, 10244.231682623982, 10445.582176712845, 10476.528556214836, 7622.618869056636, 7792.37660987131, 7815.804604884924, 7853.685852565834, 7866.331361834308, 8698.391112622056, 10581.576736930436, 10596.735081623021, 10675.786484270426, 10810.459477026274], "unit": "score", "name": "jquery.html", "value": 8517.056338138578}, {"lowerIsBetter": false, "replicates": [1512.848715128487, 1519.490970524874, 1734.193599976011, 1753.4476639858435, 1873.9156737946792, 7855.528668279907, 7944.324732397738, 8671.826563468727, 8838.0, 8841.469511829288, 6652.600843949354, 7881.645325960331, 8350.290225330838, 8463.238308552229, 8582.184692454222, 2450.3751543356466, 2482.453860150764, 2498.8750562471896, 2499.6250562415694, 2500.2624225853324, 2231.3975226688785, 2428.7571242875747, 2495.8252922295446, 2496.488219914922, 2529.165375426114, 2455.545724041049, 2479.5412848622973, 2509.372656835791, 2517.345490172559, 2519.685039370079, 2189.4416923684516, 2457.9262622121278, 2465.0, 2502.8248022638422, 2503.111395454606, 2377.3581133093967, 2378.1557547070715, 2381.1189859752003, 2383.427977285439, 2475.2697954103583, 7863.410244231705, 8765.474071555727, 8804.119588041209, 8822.0, 8834.602442890022, 5382.111951527993, 5459.07195776715, 5478.548184731049, 5489.780408783644, 5503.009458297505, 3657.195417008254, 3734.290484807878, 3782.092297848524, 3800.847966081353, 3803.4294855771577, 1054.8660190295386, 1072.3405105859918, 1076.6447072466067, 1105.9889401106047, 1108.7893300273004, 10075.143612792921, 11664.766704665859, 11965.341906195155, 11973.0, 11973.521059157623, 4898.461169271377, 4909.410870695539, 4974.850754477371, 4987.750612469435, 4992.151334273182, 10186.847197292047, 10204.23468239885, 10213.846792298122, 10226.437545935045, 10241.975802419847, 5454.91817622736, 5491.588130890199, 5501.614886957875, 5502.147167189092, 5505.779768809283, 4366.541513141138, 4522.276435770261, 4803.087413391444, 4938.975305123451, 4988.251762235694, 3928.744631598937, 3968.1071758854605, 3996.18078293949, 4006.258842114218, 4020.155767288902, 1303.387407918277, 1321.709223970725, 1325.7017171136417, 1326.2109045118139, 1331.9134256273312, 1011.3780025284398, 1108.2630051015983, 1280.0847394113255, 1312.6587087357166, 1335.8129861819352, 7850.725224617111, 7883.921160788319, 7949.0, 8036.437449378487, 8100.594970251464, 504.44511037858064, 506.5441103007249, 511.71088335090553, 512.0552580488992, 524.538406202547, 6070.119832624245, 6197.349278325801, 6203.441690247809, 6235.095911092957, 6245.531585131133, 10499.422531760674, 10671.3063650864, 10748.462576871125, 10751.731206719895, 10857.131429485622, 10166.034226748447, 10403.375797452178, 10563.419011954416, 10604.628837990787, 10622.415767132881, 8028.678852845821, 8054.275115239657, 8126.918730812735, 8182.345412366996, 8265.338772898274, 7952.244536768998, 7982.920170798217, 8004.879926800986, 8019.799505012421, 8042.557659328793, 9334.0, 9409.200217981548, 9533.856992145262, 9562.474063926273, 9582.425054496753, 9107.0, 9483.66807161746, 9523.238140948846, 9943.204543636637, 9960.850587241343, 6019.247594050744, 6743.5953842769595, 6868.347506986828, 6923.169219693669, 7210.675519601736, 8047.798805029921, 8106.8783968241705, 8143.18568143181, 8173.6321865515, 8187.754367368989, 7508.136564295176, 7654.923450765644, 7695.307422332017, 7711.575863327571, 7791.0, 6409.583377080475, 7462.4776265660885, 7598.734044308423, 7642.04474440695, 7746.883796742941, 7704.884426733715, 7757.806054848674, 7762.883556746766, 7764.844703105793, 7779.961100194463, 6997.090378250795, 7012.6844292008, 7031.648417579203, 7044.189918159477, 7116.25279345682, 7124.180719217356, 7151.82120446993, 7191.928080719335, 7199.784006479814, 7204.4956853019785], "unit": "score", "name": "mootools.html", "value": 4815.336137341374}, {"lowerIsBetter": false, "replicates": [305.63171378488926, 321.6976042520031, 325.72638983254063, 332.3353293413173, 335.4682827718067, 6541.280459149501, 6687.832804179898, 6742.426893714034, 6767.492438067146, 6820.420264277536, 5112.846614601559, 6677.933220667792, 6702.296258892819, 6719.294474080224, 6760.112935062334, 4993.825216117437, 5014.147594908856, 5031.094403007466, 5042.067217564762, 5052.292679024939, 4525.660575456837, 4625.820415793968, 5032.094223039851, 5033.697978121306, 5060.089183946888, 4724.149653062447, 4884.731339776311, 4892.290617860408, 4896.412430508344, 4907.2884431757375, 4878.000009997942, 4883.804647814083, 4887.095887260868, 4900.436449808264, 4916.852494425173, 3931.783751893645, 4014.417909403149, 4020.6783457323345, 4142.171565686875, 4148.149629325981, 5352.464753524655, 6395.744170233232, 6429.807105786834, 6476.417122459001, 6624.966875165594, 5721.256236689245, 5783.161441590946, 5783.913241301384, 5802.883942321172, 5812.505936995361, 6906.205786334585, 7196.820079498002, 7211.92788072123, 7213.4589905757275, 7234.240404757477, 4622.0293738314995, 4623.583877451012, 4625.8149674013, 4640.628749700016, 4646.744429056401, 7519.473636845423, 7583.81040473987, 7600.1639819619795, 7626.580538070404, 7661.892979430645, 4273.594008569181, 4564.38331687122, 4667.276572131325, 4686.133065382915, 4697.436307643105, 4706.905861882743, 4753.120672675566, 4776.23580227162, 4777.880552986203, 4780.350778981246, 5729.713514324267, 5735.397783232784, 5739.2252045973255, 5739.827805165852, 5766.798162064308, 7239.0, 7241.42068634508, 7256.528325658815, 7273.236310187354, 7293.744718934812, 1580.6996670632543, 1628.8452597003266, 1651.6036151323835, 1657.1714142928536, 1658.5439004273921, 1048.905598496139, 1055.9736006599896, 1064.5422468338534, 1065.3128731967838, 1068.033429746081, 6784.050232967388, 6797.388235058868, 6812.250652428229, 6825.35159159879, 6830.658467076626, 7476.813079673052, 7485.588292643957, 7495.925040749523, 7498.812529686801, 7499.887501687479, 5660.26416565852, 6567.67161641916, 6723.193216814014, 6783.118194634764, 6811.591304521745, 3173.381190667844, 3407.4207384744655, 3852.4413959975645, 3923.9803800980812, 4005.599440055971, 7788.493747906368, 7947.483413578099, 7999.0, 8007.319377852831, 8033.558154301452, 7853.175416581291, 7855.567943763033, 7865.0, 7881.802954926058, 7882.5270483771155, 6141.785037523665, 6231.052376600149, 7833.216678332121, 7968.0, 8001.759947201594, 7574.886376704244, 7575.734849280248, 7591.316781489692, 7609.086909570887, 7621.847563048819, 6084.60450070744, 6699.0, 6870.866307059279, 7188.137423509213, 7436.219196984239, 7969.203079691984, 7973.401994850294, 7973.401994850526, 7982.281594656509, 7984.321332686554, 4203.033302340417, 4709.576138147583, 4719.433667959866, 4736.336912832137, 4741.786619602057, 7090.149182098181, 7302.525335853153, 7528.284812942762, 7549.622518874145, 7556.0, 7258.927410725825, 7267.418606511572, 7278.454115941219, 7287.101400750573, 7302.6713797878165, 7273.272672732684, 7289.380402665833, 7294.890576641248, 7300.488965772345, 7301.503191845765, 6817.386435220854, 7070.363667269765, 7080.929190708233, 7128.572285662877, 7155.248698886542, 7291.52605080668, 7305.41556675454, 7312.780616581511, 7322.707091716272, 7328.461023185191, 6911.550749201286, 7148.821279468055, 7157.7136914522835, 7165.104361954755, 7172.426205903619], "unit": "score", "name": "prototype.html", "value": 5226.916988752048}, {"lowerIsBetter": false, "replicates": [681.1927865479406, 738.4941315199086, 740.0786021403354, 752.300360664582, 755.2145768400864, 14540.491082812103, 14756.262186890654, 15001.699966000688, 15007.39970401184, 15037.248137593104, 13945.58163255104, 13997.370118344674, 14014.08908420952, 14014.719705605881, 14029.719405611882, 686.4028295382999, 698.4517154034085, 828.6809578312333, 853.6030745703247, 869.3219288954625, 854.2695994924344, 869.4348673362333, 874.2175752701329, 876.5441970175505, 879.248242752447, 914.1270087066869, 915.8305713443034, 917.8164367126568, 919.1497864475333, 923.7829110159107, 874.9868751968726, 903.8779764731743, 912.0423555266976, 916.1113719691888, 916.7524768312552, 670.1522573943964, 674.6727836999052, 676.651524464901, 677.478341676906, 678.6335378895391, 14533.491327803582, 14650.633734156625, 14666.63333416673, 14718.190499522521, 14763.77854332186, 12801.679958001125, 12925.418356173996, 12927.288999105045, 13263.27052012139, 13380.929525637926, 10055.648052318134, 10195.49022548877, 10256.43589602579, 10387.013233742782, 10655.84016239757, 3580.4092324766384, 3615.3853844846176, 3619.6199399063244, 3633.94549081764, 3747.962520374816, 77363.22636773616, 77371.0, 78854.60572697157, 78867.21132788656, 78935.60532197302, 9780.902190978142, 9790.265730070274, 9932.0, 9995.400275983464, 10041.799164016678, 18833.246670133314, 21776.237831675815, 21823.127074916825, 21826.563468730856, 21834.0, 5830.650160990354, 7577.848443031108, 7620.390368770485, 7750.186230445722, 7802.726904558313, 4041.2725709372594, 4046.979765101156, 4063.146739184745, 4078.5513593504693, 4083.3466645336603, 3509.175343794206, 3545.308664810389, 3552.875649352312, 3555.7510974231554, 3558.1638314995957, 649.9122618446544, 675.8242856857253, 695.0720787748312, 704.7850405626333, 705.9082319298458, 568.3180183779481, 602.2772672792667, 608.1971797227617, 623.5603899251017, 628.9465395441439, 8336.458130221516, 8547.27348175412, 8574.399792014623, 8702.564871756387, 8826.293896488265, 535.3388565122071, 567.6594043573823, 616.6300219868043, 619.7799781077742, 624.5877720704314, 3793.6016718244737, 4006.9198616027516, 4017.91964160721, 4062.918741625151, 4120.62914337711, 90174.54912725394, 90966.0, 91085.0, 91421.88601512948, 91782.54108729414, 77529.0, 77646.61176694193, 79140.60429697814, 79172.0, 81287.18712812914, 58554.0, 66175.33824661787, 67075.99386009213, 68381.97427038598, 69711.77161712892, 7956.2043795619975, 8078.636461359135, 8091.635876385694, 8234.423590348619, 8307.376946728898, 49111.75444122757, 49672.26147084979, 50149.74625634388, 50385.74807125941, 51203.48796512136, 65017.34982650302, 68970.96543551951, 69332.3066769346, 70094.29905701082, 70322.29677703157, 10202.795944081225, 10216.744581385525, 10290.639827606297, 10456.163506919276, 10563.267911281302, 9538.523073846418, 10614.522346494581, 10969.945150274198, 11002.449877506253, 11041.613543525938, 7420.962895185489, 10179.083882450615, 10256.692299231036, 10455.895441045492, 10460.372377657364, 9064.728058158265, 10282.794344113225, 10450.903938287374, 10548.261621686408, 11122.221444499131, 7829.843403132019, 10031.899681003097, 10111.393316400745, 10173.593056277667, 10426.27016108865, 8472.406931514735, 8486.0, 8543.743687689379, 8905.777355565904, 8988.0, 8905.73282801517, 9104.317176211678, 9109.681161159593, 9138.08619138081, 9192.540372981457], "unit": "score", "name": "yui.html", "value": 6747.237028826045}], "name": "dromaeo_css", "value": 7364.169972421657}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 114.0, 168.0, 167.0, 129.0, 128.0, 113.0, 116.0, 116.0, 115.0], "unit": "score", "name": "ai-astar", "value": 127.9}, {"lowerIsBetter": true, "replicates": [146.0, 124.0, 143.0, 150.0, 123.0, 141.0, 122.0, 153.0, 145.0, 133.0], "unit": "score", "name": "audio-beat-detection", "value": 138.0}, {"lowerIsBetter": true, "replicates": [202.0, 195.0, 195.0, 208.0, 225.0, 193.0, 196.0, 199.0, 242.0, 282.0], "unit": "score", "name": "audio-dft", "value": 213.7}, {"lowerIsBetter": true, "replicates": [72.0, 74.0, 73.0, 108.0, 55.0, 56.0, 58.0, 57.0, 121.0, 74.0], "unit": "score", "name": "audio-fft", "value": 74.8}, {"lowerIsBetter": true, "replicates": [84.0, 80.0, 125.0, 81.0, 80.0, 89.0, 121.0, 83.0, 87.0, 126.0], "unit": "score", "name": "audio-oscillator", "value": 95.6}, {"lowerIsBetter": true, "replicates": [75.0, 80.0, 75.0, 80.0, 117.0, 138.0, 121.0, 92.0, 86.0, 92.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 95.6}, {"lowerIsBetter": true, "replicates": [95.0, 97.0, 149.0, 123.0, 95.0, 126.0, 146.0, 99.0, 143.0, 150.0], "unit": "score", "name": "imaging-darkroom", "value": 122.3}, {"lowerIsBetter": true, "replicates": [148.0, 128.0, 90.0, 107.0, 78.0, 124.0, 150.0, 95.0, 146.0, 145.0], "unit": "score", "name": "imaging-desaturate", "value": 121.1}, {"lowerIsBetter": true, "replicates": [56.0, 55.0, 60.0, 82.0, 50.0, 55.0, 62.0, 53.0, 49.0, 76.0], "unit": "score", "name": "json-parse-financial", "value": 59.8}, {"lowerIsBetter": true, "replicates": [65.0, 67.0, 72.0, 65.0, 72.0, 71.0, 58.0, 75.0, 101.0, 95.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 74.1}, {"lowerIsBetter": true, "replicates": [85.0, 88.0, 84.0, 88.0, 85.0, 87.0, 87.0, 84.0, 86.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.9}, {"lowerIsBetter": true, "replicates": [125.0, 123.0, 134.0, 155.0, 119.0, 115.0, 135.0, 113.0, 118.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.8}, {"lowerIsBetter": true, "replicates": [132.0, 147.0, 144.0, 140.0, 139.0, 129.0, 139.0, 139.0, 148.0, 168.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 142.5}, {"lowerIsBetter": true, "replicates": [70.0, 69.0, 69.0, 67.0, 72.0, 73.0, 91.0, 95.0, 97.0, 71.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 77.4}], "name": "kraken", "value": 1554.5}]} 05:26:14 INFO - TinderboxPrint: TalosResult: {"graphserver": {"kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,61]]", "result": "1554.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,61]]", "result": "7364.17"}}} 05:26:14 INFO - Return code: 0 05:26:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:26:14 INFO - # TBPL SUCCESS # 05:26:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:26:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:26:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:26:14 INFO - 2016-02-06 05:26:14.762 screenresolution[2457:71063] starting screenresolution argv=screenresolution get 05:26:14 INFO - 2016-02-06 05:26:14.765 screenresolution[2457:71063] Display 0: 1600x1200x32@60 05:26:14 INFO - 2016-02-06 05:26:14.770 screenresolution[2458:71066] starting screenresolution argv=screenresolution list 05:26:14 INFO - Available Modes on Display 0 05:26:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:26:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:26:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:26:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:26:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:26:15 INFO - Intel Iris: 05:26:15 INFO - Chipset Model: Intel Iris 05:26:15 INFO - Type: GPU 05:26:15 INFO - Bus: Built-In 05:26:15 INFO - VRAM (Dynamic, Max): 1536 MB 05:26:15 INFO - Vendor: Intel (0x8086) 05:26:15 INFO - Device ID: 0x0a2e 05:26:15 INFO - Revision ID: 0x0009 05:26:15 INFO - Displays: 05:26:15 INFO - Display: 05:26:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:26:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:26:15 INFO - Display Serial Number: 1600x1200 60 05:26:15 INFO - Main Display: Yes 05:26:15 INFO - Mirror: Off 05:26:15 INFO - Online: Yes 05:26:15 INFO - Rotation: Supported 05:26:15 INFO - Return code: 0 05:26:15 INFO - Running post-action listener: _resource_record_post_action 05:26:15 INFO - Running post-run listener: _resource_record_post_run 05:26:16 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 4096; Write bytes: 711745536; Read time: 0; Write time: 2365 05:26:16 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401154560; Write bytes: 396488192; Read time: 30399; Write time: 828 05:26:16 INFO - run-tests - Wall time: 1173s; CPU: 54.0%; Read bytes: 4096; Write bytes: 303723008; Read time: 0; Write time: 1500 05:26:16 INFO - Running post-run listener: _upload_blobber_files 05:26:16 INFO - Blob upload gear active. 05:26:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:26:16 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:26:16 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:26:16 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:26:16 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:26:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:26:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:17 INFO - (blobuploader) - INFO - Done attempting. 05:26:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:26:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:26:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:18 INFO - (blobuploader) - INFO - Done attempting. 05:26:18 INFO - (blobuploader) - INFO - Iteration through files over. 05:26:18 INFO - Return code: 0 05:26:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:26:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:26:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6de4ed71b5d98c721c90ec408164abd3c97bf9b4cc37ebb684ba31ffe5dd2f643a2d9075c79d7b950feea75d14c4d3b5249f4bf2b35bc3e8410937a6fb5fdb39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15f8f7296ebb4e654bd2f8f490a03dc3bb3227ffbd9a56a927f66ce874eb7c8a20196327d29eec3dc8f4589abae85673b3a8e487352900e0b8744b48e80884d6"} 05:26:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:26:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:26:18 INFO - Contents: 05:26:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6de4ed71b5d98c721c90ec408164abd3c97bf9b4cc37ebb684ba31ffe5dd2f643a2d9075c79d7b950feea75d14c4d3b5249f4bf2b35bc3e8410937a6fb5fdb39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15f8f7296ebb4e654bd2f8f490a03dc3bb3227ffbd9a56a927f66ce874eb7c8a20196327d29eec3dc8f4589abae85673b3a8e487352900e0b8744b48e80884d6"} 05:26:18 INFO - Running post-run listener: copy_logs_to_upload_dir 05:26:18 INFO - Copying logs to upload dir... 05:26:18 INFO - mkdir: /builds/slave/test/build/upload/logs 05:26:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.566694 ========= master_lag: 4.80 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-02-06 05:26:23.562921) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-02-06 05:26:23.566836) ========= 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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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/6de4ed71b5d98c721c90ec408164abd3c97bf9b4cc37ebb684ba31ffe5dd2f643a2d9075c79d7b950feea75d14c4d3b5249f4bf2b35bc3e8410937a6fb5fdb39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15f8f7296ebb4e654bd2f8f490a03dc3bb3227ffbd9a56a927f66ce874eb7c8a20196327d29eec3dc8f4589abae85673b3a8e487352900e0b8744b48e80884d6"} 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.011278 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/6de4ed71b5d98c721c90ec408164abd3c97bf9b4cc37ebb684ba31ffe5dd2f643a2d9075c79d7b950feea75d14c4d3b5249f4bf2b35bc3e8410937a6fb5fdb39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15f8f7296ebb4e654bd2f8f490a03dc3bb3227ffbd9a56a927f66ce874eb7c8a20196327d29eec3dc8f4589abae85673b3a8e487352900e0b8744b48e80884d6"}' ========= master_lag: 2.47 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-02-06 05:26:26.052934) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:26:26.053309) ========= 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.CiQ3QfSV8i/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.QwnJTBPoga/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.005379 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-06 05:26:26.130059) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-06 05:26:26.130528) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-06 05:26:26.133594) ========= ========= Total master_lag: 63.29 =========