builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0085 starttime: 1460030713.53 results: success (0) buildid: 20160407044319 builduid: 0ddf8f67f90547549bf699479b665daf revision: 2355485d9a8e09c7cfe47063bd192b72ec34d286 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.529913) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.530329) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.530632) ========= 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.sXIcP37cJM/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.MvraEzTHtE/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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.005098 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.556470) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.557943) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.574620) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.574921) ========= 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.sXIcP37cJM/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.MvraEzTHtE/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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.005366 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.622432) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.625752) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.626109) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.626401) ========= 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.sXIcP37cJM/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.MvraEzTHtE/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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-04-07 05:05:13-- 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% 45.1M=0s 2016-04-07 05:05:13 (45.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163519 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.823411) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.826731) ========= 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.sXIcP37cJM/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.MvraEzTHtE/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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.026348 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:13.875595) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-07 05:05:13.878961) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2355485d9a8e09c7cfe47063bd192b72ec34d286 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2355485d9a8e09c7cfe47063bd192b72ec34d286 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sXIcP37cJM/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.MvraEzTHtE/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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-04-07 05:05:13,965 truncating revision to first 12 chars 2016-04-07 05:05:13,965 Setting DEBUG logging. 2016-04-07 05:05:13,965 attempt 1/10 2016-04-07 05:05:13,965 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2355485d9a8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-07 05:05:14,634 unpacking tar archive at: mozilla-aurora-2355485d9a8e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.097703 ========= master_lag: 0.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-07 05:05:15.169318) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:15.169651) ========= echo 2355485d9a8e09c7cfe47063bd192b72ec34d286 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'2355485d9a8e09c7cfe47063bd192b72ec34d286'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sXIcP37cJM/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.MvraEzTHtE/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 2355485d9a8e09c7cfe47063bd192b72ec34d286 program finished with exit code 0 elapsedTime=0.005844 script_repo_revision: '2355485d9a8e09c7cfe47063bd192b72ec34d286' ========= master_lag: 0.02 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:15.198459) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:15.198816) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-07 05:05:15.216554) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-04-07 05:05:15.216945) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sXIcP37cJM/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.MvraEzTHtE/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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:15 INFO - MultiFileLogger online at 20160407 05:05:15 in /builds/slave/test 05:05:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:05:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:05:15 INFO - {'append_to_log': False, 05:05:15 INFO - 'base_work_dir': '/builds/slave/test', 05:05:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:05:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:05:15 INFO - 'branch': 'Mozilla-Aurora', 05:05:15 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:15 INFO - 'config_files': ('talos/mac_config.py',), 05:05:15 INFO - 'default_actions': ('clobber', 05:05:15 INFO - 'read-buildbot-config', 05:05:15 INFO - 'download-and-extract', 05:05:15 INFO - 'populate-webroot', 05:05:15 INFO - 'create-virtualenv', 05:05:15 INFO - 'install', 05:05:15 INFO - 'run-tests'), 05:05:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:15 INFO - 'download_minidump_stackwalk': True, 05:05:15 INFO - 'download_symbols': 'ondemand', 05:05:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:15 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:15 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:15 INFO - 'installer_path': 'installer.exe', 05:05:15 INFO - 'log_level': 'info', 05:05:15 INFO - 'log_name': 'talos', 05:05:15 INFO - 'log_to_console': True, 05:05:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:05:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:05:15 INFO - 'opt_config_files': (), 05:05:15 INFO - 'pip_index': False, 05:05:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:15 INFO - 'cmd': ('bash', 05:05:15 INFO - '-c', 05:05:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:15 INFO - 'enabled': True, 05:05:15 INFO - 'halt_on_failure': False, 05:05:15 INFO - 'name': 'check_screen_resolution'},), 05:05:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:15 INFO - 'cmd': ('bash', 05:05:15 INFO - '-c', 05:05:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:15 INFO - 'enabled': True, 05:05:15 INFO - 'halt_on_failure': False, 05:05:15 INFO - 'name': 'check_screen_resolution'},), 05:05:15 INFO - 'run_cmd_checks_enabled': True, 05:05:15 INFO - 'sps_profile': False, 05:05:15 INFO - 'sps_profile_interval': 0, 05:05:15 INFO - 'suite': 'dromaeojs', 05:05:15 INFO - 'system_bits': '32', 05:05:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:15 INFO - 'title': 't-yosemite-r7-0085', 05:05:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:15 INFO - 'use_talos_json': True, 05:05:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:05:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:15 INFO - 'work_dir': 'build'} 05:05:15 INFO - ##### 05:05:15 INFO - ##### Running clobber step. 05:05:15 INFO - ##### 05:05:15 INFO - Running pre-action listener: _resource_record_pre_action 05:05:15 INFO - Running main action method: clobber 05:05:15 INFO - rmtree: /builds/slave/test/build 05:05:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:05:17 INFO - Running post-action listener: _resource_record_post_action 05:05:17 INFO - ##### 05:05:17 INFO - ##### Running read-buildbot-config step. 05:05:17 INFO - ##### 05:05:17 INFO - Running pre-action listener: _resource_record_pre_action 05:05:17 INFO - Running main action method: read_buildbot_config 05:05:17 INFO - Using buildbot properties: 05:05:17 INFO - { 05:05:17 INFO - "project": "", 05:05:17 INFO - "product": "firefox", 05:05:17 INFO - "script_repo_revision": "production", 05:05:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 05:05:17 INFO - "repository": "", 05:05:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 05:05:17 INFO - "buildid": "20160407044319", 05:05:17 INFO - "stage_platform": "macosx64", 05:05:17 INFO - "basedir": "/builds/slave/test", 05:05:17 INFO - "buildnumber": 265, 05:05:17 INFO - "platform": "yosemite_r7", 05:05:17 INFO - "mozci_request": { 05:05:17 INFO - "priority": -1, 05:05:17 INFO - "type": "trigger_all_talos_jobs", 05:05:17 INFO - "times": 6 05:05:17 INFO - }, 05:05:17 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:05:17 INFO - "slavebuilddir": "test", 05:05:17 INFO - "branch": "mozilla-aurora", 05:05:17 INFO - "revision": "2355485d9a8e09c7cfe47063bd192b72ec34d286", 05:05:17 INFO - "slavename": "t-yosemite-r7-0085", 05:05:17 INFO - "builduid": "0ddf8f67f90547549bf699479b665daf", 05:05:17 INFO - "repo_path": "releases/mozilla-aurora" 05:05:17 INFO - } 05:05:17 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg. 05:05:17 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.test_packages.json. 05:05:17 INFO - Running post-action listener: _resource_record_post_action 05:05:17 INFO - ##### 05:05:17 INFO - ##### Running download-and-extract step. 05:05:17 INFO - ##### 05:05:17 INFO - Running pre-action listener: _resource_record_pre_action 05:05:17 INFO - Running main action method: download_and_extract 05:05:17 INFO - mkdir: /builds/slave/test/build/tests 05:05:17 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:17 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.test_packages.json 05:05:17 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 05:05:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 05:05:17 INFO - Downloaded 1277 bytes. 05:05:17 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 05:05:17 INFO - Using the following test package requirements: 05:05:17 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:05:17 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:17 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 05:05:17 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:17 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 05:05:17 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:17 INFO - u'jsshell-mac.zip'], 05:05:17 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:17 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 05:05:17 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:05:17 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:17 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 05:05:17 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:17 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 05:05:17 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:17 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 05:05:17 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 05:05:17 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 05:05:17 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 05:05:17 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:05:17 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:17 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip 05:05:17 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 05:05:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:05:18 INFO - Downloaded 17904082 bytes. 05:05:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:05:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:05:21 INFO - Return code: 0 05:05:21 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 05:05:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip 05:05:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 05:05:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:05:21 INFO - Downloaded 17904082 bytes. 05:05:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:05:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:05:24 INFO - Return code: 0 05:05:24 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:24 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.talos.tests.zip 05:05:24 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 05:05:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:05:25 INFO - Downloaded 10845168 bytes. 05:05:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:05:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:05:25 INFO - Return code: 0 05:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:25 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg 05:05:25 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 05:05:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 05:05:28 INFO - Downloaded 115185382 bytes. 05:05:28 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg 05:05:28 INFO - mkdir: /builds/slave/test/properties 05:05:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:05:28 INFO - Writing to file /builds/slave/test/properties/build_url 05:05:28 INFO - Contents: 05:05:28 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg 05:05:28 INFO - Running post-action listener: _resource_record_post_action 05:05:28 INFO - Running post-action listener: set_extra_try_arguments 05:05:28 INFO - ##### 05:05:28 INFO - ##### Running populate-webroot step. 05:05:28 INFO - ##### 05:05:28 INFO - Running pre-action listener: _resource_record_pre_action 05:05:28 INFO - Running main action method: populate_webroot 05:05:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:05:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:05:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:05:28 INFO - '--tppagecycles', 05:05:28 INFO - '7'], 05:05:28 INFO - 'tests': ['tsvgm']}}, 05:05:28 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:05:28 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:05:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:05:28 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 05:05:28 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:05:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:05:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:28 INFO - 'talos_options': ['--e10s'], 05:05:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:28 INFO - 'tests': ['damp', 'tps']}, 05:05:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:28 INFO - 'talos_options': ['--e10s'], 05:05:28 INFO - 'tests': ['damp', 'tps']}, 05:05:28 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:05:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:05:28 INFO - 'other': {'tests': ['a11yr', 05:05:28 INFO - 'ts_paint', 05:05:28 INFO - 'tpaint', 05:05:28 INFO - 'sessionrestore', 05:05:28 INFO - 'sessionrestore_no_auto_restore', 05:05:28 INFO - 'tabpaint']}, 05:05:28 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:05:28 INFO - 'tests': ['a11yr', 05:05:28 INFO - 'ts_paint', 05:05:28 INFO - 'tpaint', 05:05:28 INFO - 'sessionrestore', 05:05:28 INFO - 'sessionrestore_no_auto_restore', 05:05:28 INFO - 'tabpaint']}, 05:05:28 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:05:28 INFO - 'tests': ['a11yr', 05:05:28 INFO - 'ts_paint', 05:05:28 INFO - 'tpaint', 05:05:28 INFO - 'sessionrestore', 05:05:28 INFO - 'sessionrestore_no_auto_restore', 05:05:28 INFO - 'tabpaint']}, 05:05:28 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:05:28 INFO - 'tests': ['a11yr', 05:05:28 INFO - 'ts_paint', 05:05:28 INFO - 'tpaint', 05:05:28 INFO - 'sessionrestore', 05:05:28 INFO - 'sessionrestore_no_auto_restore', 05:05:28 INFO - 'tabpaint']}, 05:05:28 INFO - 'other_l64': {'tests': ['a11yr', 05:05:28 INFO - 'ts_paint', 05:05:28 INFO - 'tpaint', 05:05:28 INFO - 'sessionrestore', 05:05:28 INFO - 'sessionrestore_no_auto_restore', 05:05:28 INFO - 'tabpaint']}, 05:05:28 INFO - 'other_nol64': {'tests': ['a11yr', 05:05:28 INFO - 'ts_paint', 05:05:28 INFO - 'tpaint', 05:05:28 INFO - 'sessionrestore', 05:05:28 INFO - 'sessionrestore_no_auto_restore', 05:05:28 INFO - 'tabpaint']}, 05:05:28 INFO - 'svgr': {'tests': ['tsvgx', 05:05:28 INFO - 'tsvgr_opacity', 05:05:28 INFO - 'tart', 05:05:28 INFO - 'tscrollx', 05:05:28 INFO - 'cart']}, 05:05:28 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:05:28 INFO - 'tests': ['tsvgx', 05:05:28 INFO - 'tsvgr_opacity', 05:05:28 INFO - 'tart', 05:05:28 INFO - 'tscrollx', 05:05:28 INFO - 'cart']}, 05:05:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:28 INFO - 'tests': ['tp5o']}, 05:05:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:28 INFO - 'talos_options': ['--e10s'], 05:05:28 INFO - 'tests': ['tp5o']}, 05:05:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:28 INFO - 'talos_options': ['--xperf_path', 05:05:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:28 INFO - 'tests': ['tp5n']}, 05:05:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:28 INFO - 'talos_options': ['--e10s', 05:05:28 INFO - '--xperf_path', 05:05:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:28 INFO - 'tests': ['tp5n']}}, 05:05:28 INFO - 'talos.zip': {'path': '', 05:05:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:05:28 INFO - Running post-action listener: _resource_record_post_action 05:05:28 INFO - ##### 05:05:28 INFO - ##### Running create-virtualenv step. 05:05:28 INFO - ##### 05:05:28 INFO - Running pre-action listener: _resource_record_pre_action 05:05:28 INFO - Running main action method: create_virtualenv 05:05:28 INFO - Creating virtualenv /builds/slave/test/build/venv 05:05:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:05:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:05:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:05:28 INFO - Using real prefix '/tools/python27' 05:05:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:05:29 INFO - Installing distribute.............................................................................................................................................................................................done. 05:05:32 INFO - Installing pip.................done. 05:05:32 INFO - Return code: 0 05:05:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f89ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8502daeb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f73fd30>, '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 0x10f5a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f736b20>, '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.sXIcP37cJM/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.MvraEzTHtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:05:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sXIcP37cJM/Render', 05:05:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:32 INFO - 'HOME': '/Users/cltbld', 05:05:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:32 INFO - 'LOGNAME': 'cltbld', 05:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:32 INFO - 'MOZ_NO_REMOTE': '1', 05:05:32 INFO - 'NO_EM_RESTART': '1', 05:05:32 INFO - 'PAGER': '/bin/cat', 05:05:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:32 INFO - 'PWD': '/builds/slave/test', 05:05:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:32 INFO - 'SHELL': '/bin/bash', 05:05:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvraEzTHtE/Listeners', 05:05:32 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 05:05:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:32 INFO - 'USER': 'cltbld', 05:05:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:32 INFO - 'XPC_FLAGS': '0x0', 05:05:32 INFO - 'XPC_SERVICE_NAME': '0', 05:05:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:05:32 INFO - Cleaning up... 05:05:32 INFO - Return code: 0 05:05:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f89ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8502daeb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f73fd30>, '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 0x10f5a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f736b20>, '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.sXIcP37cJM/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.MvraEzTHtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:05:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sXIcP37cJM/Render', 05:05:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:32 INFO - 'HOME': '/Users/cltbld', 05:05:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:32 INFO - 'LOGNAME': 'cltbld', 05:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:32 INFO - 'MOZ_NO_REMOTE': '1', 05:05:32 INFO - 'NO_EM_RESTART': '1', 05:05:32 INFO - 'PAGER': '/bin/cat', 05:05:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:32 INFO - 'PWD': '/builds/slave/test', 05:05:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:32 INFO - 'SHELL': '/bin/bash', 05:05:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvraEzTHtE/Listeners', 05:05:32 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 05:05:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:32 INFO - 'USER': 'cltbld', 05:05:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:32 INFO - 'XPC_FLAGS': '0x0', 05:05:32 INFO - 'XPC_SERVICE_NAME': '0', 05:05:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:33 INFO - Downloading/unpacking psutil>=0.7.1 05:05:33 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:33 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:33 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:33 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:33 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:33 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:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:05:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:36 INFO - Installing collected packages: psutil 05:05:36 INFO - Running setup.py install for psutil 05:05:36 INFO - building 'psutil._psutil_osx' extension 05:05:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:05:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:05:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:05:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:05:37 INFO - building 'psutil._psutil_posix' extension 05:05:37 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:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:05:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:05:37 INFO - ^ 05:05:37 INFO - 1 warning generated. 05:05:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:05:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:37 INFO - Successfully installed psutil 05:05:37 INFO - Cleaning up... 05:05:37 INFO - Return code: 0 05:05:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:05:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f89ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8502daeb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f73fd30>, '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 0x10f5a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f736b20>, '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.sXIcP37cJM/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.MvraEzTHtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:05:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:05:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sXIcP37cJM/Render', 05:05:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:37 INFO - 'HOME': '/Users/cltbld', 05:05:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:37 INFO - 'LOGNAME': 'cltbld', 05:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:37 INFO - 'MOZ_NO_REMOTE': '1', 05:05:37 INFO - 'NO_EM_RESTART': '1', 05:05:37 INFO - 'PAGER': '/bin/cat', 05:05:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:37 INFO - 'PWD': '/builds/slave/test', 05:05:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:37 INFO - 'SHELL': '/bin/bash', 05:05:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvraEzTHtE/Listeners', 05:05:37 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 05:05:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:37 INFO - 'USER': 'cltbld', 05:05:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:37 INFO - 'XPC_FLAGS': '0x0', 05:05:37 INFO - 'XPC_SERVICE_NAME': '0', 05:05:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:05:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:05:40 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:40 INFO - Installing collected packages: mozsystemmonitor 05:05:40 INFO - Running setup.py install for mozsystemmonitor 05:05:40 INFO - Successfully installed mozsystemmonitor 05:05:40 INFO - Cleaning up... 05:05:40 INFO - Return code: 0 05:05:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f89ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8502daeb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f73fd30>, '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 0x10f5a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f736b20>, '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.sXIcP37cJM/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.MvraEzTHtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:05:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:05:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sXIcP37cJM/Render', 05:05:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:40 INFO - 'HOME': '/Users/cltbld', 05:05:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:40 INFO - 'LOGNAME': 'cltbld', 05:05:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:40 INFO - 'MOZ_NO_REMOTE': '1', 05:05:40 INFO - 'NO_EM_RESTART': '1', 05:05:40 INFO - 'PAGER': '/bin/cat', 05:05:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:40 INFO - 'PWD': '/builds/slave/test', 05:05:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:40 INFO - 'SHELL': '/bin/bash', 05:05:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvraEzTHtE/Listeners', 05:05:40 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 05:05:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:40 INFO - 'USER': 'cltbld', 05:05:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:40 INFO - 'XPC_FLAGS': '0x0', 05:05:40 INFO - 'XPC_SERVICE_NAME': '0', 05:05:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:40 INFO - Downloading/unpacking blobuploader==1.2.4 05:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:42 INFO - Downloading blobuploader-1.2.4.tar.gz 05:05:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:05:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 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:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:05:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 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:44 INFO - Downloading docopt-0.6.1.tar.gz 05:05:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:05:44 INFO - Installing collected packages: blobuploader, requests, docopt 05:05:44 INFO - Running setup.py install for blobuploader 05:05:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:05:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:05:44 INFO - Running setup.py install for requests 05:05:44 INFO - Running setup.py install for docopt 05:05:45 INFO - Successfully installed blobuploader requests docopt 05:05:45 INFO - Cleaning up... 05:05:45 INFO - Return code: 0 05:05:45 INFO - Installing None 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', '--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 0x10f89ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8502daeb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f73fd30>, '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 0x10f5a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f736b20>, '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.sXIcP37cJM/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.MvraEzTHtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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', '--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:45 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:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sXIcP37cJM/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.MvraEzTHtE/Listeners', 05:05:45 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-30y9Xk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-9AUP8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-zqxjYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-AMXCRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-ju9ekm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-AYEmHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-9GagwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-rV689k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-QVLWE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-GJZ5yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Nv9hBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-C3pqhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Ts0jLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Tv2BGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-_BBQUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:47 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-U1ekGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:47 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-xnvkEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:05:47 INFO - Running setup.py install for manifestparser 05:05:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:05:47 INFO - Running setup.py install for mozcrash 05:05:47 INFO - Running setup.py install for mozdebug 05:05:47 INFO - Running setup.py install for mozdevice 05:05:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:05:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:05:47 INFO - Running setup.py install for mozfile 05:05:47 INFO - Running setup.py install for mozhttpd 05:05:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:05:48 INFO - Running setup.py install for mozinfo 05:05:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:05:48 INFO - Running setup.py install for mozInstall 05:05:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:05:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:05:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:05:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:05:48 INFO - Running setup.py install for mozleak 05:05:48 INFO - Running setup.py install for mozlog 05:05:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:05:48 INFO - Running setup.py install for moznetwork 05:05:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:05:48 INFO - Running setup.py install for mozprocess 05:05:48 INFO - Running setup.py install for mozprofile 05:05:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:05:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:05:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:05:49 INFO - Running setup.py install for mozrunner 05:05:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:05:49 INFO - Running setup.py install for mozscreenshot 05:05:49 INFO - Running setup.py install for moztest 05:05:49 INFO - Running setup.py install for mozversion 05:05:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:05:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:05:49 INFO - Cleaning up... 05:05:49 INFO - Return code: 0 05:05:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f89ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8502daeb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f73fd30>, '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 0x10f5a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f736b20>, '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.sXIcP37cJM/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.MvraEzTHtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sXIcP37cJM/Render', 05:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:49 INFO - 'HOME': '/Users/cltbld', 05:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:49 INFO - 'LOGNAME': 'cltbld', 05:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:49 INFO - 'MOZ_NO_REMOTE': '1', 05:05:49 INFO - 'NO_EM_RESTART': '1', 05:05:49 INFO - 'PAGER': '/bin/cat', 05:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:49 INFO - 'PWD': '/builds/slave/test', 05:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:49 INFO - 'SHELL': '/bin/bash', 05:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvraEzTHtE/Listeners', 05:05:49 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 05:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:49 INFO - 'USER': 'cltbld', 05:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:49 INFO - 'XPC_FLAGS': '0x0', 05:05:49 INFO - 'XPC_SERVICE_NAME': '0', 05:05:49 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/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-MjmyK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-jQ3mfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-e5mNna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-w1O5Yi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-EAliJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-mCcPTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-ffCPws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-f1_pjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-jYcOwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:50 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:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-PhCVzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:51 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-PHjWnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:51 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-OJeH8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:51 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-mESJV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:51 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Xb8c41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:51 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-2DDmep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:51 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Vo0u5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:51 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-DfwgVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:05:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:54 INFO - Downloading blessings-1.6.tar.gz 05:05:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:05:54 INFO - Installing collected packages: blessings 05:05:54 INFO - Running setup.py install for blessings 05:05:54 INFO - Successfully installed blessings 05:05:54 INFO - Cleaning up... 05:05:54 INFO - Return code: 0 05:05:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:05:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:54 INFO - Reading from file tmpfile_stdout 05:05:54 INFO - Current package versions: 05:05:54 INFO - blessings == 1.6 05:05:54 INFO - blobuploader == 1.2.4 05:05:54 INFO - docopt == 0.6.1 05:05:54 INFO - manifestparser == 1.1 05:05:54 INFO - mozInstall == 1.12 05:05:54 INFO - mozcrash == 0.17 05:05:54 INFO - mozdebug == 0.1 05:05:54 INFO - mozdevice == 0.48 05:05:54 INFO - mozfile == 1.2 05:05:54 INFO - mozhttpd == 0.7 05:05:54 INFO - mozinfo == 0.9 05:05:54 INFO - mozleak == 0.1 05:05:54 INFO - mozlog == 3.1 05:05:54 INFO - moznetwork == 0.27 05:05:54 INFO - mozprocess == 0.22 05:05:54 INFO - mozprofile == 0.28 05:05:54 INFO - mozrunner == 6.11 05:05:54 INFO - mozscreenshot == 0.1 05:05:54 INFO - mozsystemmonitor == 0.0 05:05:54 INFO - moztest == 0.7 05:05:54 INFO - mozversion == 1.4 05:05:54 INFO - psutil == 3.1.1 05:05:54 INFO - requests == 1.2.3 05:05:54 INFO - wsgiref == 0.1.2 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/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 0x10f89ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8502daeb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f73fd30>, '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 0x10f5a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f736b20>, '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.sXIcP37cJM/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.MvraEzTHtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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/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:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:05:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sXIcP37cJM/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.MvraEzTHtE/Listeners', 05:05:54 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 - 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:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:05:55 INFO - Cleaning up... 05:05:55 INFO - Return code: 0 05:05:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f89ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8502daeb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f73fd30>, '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 0x10f5a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f736b20>, '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.sXIcP37cJM/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.MvraEzTHtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:05:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:05:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sXIcP37cJM/Render', 05:05:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:55 INFO - 'HOME': '/Users/cltbld', 05:05:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:55 INFO - 'LOGNAME': 'cltbld', 05:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:55 INFO - 'MOZ_NO_REMOTE': '1', 05:05:55 INFO - 'NO_EM_RESTART': '1', 05:05:55 INFO - 'PAGER': '/bin/cat', 05:05:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:55 INFO - 'PWD': '/builds/slave/test', 05:05:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:55 INFO - 'SHELL': '/bin/bash', 05:05:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvraEzTHtE/Listeners', 05:05:55 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 05:05:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:55 INFO - 'USER': 'cltbld', 05:05:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:55 INFO - 'XPC_FLAGS': '0x0', 05:05:55 INFO - 'XPC_SERVICE_NAME': '0', 05:05:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:55 INFO - Downloading/unpacking jsonschema 05:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:05:57 INFO - Downloading/unpacking functools32 (from jsonschema) 05:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:58 INFO - Downloading functools32-3.2.3-2.tar.gz 05:05:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:05:58 INFO - warning: no files found matching '*.txt' 05:05:58 INFO - no previously-included directories found matching 'build' 05:05:58 INFO - no previously-included directories found matching 'dist' 05:05:58 INFO - no previously-included directories found matching '.git*' 05:05:58 INFO - Installing collected packages: jsonschema, functools32 05:05:58 INFO - Running setup.py install for functools32 05:05:58 INFO - warning: no files found matching '*.txt' 05:05:58 INFO - no previously-included directories found matching 'build' 05:05:58 INFO - no previously-included directories found matching 'dist' 05:05:58 INFO - no previously-included directories found matching '.git*' 05:05:58 INFO - Successfully installed jsonschema functools32 05:05:58 INFO - Cleaning up... 05:05:58 INFO - Return code: 0 05:05:58 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:05:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f89ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8a24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8502daeb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f73fd30>, '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 0x10f5a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f736b20>, '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.sXIcP37cJM/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.MvraEzTHtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:05:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sXIcP37cJM/Render', 05:05:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:58 INFO - 'HOME': '/Users/cltbld', 05:05:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:58 INFO - 'LOGNAME': 'cltbld', 05:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:58 INFO - 'MOZ_NO_REMOTE': '1', 05:05:58 INFO - 'NO_EM_RESTART': '1', 05:05:58 INFO - 'PAGER': '/bin/cat', 05:05:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:58 INFO - 'PWD': '/builds/slave/test', 05:05:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:58 INFO - 'SHELL': '/bin/bash', 05:05:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvraEzTHtE/Listeners', 05:05:58 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 05:05:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:58 INFO - 'USER': 'cltbld', 05:05:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:58 INFO - 'XPC_FLAGS': '0x0', 05:05:58 INFO - 'XPC_SERVICE_NAME': '0', 05:05:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:58 INFO - Downloading/unpacking flake8 05:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:06:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:06:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:06:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:06:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:06:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:06:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:06:02 INFO - no previously-included directories found matching 'docs/_build' 05:06:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:06:02 INFO - Running setup.py install for pep8 05:06:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:06:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:06:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:06:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:06:02 INFO - no previously-included directories found matching 'docs/_build' 05:06:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:06:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:06:02 INFO - Cleaning up... 05:06:02 INFO - Return code: 0 05:06:02 INFO - Running post-action listener: _resource_record_post_action 05:06:02 INFO - Running post-action listener: _start_resource_monitoring 05:06:02 INFO - Starting resource monitoring. 05:06:02 INFO - ##### 05:06:02 INFO - ##### Running install step. 05:06:02 INFO - ##### 05:06:02 INFO - Running pre-action listener: _resource_record_pre_action 05:06:02 INFO - Running main action method: install 05:06:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:03 INFO - Reading from file tmpfile_stdout 05:06:03 INFO - Detecting whether we're running mozinstall >=1.0... 05:06:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:06:03 INFO - Reading from file tmpfile_stdout 05:06:03 INFO - Output received: 05:06:03 INFO - Usage: mozinstall [options] installer 05:06:03 INFO - Options: 05:06:03 INFO - -h, --help show this help message and exit 05:06:03 INFO - -d DEST, --destination=DEST 05:06:03 INFO - Directory to install application into. [default: 05:06:03 INFO - "/builds/slave/test"] 05:06:03 INFO - --app=APP Application being installed. [default: firefox] 05:06:03 INFO - mkdir: /builds/slave/test/build/application 05:06:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 05:06:35 INFO - Reading from file tmpfile_stdout 05:06:35 INFO - Output received: 05:06:35 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:06:35 INFO - Running post-action listener: _resource_record_post_action 05:06:35 INFO - ##### 05:06:35 INFO - ##### Running run-tests step. 05:06:35 INFO - ##### 05:06:35 INFO - Running pre-action listener: _resource_record_pre_action 05:06:35 INFO - Running main action method: run_tests 05:06:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:06:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:06:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:06:35 INFO - 2016-04-07 05:06:35.257 screenresolution[1668:7834] starting screenresolution argv=screenresolution get 05:06:35 INFO - 2016-04-07 05:06:35.259 screenresolution[1668:7834] Display 0: 1600x1200x32@60 05:06:35 INFO - 2016-04-07 05:06:35.265 screenresolution[1669:7836] starting screenresolution argv=screenresolution list 05:06:35 INFO - Available Modes on Display 0 05:06:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:06:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:06:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:06:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:06:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:06:35 INFO - Intel Iris: 05:06:35 INFO - Chipset Model: Intel Iris 05:06:35 INFO - Type: GPU 05:06:35 INFO - Bus: Built-In 05:06:35 INFO - VRAM (Dynamic, Max): 1536 MB 05:06:35 INFO - Vendor: Intel (0x8086) 05:06:35 INFO - Device ID: 0x0a2e 05:06:35 INFO - Revision ID: 0x0009 05:06:35 INFO - Displays: 05:06:35 INFO - Display: 05:06:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:06:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:06:35 INFO - Display Serial Number: 1600x1200 60 05:06:35 INFO - Main Display: Yes 05:06:35 INFO - Mirror: Off 05:06:35 INFO - Online: Yes 05:06:35 INFO - Rotation: Supported 05:06:35 INFO - Return code: 0 05:06:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:35 INFO - Python 2.7.3 05:06:35 INFO - Return code: 0 05:06:35 INFO - grabbing minidump binary from tooltool 05:06:35 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:35 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 0x10f73fd30>, '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 0x10f5a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f736b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:35 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:35 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:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:35 INFO - Return code: 0 05:06:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:06:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:06:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:06:36 INFO - Return code: 0 05:06:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0085 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:06:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sXIcP37cJM/Render', 05:06:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:36 INFO - 'HOME': '/Users/cltbld', 05:06:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:36 INFO - 'LOGNAME': 'cltbld', 05:06:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:36 INFO - 'MOZ_NO_REMOTE': '1', 05:06:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:36 INFO - 'NO_EM_RESTART': '1', 05:06:36 INFO - 'PAGER': '/bin/cat', 05:06:36 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:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:36 INFO - 'PWD': '/builds/slave/test', 05:06:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:36 INFO - 'SHELL': '/bin/bash', 05:06:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MvraEzTHtE/Listeners', 05:06:36 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 05:06:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:36 INFO - 'USER': 'cltbld', 05:06:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:36 INFO - 'XPC_FLAGS': '0x0', 05:06:36 INFO - 'XPC_SERVICE_NAME': '0', 05:06:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:06:36 INFO - mozversion application_buildid: 20160406205952 05:06:36 INFO - mozversion application_changeset: 2355485d9a8e09c7cfe47063bd192b72ec34d286 05:06:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:06:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:36 INFO - mozversion application_name: Firefox 05:06:36 INFO - mozversion application_remotingname: firefox-dev 05:06:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:06:36 INFO - mozversion application_vendor: Mozilla 05:06:36 INFO - mozversion application_version: 47.0a2 05:06:36 INFO - mozversion platform_buildid: 20160406205952 05:06:36 INFO - mozversion platform_changeset: 2355485d9a8e09c7cfe47063bd192b72ec34d286 05:06:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:06:36 INFO - mozversion platform_version: 47.0a2 05:06:36 INFO - using testdate: 1460030796 05:06:36 INFO - actual date: 1460030796 05:06:36 INFO - starting webserver on 'localhost:49270' 05:06:36 INFO - SUITE-START | Running 2 tests 05:06:36 INFO - TEST-START | dromaeo_css 05:06:36 INFO - operating with platform_type : mac_ 05:06:36 INFO - Initialising browser for dromaeo_css test... 05:06:37 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpeNJkyf/profile http://localhost:49270/getInfo.html) 05:06:39 INFO - PROCESS | 1687 | __metrics Screen width/height:1600/1200 05:06:39 INFO - PROCESS | 1687 | colorDepth:24 05:06:39 INFO - PROCESS | 1687 | Browser inner width/height: 1024/689 05:06:39 INFO - PROCESS | 1687 | __metrics 05:06:39 INFO - PROCESS | 1687 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:06:39 INFO - PROCESS | 1687 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:06:39 INFO - TEST-INFO | 1687: exit 0 05:06:39 INFO - Browser initialized. 05:06:39 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:40 INFO - TEST-INFO | started process 1689 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpeNJkyf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:51 INFO - PROCESS | 1689 | RSS: Main: 207515648 05:06:51 INFO - PROCESS | 1689 | 05:07:42 INFO - PROCESS | 1689 | 1460030862894 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:09:52 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 05:09:54 INFO - PROCESS | 1689 | RSS: Main: 355143680 05:09:54 INFO - PROCESS | 1689 | 05:12:56 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 05:12:57 INFO - PROCESS | 1689 | RSS: Main: 344997888 05:12:57 INFO - PROCESS | 1689 | 05:15:59 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 05:16:00 INFO - PROCESS | 1689 | RSS: Main: 349089792 05:16:00 INFO - PROCESS | 1689 | 05:19:02 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 05:19:02 INFO - PROCESS | 1689 | RSS: Main: 307638272 05:19:02 INFO - PROCESS | 1689 | 05:22:05 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 05:22:06 INFO - PROCESS | 1689 | RSS: Main: 308375552 05:22:06 INFO - PROCESS | 1689 | 05:25:08 INFO - PROCESS | 1689 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 05:25:09 INFO - PROCESS | 1689 | RSS: Main: 358395904 05:25:09 INFO - PROCESS | 1689 | 05:25:09 INFO - PROCESS | 1689 | __start_tp_report 05:25:09 INFO - PROCESS | 1689 | _x_x_mozilla_page_load 05:25:09 INFO - PROCESS | 1689 | _x_x_mozilla_page_load_details 05:25:09 INFO - PROCESS | 1689 | |i|pagename|runs| 05:25:09 INFO - PROCESS | 1689 | |0;dojo.html;3038.921182980042;3524.2599054198618;3546.893593192204;3561.056320075179;3594.3170797548464;5787.392323806003;5790.68151251681;5792.913106303409;5797.768089276429;5820.738066786994;5756.338021127572;5790.942090579093;5810.89592977334;5813.651180929137;5821.068629019358;5107.668001579886;5166.250893620432;5177.275181474596;5191.610629202806;5291.126964050927;5262.158054711247;5268.578513718893;5271.4464981177;5274.287971123907;5278.261043453919;4377.6935614507;5166.328377310963;5203.271541984125;5223.37319521656;5264.789408423659;5216.7913283468615;5264.9736751315995;5274.657147285453;5284.260203571503;5318.787248510074;4179.895502612459;5175.456577059392;5188.066148093341;5192.909489007313;5210.739463026834;5608.691521966291;5640.492355688008;5650.0959846424785;5665.348484924245;5679.09134538476;6113.506711826627;6280.309165991783;6296.433321001086;6309.747610095591;6318.74725010999;6214.782482613087;6268.780592679234;6273.215848018997;6289.2831173488175;6309.305976342599;5811.128330750379;5818.767249310023;5836.851613418393;5860.208871802252;5976.7908123215475;6190.783322583688;6268.059791031382;6307.5269354798575;6320.115183874262;6325;6172.845678858064;6179.28938172116;6182.814515564541;6183.165272688129;6220.502359811295;6251.156093927261;6262;6265.27949285838;6278.4035516625845;6280.874382512324;6043.12374705674;6114.002540030451;6175.042868355412;6220.160278362454;6223.439890409885;6299.992001279773;6309.873802523924;6331.715072821734;6338.429541341301;6345.90481142774;4948.480409557017;4950.628702847301;4953.405591329064;4959.404871415453;4978.278149668277;4340.348947657813;4448.7108337957925;4458.554144585515;4475.977620111878;4484.439445069366;5662.37713851476;5728.684922329228;5735.942640573624;5774.48029677331;5792.623479473821;6274.435300822948;6287.308396076428;6287.528435367366;6292.842678933063;6302.747890084437;5548;5737.971310143423;5788.305403351625;5817.476427121579;5849.824505264849;5146;5372.247885296062;5380.2736630555155;5382.730863456812;5388.811391601275;6312.621242725451;6330.240371155491;6332.430081292614;6335.461485773577;6336.112944187817;6188.907166392598;6299.08663243818;6308.810735677937;6311.084892690443;6313.873722525615;6199.442050215503;6239.220097487814;6289.465395441439;6340.9365906340345;6352.237731472253;6244.094606281973;6269.62382257066;6272.027835685476;6273.843153921189;6278.089676996901;6252.687365631609;6302.369763023661;6302.810915672537;6310.432061114522;6325.462335701517;5865.765369385177;5949.434803693815;6020.909686354796;6095.116208149705;6129.4483496483745;5964.284285885721;6125.11336343068;6136.64993701385;6146.354632763496;6154.292256390393;5987.550933680079;6018;6028.849278768066;6034.849128771641;6039.5470339723815;6002.549808764273;6029.216201893726;6083.300420451527;6135.325114237341;6146.139540464249;5999.9100013498955;6007.639541627516;6029.939700602938;6030.5778595497895;6032.698365081641 05:25:09 INFO - PROCESS | 1689 | |1;ext.html;499.91268989946855;571.1062187676287;595.1845971019703;660.7456129390184;676.1953275602032;32174.839125804367;32830.835845820795;32889;32919;32978;33958.32083358331;42470;42897.14205715892;42955.57044429563;42965.78517107418;9583.712488625335;9728.367656102366;9742.171915387165;9746.463944483086;9788.119069283763;9643.80712385752;9773.853392199122;9789.070038346346;9793.167580755644;9800.803983920317;9786.461744604045;9856.90143098567;9861.063198996084;9863.457509836957;9863.802723945517;9732.270079744012;9934.25493088014;9938.9503052485;9939.950300248453;9942;9686.273529485246;9715.46564938928;9720.95139524305;9801.607935682636;9818.45998470084;42803.35794963078;43087.49193778234;43172.78413607912;43190.568094319286;43233.567664323265;3117.5011998080427;3270.329582435595;3327.085051610802;3343.3981883261004;3356.7314614830757;23624;28669;29674.554881676577;30195;30360.69639303623;2721.1020363280268;2768.0588599875937;2811.086396921009;2836.9007084752143;2879.3809330993727;464813;467441;467597.9999999966;467749.32250677736;469016;49033.50966490289;72636.63681681558;72948;73160.63419682973;73301.6334918322;48154.75922620364;48234.758826205645;48367.51632483701;48475.03049938982;48623.27065094026;16331.183440828148;16692.58268543296;16716;16720.331186752635;16743.58141046459;13220.867791321964;13267;13297.135686180367;13306.534271300652;13309.600711978655;15321.23393830304;15356;15438.9228053859;15454.304556294994;15502.9224853875;5568.638038527482;6423.839404014937;6549.9345006550275;6580.21037475506;6587.835304117435;1013.8276492996208;1022.3967858963248;1029.459533744775;1086.462201210406;1168.5617893290016;28491.715082848907;29465.705342946298;29712;29773.553396699066;30814.845925770675;982.4792859784326;1081.4106312059946;1161.988380116205;1193.952241910314;1201.5674357231564;14917.925410372878;15037.699246015018;15093.849061509245;15490.535283941499;15496.767548486781;538731;772855;778075;780064;781810;65076;65794.05235685874;65816;65983;66737;1440.265464613055;1468.1778204205893;1585.6749366379865;1606.3574570172025;1720.7332863406193;27161.728382716712;29517.704822952353;30546.694533054386;32916;33738.3252334947;59892;60225.39774602373;60326;60446.395536045835;60529.0920636182;471968.640156797;472816;474732;474773;475391;27494.450110998067;34774.47838282478;34898.82550587231;35257;35328.64671353254;42953.926151845204;44348;44660.776696116314;44772.77613611911;45457.54542454533;36769.448458273684;37016.25967480689;37191.814040929625;37655.62344376631;37782;36158.63841361658;36468;36911;36996.815015924745;37139;36522.8173859129;36959.44560831536;37774.62225377711;39443.83058931671;41428;31290.435953410113;34081.829590851885;34218;34519;34562;25414.364640884127;25724.356891077874;25790.766305531928;26094.956201752477;26247.868760656074 05:25:09 INFO - PROCESS | 1689 | |2;jquery.html;383.80042377963457;384.51167017887286;385.89002134391706;386.52071431425026;390.1027636436196;16525.586860328498;16909.91545042273;19617.411477655674;20692.89653551734;21195.788042119573;20421.48946276344;20923.372298831022;21533.353999380004;21981.34055978323;22001.66997495039;5600.299962504687;5798.855028624297;5870.26621672291;5871.530277577784;5878.559108066891;4340.479142502904;5951.970240148793;5982.3120341160675;6315.778947736831;6584.407403333699;6498.187726534184;6518.185226846645;6519.8044058678315;6538.542302038859;6543.607383556978;5731.4555117264;5971.581989260753;5983.282006159246;5994.640321580676;6488.93511064888;5711.143328500717;5711.343195532525;5715.857103572403;5721.856953576153;5733.684647344395;13846.584602461942;17443.825561744346;21439.67840482394;26131.82406791699;26152.34619134518;2130.62714025045;2185.8032777049984;2191.7808219178114;2210.4473881529616;2278.7493375728654;9762.219022478255;9806.704379634988;9889.703308900744;10265.538050787733;10412.958704129527;1680.6134589044589;1726.5597272695509;1765.9646807063787;1858.1452531835373;1915.7030660247683;86459;99154.51268230983;103032;110451;112824.43587782091;11299.943500282447;11387.202895797214;11524.36615986112;11561.479733412016;11567.76864462723;11263.549458021587;12339;13423.664408389868;14543.490977815725;14580.1980891052;7316.743913962986;7383.187849336568;8883.156100170474;8906;9007.594658240396;9021.097890210927;9034.728958131267;9060.957989831253;9102.81794364109;9166.67916622915;4495.100979804052;4564.269716845289;4566.109608626286;4587.8623641290815;4606.608438282784;1890.8770929889788;1911.7897031326543;1941.805819418075;1943.1838627776367;1983.5239542509983;942.886853577575;954.2593035301634;960.0159836167844;975.7463059604554;1068.3643232276784;18388.816111838976;20457.89771051135;20963;21172.835494047977;21186.894065529574;894.5169608411516;951.2675240065112;1034.730969947804;1061.7186445591924;1075.0700643942932;9053.77365565853;9132.634694612274;11188.440577971069;11336.6032188873;11337.26307789991;439093;448786;524091;525205.617803375;525642;101507;116031;129137.70862291445;132863;136528;469.7956388970809;485.78625404821764;487.7804987755524;489.307629703972;491.2778216022441;9841.311108222355;11199.656684913323;11220.38287894141;11502.252353596989;11777.705557361134;52740.736296318275;52860.47139528555;53462.66343341448;53945.46054539561;54296.18555721746;77328.61335693285;77556.61221693855;78466;80593;80663.3867322662;7989.161138080418;8281.585920703821;8428.199321064489;8472.957635211784;8486.278666313432;9610.51947402613;13203.373496382437;13383.598492045254;13512.189268643913;13849.892008639083;11365.147613929154;11541.769164616828;11637.476313565741;11858.051355891681;11963.461644225858;11326.830097548378;11363.090952723927;11483.425828708698;11496.597619083292;11727.472263748323;10253.948730256301;11466.197366184288;11714.297142171497;11851.170418070653;11895.821562676394;8171.5505647189975;8281.668733250603;8350.958245208734;8523.318134549098;8599.312055035707;11236.83144752813;11655.475503602527;11870.347130907883;11952.940235298767;11966.820497692715 05:25:09 INFO - PROCESS | 1689 | |3;mootools.html;1491.4928924165781;1609.2114863716781;1629.3075442936752;1961.9705704414437;2062.236972320242;8124.553149576771;8986.37095403322;8993.505357205351;9026.097390260971;9034;8577.14228577142;8823.779405514866;8831.602577883994;8835.293176545876;8845.248153906925;2183.301343570058;2482.416632091457;2506.410993416546;2513.40934880303;2538.060917460533;2542.6694529711112;2550.0947163756878;2551.2091251711936;2556.0542599238215;2558.3859873630286;2426.7694569016003;2539.5301869154173;2540.6697129373156;2548.439343344461;2550.1074623881677;2226.421130506072;2490.887910044052;2522.016413598698;2522.962155567668;2547.8343907646035;2229.665550167472;2504.9874750626313;2532.392225865779;2532.6201069839485;2571.100114959768;8822;8874.822503549958;8880.911190888073;8881.911180888237;8884.067172946812;5368.570514358881;5531.419200983879;5565.026120428909;5568.805091821767;5571.470710282517;3571.74997750155;3651.8721844735574;3682.4660424238336;3697.482352470656;3718.7954662493553;1030.9123724483427;1044.8693913260843;1057.2493529593999;1061.7186445591924;1097.7255686078481;11909.821352679717;11985.940070299679;11986.520539178422;11995.16033877629;11998.10014248926;4810.254410566367;4832.106060378771;4832.226843704991;4863.2461968394955;4910.852674419773;10137.847932281022;10144.746381340525;10168.440735759455;10170.440625765654;10184.949075254724;4679;5456.563474921997;5513.862153446115;5516.668999860021;5519.39286678465;4528.592426681548;4632.050429661911;4916.508349165127;4947.777350019258;5016.372953380827;3940.7438516496336;4011.7793521356607;4024.1951609677594;4056.8782936511952;4063.5733248009124;1116.7375666718315;1321.2072756346308;1324.423875614111;1325.3770727757937;1341.2086868747485;1388.7083712420304;1394.0799072612028;1423.053669309918;1442.4230307876933;1447.0883343493742;8484.27883629886;8505;8528.232459078712;8538.01812791537;8542.957285213533;520.3963402453136;529.2141170362087;1305.764962306794;1350.338334216227;1351.094766506443;5327.600429967768;5462.672239665553;6343;6369.490440764727;6389.584676995981;10530.420826854443;10536.631217907336;10786.78426431467;10808.297460665031;10813.134949204203;9255.537223138816;10625.628103016357;10664.200184986157;10666.040056394962;10706.197035222234;7197.532160409557;8475.067742548315;8491.15088491146;8678.913210867937;8719.12808719123;8039.678412863654;8086.5148091114725;8109;8123.75628731139;8125.512469251864;9736.513174341397;9791.804163916539;9912.553935073083;9937.751556210864;9984;10179.796404072025;10258.076773090457;10259.97400259968;10464.215183861088;10490.842637360292;6467.288598254094;6543.5419520633095;6981.965090174516;7103.786886393416;7215.60314182725;8311.6675332986;8376.49741015541;8380.58097095155;8405.32757379396;8407.033191183093;7250.274972502708;7305.415566754753;7324.780256592311;7468.402527797654;7488.363489103487;6271.216097987752;7263.0921134858145;7359.264073592812;7363.668634911334;7366.373858222111;7698.92301076982;7800.921990780246;7800.960995194988;7809.531428114331;7820.530768153929;6821.045053692388;6830.044241189004;6899.068625735662;6910.032595436543;6930.06444130036;7379.188289288281;7433.628318583941;7452.441066919894;7472.514286571355;7483.251674832473 05:25:09 INFO - PROCESS | 1689 | |4;prototype.html;324.57804853690203;331.1984996308932;334.33801073873747;335.2507146527511;339.0658735184566;5300.840974770763;6465.868473017221;6493.740250389985;6504.512161587876;6568.671566421684;6422.518311126672;6431.9035214471705;6433.195850518685;6434.0670602762575;6467.2885982542;4576.519465456129;4653.88365290867;4711.0342379812055;4784.210605250147;4799.592034677056;4311.542420820134;4395.252807022798;4739.336492890998;4779.9962007978365;4791.401074865642;4739.123262196487;4740.928886066712;4745.691530050535;4746.145693775119;4754.595859351942;4525.072360166158;4623.491415944261;4751.524847515256;4772.832950846704;4777.211760059586;4082.2856000199845;4090.550039495653;4092.508898932147;4094.2220978014084;4108.383742438628;6168.660723660197;6194.5973511721595;6211.34780848009;6239.2824825145235;6244.344343843876;5636.971815140939;5643.887122257532;5650.74571644277;5654.208410822488;5662.263905692274;6711.6595711286445;6909.7581584644795;6911.101556797635;6915.861682766367;6930.480213983921;4551.931721024187;4635.976820115911;4636.35091087248;4657.324687920266;4660.627149828006;6638.344662135185;7253.310935461122;7322.96338518304;7332.853342933165;7342.9632851835395;4351.934720979188;4741.596964258008;4742.52574742523;4745.928811067837;4752.976235118871;4778.593819525309;4791.784369703371;4793.0413917216;4805.447373552016;4805.639577031737;4669.7431641260055;5655.858603534863;5664.68844213564;5668.149777533321;5671.829845104653;6664.500162487736;6916.412104971134;6931.861362772817;6951.6871740771785;6968.651567421609;1584.881133914938;1586.1672621873379;1598.19291257914;1599.872010239178;1600.5994754589733;933.3419938943028;940.4263399326405;1009.7828966772179;1010.3937637417491;1031.2265800649513;5339.279197308391;6370.267419246846;6508.707108180143;6519.576227545194;6539.169032670859;7251.150956506162;7260.9636951815955;7296.3433291003;7296.525725827804;7314.195438501761;6530.987696906987;6537.280899101095;6548.083268342436;6549.803505894831;6551.934480655133;3761.435784632327;3791.829367678461;3816.790076545761;3818.2745278397015;3827.923441531154;5995;7303.087114110736;7548.056492938383;7551.697932082655;7564.621768911533;5458.536024437888;6022.638641681513;6033.879322413527;7463.738769143162;7474.738384156529;7522.699092036476;7525.849483010309;7531.740477856731;7538.849223015509;7542.83086121663;7200.1719802223415;7201.855962880608;7225.385842203472;7229.783106506813;7236.565806051653;6377.489800816016;6947.756828510978;7056.682449289691;7070.328318809705;7086.397656199075;7424.554526728413;7478.1774004858335;7489.101307843093;7489.251074892467;7498.175200728025;4541.772911354486;4594.724316541018;4595.287730401793;4603.148417542765;4605.286180642006;7181.748638797617;7190.928090719026;7202.3877970373105;7209.71161153548;7210.89183662256;6709.52390474095;6937.7571784987285;6981.650917454209;6985.26654701249;7006.264342244195;6908.511201843037;6941.2364639888565;6941.409980151744;6974.965125174342;6995;6808.489363297872;6819.69311380999;6825.2833452486775;6834.2482326943;6835.1114355135505;6717.462602991846;6959.095317608679;6971.5468494547695;6978.930210698031;6983.162020557363;5693.886122277614;6516.609003459807;6542.738290468328;6726.293739157318;6754.797356079325 05:25:09 INFO - PROCESS | 1689 | |5;yui.html;720.2725247500025;738.7082102569489;769.1539306762562;787.0987719061678;817.3706246190434;12632.494700212004;14894.702105957875;14925.62685932851;15165.772513412294;15253.466128685499;14066.718665626682;14151.575452736435;14155.079919805205;14175.858241417583;14190.71618567628;813.4793732011487;830.4145577367972;978.6623614852882;998.4558415663481;1003.0471052500118;1014.5941623350645;1017.7760892603652;1019.4749703902459;1021.019820971868;1027.0294571629813;1069.9781708468395;1078.3044936016265;1079.3685693869097;1080.0063941174099;1081.0594782539201;841.5161282262693;954.885413750351;989.747614358334;995.3729150635054;1048.0305717211586;671.6977360187886;778.8598052350575;785.4109417936547;786.8426314737018;792.9762107136795;14979.325930333162;15032.248387580687;15113.924430377887;15116.09303441786;15130.016548924285;12955.546555870593;13031.283279419627;13055.804162937564;13248.337583120805;13346.666333341647;10056.44689542075;10123.797524049478;10144.087032167141;10291.33106348085;10344.482775861177;3539.982300088509;3546.8935931922083;3553.502509648651;3604.42329227325;3811.123441608418;73266.9009964851;73358.63320683362;73454.63272683602;73533.26466735317;73537;9516.095970882756;9619.51902404877;9693.903060969442;9750;9766.07222313879;22274.554508910056;22433.102675893108;22473;22485.4378640532;22487.66268505974;7267.30960558746;7316.341529262286;7457.366123879422;7534.133574638987;7588.165301816795;4067.7966101694797;4084.571120032413;4126.195391898611;4252.425922500484;4302.376155457504;3598.1724203433355;3605.6394360563736;3626.9455958160647;3640.6541378568995;3647.3981793004386;532.7176596403912;603.6891001134419;617.3178637605471;620.1906512001881;624.3631495874183;589.6933594530819;591.8313280714975;601.9097135429633;624.6595605395071;628.0641843653016;7206.855862882713;7235.421166306683;7322.926770732331;7537.2085930977555;7656.770296891103;547.5072434808704;581.3372755059236;593.8752861898963;627.1815281058263;629.6945981199115;3754.9436758448646;3774.4149656802692;3830.616938306203;3853.7687738735203;3860.7876566788536;87544;88125.559372204;88439;88898.55550722204;88978.55510722405;75421.62289188518;77663;78553.60723196347;79345.60327198327;79775;42709.57290427056;52842;67782;67912.66043669848;69650.65174674094;7402.777916662509;7542.773716788286;7614.505057171266;7699.922010918363;7907.56508392021;47836;48168.55494335176;48954;49801.50198497969;49976.00047999092;63082;68537.31462685509;68560;69603;69993.9500907497;10144.695659130239;10281.331713438603;10364.792704145724;10512.894871051192;10798.838017429902;10357.482125893826;10456.222442184695;10617.362958222531;10712.410817405118;10949.616763413242;10133.240006999651;10168.237382196512;10170.288079834341;10302.484875756332;10352.585896564053;10163;10240.48797560134;10313.639022634172;10319.535620897228;10456.26806123564;9257.25941924631;9933.850992234979;10050.346727462691;10194.133498652698;10287.84568231462;8686.869696954676;8768.167024132697;8845;8901.243394311297;8945.865812012955;8739.519326437106;8794.384393092421;8870.467771933705;8957.641694332153;8990.82018359616 05:25:09 INFO - PROCESS | 1689 | __end_tp_report 05:25:09 INFO - PROCESS | 1689 | __start_cc_report 05:25:09 INFO - PROCESS | 1689 | _x_x_mozilla_cycle_collect,1365 05:25:09 INFO - PROCESS | 1689 | __end_cc_report 05:25:09 INFO - PROCESS | 1689 | __startTimestamp1460031909077__endTimestamp 05:25:09 INFO - PROCESS | 1689 | 05:25:09 INFO - PROCESS | 1689 | ------- Summary: start ------- 05:25:09 INFO - PROCESS | 1689 | Number of tests: 6 05:25:09 INFO - PROCESS | 1689 | 05:25:09 INFO - PROCESS | 1689 | [#0] dojo.html Cycles:165 Average:5766.38 Median:6018.00 stddev:654.21 (10.9%) stddev-sans-first:620.25 05:25:09 INFO - PROCESS | 1689 | Values: 3038.9 3524.3 3546.9 3561.1 3594.3 5787.4 5790.7 5792.9 5797.8 5820.7 5756.3 5790.9 5810.9 5813.7 5821.1 5107.7 5166.3 5177.3 5191.6 5291.1 5262.2 5268.6 5271.4 5274.3 5278.3 4377.7 5166.3 5203.3 5223.4 5264.8 5216.8 5265.0 5274.7 5284.3 5318.8 4179.9 5175.5 5188.1 5192.9 5210.7 5608.7 5640.5 5650.1 5665.3 5679.1 6113.5 6280.3 6296.4 6309.7 6318.7 6214.8 6268.8 6273.2 6289.3 6309.3 5811.1 5818.8 5836.9 5860.2 5976.8 6190.8 6268.1 6307.5 6320.1 6325.0 6172.8 6179.3 6182.8 6183.2 6220.5 6251.2 6262.0 6265.3 6278.4 6280.9 6043.1 6114.0 6175.0 6220.2 6223.4 6300.0 6309.9 6331.7 6338.4 6345.9 4948.5 4950.6 4953.4 4959.4 4978.3 4340.3 4448.7 4458.6 4476.0 4484.4 5662.4 5728.7 5735.9 5774.5 5792.6 6274.4 6287.3 6287.5 6292.8 6302.7 5548.0 5738.0 5788.3 5817.5 5849.8 5146.0 5372.2 5380.3 5382.7 5388.8 6312.6 6330.2 6332.4 6335.5 6336.1 6188.9 6299.1 6308.8 6311.1 6313.9 6199.4 6239.2 6289.5 6340.9 6352.2 6244.1 6269.6 6272.0 6273.8 6278.1 6252.7 6302.4 6302.8 6310.4 6325.5 5865.8 5949.4 6020.9 6095.1 6129.4 5964.3 6125.1 6136.6 6146.4 6154.3 5987.6 6018.0 6028.8 6034.8 6039.5 6002.5 6029.2 6083.3 6135.3 6146.1 5999.9 6007.6 6029.9 6030.6 6032.7 05:25:09 INFO - PROCESS | 1689 | 05:25:09 INFO - PROCESS | 1689 | [#1] ext.html Cycles:180 Average:69346.46 Median:37715.12 stddev:153667.02 (407.4%) stddev-sans-first:154011.15 05:25:09 INFO - PROCESS | 1689 | Values: 499.9 571.1 595.2 660.7 676.2 32174.8 32830.8 32889.0 32919.0 32978.0 33958.3 42470.0 42897.1 42955.6 42965.8 9583.7 9728.4 9742.2 9746.5 9788.1 9643.8 9773.9 9789.1 9793.2 9800.8 9786.5 9856.9 9861.1 9863.5 9863.8 9732.3 9934.3 9939.0 9940.0 9942.0 9686.3 9715.5 9721.0 9801.6 9818.5 42803.4 43087.5 43172.8 43190.6 43233.6 3117.5 3270.3 3327.1 3343.4 3356.7 23624.0 28669.0 29674.6 30195.0 30360.7 2721.1 2768.1 2811.1 2836.9 2879.4 464813.0 467441.0 467598.0 467749.3 469016.0 49033.5 72636.6 72948.0 73160.6 73301.6 48154.8 48234.8 48367.5 48475.0 48623.3 16331.2 16692.6 16716.0 16720.3 16743.6 13220.9 13267.0 13297.1 13306.5 13309.6 15321.2 15356.0 15438.9 15454.3 15502.9 5568.6 6423.8 6549.9 6580.2 6587.8 1013.8 1022.4 1029.5 1086.5 1168.6 28491.7 29465.7 29712.0 29773.6 30814.8 982.5 1081.4 1162.0 1194.0 1201.6 14917.9 15037.7 15093.8 15490.5 15496.8 538731.0 772855.0 778075.0 780064.0 781810.0 65076.0 65794.1 65816.0 65983.0 66737.0 1440.3 1468.2 1585.7 1606.4 1720.7 27161.7 29517.7 30546.7 32916.0 33738.3 59892.0 60225.4 60326.0 60446.4 60529.1 471968.6 472816.0 474732.0 474773.0 475391.0 27494.5 34774.5 34898.8 35257.0 35328.6 42953.9 44348.0 44660.8 44772.8 45457.5 36769.4 37016.3 37191.8 37655.6 37782.0 36158.6 36468.0 36911.0 36996.8 37139.0 36522.8 36959.4 37774.6 39443.8 41428.0 31290.4 34081.8 34218.0 34519.0 34562.0 25414.4 25724.4 25790.8 26095.0 26247.9 05:25:09 INFO - PROCESS | 1689 | 05:25:09 INFO - PROCESS | 1689 | [#2] jquery.html Cycles:180 Average:31249.24 Median:384.16 stddev:83165.60 (21648.9%) stddev-sans-first:83366.61 05:25:09 INFO - PROCESS | 1689 | Values: 383.8 384.5 385.9 386.5 390.1 16525.6 16909.9 19617.4 20692.9 21195.8 20421.5 20923.4 21533.4 21981.3 22001.7 5600.3 5798.9 5870.3 5871.5 5878.6 4340.5 5952.0 5982.3 6315.8 6584.4 6498.2 6518.2 6519.8 6538.5 6543.6 5731.5 5971.6 5983.3 5994.6 6488.9 5711.1 5711.3 5715.9 5721.9 5733.7 13846.6 17443.8 21439.7 26131.8 26152.3 2130.6 2185.8 2191.8 2210.4 2278.7 9762.2 9806.7 9889.7 10265.5 10413.0 1680.6 1726.6 1766.0 1858.1 1915.7 86459.0 99154.5 103032.0 110451.0 112824.4 11299.9 11387.2 11524.4 11561.5 11567.8 11263.5 12339.0 13423.7 14543.5 14580.2 7316.7 7383.2 8883.2 8906.0 9007.6 9021.1 9034.7 9061.0 9102.8 9166.7 4495.1 4564.3 4566.1 4587.9 4606.6 1890.9 1911.8 1941.8 1943.2 1983.5 942.9 954.3 960.0 975.7 1068.4 18388.8 20457.9 20963.0 21172.8 21186.9 894.5 951.3 1034.7 1061.7 1075.1 9053.8 9132.6 11188.4 11336.6 11337.3 439093.0 448786.0 524091.0 525205.6 525642.0 101507.0 116031.0 129137.7 132863.0 136528.0 469.8 485.8 487.8 489.3 491.3 9841.3 11199.7 11220.4 11502.3 11777.7 52740.7 52860.5 53462.7 53945.5 54296.2 77328.6 77556.6 78466.0 80593.0 80663.4 7989.2 8281.6 8428.2 8473.0 8486.3 9610.5 13203.4 13383.6 13512.2 13849.9 11365.1 11541.8 11637.5 11858.1 11963.5 11326.8 11363.1 11483.4 11496.6 11727.5 10253.9 11466.2 11714.3 11851.2 11895.8 8171.6 8281.7 8351.0 8523.3 8599.3 11236.8 11655.5 11870.3 11952.9 11966.8 05:25:09 INFO - PROCESS | 1689 | 05:25:09 INFO - PROCESS | 1689 | [#3] mootools.html Cycles:180 Average:6106.34 Median:4932.14 stddev:3214.78 (65.2%) stddev-sans-first:3205.08 05:25:09 INFO - PROCESS | 1689 | Values: 1491.5 1609.2 1629.3 1962.0 2062.2 8124.6 8986.4 8993.5 9026.1 9034.0 8577.1 8823.8 8831.6 8835.3 8845.2 2183.3 2482.4 2506.4 2513.4 2538.1 2542.7 2550.1 2551.2 2556.1 2558.4 2426.8 2539.5 2540.7 2548.4 2550.1 2226.4 2490.9 2522.0 2523.0 2547.8 2229.7 2505.0 2532.4 2532.6 2571.1 8822.0 8874.8 8880.9 8881.9 8884.1 5368.6 5531.4 5565.0 5568.8 5571.5 3571.7 3651.9 3682.5 3697.5 3718.8 1030.9 1044.9 1057.2 1061.7 1097.7 11909.8 11985.9 11986.5 11995.2 11998.1 4810.3 4832.1 4832.2 4863.2 4910.9 10137.8 10144.7 10168.4 10170.4 10184.9 4679.0 5456.6 5513.9 5516.7 5519.4 4528.6 4632.1 4916.5 4947.8 5016.4 3940.7 4011.8 4024.2 4056.9 4063.6 1116.7 1321.2 1324.4 1325.4 1341.2 1388.7 1394.1 1423.1 1442.4 1447.1 8484.3 8505.0 8528.2 8538.0 8543.0 520.4 529.2 1305.8 1350.3 1351.1 5327.6 5462.7 6343.0 6369.5 6389.6 10530.4 10536.6 10786.8 10808.3 10813.1 9255.5 10625.6 10664.2 10666.0 10706.2 7197.5 8475.1 8491.2 8678.9 8719.1 8039.7 8086.5 8109.0 8123.8 8125.5 9736.5 9791.8 9912.6 9937.8 9984.0 10179.8 10258.1 10260.0 10464.2 10490.8 6467.3 6543.5 6982.0 7103.8 7215.6 8311.7 8376.5 8380.6 8405.3 8407.0 7250.3 7305.4 7324.8 7468.4 7488.4 6271.2 7263.1 7359.3 7363.7 7366.4 7698.9 7800.9 7801.0 7809.5 7820.5 6821.0 6830.0 6899.1 6910.0 6930.1 7379.2 7433.6 7452.4 7472.5 7483.3 05:25:09 INFO - PROCESS | 1689 | 05:25:09 INFO - PROCESS | 1689 | [#4] prototype.html Cycles:180 Average:5649.25 Median:6449.97 stddev:1799.38 (27.9%) stddev-sans-first:1759.49 05:25:09 INFO - PROCESS | 1689 | Values: 324.6 331.2 334.3 335.3 339.1 5300.8 6465.9 6493.7 6504.5 6568.7 6422.5 6431.9 6433.2 6434.1 6467.3 4576.5 4653.9 4711.0 4784.2 4799.6 4311.5 4395.3 4739.3 4780.0 4791.4 4739.1 4740.9 4745.7 4746.1 4754.6 4525.1 4623.5 4751.5 4772.8 4777.2 4082.3 4090.6 4092.5 4094.2 4108.4 6168.7 6194.6 6211.3 6239.3 6244.3 5637.0 5643.9 5650.7 5654.2 5662.3 6711.7 6909.8 6911.1 6915.9 6930.5 4551.9 4636.0 4636.4 4657.3 4660.6 6638.3 7253.3 7323.0 7332.9 7343.0 4351.9 4741.6 4742.5 4745.9 4753.0 4778.6 4791.8 4793.0 4805.4 4805.6 4669.7 5655.9 5664.7 5668.1 5671.8 6664.5 6916.4 6931.9 6951.7 6968.7 1584.9 1586.2 1598.2 1599.9 1600.6 933.3 940.4 1009.8 1010.4 1031.2 5339.3 6370.3 6508.7 6519.6 6539.2 7251.2 7261.0 7296.3 7296.5 7314.2 6531.0 6537.3 6548.1 6549.8 6551.9 3761.4 3791.8 3816.8 3818.3 3827.9 5995.0 7303.1 7548.1 7551.7 7564.6 5458.5 6022.6 6033.9 7463.7 7474.7 7522.7 7525.8 7531.7 7538.8 7542.8 7200.2 7201.9 7225.4 7229.8 7236.6 6377.5 6947.8 7056.7 7070.3 7086.4 7424.6 7478.2 7489.1 7489.3 7498.2 4541.8 4594.7 4595.3 4603.1 4605.3 7181.7 7190.9 7202.4 7209.7 7210.9 6709.5 6937.8 6981.7 6985.3 7006.3 6908.5 6941.2 6941.4 6975.0 6995.0 6808.5 6819.7 6825.3 6834.2 6835.1 6717.5 6959.1 6971.5 6978.9 6983.2 5693.9 6516.6 6542.7 6726.3 6754.8 05:25:09 INFO - PROCESS | 1689 | 05:25:09 INFO - PROCESS | 1689 | [#5] yui.html Cycles:180 Average:17513.34 Median:51409.00 stddev:24485.88 (47.6%) stddev-sans-first:24522.10 05:25:09 INFO - PROCESS | 1689 | Values: 720.3 738.7 769.2 787.1 817.4 12632.5 14894.7 14925.6 15165.8 15253.5 14066.7 14151.6 14155.1 14175.9 14190.7 813.5 830.4 978.7 998.5 1003.0 1014.6 1017.8 1019.5 1021.0 1027.0 1070.0 1078.3 1079.4 1080.0 1081.1 841.5 954.9 989.7 995.4 1048.0 671.7 778.9 785.4 786.8 793.0 14979.3 15032.2 15113.9 15116.1 15130.0 12955.5 13031.3 13055.8 13248.3 13346.7 10056.4 10123.8 10144.1 10291.3 10344.5 3540.0 3546.9 3553.5 3604.4 3811.1 73266.9 73358.6 73454.6 73533.3 73537.0 9516.1 9619.5 9693.9 9750.0 9766.1 22274.6 22433.1 22473.0 22485.4 22487.7 7267.3 7316.3 7457.4 7534.1 7588.2 4067.8 4084.6 4126.2 4252.4 4302.4 3598.2 3605.6 3626.9 3640.7 3647.4 532.7 603.7 617.3 620.2 624.4 589.7 591.8 601.9 624.7 628.1 7206.9 7235.4 7322.9 7537.2 7656.8 547.5 581.3 593.9 627.2 629.7 3754.9 3774.4 3830.6 3853.8 3860.8 87544.0 88125.6 88439.0 88898.6 88978.6 75421.6 77663.0 78553.6 79345.6 79775.0 42709.6 52842.0 67782.0 67912.7 69650.7 7402.8 7542.8 7614.5 7699.9 7907.6 47836.0 48168.6 48954.0 49801.5 49976.0 63082.0 68537.3 68560.0 69603.0 69994.0 10144.7 10281.3 10364.8 10512.9 10798.8 10357.5 10456.2 10617.4 10712.4 10949.6 10133.2 10168.2 10170.3 10302.5 10352.6 10163.0 10240.5 10313.6 10319.5 10456.3 9257.3 9933.9 10050.3 10194.1 10287.8 8686.9 8768.2 8845.0 8901.2 8945.9 8739.5 8794.4 8870.5 8957.6 8990.8 05:25:09 INFO - PROCESS | 1689 | -------- Summary: end -------- 05:25:09 INFO - PROCESS | 1689 | 05:25:09 INFO - TEST-INFO | 1689: exit 0 05:25:09 INFO - TEST-OK | dromaeo_css | took 1112613ms 05:25:09 INFO - TEST-START | kraken 05:25:09 INFO - operating with platform_type : mac_ 05:25:09 INFO - Initialising browser for kraken test... 05:25:09 INFO - TEST-INFO | started process 1696 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpOgj6S5/profile http://localhost:49270/getInfo.html) 05:25:11 INFO - PROCESS | 1696 | __metrics Screen width/height:1600/1200 05:25:11 INFO - PROCESS | 1696 | colorDepth:24 05:25:11 INFO - PROCESS | 1696 | Browser inner width/height: 1024/689 05:25:11 INFO - PROCESS | 1696 | __metrics 05:25:11 INFO - PROCESS | 1696 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:25:11 INFO - PROCESS | 1696 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:25:12 INFO - TEST-INFO | 1696: exit 0 05:25:12 INFO - Browser initialized. 05:25:12 INFO - Running cycle 1/1 for kraken test... 05:25:12 INFO - TEST-INFO | started process 1698 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpOgj6S5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:24 INFO - PROCESS | 1698 | RSS: Main: 207572992 05:25:24 INFO - PROCESS | 1698 | 05:25:26 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 05:25:27 INFO - PROCESS | 1698 | RSS: Main: 263135232 05:25:27 INFO - PROCESS | 1698 | 05:25:30 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 05:25:30 INFO - PROCESS | 1698 | RSS: Main: 273436672 05:25:30 INFO - PROCESS | 1698 | 05:25:33 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 05:25:34 INFO - PROCESS | 1698 | RSS: Main: 262750208 05:25:34 INFO - PROCESS | 1698 | 05:25:36 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 05:25:36 INFO - PROCESS | 1698 | RSS: Main: 263729152 05:25:36 INFO - PROCESS | 1698 | 05:25:39 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:25:39 INFO - PROCESS | 1698 | RSS: Main: 265007104 05:25:39 INFO - PROCESS | 1698 | 05:25:42 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 05:25:42 INFO - PROCESS | 1698 | RSS: Main: 264822784 05:25:42 INFO - PROCESS | 1698 | 05:25:45 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 05:25:46 INFO - PROCESS | 1698 | RSS: Main: 264978432 05:25:46 INFO - PROCESS | 1698 | 05:25:49 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 05:25:49 INFO - PROCESS | 1698 | RSS: Main: 265265152 05:25:49 INFO - PROCESS | 1698 | 05:25:51 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:25:51 INFO - PROCESS | 1698 | RSS: Main: 297738240 05:25:51 INFO - PROCESS | 1698 | 05:25:53 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:25:54 INFO - PROCESS | 1698 | RSS: Main: 298868736 05:25:54 INFO - PROCESS | 1698 | 05:25:56 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:25:56 INFO - PROCESS | 1698 | RSS: Main: 306155520 05:25:56 INFO - PROCESS | 1698 | 05:25:59 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:26:00 INFO - PROCESS | 1698 | RSS: Main: 307466240 05:26:00 INFO - PROCESS | 1698 | 05:26:02 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:26:02 INFO - PROCESS | 1698 | RSS: Main: 307597312 05:26:02 INFO - PROCESS | 1698 | 05:26:04 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 05:26:05 INFO - PROCESS | 1698 | RSS: Main: 369627136 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | __start_tp_report 05:26:05 INFO - PROCESS | 1698 | _x_x_mozilla_page_load 05:26:05 INFO - PROCESS | 1698 | _x_x_mozilla_page_load_details 05:26:05 INFO - PROCESS | 1698 | |i|pagename|runs| 05:26:05 INFO - PROCESS | 1698 | |0;ai-astar;112;109;111;112;113;111;120;114;109;111 05:26:05 INFO - PROCESS | 1698 | |1;audio-beat-detection;157;119;143;156;117;139;117;148;146;116 05:26:05 INFO - PROCESS | 1698 | |2;audio-dft;184;194;258;183;182;183;184;184;182;185 05:26:05 INFO - PROCESS | 1698 | |3;audio-fft;70;75;77;116;55;58;58;58;117;55 05:26:05 INFO - PROCESS | 1698 | |4;audio-oscillator;93;92;131;83;78;85;116;75;81;119 05:26:05 INFO - PROCESS | 1698 | |5;imaging-gaussian-blur;86;84;86;84;86;84;86;87;135;84 05:26:05 INFO - PROCESS | 1698 | |6;imaging-darkroom;92;93;91;92;91;91;92;92;91;92 05:26:05 INFO - PROCESS | 1698 | |7;imaging-desaturate;85;83;85;83;86;87;85;86;96;141 05:26:05 INFO - PROCESS | 1698 | |8;json-parse-financial;56;56;58;58;59;59;59;69;54;54 05:26:05 INFO - PROCESS | 1698 | |9;json-stringify-tinderbox;61;61;64;72;70;68;65;66;65;65 05:26:05 INFO - PROCESS | 1698 | |10;stanford-crypto-aes;81;81;80;79;80;78;78;79;82;86 05:26:05 INFO - PROCESS | 1698 | |11;stanford-crypto-ccm;121;131;129;118;118;116;132;127;125;119 05:26:05 INFO - PROCESS | 1698 | |12;stanford-crypto-pbkdf2;115;123;133;136;133;122;133;131;132;134 05:26:05 INFO - PROCESS | 1698 | |13;stanford-crypto-sha256-iterative;67;65;66;66;70;70;92;97;98;79 05:26:05 INFO - PROCESS | 1698 | __end_tp_report 05:26:05 INFO - PROCESS | 1698 | __start_cc_report 05:26:05 INFO - PROCESS | 1698 | _x_x_mozilla_cycle_collect,1200 05:26:05 INFO - PROCESS | 1698 | __end_cc_report 05:26:05 INFO - PROCESS | 1698 | __startTimestamp1460031965030__endTimestamp 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | ------- Summary: start ------- 05:26:05 INFO - PROCESS | 1698 | Number of tests: 14 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#0] ai-astar Cycles:10 Average:112.20 Median:112.00 stddev:3.16 (2.8%) stddev-sans-first:3.35 05:26:05 INFO - PROCESS | 1698 | Values: 112.0 109.0 111.0 112.0 113.0 111.0 120.0 114.0 109.0 111.0 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:144.50 stddev:16.84 (11.7%) stddev-sans-first:16.02 05:26:05 INFO - PROCESS | 1698 | Values: 157.0 119.0 143.0 156.0 117.0 139.0 117.0 148.0 146.0 116.0 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#2] audio-dft Cycles:10 Average:191.90 Median:184.00 stddev:23.48 (12.8%) stddev-sans-first:24.73 05:26:05 INFO - PROCESS | 1698 | Values: 184.0 194.0 258.0 183.0 182.0 183.0 184.0 184.0 182.0 185.0 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#3] audio-fft Cycles:10 Average:73.90 Median:58.00 stddev:23.87 (41.2%) stddev-sans-first:25.28 05:26:05 INFO - PROCESS | 1698 | Values: 70.0 75.0 77.0 116.0 55.0 58.0 58.0 58.0 117.0 55.0 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:82.00 stddev:19.59 (23.9%) stddev-sans-first:20.76 05:26:05 INFO - PROCESS | 1698 | Values: 93.0 92.0 131.0 83.0 78.0 85.0 116.0 75.0 81.0 119.0 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:86.00 stddev:15.78 (18.4%) stddev-sans-first:16.67 05:26:05 INFO - PROCESS | 1698 | Values: 86.0 84.0 86.0 84.0 86.0 84.0 86.0 87.0 135.0 84.0 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#6] imaging-darkroom Cycles:10 Average:91.70 Median:92.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 05:26:05 INFO - PROCESS | 1698 | Values: 92.0 93.0 91.0 92.0 91.0 91.0 92.0 92.0 91.0 92.0 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:85.50 stddev:17.71 (20.7%) stddev-sans-first:18.62 05:26:05 INFO - PROCESS | 1698 | Values: 85.0 83.0 85.0 83.0 86.0 87.0 85.0 86.0 96.0 141.0 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:58.50 stddev:4.26 (7.3%) stddev-sans-first:4.45 05:26:05 INFO - PROCESS | 1698 | Values: 56.0 56.0 58.0 58.0 59.0 59.0 59.0 69.0 54.0 54.0 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:65.50 stddev:3.53 (5.4%) stddev-sans-first:3.31 05:26:05 INFO - PROCESS | 1698 | Values: 61.0 61.0 64.0 72.0 70.0 68.0 65.0 66.0 65.0 65.0 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:80.50 stddev:2.37 (2.9%) stddev-sans-first:2.50 05:26:05 INFO - PROCESS | 1698 | Values: 81.0 81.0 80.0 79.0 80.0 78.0 78.0 79.0 82.0 86.0 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#11] stanford-crypto-ccm Cycles:10 Average:123.60 Median:126.00 stddev:5.93 (4.7%) stddev-sans-first:6.21 05:26:05 INFO - PROCESS | 1698 | Values: 121.0 131.0 129.0 118.0 118.0 116.0 132.0 127.0 125.0 119.0 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:129.20 Median:133.00 stddev:6.80 (5.1%) stddev-sans-first:4.89 05:26:05 INFO - PROCESS | 1698 | Values: 115.0 123.0 133.0 136.0 133.0 122.0 133.0 131.0 132.0 134.0 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - PROCESS | 1698 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.00 Median:74.50 stddev:13.56 (18.2%) stddev-sans-first:13.89 05:26:05 INFO - PROCESS | 1698 | Values: 67.0 65.0 66.0 66.0 70.0 70.0 92.0 97.0 98.0 79.0 05:26:05 INFO - PROCESS | 1698 | -------- Summary: end -------- 05:26:05 INFO - PROCESS | 1698 | 05:26:05 INFO - TEST-INFO | 1698: exit 0 05:26:05 INFO - TEST-OK | kraken | took 56199ms 05:26:05 INFO - SUITE-END | took 1168s 05:26:06 INFO - Completed test suite (00:19:29) 05:26:06 INFO - javascript benchmark 05:26:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3038.921182980042, 3524.2599054198618, 3546.893593192204, 3561.056320075179, 3594.3170797548464, 5787.392323806003, 5790.68151251681, 5792.913106303409, 5797.768089276429, 5820.738066786994, 5756.338021127572, 5790.942090579093, 5810.89592977334, 5813.651180929137, 5821.068629019358, 5107.668001579886, 5166.250893620432, 5177.275181474596, 5191.610629202806, 5291.126964050927, 5262.158054711247, 5268.578513718893, 5271.4464981177, 5274.287971123907, 5278.261043453919, 4377.6935614507, 5166.328377310963, 5203.271541984125, 5223.37319521656, 5264.789408423659, 5216.7913283468615, 5264.9736751315995, 5274.657147285453, 5284.260203571503, 5318.787248510074, 4179.895502612459, 5175.456577059392, 5188.066148093341, 5192.909489007313, 5210.739463026834, 5608.691521966291, 5640.492355688008, 5650.0959846424785, 5665.348484924245, 5679.09134538476, 6113.506711826627, 6280.309165991783, 6296.433321001086, 6309.747610095591, 6318.74725010999, 6214.782482613087, 6268.780592679234, 6273.215848018997, 6289.2831173488175, 6309.305976342599, 5811.128330750379, 5818.767249310023, 5836.851613418393, 5860.208871802252, 5976.7908123215475, 6190.783322583688, 6268.059791031382, 6307.5269354798575, 6320.115183874262, 6325.0, 6172.845678858064, 6179.28938172116, 6182.814515564541, 6183.165272688129, 6220.502359811295, 6251.156093927261, 6262.0, 6265.27949285838, 6278.4035516625845, 6280.874382512324, 6043.12374705674, 6114.002540030451, 6175.042868355412, 6220.160278362454, 6223.439890409885, 6299.992001279773, 6309.873802523924, 6331.715072821734, 6338.429541341301, 6345.90481142774, 4948.480409557017, 4950.628702847301, 4953.405591329064, 4959.404871415453, 4978.278149668277, 4340.348947657813, 4448.7108337957925, 4458.554144585515, 4475.977620111878, 4484.439445069366, 5662.37713851476, 5728.684922329228, 5735.942640573624, 5774.48029677331, 5792.623479473821, 6274.435300822948, 6287.308396076428, 6287.528435367366, 6292.842678933063, 6302.747890084437, 5548.0, 5737.971310143423, 5788.305403351625, 5817.476427121579, 5849.824505264849, 5146.0, 5372.247885296062, 5380.2736630555155, 5382.730863456812, 5388.811391601275, 6312.621242725451, 6330.240371155491, 6332.430081292614, 6335.461485773577, 6336.112944187817, 6188.907166392598, 6299.08663243818, 6308.810735677937, 6311.084892690443, 6313.873722525615, 6199.442050215503, 6239.220097487814, 6289.465395441439, 6340.9365906340345, 6352.237731472253, 6244.094606281973, 6269.62382257066, 6272.027835685476, 6273.843153921189, 6278.089676996901, 6252.687365631609, 6302.369763023661, 6302.810915672537, 6310.432061114522, 6325.462335701517, 5865.765369385177, 5949.434803693815, 6020.909686354796, 6095.116208149705, 6129.4483496483745, 5964.284285885721, 6125.11336343068, 6136.64993701385, 6146.354632763496, 6154.292256390393, 5987.550933680079, 6018.0, 6028.849278768066, 6034.849128771641, 6039.5470339723815, 6002.549808764273, 6029.216201893726, 6083.300420451527, 6135.325114237341, 6146.139540464249, 5999.9100013498955, 6007.639541627516, 6029.939700602938, 6030.5778595497895, 6032.698365081641], "unit": "score", "name": "dojo.html", "value": 5724.286012210086}, {"lowerIsBetter": false, "replicates": [499.91268989946855, 571.1062187676287, 595.1845971019703, 660.7456129390184, 676.1953275602032, 32174.839125804367, 32830.835845820795, 32889.0, 32919.0, 32978.0, 33958.32083358331, 42470.0, 42897.14205715892, 42955.57044429563, 42965.78517107418, 9583.712488625335, 9728.367656102366, 9742.171915387165, 9746.463944483086, 9788.119069283763, 9643.80712385752, 9773.853392199122, 9789.070038346346, 9793.167580755644, 9800.803983920317, 9786.461744604045, 9856.90143098567, 9861.063198996084, 9863.457509836957, 9863.802723945517, 9732.270079744012, 9934.25493088014, 9938.9503052485, 9939.950300248453, 9942.0, 9686.273529485246, 9715.46564938928, 9720.95139524305, 9801.607935682636, 9818.45998470084, 42803.35794963078, 43087.49193778234, 43172.78413607912, 43190.568094319286, 43233.567664323265, 3117.5011998080427, 3270.329582435595, 3327.085051610802, 3343.3981883261004, 3356.7314614830757, 23624.0, 28669.0, 29674.554881676577, 30195.0, 30360.69639303623, 2721.1020363280268, 2768.0588599875937, 2811.086396921009, 2836.9007084752143, 2879.3809330993727, 464813.0, 467441.0, 467597.9999999966, 467749.32250677736, 469016.0, 49033.50966490289, 72636.63681681558, 72948.0, 73160.63419682973, 73301.6334918322, 48154.75922620364, 48234.758826205645, 48367.51632483701, 48475.03049938982, 48623.27065094026, 16331.183440828148, 16692.58268543296, 16716.0, 16720.331186752635, 16743.58141046459, 13220.867791321964, 13267.0, 13297.135686180367, 13306.534271300652, 13309.600711978655, 15321.23393830304, 15356.0, 15438.9228053859, 15454.304556294994, 15502.9224853875, 5568.638038527482, 6423.839404014937, 6549.9345006550275, 6580.21037475506, 6587.835304117435, 1013.8276492996208, 1022.3967858963248, 1029.459533744775, 1086.462201210406, 1168.5617893290016, 28491.715082848907, 29465.705342946298, 29712.0, 29773.553396699066, 30814.845925770675, 982.4792859784326, 1081.4106312059946, 1161.988380116205, 1193.952241910314, 1201.5674357231564, 14917.925410372878, 15037.699246015018, 15093.849061509245, 15490.535283941499, 15496.767548486781, 538731.0, 772855.0, 778075.0, 780064.0, 781810.0, 65076.0, 65794.05235685874, 65816.0, 65983.0, 66737.0, 1440.265464613055, 1468.1778204205893, 1585.6749366379865, 1606.3574570172025, 1720.7332863406193, 27161.728382716712, 29517.704822952353, 30546.694533054386, 32916.0, 33738.3252334947, 59892.0, 60225.39774602373, 60326.0, 60446.395536045835, 60529.0920636182, 471968.640156797, 472816.0, 474732.0, 474773.0, 475391.0, 27494.450110998067, 34774.47838282478, 34898.82550587231, 35257.0, 35328.64671353254, 42953.926151845204, 44348.0, 44660.776696116314, 44772.77613611911, 45457.54542454533, 36769.448458273684, 37016.25967480689, 37191.814040929625, 37655.62344376631, 37782.0, 36158.63841361658, 36468.0, 36911.0, 36996.815015924745, 37139.0, 36522.8173859129, 36959.44560831536, 37774.62225377711, 39443.83058931671, 41428.0, 31290.435953410113, 34081.829590851885, 34218.0, 34519.0, 34562.0, 25414.364640884127, 25724.356891077874, 25790.766305531928, 26094.956201752477, 26247.868760656074], "unit": "score", "name": "ext.html", "value": 19988.135030916612}, {"lowerIsBetter": false, "replicates": [383.80042377963457, 384.51167017887286, 385.89002134391706, 386.52071431425026, 390.1027636436196, 16525.586860328498, 16909.91545042273, 19617.411477655674, 20692.89653551734, 21195.788042119573, 20421.48946276344, 20923.372298831022, 21533.353999380004, 21981.34055978323, 22001.66997495039, 5600.299962504687, 5798.855028624297, 5870.26621672291, 5871.530277577784, 5878.559108066891, 4340.479142502904, 5951.970240148793, 5982.3120341160675, 6315.778947736831, 6584.407403333699, 6498.187726534184, 6518.185226846645, 6519.8044058678315, 6538.542302038859, 6543.607383556978, 5731.4555117264, 5971.581989260753, 5983.282006159246, 5994.640321580676, 6488.93511064888, 5711.143328500717, 5711.343195532525, 5715.857103572403, 5721.856953576153, 5733.684647344395, 13846.584602461942, 17443.825561744346, 21439.67840482394, 26131.82406791699, 26152.34619134518, 2130.62714025045, 2185.8032777049984, 2191.7808219178114, 2210.4473881529616, 2278.7493375728654, 9762.219022478255, 9806.704379634988, 9889.703308900744, 10265.538050787733, 10412.958704129527, 1680.6134589044589, 1726.5597272695509, 1765.9646807063787, 1858.1452531835373, 1915.7030660247683, 86459.0, 99154.51268230983, 103032.0, 110451.0, 112824.43587782091, 11299.943500282447, 11387.202895797214, 11524.36615986112, 11561.479733412016, 11567.76864462723, 11263.549458021587, 12339.0, 13423.664408389868, 14543.490977815725, 14580.1980891052, 7316.743913962986, 7383.187849336568, 8883.156100170474, 8906.0, 9007.594658240396, 9021.097890210927, 9034.728958131267, 9060.957989831253, 9102.81794364109, 9166.67916622915, 4495.100979804052, 4564.269716845289, 4566.109608626286, 4587.8623641290815, 4606.608438282784, 1890.8770929889788, 1911.7897031326543, 1941.805819418075, 1943.1838627776367, 1983.5239542509983, 942.886853577575, 954.2593035301634, 960.0159836167844, 975.7463059604554, 1068.3643232276784, 18388.816111838976, 20457.89771051135, 20963.0, 21172.835494047977, 21186.894065529574, 894.5169608411516, 951.2675240065112, 1034.730969947804, 1061.7186445591924, 1075.0700643942932, 9053.77365565853, 9132.634694612274, 11188.440577971069, 11336.6032188873, 11337.26307789991, 439093.0, 448786.0, 524091.0, 525205.617803375, 525642.0, 101507.0, 116031.0, 129137.70862291445, 132863.0, 136528.0, 469.7956388970809, 485.78625404821764, 487.7804987755524, 489.307629703972, 491.2778216022441, 9841.311108222355, 11199.656684913323, 11220.38287894141, 11502.252353596989, 11777.705557361134, 52740.736296318275, 52860.47139528555, 53462.66343341448, 53945.46054539561, 54296.18555721746, 77328.61335693285, 77556.61221693855, 78466.0, 80593.0, 80663.3867322662, 7989.161138080418, 8281.585920703821, 8428.199321064489, 8472.957635211784, 8486.278666313432, 9610.51947402613, 13203.373496382437, 13383.598492045254, 13512.189268643913, 13849.892008639083, 11365.147613929154, 11541.769164616828, 11637.476313565741, 11858.051355891681, 11963.461644225858, 11326.830097548378, 11363.090952723927, 11483.425828708698, 11496.597619083292, 11727.472263748323, 10253.948730256301, 11466.197366184288, 11714.297142171497, 11851.170418070653, 11895.821562676394, 8171.5505647189975, 8281.668733250603, 8350.958245208734, 8523.318134549098, 8599.312055035707, 11236.83144752813, 11655.475503602527, 11870.347130907883, 11952.940235298767, 11966.820497692715], "unit": "score", "name": "jquery.html", "value": 9188.312686865851}, {"lowerIsBetter": false, "replicates": [1491.4928924165781, 1609.2114863716781, 1629.3075442936752, 1961.9705704414437, 2062.236972320242, 8124.553149576771, 8986.37095403322, 8993.505357205351, 9026.097390260971, 9034.0, 8577.14228577142, 8823.779405514866, 8831.602577883994, 8835.293176545876, 8845.248153906925, 2183.301343570058, 2482.416632091457, 2506.410993416546, 2513.40934880303, 2538.060917460533, 2542.6694529711112, 2550.0947163756878, 2551.2091251711936, 2556.0542599238215, 2558.3859873630286, 2426.7694569016003, 2539.5301869154173, 2540.6697129373156, 2548.439343344461, 2550.1074623881677, 2226.421130506072, 2490.887910044052, 2522.016413598698, 2522.962155567668, 2547.8343907646035, 2229.665550167472, 2504.9874750626313, 2532.392225865779, 2532.6201069839485, 2571.100114959768, 8822.0, 8874.822503549958, 8880.911190888073, 8881.911180888237, 8884.067172946812, 5368.570514358881, 5531.419200983879, 5565.026120428909, 5568.805091821767, 5571.470710282517, 3571.74997750155, 3651.8721844735574, 3682.4660424238336, 3697.482352470656, 3718.7954662493553, 1030.9123724483427, 1044.8693913260843, 1057.2493529593999, 1061.7186445591924, 1097.7255686078481, 11909.821352679717, 11985.940070299679, 11986.520539178422, 11995.16033877629, 11998.10014248926, 4810.254410566367, 4832.106060378771, 4832.226843704991, 4863.2461968394955, 4910.852674419773, 10137.847932281022, 10144.746381340525, 10168.440735759455, 10170.440625765654, 10184.949075254724, 4679.0, 5456.563474921997, 5513.862153446115, 5516.668999860021, 5519.39286678465, 4528.592426681548, 4632.050429661911, 4916.508349165127, 4947.777350019258, 5016.372953380827, 3940.7438516496336, 4011.7793521356607, 4024.1951609677594, 4056.8782936511952, 4063.5733248009124, 1116.7375666718315, 1321.2072756346308, 1324.423875614111, 1325.3770727757937, 1341.2086868747485, 1388.7083712420304, 1394.0799072612028, 1423.053669309918, 1442.4230307876933, 1447.0883343493742, 8484.27883629886, 8505.0, 8528.232459078712, 8538.01812791537, 8542.957285213533, 520.3963402453136, 529.2141170362087, 1305.764962306794, 1350.338334216227, 1351.094766506443, 5327.600429967768, 5462.672239665553, 6343.0, 6369.490440764727, 6389.584676995981, 10530.420826854443, 10536.631217907336, 10786.78426431467, 10808.297460665031, 10813.134949204203, 9255.537223138816, 10625.628103016357, 10664.200184986157, 10666.040056394962, 10706.197035222234, 7197.532160409557, 8475.067742548315, 8491.15088491146, 8678.913210867937, 8719.12808719123, 8039.678412863654, 8086.5148091114725, 8109.0, 8123.75628731139, 8125.512469251864, 9736.513174341397, 9791.804163916539, 9912.553935073083, 9937.751556210864, 9984.0, 10179.796404072025, 10258.076773090457, 10259.97400259968, 10464.215183861088, 10490.842637360292, 6467.288598254094, 6543.5419520633095, 6981.965090174516, 7103.786886393416, 7215.60314182725, 8311.6675332986, 8376.49741015541, 8380.58097095155, 8405.32757379396, 8407.033191183093, 7250.274972502708, 7305.415566754753, 7324.780256592311, 7468.402527797654, 7488.363489103487, 6271.216097987752, 7263.0921134858145, 7359.264073592812, 7363.668634911334, 7366.373858222111, 7698.92301076982, 7800.921990780246, 7800.960995194988, 7809.531428114331, 7820.530768153929, 6821.045053692388, 6830.044241189004, 6899.068625735662, 6910.032595436543, 6930.06444130036, 7379.188289288281, 7433.628318583941, 7452.441066919894, 7472.514286571355, 7483.251674832473], "unit": "score", "name": "mootools.html", "value": 4984.327396664378}, {"lowerIsBetter": false, "replicates": [324.57804853690203, 331.1984996308932, 334.33801073873747, 335.2507146527511, 339.0658735184566, 5300.840974770763, 6465.868473017221, 6493.740250389985, 6504.512161587876, 6568.671566421684, 6422.518311126672, 6431.9035214471705, 6433.195850518685, 6434.0670602762575, 6467.2885982542, 4576.519465456129, 4653.88365290867, 4711.0342379812055, 4784.210605250147, 4799.592034677056, 4311.542420820134, 4395.252807022798, 4739.336492890998, 4779.9962007978365, 4791.401074865642, 4739.123262196487, 4740.928886066712, 4745.691530050535, 4746.145693775119, 4754.595859351942, 4525.072360166158, 4623.491415944261, 4751.524847515256, 4772.832950846704, 4777.211760059586, 4082.2856000199845, 4090.550039495653, 4092.508898932147, 4094.2220978014084, 4108.383742438628, 6168.660723660197, 6194.5973511721595, 6211.34780848009, 6239.2824825145235, 6244.344343843876, 5636.971815140939, 5643.887122257532, 5650.74571644277, 5654.208410822488, 5662.263905692274, 6711.6595711286445, 6909.7581584644795, 6911.101556797635, 6915.861682766367, 6930.480213983921, 4551.931721024187, 4635.976820115911, 4636.35091087248, 4657.324687920266, 4660.627149828006, 6638.344662135185, 7253.310935461122, 7322.96338518304, 7332.853342933165, 7342.9632851835395, 4351.934720979188, 4741.596964258008, 4742.52574742523, 4745.928811067837, 4752.976235118871, 4778.593819525309, 4791.784369703371, 4793.0413917216, 4805.447373552016, 4805.639577031737, 4669.7431641260055, 5655.858603534863, 5664.68844213564, 5668.149777533321, 5671.829845104653, 6664.500162487736, 6916.412104971134, 6931.861362772817, 6951.6871740771785, 6968.651567421609, 1584.881133914938, 1586.1672621873379, 1598.19291257914, 1599.872010239178, 1600.5994754589733, 933.3419938943028, 940.4263399326405, 1009.7828966772179, 1010.3937637417491, 1031.2265800649513, 5339.279197308391, 6370.267419246846, 6508.707108180143, 6519.576227545194, 6539.169032670859, 7251.150956506162, 7260.9636951815955, 7296.3433291003, 7296.525725827804, 7314.195438501761, 6530.987696906987, 6537.280899101095, 6548.083268342436, 6549.803505894831, 6551.934480655133, 3761.435784632327, 3791.829367678461, 3816.790076545761, 3818.2745278397015, 3827.923441531154, 5995.0, 7303.087114110736, 7548.056492938383, 7551.697932082655, 7564.621768911533, 5458.536024437888, 6022.638641681513, 6033.879322413527, 7463.738769143162, 7474.738384156529, 7522.699092036476, 7525.849483010309, 7531.740477856731, 7538.849223015509, 7542.83086121663, 7200.1719802223415, 7201.855962880608, 7225.385842203472, 7229.783106506813, 7236.565806051653, 6377.489800816016, 6947.756828510978, 7056.682449289691, 7070.328318809705, 7086.397656199075, 7424.554526728413, 7478.1774004858335, 7489.101307843093, 7489.251074892467, 7498.175200728025, 4541.772911354486, 4594.724316541018, 4595.287730401793, 4603.148417542765, 4605.286180642006, 7181.748638797617, 7190.928090719026, 7202.3877970373105, 7209.71161153548, 7210.89183662256, 6709.52390474095, 6937.7571784987285, 6981.650917454209, 6985.26654701249, 7006.264342244195, 6908.511201843037, 6941.2364639888565, 6941.409980151744, 6974.965125174342, 6995.0, 6808.489363297872, 6819.69311380999, 6825.2833452486775, 6834.2482326943, 6835.1114355135505, 6717.462602991846, 6959.095317608679, 6971.5468494547695, 6978.930210698031, 6983.162020557363, 5693.886122277614, 6516.609003459807, 6542.738290468328, 6726.293739157318, 6754.797356079325], "unit": "score", "name": "prototype.html", "value": 5046.372607361942}, {"lowerIsBetter": false, "replicates": [720.2725247500025, 738.7082102569489, 769.1539306762562, 787.0987719061678, 817.3706246190434, 12632.494700212004, 14894.702105957875, 14925.62685932851, 15165.772513412294, 15253.466128685499, 14066.718665626682, 14151.575452736435, 14155.079919805205, 14175.858241417583, 14190.71618567628, 813.4793732011487, 830.4145577367972, 978.6623614852882, 998.4558415663481, 1003.0471052500118, 1014.5941623350645, 1017.7760892603652, 1019.4749703902459, 1021.019820971868, 1027.0294571629813, 1069.9781708468395, 1078.3044936016265, 1079.3685693869097, 1080.0063941174099, 1081.0594782539201, 841.5161282262693, 954.885413750351, 989.747614358334, 995.3729150635054, 1048.0305717211586, 671.6977360187886, 778.8598052350575, 785.4109417936547, 786.8426314737018, 792.9762107136795, 14979.325930333162, 15032.248387580687, 15113.924430377887, 15116.09303441786, 15130.016548924285, 12955.546555870593, 13031.283279419627, 13055.804162937564, 13248.337583120805, 13346.666333341647, 10056.44689542075, 10123.797524049478, 10144.087032167141, 10291.33106348085, 10344.482775861177, 3539.982300088509, 3546.8935931922083, 3553.502509648651, 3604.42329227325, 3811.123441608418, 73266.9009964851, 73358.63320683362, 73454.63272683602, 73533.26466735317, 73537.0, 9516.095970882756, 9619.51902404877, 9693.903060969442, 9750.0, 9766.07222313879, 22274.554508910056, 22433.102675893108, 22473.0, 22485.4378640532, 22487.66268505974, 7267.30960558746, 7316.341529262286, 7457.366123879422, 7534.133574638987, 7588.165301816795, 4067.7966101694797, 4084.571120032413, 4126.195391898611, 4252.425922500484, 4302.376155457504, 3598.1724203433355, 3605.6394360563736, 3626.9455958160647, 3640.6541378568995, 3647.3981793004386, 532.7176596403912, 603.6891001134419, 617.3178637605471, 620.1906512001881, 624.3631495874183, 589.6933594530819, 591.8313280714975, 601.9097135429633, 624.6595605395071, 628.0641843653016, 7206.855862882713, 7235.421166306683, 7322.926770732331, 7537.2085930977555, 7656.770296891103, 547.5072434808704, 581.3372755059236, 593.8752861898963, 627.1815281058263, 629.6945981199115, 3754.9436758448646, 3774.4149656802692, 3830.616938306203, 3853.7687738735203, 3860.7876566788536, 87544.0, 88125.559372204, 88439.0, 88898.55550722204, 88978.55510722405, 75421.62289188518, 77663.0, 78553.60723196347, 79345.60327198327, 79775.0, 42709.57290427056, 52842.0, 67782.0, 67912.66043669848, 69650.65174674094, 7402.777916662509, 7542.773716788286, 7614.505057171266, 7699.922010918363, 7907.56508392021, 47836.0, 48168.55494335176, 48954.0, 49801.50198497969, 49976.00047999092, 63082.0, 68537.31462685509, 68560.0, 69603.0, 69993.9500907497, 10144.695659130239, 10281.331713438603, 10364.792704145724, 10512.894871051192, 10798.838017429902, 10357.482125893826, 10456.222442184695, 10617.362958222531, 10712.410817405118, 10949.616763413242, 10133.240006999651, 10168.237382196512, 10170.288079834341, 10302.484875756332, 10352.585896564053, 10163.0, 10240.48797560134, 10313.639022634172, 10319.535620897228, 10456.26806123564, 9257.25941924631, 9933.850992234979, 10050.346727462691, 10194.133498652698, 10287.84568231462, 8686.869696954676, 8768.167024132697, 8845.0, 8901.243394311297, 8945.865812012955, 8739.519326437106, 8794.384393092421, 8870.467771933705, 8957.641694332153, 8990.82018359616], "unit": "score", "name": "yui.html", "value": 6795.545573500399}], "name": "dromaeo_css", "value": 7512.118702711508}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 109.0, 111.0, 112.0, 113.0, 111.0, 120.0, 114.0, 109.0, 111.0], "unit": "score", "name": "ai-astar", "value": 112.2}, {"lowerIsBetter": true, "replicates": [157.0, 119.0, 143.0, 156.0, 117.0, 139.0, 117.0, 148.0, 146.0, 116.0], "unit": "score", "name": "audio-beat-detection", "value": 135.8}, {"lowerIsBetter": true, "replicates": [184.0, 194.0, 258.0, 183.0, 182.0, 183.0, 184.0, 184.0, 182.0, 185.0], "unit": "score", "name": "audio-dft", "value": 191.9}, {"lowerIsBetter": true, "replicates": [70.0, 75.0, 77.0, 116.0, 55.0, 58.0, 58.0, 58.0, 117.0, 55.0], "unit": "score", "name": "audio-fft", "value": 73.9}, {"lowerIsBetter": true, "replicates": [93.0, 92.0, 131.0, 83.0, 78.0, 85.0, 116.0, 75.0, 81.0, 119.0], "unit": "score", "name": "audio-oscillator", "value": 95.3}, {"lowerIsBetter": true, "replicates": [86.0, 84.0, 86.0, 84.0, 86.0, 84.0, 86.0, 87.0, 135.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 90.2}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 91.0, 92.0, 91.0, 91.0, 92.0, 92.0, 91.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 91.7}, {"lowerIsBetter": true, "replicates": [85.0, 83.0, 85.0, 83.0, 86.0, 87.0, 85.0, 86.0, 96.0, 141.0], "unit": "score", "name": "imaging-desaturate", "value": 91.7}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 58.0, 58.0, 59.0, 59.0, 59.0, 69.0, 54.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 58.2}, {"lowerIsBetter": true, "replicates": [61.0, 61.0, 64.0, 72.0, 70.0, 68.0, 65.0, 66.0, 65.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 65.7}, {"lowerIsBetter": true, "replicates": [81.0, 81.0, 80.0, 79.0, 80.0, 78.0, 78.0, 79.0, 82.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.4}, {"lowerIsBetter": true, "replicates": [121.0, 131.0, 129.0, 118.0, 118.0, 116.0, 132.0, 127.0, 125.0, 119.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.6}, {"lowerIsBetter": true, "replicates": [115.0, 123.0, 133.0, 136.0, 133.0, 122.0, 133.0, 131.0, 132.0, 134.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 129.2}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 66.0, 66.0, 70.0, 70.0, 92.0, 97.0, 98.0, 79.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 77.0}], "name": "kraken", "value": 1416.8000000000002}]} 05:26:06 INFO - Return code: 0 05:26:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:26:06 INFO - # TBPL SUCCESS # 05:26:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:26:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:26:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:26:06 INFO - 2016-04-07 05:26:06.318 screenresolution[1702:9993] starting screenresolution argv=screenresolution get 05:26:06 INFO - 2016-04-07 05:26:06.320 screenresolution[1702:9993] Display 0: 1600x1200x32@60 05:26:06 INFO - 2016-04-07 05:26:06.326 screenresolution[1703:9996] starting screenresolution argv=screenresolution list 05:26:06 INFO - Available Modes on Display 0 05:26:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:26:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:26:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:26:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:26:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:26:06 INFO - Intel Iris: 05:26:06 INFO - Chipset Model: Intel Iris 05:26:06 INFO - Type: GPU 05:26:06 INFO - Bus: Built-In 05:26:06 INFO - VRAM (Dynamic, Max): 1536 MB 05:26:06 INFO - Vendor: Intel (0x8086) 05:26:06 INFO - Device ID: 0x0a2e 05:26:06 INFO - Revision ID: 0x0009 05:26:06 INFO - Displays: 05:26:06 INFO - Display: 05:26:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:26:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:26:06 INFO - Display Serial Number: 1600x1200 60 05:26:06 INFO - Main Display: Yes 05:26:06 INFO - Mirror: Off 05:26:06 INFO - Online: Yes 05:26:06 INFO - Rotation: Supported 05:26:06 INFO - Return code: 0 05:26:06 INFO - Running post-action listener: _resource_record_post_action 05:26:06 INFO - Running post-run listener: _resource_record_post_run 05:26:07 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 36877824; Write bytes: 663694336; Read time: 379; Write time: 1816 05:26:07 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 363475456; Write bytes: 353314304; Read time: 28072; Write time: 662 05:26:07 INFO - run-tests - Wall time: 1171s; CPU: 53.0%; Read bytes: 30449152; Write bytes: 299152896; Read time: 301; Write time: 1119 05:26:07 INFO - Running post-run listener: _upload_blobber_files 05:26:07 INFO - Blob upload gear active. 05:26:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:26:07 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:07 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:26:07 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:26:08 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:26:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:26:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:08 INFO - (blobuploader) - INFO - Done attempting. 05:26:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:26:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:26:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:10 INFO - (blobuploader) - INFO - Done attempting. 05:26:10 INFO - (blobuploader) - INFO - Iteration through files over. 05:26:10 INFO - Return code: 0 05:26:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:26:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:26:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/79cc57331c24a1d5f7b7cd17f3b1b8ee7b3daacc11b8bb53adf601cfb162ce0e3b735641e315778de00930e7ad987a2ea5eb7101e62eb1572fd65e26ed6a716e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/031535582937b69574f47f6097bbd77faf6c2ad293fdf9a6ddcfb8c48a20989dc984c86460c35d0dda5b5877c5665567f3ab5957294f3dc5ef8582c3ff25cfd0"} 05:26:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:26:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:26:10 INFO - Contents: 05:26:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/79cc57331c24a1d5f7b7cd17f3b1b8ee7b3daacc11b8bb53adf601cfb162ce0e3b735641e315778de00930e7ad987a2ea5eb7101e62eb1572fd65e26ed6a716e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/031535582937b69574f47f6097bbd77faf6c2ad293fdf9a6ddcfb8c48a20989dc984c86460c35d0dda5b5877c5665567f3ab5957294f3dc5ef8582c3ff25cfd0"} 05:26:10 INFO - Running post-run listener: copy_logs_to_upload_dir 05:26:10 INFO - Copying logs to upload dir... 05:26:10 INFO - mkdir: /builds/slave/test/build/upload/logs 05:26:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.855750 ========= master_lag: 2.94 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-04-07 05:26:13.014986) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:13.016111) ========= 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.sXIcP37cJM/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.MvraEzTHtE/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/79cc57331c24a1d5f7b7cd17f3b1b8ee7b3daacc11b8bb53adf601cfb162ce0e3b735641e315778de00930e7ad987a2ea5eb7101e62eb1572fd65e26ed6a716e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/031535582937b69574f47f6097bbd77faf6c2ad293fdf9a6ddcfb8c48a20989dc984c86460c35d0dda5b5877c5665567f3ab5957294f3dc5ef8582c3ff25cfd0"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010965 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-aurora-macosx64/1460001592/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/79cc57331c24a1d5f7b7cd17f3b1b8ee7b3daacc11b8bb53adf601cfb162ce0e3b735641e315778de00930e7ad987a2ea5eb7101e62eb1572fd65e26ed6a716e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/031535582937b69574f47f6097bbd77faf6c2ad293fdf9a6ddcfb8c48a20989dc984c86460c35d0dda5b5877c5665567f3ab5957294f3dc5ef8582c3ff25cfd0"}' ========= master_lag: 0.30 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:13.330153) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:13.330572) ========= 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.sXIcP37cJM/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.MvraEzTHtE/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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.005132 ========= master_lag: 0.18 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-07 05:26:13.517444) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 05:26:13.517820) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-07 05:26:13.542438) ========= ========= Total master_lag: 3.76 =========