builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0089 starttime: 1484224122.05 results: success (0) buildid: 20170112030301 builduid: a87348b09fb347a4a60989c270650435 revision: 97d6f73643940256c0eb61e384c49bf6f6c49847 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.052441) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.053025) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.053310) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.128209) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.129117) ========= 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.HMZ83PQT3H/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.37NCcWN44g/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 --2017-01-12 04:28:42-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.78M=0.001s 2017-01-12 04:28:42 (7.78 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.214933 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.379987) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.380294) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HMZ83PQT3H/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.37NCcWN44g/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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.019094 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.475991) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:42.476341) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 97d6f73643940256c0eb61e384c49bf6f6c49847 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 97d6f73643940256c0eb61e384c49bf6f6c49847 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HMZ83PQT3H/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.37NCcWN44g/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 2017-01-12 04:28:42,567 truncating revision to first 12 chars 2017-01-12 04:28:42,567 Setting DEBUG logging. 2017-01-12 04:28:42,567 attempt 1/10 2017-01-12 04:28:42,567 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/97d6f7364394?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-12 04:28:42,963 unpacking tar archive at: mozilla-central-97d6f7364394/testing/mozharness/ program finished with exit code 0 elapsedTime=0.661650 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:43.171910) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:43.172238) ========= script_repo_revision: 97d6f73643940256c0eb61e384c49bf6f6c49847 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:43.172662) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:43.173019) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 04:28:43.196649) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-01-12 04:28:43.197048) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HMZ83PQT3H/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.37NCcWN44g/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 04:28:43 INFO - MultiFileLogger online at 20170112 04:28:43 in /builds/slave/test 04:28:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:28:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:28:43 INFO - {'append_to_log': False, 04:28:43 INFO - 'base_work_dir': '/builds/slave/test', 04:28:43 INFO - 'blob_upload_branch': 'Firefox', 04:28:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:28:43 INFO - 'branch': 'Firefox', 04:28:43 INFO - 'buildbot_json_path': 'buildprops.json', 04:28:43 INFO - 'config_files': ('talos/mac_config.py',), 04:28:43 INFO - 'default_actions': ('clobber', 04:28:43 INFO - 'read-buildbot-config', 04:28:43 INFO - 'download-and-extract', 04:28:43 INFO - 'populate-webroot', 04:28:43 INFO - 'create-virtualenv', 04:28:43 INFO - 'install', 04:28:43 INFO - 'run-tests'), 04:28:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:28:43 INFO - 'download_minidump_stackwalk': True, 04:28:43 INFO - 'download_symbols': 'ondemand', 04:28:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:28:43 INFO - 'tooltool.py': '/tools/tooltool.py', 04:28:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:28:43 INFO - '/tools/misc-python/virtualenv.py')}, 04:28:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:28:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:28:43 INFO - 'installer_path': 'installer.exe', 04:28:43 INFO - 'log_level': 'info', 04:28:43 INFO - 'log_name': 'talos', 04:28:43 INFO - 'log_to_console': True, 04:28:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:28:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:28:43 INFO - 'opt_config_files': (), 04:28:43 INFO - 'pip_index': False, 04:28:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:28:43 INFO - 'cmd': ('bash', 04:28:43 INFO - '-c', 04:28:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:28:43 INFO - 'enabled': True, 04:28:43 INFO - 'halt_on_failure': False, 04:28:43 INFO - 'name': 'check_screen_resolution'},), 04:28:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:28:43 INFO - 'cmd': ('bash', 04:28:43 INFO - '-c', 04:28:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:28:43 INFO - 'enabled': True, 04:28:43 INFO - 'halt_on_failure': False, 04:28:43 INFO - 'name': 'check_screen_resolution'},), 04:28:43 INFO - 'run_cmd_checks_enabled': True, 04:28:43 INFO - 'sps_profile': False, 04:28:43 INFO - 'sps_profile_interval': 0, 04:28:43 INFO - 'suite': 'dromaeojs-e10s', 04:28:43 INFO - 'system_bits': '32', 04:28:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:28:43 INFO - 'title': 't-yosemite-r7-0089', 04:28:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:28:43 INFO - 'use_talos_json': True, 04:28:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:28:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:28:43 INFO - 'work_dir': 'build'} 04:28:43 INFO - [mozharness: 2017-01-12 12:28:43.400703Z] Running clobber step. 04:28:43 INFO - Running pre-action listener: _resource_record_pre_action 04:28:43 INFO - Running main action method: clobber 04:28:43 INFO - rmtree: /builds/slave/test/build 04:28:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:28:44 INFO - Running post-action listener: _resource_record_post_action 04:28:44 INFO - [mozharness: 2017-01-12 12:28:44.980925Z] Finished clobber step (success) 04:28:44 INFO - [mozharness: 2017-01-12 12:28:44.981093Z] Running read-buildbot-config step. 04:28:44 INFO - Running pre-action listener: _resource_record_pre_action 04:28:44 INFO - Running main action method: read_buildbot_config 04:28:44 INFO - Using buildbot properties: 04:28:44 INFO - { 04:28:44 INFO - "project": "", 04:28:44 INFO - "product": "firefox", 04:28:44 INFO - "script_repo_revision": "production", 04:28:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:28:44 INFO - "repository": "", 04:28:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:28:44 INFO - "buildid": "20170112030301", 04:28:44 INFO - "stage_platform": "macosx64", 04:28:44 INFO - "basedir": "/builds/slave/test", 04:28:44 INFO - "nightly_build": "True", 04:28:44 INFO - "buildnumber": 50, 04:28:44 INFO - "slavename": "t-yosemite-r7-0089", 04:28:44 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:28:44 INFO - "platform": "yosemite_r7", 04:28:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:28:44 INFO - "branch": "mozilla-central", 04:28:44 INFO - "repo_path": "mozilla-central", 04:28:44 INFO - "revision": "97d6f73643940256c0eb61e384c49bf6f6c49847", 04:28:44 INFO - "pgo_build": "False", 04:28:44 INFO - "builduid": "a87348b09fb347a4a60989c270650435", 04:28:44 INFO - "slavebuilddir": "test" 04:28:44 INFO - } 04:28:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:28:44 INFO - Running post-action listener: _resource_record_post_action 04:28:44 INFO - [mozharness: 2017-01-12 12:28:44.984520Z] Finished read-buildbot-config step (success) 04:28:44 INFO - [mozharness: 2017-01-12 12:28:44.984610Z] Running download-and-extract step. 04:28:44 INFO - Running pre-action listener: _resource_record_pre_action 04:28:44 INFO - Running main action method: download_and_extract 04:28:44 INFO - mkdir: /builds/slave/test/build/tests 04:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:44 INFO - trying https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:28:44 INFO - Downloading https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:28:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 04:28:45 INFO - Downloaded 1199 bytes. 04:28:45 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:28:45 INFO - Using the following test package requirements: 04:28:45 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:28:45 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:45 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:28:45 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:45 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:28:45 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:45 INFO - u'jsshell-mac.zip'], 04:28:45 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:45 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:28:45 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:28:45 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:45 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:28:45 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:45 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:28:45 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:45 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:28:45 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:28:45 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:28:45 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:28:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:28:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:28:45 INFO - Fetch https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:28:46 INFO - Expected file size: 16314361 04:28:46 INFO - Obtained file size: 16314361 04:28:53 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:28:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:28:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:28:53 INFO - Fetch https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:28:54 INFO - Expected file size: 16314361 04:28:54 INFO - Obtained file size: 16314361 04:29:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:29:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:29:01 INFO - Fetch https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:29:02 INFO - Expected file size: 14403532 04:29:02 INFO - Obtained file size: 14403532 04:29:02 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:02 INFO - trying https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:29:02 INFO - Downloading https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:29:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 04:29:04 INFO - Downloaded 70209990 bytes. 04:29:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:29:04 INFO - mkdir: /builds/slave/test/properties 04:29:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:29:04 INFO - Writing to file /builds/slave/test/properties/build_url 04:29:04 INFO - Contents: 04:29:04 INFO - build_url:https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:29:05 INFO - Running post-action listener: _resource_record_post_action 04:29:05 INFO - Running post-action listener: set_extra_try_arguments 04:29:05 INFO - [mozharness: 2017-01-12 12:29:05.572308Z] Finished download-and-extract step (success) 04:29:05 INFO - [mozharness: 2017-01-12 12:29:05.572449Z] Running populate-webroot step. 04:29:05 INFO - Running pre-action listener: _resource_record_pre_action 04:29:05 INFO - Running main action method: populate_webroot 04:29:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:29:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:29:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:29:05 INFO - '--tppagecycles', 04:29:05 INFO - '7'], 04:29:05 INFO - 'tests': ['tsvgm']}}, 04:29:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:29:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:29:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:29:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:29:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:29:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:29:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:29:05 INFO - 'talos_options': ['--disable-e10s'], 04:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:29:05 INFO - 'talos_options': ['--disable-e10s'], 04:29:05 INFO - 'tests': ['damp', 'tps']}, 04:29:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:29:05 INFO - 'tests': ['damp', 'tps']}, 04:29:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:29:05 INFO - 'tests': ['dromaeo_dom']}, 04:29:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:29:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:29:05 INFO - 'tests': ['basic_compositor_video']}, 04:29:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:29:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:29:05 INFO - 'tests': ['a11yr', 04:29:05 INFO - 'ts_paint', 04:29:05 INFO - 'tpaint', 04:29:05 INFO - 'sessionrestore', 04:29:05 INFO - 'sessionrestore_no_auto_restore', 04:29:05 INFO - 'tabpaint']}, 04:29:05 INFO - 'other-e10s': {'tests': ['a11yr', 04:29:05 INFO - 'ts_paint', 04:29:05 INFO - 'tpaint', 04:29:05 INFO - 'sessionrestore', 04:29:05 INFO - 'sessionrestore_no_auto_restore', 04:29:05 INFO - 'tabpaint']}, 04:29:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:29:05 INFO - 'ts_paint', 04:29:05 INFO - 'tpaint', 04:29:05 INFO - 'sessionrestore', 04:29:05 INFO - 'sessionrestore_no_auto_restore', 04:29:05 INFO - 'tabpaint']}, 04:29:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:29:05 INFO - 'ts_paint', 04:29:05 INFO - 'tpaint', 04:29:05 INFO - 'sessionrestore', 04:29:05 INFO - 'sessionrestore_no_auto_restore', 04:29:05 INFO - 'tabpaint']}, 04:29:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:29:05 INFO - 'tests': ['a11yr', 04:29:05 INFO - 'ts_paint', 04:29:05 INFO - 'tpaint', 04:29:05 INFO - 'sessionrestore', 04:29:05 INFO - 'sessionrestore_no_auto_restore', 04:29:05 INFO - 'tabpaint']}, 04:29:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:29:05 INFO - 'tests': ['a11yr', 04:29:05 INFO - 'ts_paint', 04:29:05 INFO - 'tpaint', 04:29:05 INFO - 'sessionrestore', 04:29:05 INFO - 'sessionrestore_no_auto_restore', 04:29:05 INFO - 'tabpaint']}, 04:29:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:29:05 INFO - 'tests': ['tsvgx', 04:29:05 INFO - 'tsvgr_opacity', 04:29:05 INFO - 'tart', 04:29:05 INFO - 'tscrollx', 04:29:05 INFO - 'cart', 04:29:05 INFO - 'tsvg_static']}, 04:29:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:29:05 INFO - 'tsvgr_opacity', 04:29:05 INFO - 'tart', 04:29:05 INFO - 'tscrollx', 04:29:05 INFO - 'cart', 04:29:05 INFO - 'tsvg_static']}, 04:29:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:29:05 INFO - 'talos_options': ['--disable-e10s'], 04:29:05 INFO - 'tests': ['tp5o']}, 04:29:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:29:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:29:05 INFO - 'talos_options': ['--disable-e10s', 04:29:05 INFO - '--xperf_path', 04:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:05 INFO - 'tests': ['tp5n']}, 04:29:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:29:05 INFO - 'talos_options': ['--xperf_path', 04:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:05 INFO - 'tests': ['tp5n']}}, 04:29:05 INFO - 'talos.zip': {'path': '', 04:29:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:29:05 INFO - Running post-action listener: _resource_record_post_action 04:29:05 INFO - [mozharness: 2017-01-12 12:29:05.591049Z] Finished populate-webroot step (success) 04:29:05 INFO - [mozharness: 2017-01-12 12:29:05.591185Z] Running create-virtualenv step. 04:29:05 INFO - Running pre-action listener: _resource_record_pre_action 04:29:05 INFO - Running main action method: create_virtualenv 04:29:05 INFO - Creating virtualenv /builds/slave/test/build/venv 04:29:05 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 04:29:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:29:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:29:05 INFO - Using real prefix '/tools/python27' 04:29:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:29:06 INFO - Installing distribute.............................................................................................................................................................................................done. 04:29:09 INFO - Installing pip.................done. 04:29:09 INFO - Return code: 0 04:29:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:29:09 INFO - Reading from file tmpfile_stdout 04:29:09 INFO - Output received: 04:29:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:29:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:09 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 0x1037c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a53c91740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a7d30>, '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 0x1034cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359cbe8>, '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.HMZ83PQT3H/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.37NCcWN44g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 04:29:09 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 04:29:09 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 04:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMZ83PQT3H/Render', 04:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:09 INFO - 'HOME': '/Users/cltbld', 04:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:09 INFO - 'LOGNAME': 'cltbld', 04:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:09 INFO - 'MOZ_NO_REMOTE': '1', 04:29:09 INFO - 'NO_EM_RESTART': '1', 04:29:09 INFO - 'PAGER': '/bin/cat', 04:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:09 INFO - 'PWD': '/builds/slave/test', 04:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:09 INFO - 'SHELL': '/bin/bash', 04:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37NCcWN44g/Listeners', 04:29:09 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 04:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:09 INFO - 'USER': 'cltbld', 04:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:09 INFO - 'XPC_FLAGS': '0x0', 04:29:09 INFO - 'XPC_SERVICE_NAME': '0', 04:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:10 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 04:29:10 INFO - Cleaning up... 04:29:10 INFO - Return code: 0 04:29:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:10 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1037c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a53c91740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a7d30>, '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 0x1034cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359cbe8>, '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.HMZ83PQT3H/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.37NCcWN44g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 04:29:10 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>=3.1.1'] in /builds/slave/test/build 04:29:10 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>=3.1.1 04:29:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMZ83PQT3H/Render', 04:29:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:10 INFO - 'HOME': '/Users/cltbld', 04:29:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:10 INFO - 'LOGNAME': 'cltbld', 04:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:10 INFO - 'MOZ_NO_REMOTE': '1', 04:29:10 INFO - 'NO_EM_RESTART': '1', 04:29:10 INFO - 'PAGER': '/bin/cat', 04:29:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:10 INFO - 'PWD': '/builds/slave/test', 04:29:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:10 INFO - 'SHELL': '/bin/bash', 04:29:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37NCcWN44g/Listeners', 04:29:10 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 04:29:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:10 INFO - 'USER': 'cltbld', 04:29:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:10 INFO - 'XPC_FLAGS': '0x0', 04:29:10 INFO - 'XPC_SERVICE_NAME': '0', 04:29:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:10 INFO - Downloading/unpacking psutil>=3.1.1 04:29:10 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 04:29:10 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 04:29:10 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 04:29:10 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 04:29:10 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 04:29:10 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 04:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:29:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:14 INFO - Installing collected packages: psutil 04:29:14 INFO - Running setup.py install for psutil 04:29:14 INFO - building 'psutil._psutil_osx' extension 04:29:14 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 04:29:15 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 04:29:15 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 04:29:15 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 04:29:15 INFO - building 'psutil._psutil_posix' extension 04:29:15 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 04:29:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:29:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:29:15 INFO - ^ 04:29:15 INFO - 1 warning generated. 04:29:15 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 04:29:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:15 INFO - Successfully installed psutil 04:29:15 INFO - Cleaning up... 04:29:15 INFO - Return code: 0 04:29:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:15 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1037c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a53c91740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a7d30>, '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 0x1034cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359cbe8>, '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.HMZ83PQT3H/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.37NCcWN44g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 04:29:15 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.3'] in /builds/slave/test/build 04:29:15 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.3 04:29:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMZ83PQT3H/Render', 04:29:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:15 INFO - 'HOME': '/Users/cltbld', 04:29:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:15 INFO - 'LOGNAME': 'cltbld', 04:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:15 INFO - 'MOZ_NO_REMOTE': '1', 04:29:15 INFO - 'NO_EM_RESTART': '1', 04:29:15 INFO - 'PAGER': '/bin/cat', 04:29:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:15 INFO - 'PWD': '/builds/slave/test', 04:29:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:15 INFO - 'SHELL': '/bin/bash', 04:29:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37NCcWN44g/Listeners', 04:29:15 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 04:29:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:15 INFO - 'USER': 'cltbld', 04:29:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:15 INFO - 'XPC_FLAGS': '0x0', 04:29:15 INFO - 'XPC_SERVICE_NAME': '0', 04:29:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:29:15 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 04:29:15 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 04:29:15 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 04:29:15 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 04:29:15 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 04:29:15 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 04:29:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:29:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:29:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:29:18 INFO - Installing collected packages: mozsystemmonitor 04:29:18 INFO - Running setup.py install for mozsystemmonitor 04:29:18 INFO - Successfully installed mozsystemmonitor 04:29:18 INFO - Cleaning up... 04:29:18 INFO - Return code: 0 04:29:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:29:18 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:18 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:18 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1037c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a53c91740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a7d30>, '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 0x1034cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359cbe8>, '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.HMZ83PQT3H/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.37NCcWN44g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 04:29:18 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==2.5.1'] in /builds/slave/test/build 04:29:18 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==2.5.1 04:29:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMZ83PQT3H/Render', 04:29:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:18 INFO - 'HOME': '/Users/cltbld', 04:29:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:18 INFO - 'LOGNAME': 'cltbld', 04:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:18 INFO - 'MOZ_NO_REMOTE': '1', 04:29:18 INFO - 'NO_EM_RESTART': '1', 04:29:18 INFO - 'PAGER': '/bin/cat', 04:29:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:18 INFO - 'PWD': '/builds/slave/test', 04:29:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:18 INFO - 'SHELL': '/bin/bash', 04:29:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37NCcWN44g/Listeners', 04:29:18 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 04:29:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:18 INFO - 'USER': 'cltbld', 04:29:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:18 INFO - 'XPC_FLAGS': '0x0', 04:29:18 INFO - 'XPC_SERVICE_NAME': '0', 04:29:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:19 INFO - Downloading/unpacking jsonschema==2.5.1 04:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:29:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:22 INFO - Downloading functools32-3.2.3-2.tar.gz 04:29:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:29:22 INFO - warning: no files found matching '*.txt' 04:29:22 INFO - no previously-included directories found matching 'build' 04:29:22 INFO - no previously-included directories found matching 'dist' 04:29:22 INFO - no previously-included directories found matching '.git*' 04:29:22 INFO - Installing collected packages: jsonschema, functools32 04:29:22 INFO - Running setup.py install for functools32 04:29:23 INFO - warning: no files found matching '*.txt' 04:29:23 INFO - no previously-included directories found matching 'build' 04:29:23 INFO - no previously-included directories found matching 'dist' 04:29:23 INFO - no previously-included directories found matching '.git*' 04:29:23 INFO - Successfully installed jsonschema functools32 04:29:23 INFO - Cleaning up... 04:29:23 INFO - Return code: 0 04:29:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:23 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1037c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a53c91740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a7d30>, '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 0x1034cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359cbe8>, '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.HMZ83PQT3H/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.37NCcWN44g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 04:29:23 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:29:23 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 functools32==3.2.3-2 04:29:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMZ83PQT3H/Render', 04:29:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:23 INFO - 'HOME': '/Users/cltbld', 04:29:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:23 INFO - 'LOGNAME': 'cltbld', 04:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:23 INFO - 'MOZ_NO_REMOTE': '1', 04:29:23 INFO - 'NO_EM_RESTART': '1', 04:29:23 INFO - 'PAGER': '/bin/cat', 04:29:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:23 INFO - 'PWD': '/builds/slave/test', 04:29:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:23 INFO - 'SHELL': '/bin/bash', 04:29:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37NCcWN44g/Listeners', 04:29:23 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 04:29:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:23 INFO - 'USER': 'cltbld', 04:29:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:23 INFO - 'XPC_FLAGS': '0x0', 04:29:23 INFO - 'XPC_SERVICE_NAME': '0', 04:29:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:29:23 INFO - Cleaning up... 04:29:23 INFO - Return code: 0 04:29:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:23 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 0x1037c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a53c91740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a7d30>, '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 0x1034cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359cbe8>, '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.HMZ83PQT3H/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.37NCcWN44g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 04:29:23 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 04:29:23 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 04:29:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMZ83PQT3H/Render', 04:29:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:23 INFO - 'HOME': '/Users/cltbld', 04:29:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:23 INFO - 'LOGNAME': 'cltbld', 04:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:23 INFO - 'MOZ_NO_REMOTE': '1', 04:29:23 INFO - 'NO_EM_RESTART': '1', 04:29:23 INFO - 'PAGER': '/bin/cat', 04:29:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:23 INFO - 'PWD': '/builds/slave/test', 04:29:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:23 INFO - 'SHELL': '/bin/bash', 04:29:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37NCcWN44g/Listeners', 04:29:23 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 04:29:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:23 INFO - 'USER': 'cltbld', 04:29:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:23 INFO - 'XPC_FLAGS': '0x0', 04:29:23 INFO - 'XPC_SERVICE_NAME': '0', 04:29:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:23 INFO - Downloading/unpacking blobuploader==1.2.4 04:29:23 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 04:29:23 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 04:29:23 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 04:29:23 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 04:29:23 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 04:29:23 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 04:29:28 INFO - Downloading blobuploader-1.2.4.tar.gz 04:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:29:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:29:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:29:29 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 04:29:29 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 04:29:29 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 04:29:29 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 04:29:29 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 04:29:29 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 04:29:30 INFO - Downloading docopt-0.6.1.tar.gz 04:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:29:30 INFO - Installing collected packages: blobuploader, requests, docopt 04:29:30 INFO - Running setup.py install for blobuploader 04:29:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:29:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:29:30 INFO - Running setup.py install for requests 04:29:30 INFO - Running setup.py install for docopt 04:29:31 INFO - Successfully installed blobuploader requests docopt 04:29:31 INFO - Cleaning up... 04:29:31 INFO - Return code: 0 04:29:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:31 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 0x1037c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a53c91740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a7d30>, '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 0x1034cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359cbe8>, '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.HMZ83PQT3H/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.37NCcWN44g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 04:29:31 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 04:29:31 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 04:29:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMZ83PQT3H/Render', 04:29:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:31 INFO - 'HOME': '/Users/cltbld', 04:29:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:31 INFO - 'LOGNAME': 'cltbld', 04:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:31 INFO - 'MOZ_NO_REMOTE': '1', 04:29:31 INFO - 'NO_EM_RESTART': '1', 04:29:31 INFO - 'PAGER': '/bin/cat', 04:29:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:31 INFO - 'PWD': '/builds/slave/test', 04:29:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:31 INFO - 'SHELL': '/bin/bash', 04:29:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37NCcWN44g/Listeners', 04:29:31 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 04:29:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:31 INFO - 'USER': 'cltbld', 04:29:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:31 INFO - 'XPC_FLAGS': '0x0', 04:29:31 INFO - 'XPC_SERVICE_NAME': '0', 04:29:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:29:31 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-cEvMkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:29:31 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-Oev9DE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:29:31 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-GeQkfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:29:31 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-avBNaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:29:31 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-D_3AeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:29:31 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-8UGUAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:29:32 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-FueLgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:29:32 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-_N_x57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:29:32 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-MOn0xV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:29:32 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-8k9d19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:29:32 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-VdNPfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:29:32 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-m3yBqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:29:32 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-1JKHe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:29:32 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-pLWXdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:32 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-uLkZE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:29:33 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-VBoXim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:29:33 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-m8mtYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:29:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:29:33 INFO - Running setup.py install for manifestparser 04:29:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:29:33 INFO - Running setup.py install for mozcrash 04:29:33 INFO - Running setup.py install for mozdebug 04:29:33 INFO - Running setup.py install for mozdevice 04:29:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:29:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:29:33 INFO - Running setup.py install for mozfile 04:29:33 INFO - Running setup.py install for mozhttpd 04:29:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:29:34 INFO - Running setup.py install for mozinfo 04:29:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:29:34 INFO - Running setup.py install for mozInstall 04:29:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:29:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:29:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:29:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:29:34 INFO - Running setup.py install for mozleak 04:29:34 INFO - Running setup.py install for mozlog 04:29:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:29:34 INFO - Running setup.py install for moznetwork 04:29:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:29:34 INFO - Running setup.py install for mozprocess 04:29:35 INFO - Running setup.py install for mozprofile 04:29:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:29:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:29:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:29:35 INFO - Running setup.py install for mozrunner 04:29:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:29:35 INFO - Running setup.py install for mozscreenshot 04:29:35 INFO - Running setup.py install for moztest 04:29:35 INFO - Running setup.py install for mozversion 04:29:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:29:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:29:35 INFO - Cleaning up... 04:29:35 INFO - Return code: 0 04:29:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:35 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 0x1037c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a53c91740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a7d30>, '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 0x1034cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359cbe8>, '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.HMZ83PQT3H/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.37NCcWN44g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 04:29:35 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 04:29:35 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 04:29:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMZ83PQT3H/Render', 04:29:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:35 INFO - 'HOME': '/Users/cltbld', 04:29:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:35 INFO - 'LOGNAME': 'cltbld', 04:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:35 INFO - 'MOZ_NO_REMOTE': '1', 04:29:35 INFO - 'NO_EM_RESTART': '1', 04:29:35 INFO - 'PAGER': '/bin/cat', 04:29:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:35 INFO - 'PWD': '/builds/slave/test', 04:29:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:35 INFO - 'SHELL': '/bin/bash', 04:29:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37NCcWN44g/Listeners', 04:29:35 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 04:29:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:35 INFO - 'USER': 'cltbld', 04:29:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:35 INFO - 'XPC_FLAGS': '0x0', 04:29:35 INFO - 'XPC_SERVICE_NAME': '0', 04:29:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:29:36 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-Fo9yoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:29:36 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)) 04:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:29:36 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-s7z0Yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 04:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:29:36 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-UaPF86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:29:36 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)) 04:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:29:36 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-XaQ6Ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 04:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:29:36 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-mFOZXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:29:36 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)) 04:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:29:36 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-nvQsbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:29:36 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)) 04:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:29:36 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-CiruK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:29:36 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)) 04:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:29:36 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-B8CZGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:29:36 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)) 04:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:29:36 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-6XrdIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:29:37 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)) 04:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:29:37 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-Vl6rPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 04:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:29:37 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-pWUfsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:29:37 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)) 04:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:29:37 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-eGnkqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 04:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:29:37 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-s0YnPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:29:37 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)) 04:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:29:37 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-B0XBXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 04:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:37 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-Yx3axt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:37 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)) 04:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:29:37 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-yivlr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 04:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:29:37 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-U1Hnbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:29:37 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)) 04:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:29:37 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)) 04:29:37 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:29:37 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:29:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:29: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 04:29: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 04:29: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 04:29: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 04:29: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 04:29: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 04:29:40 INFO - Downloading blessings-1.6.tar.gz 04:29:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:29:40 INFO - Installing collected packages: blessings 04:29:40 INFO - Running setup.py install for blessings 04:29:41 INFO - Successfully installed blessings 04:29:41 INFO - Cleaning up... 04:29:41 INFO - Return code: 0 04:29:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:29:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:29:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:29:41 INFO - Reading from file tmpfile_stdout 04:29:41 INFO - Current package versions: 04:29:41 INFO - blessings == 1.6 04:29:41 INFO - blobuploader == 1.2.4 04:29:41 INFO - docopt == 0.6.1 04:29:41 INFO - functools32 == 3.2.3-2 04:29:41 INFO - jsonschema == 2.5.1 04:29:41 INFO - manifestparser == 1.1 04:29:41 INFO - mozInstall == 1.12 04:29:41 INFO - mozcrash == 1.0 04:29:41 INFO - mozdebug == 0.1 04:29:41 INFO - mozdevice == 0.49 04:29:41 INFO - mozfile == 1.2 04:29:41 INFO - mozhttpd == 0.7 04:29:41 INFO - mozinfo == 0.9 04:29:41 INFO - mozleak == 0.1 04:29:41 INFO - mozlog == 3.4 04:29:41 INFO - moznetwork == 0.27 04:29:41 INFO - mozprocess == 0.24 04:29:41 INFO - mozprofile == 0.28 04:29:41 INFO - mozrunner == 6.13 04:29:41 INFO - mozscreenshot == 0.1 04:29:41 INFO - mozsystemmonitor == 0.3 04:29:41 INFO - moztest == 0.8 04:29:41 INFO - mozversion == 1.4 04:29:41 INFO - psutil == 3.1.1 04:29:41 INFO - requests == 1.2.3 04:29:41 INFO - wsgiref == 0.1.2 04:29:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:41 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 0x1037c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a53c91740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a7d30>, '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 0x1034cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359cbe8>, '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.HMZ83PQT3H/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.37NCcWN44g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 04:29:41 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 04:29:41 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 04:29:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMZ83PQT3H/Render', 04:29:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:41 INFO - 'HOME': '/Users/cltbld', 04:29:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:41 INFO - 'LOGNAME': 'cltbld', 04:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:41 INFO - 'MOZ_NO_REMOTE': '1', 04:29:41 INFO - 'NO_EM_RESTART': '1', 04:29:41 INFO - 'PAGER': '/bin/cat', 04:29:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:41 INFO - 'PWD': '/builds/slave/test', 04:29:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:41 INFO - 'SHELL': '/bin/bash', 04:29:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37NCcWN44g/Listeners', 04:29:41 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 04:29:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:41 INFO - 'USER': 'cltbld', 04:29:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:41 INFO - 'XPC_FLAGS': '0x0', 04:29:41 INFO - 'XPC_SERVICE_NAME': '0', 04:29:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:41 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)) 04:29:41 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)) 04:29:41 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)) 04:29:41 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)) 04:29:41 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)) 04:29:41 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)) 04:29:41 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)) 04:29:41 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)) 04:29:41 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)) 04:29:41 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)) 04:29:41 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)) 04:29:41 INFO - Cleaning up... 04:29:41 INFO - Return code: 0 04:29:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:41 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 0x1037c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a53c91740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a7d30>, '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 0x1034cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359cbe8>, '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.HMZ83PQT3H/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.37NCcWN44g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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 04:29:41 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 04:29:41 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 04:29:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMZ83PQT3H/Render', 04:29:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:41 INFO - 'HOME': '/Users/cltbld', 04:29:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:41 INFO - 'LOGNAME': 'cltbld', 04:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:41 INFO - 'MOZ_NO_REMOTE': '1', 04:29:41 INFO - 'NO_EM_RESTART': '1', 04:29:41 INFO - 'PAGER': '/bin/cat', 04:29:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:41 INFO - 'PWD': '/builds/slave/test', 04:29:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:41 INFO - 'SHELL': '/bin/bash', 04:29:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37NCcWN44g/Listeners', 04:29:41 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 04:29:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:41 INFO - 'USER': 'cltbld', 04:29:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:41 INFO - 'XPC_FLAGS': '0x0', 04:29:41 INFO - 'XPC_SERVICE_NAME': '0', 04:29:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:29:42 INFO - Cleaning up... 04:29:42 INFO - Return code: 0 04:29:42 INFO - Running post-action listener: _resource_record_post_action 04:29:42 INFO - Running post-action listener: _start_resource_monitoring 04:29:42 INFO - Starting resource monitoring. 04:29:42 INFO - [mozharness: 2017-01-12 12:29:42.057646Z] Finished create-virtualenv step (success) 04:29:42 INFO - [mozharness: 2017-01-12 12:29:42.058703Z] Running install step. 04:29:42 INFO - Running pre-action listener: _resource_record_pre_action 04:29:42 INFO - Running main action method: install 04:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:29:42 INFO - Reading from file tmpfile_stdout 04:29:42 INFO - Detecting whether we're running mozinstall >=1.0... 04:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:29:42 INFO - Reading from file tmpfile_stdout 04:29:42 INFO - Output received: 04:29:42 INFO - Usage: mozinstall [options] installer 04:29:42 INFO - Options: 04:29:42 INFO - -h, --help show this help message and exit 04:29:42 INFO - -d DEST, --destination=DEST 04:29:42 INFO - Directory to install application into. [default: 04:29:42 INFO - "/builds/slave/test"] 04:29:42 INFO - --app=APP Application being installed. [default: firefox] 04:29:42 INFO - mkdir: /builds/slave/test/build/application 04:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:30:04 INFO - Reading from file tmpfile_stdout 04:30:04 INFO - Output received: 04:30:04 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:30:04 INFO - Running post-action listener: _resource_record_post_action 04:30:04 INFO - [mozharness: 2017-01-12 12:30:04.453228Z] Finished install step (success) 04:30:04 INFO - [mozharness: 2017-01-12 12:30:04.453378Z] Running run-tests step. 04:30:04 INFO - Running pre-action listener: _resource_record_pre_action 04:30:04 INFO - Running main action method: run_tests 04:30:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:30:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:30:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:30:04 INFO - 2017-01-12 04:30:04.468 screenresolution[1911:20067] starting screenresolution argv=screenresolution get 04:30:04 INFO - 2017-01-12 04:30:04.470 screenresolution[1911:20067] Display 0: 1600x1200x32@60 04:30:04 INFO - 2017-01-12 04:30:04.476 screenresolution[1912:20069] starting screenresolution argv=screenresolution list 04:30:04 INFO - Available Modes on Display 0 04:30:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:30:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:30:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:30:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:30:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:30:04 INFO - Intel Iris: 04:30:04 INFO - Chipset Model: Intel Iris 04:30:04 INFO - Type: GPU 04:30:04 INFO - Bus: Built-In 04:30:04 INFO - VRAM (Dynamic, Max): 1536 MB 04:30:04 INFO - Vendor: Intel (0x8086) 04:30:04 INFO - Device ID: 0x0a2e 04:30:04 INFO - Revision ID: 0x0009 04:30:04 INFO - Displays: 04:30:04 INFO - Display: 04:30:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:30:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:30:04 INFO - Display Serial Number: 1600x1200 60 04:30:04 INFO - Main Display: Yes 04:30:04 INFO - Mirror: Off 04:30:04 INFO - Online: Yes 04:30:04 INFO - Rotation: Supported 04:30:04 INFO - Return code: 0 04:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:30:04 INFO - Python 2.7.3 04:30:04 INFO - Return code: 0 04:30:04 INFO - grabbing minidump binary from tooltool 04:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:04 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035a7d30>, '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 0x1034cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10359cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:30:04 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 04:30:04 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 04:30:04 INFO - Calling ['/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'] with output_timeout 600 04:30:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:30:04 INFO - Return code: 0 04:30:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:30:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:30:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:30:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:30:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0089', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0089 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMZ83PQT3H/Render', 04:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:04 INFO - 'HOME': '/Users/cltbld', 04:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:04 INFO - 'LOGNAME': 'cltbld', 04:30:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:30:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:04 INFO - 'MOZ_NO_REMOTE': '1', 04:30:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:30:04 INFO - 'NO_EM_RESTART': '1', 04:30:04 INFO - 'PAGER': '/bin/cat', 04:30:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:04 INFO - 'PWD': '/builds/slave/test', 04:30:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:04 INFO - 'SHELL': '/bin/bash', 04:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.37NCcWN44g/Listeners', 04:30:04 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 04:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:04 INFO - 'USER': 'cltbld', 04:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:04 INFO - 'XPC_FLAGS': '0x0', 04:30:04 INFO - 'XPC_SERVICE_NAME': '0', 04:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0089', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:30:05 INFO - mozversion application_buildid: 20170112030301 04:30:05 INFO - mozversion application_changeset: 97d6f73643940256c0eb61e384c49bf6f6c49847 04:30:05 INFO - mozversion application_display_name: Nightly 04:30:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:30:05 INFO - mozversion application_name: Firefox 04:30:05 INFO - mozversion application_remotingname: firefox 04:30:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:30:05 INFO - mozversion application_vendor: Mozilla 04:30:05 INFO - mozversion application_version: 53.0a1 04:30:05 INFO - mozversion platform_buildid: 20170112030301 04:30:05 INFO - mozversion platform_changeset: 97d6f73643940256c0eb61e384c49bf6f6c49847 04:30:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:30:05 INFO - mozversion platform_version: 53.0a1 04:30:05 INFO - using testdate: 1484224205 04:30:05 INFO - actual date: 1484224205 04:30:05 INFO - starting webserver on 'localhost:55658' 04:30:05 INFO - SUITE-START | Running 2 tests 04:30:05 INFO - TEST-START | dromaeo_css 04:30:05 INFO - operating with platform_type : mac_ 04:30:05 INFO - Initialising browser for dromaeo_css test... 04:30:05 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmphTtzZX/profile http://localhost:55658/getInfo.html) 04:30:08 INFO - PROCESS | 1922 | 2017-01-12 04:30:08.175 plugin-container[1926:20232] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 04:30:08 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:30:08 INFO - PROCESS | 1922 | 2017-01-12 04:30:08.176 plugin-container[1926:20232] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 04:30:08 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:30:08 INFO - PROCESS | 1922 | 2017-01-12 04:30:08.176 plugin-container[1926:20232] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:30:08 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 04:30:08 INFO - PROCESS | 1922 | colorDepth:24 04:30:08 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 04:30:08 INFO - PROCESS | 1922 | __metrics 04:30:08 ERROR - PROCESS | 1922 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3242: TypeError: this.tabs is undefined 04:30:08 INFO - TEST-INFO | 1922: exit 0 04:30:08 INFO - Browser initialized. 04:30:08 INFO - Running cycle 1/1 for dromaeo_css test... 04:30:08 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmphTtzZX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:30:11 INFO - PROCESS | 1927 | 2017-01-12 04:30:11.531 plugin-container[1928:20355] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 04:30:11 INFO - PROCESS | 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:30:11 INFO - PROCESS | 1927 | 2017-01-12 04:30:11.532 plugin-container[1928:20355] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 04:30:11 INFO - PROCESS | 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:30:11 INFO - PROCESS | 1927 | 2017-01-12 04:30:11.532 plugin-container[1928:20355] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:30:21 INFO - PROCESS | 1927 | RSS: Main: 233328640 04:30:21 INFO - PROCESS | 1927 | 04:33:24 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:55658/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55658/tests/dromaeo/cssquery-ext.html) 04:33:25 INFO - PROCESS | 1927 | RSS: Main: 255713280 04:33:25 INFO - PROCESS | 1927 | 04:35:09 INFO - PROCESS | 1927 | ************************* 04:35:09 INFO - PROCESS | 1927 | A coding exception was thrown and uncaught in a Task. 04:35:09 INFO - PROCESS | 1927 | 04:35:09 INFO - PROCESS | 1927 | Full message: ReferenceError: fetch is not defined 04:35:09 INFO - PROCESS | 1927 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:35:09 INFO - PROCESS | 1927 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:35:09 INFO - PROCESS | 1927 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:35:09 INFO - PROCESS | 1927 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:35:09 INFO - PROCESS | 1927 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:35:09 INFO - PROCESS | 1927 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:35:09 INFO - PROCESS | 1927 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:35:09 INFO - PROCESS | 1927 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:35:09 INFO - PROCESS | 1927 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:35:09 INFO - PROCESS | 1927 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:35:09 INFO - PROCESS | 1927 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:35:09 INFO - PROCESS | 1927 | 04:35:09 INFO - PROCESS | 1927 | ************************* 04:36:28 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:55658/tests/dromaeo/cssquery-ext.html (next: http://localhost:55658/tests/dromaeo/cssquery-jquery.html) 04:36:28 INFO - PROCESS | 1927 | RSS: Main: 265777152 04:36:28 INFO - PROCESS | 1927 | 04:39:30 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:55658/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55658/tests/dromaeo/cssquery-mootools.html) 04:39:31 INFO - PROCESS | 1927 | RSS: Main: 274432000 04:39:31 INFO - PROCESS | 1927 | 04:42:33 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:55658/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55658/tests/dromaeo/cssquery-prototype.html) 04:42:33 INFO - PROCESS | 1927 | RSS: Main: 271527936 04:42:33 INFO - PROCESS | 1927 | 04:45:36 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:55658/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55658/tests/dromaeo/cssquery-yui.html) 04:45:37 INFO - PROCESS | 1927 | RSS: Main: 276123648 04:45:37 INFO - PROCESS | 1927 | 04:48:40 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:55658/tests/dromaeo/cssquery-yui.html (next: http://localhost:55658/tests/dromaeo/cssquery-dojo.html) 04:48:40 INFO - PROCESS | 1927 | RSS: Main: 278327296 04:48:40 INFO - PROCESS | 1927 | 04:48:40 INFO - PROCESS | 1927 | __start_tp_report 04:48:40 INFO - PROCESS | 1927 | _x_x_mozilla_page_load 04:48:40 INFO - PROCESS | 1927 | _x_x_mozilla_page_load_details 04:48:40 INFO - PROCESS | 1927 | |i|pagename|runs| 04:48:40 INFO - PROCESS | 1927 | |0;dojo.html;3275.721563667088;3325.7006869381753;3343.8996830095093;3379.5944486661606;3398.269372085002;5131.384233891934;5595.38450770415;5634.830955071355;5644.096944488888;5746.511546518541;5618.775248990036;5668.773249070032;5698.886022279562;5735.139729040646;5802.796902108427;5156.948430515685;5159.974200128994;5241.633085684004;5273.683578985273;5340.652857564246;5238.947610523903;5335.599830012745;5382.703951282678;5399.460053994609;5425.186222066682;5146.922796158061;5211.24436956643;5216.113260745682;5308.761105750251;5320.068987927098;5101.897962040738;5180.041692286902;5200.037992971313;5250.501202385767;5254.054270231357;4208.621224089816;5130.819401754276;5165.896682066337;5204.505571970694;5226.843194704165;5537.280153580009;5600.383957764683;5657.1514272858985;5695.14572814077;5709.74306156224;6102.701334035014;6121.422488707842;6134.04922237054;6144.631322120687;6177.845553861144;6215.2541694996435;6236.158118653969;6241.188645476104;6242.5942313749465;6248;5766.509846663039;5779.797707080232;5792.304923409218;5802.564807639402;5853.882922341572;5229.712365819841;6160.83715312499;6275.937240627627;6299.055141728777;6329.177206963065;6184.969075154595;6203.038529028008;6229;6242.719077641517;6251.156093927352;6115.970584430425;6282.591631543935;6288.308286088527;6297.96851015742;6298.559100862895;5608.747606357723;5748.942510574924;6136.917362303815;6169.907451388232;6262.46769024638;6247.656378899204;6256.6871656417;6271.247450305901;6272.278687950944;6275.278342990523;4343.457067866517;4987.202047672355;5058.620603454756;5078.415982162025;5121;4451.0430257494145;4484.148011877733;4518.322251662211;4588.990422107131;4608.124456353282;5815.796447124415;5839.445252700986;5861.6189947653265;5935.198748168942;5945.227120474311;6235.282942461584;6270.372962703785;6273.059041143865;6288.748450062038;6293.056041593706;5852.122181672783;5896.587238893237;5925.940740592625;5928.198407497858;5940.34656187819;5401.3248343957;5467.699276539749;5475.151351540518;5528.502434780889;5531.3362396511875;6446.967765161145;6498.155239818888;6501.05734668461;6513.837154071091;6528.118703975093;4862.781174847069;6264.968675156777;6266.373362663697;6295.779647712308;6314.242290925118;6229.937700622936;6284.308726040041;6477.125588045554;6514.120593719977;6526.575772574767;6146.293176284785;6158.0147176451555;6192.783252586138;6244.969580019246;6260.467860231931;6264.843378915563;6270.184875966095;6275.497960163085;6279.246490421178;6287.088372186099;6071.423214794588;6075.2405949256345;6080.513558915188;6173.166622505904;6211.596246243979;5752.367239603724;6104.368208815833;6104.7926496150985;6119.602225855305;6214.875702486015;5956.97021514907;5980.312264089685;6062.575619706755;6074.205865301834;6087.57386982907;5931.584789064724;6071.483923866521;6097.743237184312;6112.113743507255;6138.296437970497;6097.634141951497;6121.8493044937295;6134.7427470591965;6135.722664536972;6139.522803442411 04:48:40 INFO - PROCESS | 1927 | |1;ext.html;435.8866694659388;459.45554517896284;467.201086142696;504.67952849940286;515.556621305677;39420.802895985515;41161.176776464454;41241.38137927934;41259;41494.962625934364;42286.78856605721;42668;42762.35856462156;42828.929276768096;42856.571434285645;9271.026542213054;9456.905430945671;9491.95254023729;9492.620295188184;10808.081313088396;10903.618373356934;11082.778344433107;11114.999650031496;11118.444077796119;11125.777484450306;10164.54259558318;10685.78628427431;11122.721931951684;11148.491589392403;11166.274192177523;9964.302498825085;10436.739081522946;11091.445427728662;11133.944330278378;11176.776464470746;9706.883708373556;10449.68650940473;10955;10989.340639561651;10993.725156871062;36669.63330366689;36912.81543592265;36926;37222.81388593067;37272.44091338632;3158.652548219694;3985.820638071265;4020.959790402088;4028.516578010628;4165.000399904001;27883;28571.142865713853;28845.278868028257;28882.42235155306;29142.125736227736;3107.456195165837;3167.762417818673;3186.314942287396;3276.0990727550115;3284.359549887749;441891;448903;449409;451197;451350.99999999674;56014.15978760322;56074.71962640161;56300.43699562952;56408.43591564114;56462.43537564572;47367;55655.721721391135;59046.40953590409;59249;59529.40470595239;15164.469243576423;15279.92360038215;15442.382304707686;15472.458463953708;15481.380744770084;10689.198310126772;11018.614348497924;11679.649610511698;11882.16824822254;12034.879651203375;17841.7323740144;17939.192736326895;17986.91006544959;18013.27946882136;18044.007579582987;6833.6241506716615;6887.380135787803;7858;7966.561839098905;8367.372447066495;887.2325438495784;895.9641614335484;899.1727610598265;920.1902325953118;931.3992474853817;30101.946431874778;30256.09231723052;30427.543586846215;30652.38695226083;30751;1078.8435676826973;1100.9394483303495;1103.089950790601;1130.5477808876515;1260.2627462934104;12068.517259309705;15722.921385393;15737.842621573867;15981.200939952956;16009.919950400174;599205;858986;876022;878973;880791;52901.47098529043;53199.468005320225;53478.46521534813;53537.1969420459;54528.45471545313;1423.8647328503775;1440.1503113163285;1466.2448838848002;1466.2741942738412;1479.6522817137964;28442.857785710938;28527.714722853336;28688.713112868605;28898.27754306159;28970.27574310659;45360;45985.85035374142;46082;46093.07813843771;46351.3047304284;568937;572178;575376;575678;577412;25160.622590661522;25220.74779252184;25408.983640654165;25586.360340991625;25592.360190995376;34721.9583412498;35337;41270.58729412667;41595.58404415917;41728;34320.313593727486;34477.82761086179;35119;36342.63657363393;36376.81811590925;32638.673613263563;34577.65422345845;34668.47997279993;34838.9548313551;36329.46633411574;33418.83290583531;33685.49471757877;33741.83129084339;33857.322853543286;35680.7511737088;23859.284221473383;30094.84952575223;31312.686873130977;32128.839355804008;32639.34721305608;20039.398818035483;24083.554986700856;24806.50386992286;24842;24978.37554061163 04:48:40 INFO - PROCESS | 1927 | |2;jquery.html;341.3769869987273;385.6567654787238;397.21153510282096;399.26535175277485;412.4802748536845;19886.602267954633;20000.4999875003;20887.791122088776;21228.469288267803;21342.89328553355;12334.81497777534;12457.439415226314;14186.503472378443;21147.894260528716;21846.672299915474;5263.578913686905;5489.451054894498;5493.752781124859;5498.917516237259;5569.443055694439;5863.677497737623;5985.132155837401;6015.3383127855905;6038.516918646497;6040.697965101727;5324.094903866333;5449.100898351767;5991.910121348184;5992.640441573498;5994.790182343619;5379.784808607651;5396.325459317585;5400.351957765054;5432.511074003319;5435;4537.614302784267;4988.802687355045;5298;5312.442193569658;5338.27933229013;13649.454021839114;13685.384157712828;13734.45062197511;13784.104033237907;24666.630000550005;2694.8517831519257;2858.7141285871453;3003.234175285288;3018.2756138526815;3132.7650426218124;9297.349185557014;9593.856092158623;9972.15236704881;10037.297389182688;10627;1782.6880295948158;1878.8872667639985;1880.2666959885655;1903.7810651775085;1907.9332223372255;107620;108690.45654771675;125094;133484;136022;11638.185327027028;11857.822132668018;12024.939875300743;12065.155439119353;12092.334921579222;15634.45279415215;15740.842591574166;15778.92110539463;15793.842061579237;15819.209039547977;8087.47431416956;8768.605412756442;8908.510031948304;8999.280057595377;9024.729258122266;7184.676689548878;7368.926310736932;8694.478466268378;8819.867701984474;8898.59956301968;4801.375821143229;4813.879653008633;4854.368932038883;4908.828191013369;4911.386076740408;1684.5114916674027;2055.660815965379;2057.403353027635;2058.5779915117364;2068.803463670949;946.7633091727068;956.31623389277;962.764122789921;972.1713476750051;1019.90820826126;13529.18824870511;13702.314884255748;13925.025248232527;14105;14223.573292801233;958.4249450329886;970.1421777296539;977.1044174301832;993.6969224386631;1006.0332050840589;7106.928930710626;7135.286471352823;7274.345308922222;7299.708011679473;7342.155652100077;664397;673639;677213;681755;689229;118251;138135;138884.30557847145;139554;167352.16323918302;971.965981190669;997.5535473563217;1029.7528593137592;1030.3972176276811;1033.534909290822;17534.668413001487;19870.90064549668;20402.591948160658;20539.178432863115;20791.376258712262;59516.70241648764;59696.80606387935;60844.39155608387;60962.390376095675;62095.37904620896;85792;88747.22505549982;89780.5510972441;90002;90509.54745226231;7156.928430715834;8197.098319184766;8409.603263069996;8428.873566896624;8560.315174785877;8864.113588641085;13082.934585327013;13160.80258796138;13163.67090822737;13647.044706870423;11319.830202546804;11519.88480115188;11573.826392603949;11699;11795.115366347387;9939.254555908192;11334.659960201207;11605.245659032136;11636.476358563716;11820.82268765952;10096.949515252376;11047.226694131656;11461.942690286494;11466.77066458683;11743.530258789553;8196.508209507449;8208.958955205424;8399.160083991552;8582.527960962207;8619.741407757778;8253.380996425172;10538.57845686164;10835.891641083488;11078.224524283223;11177.888221117684 04:48:40 INFO - PROCESS | 1927 | |3;mootools.html;1198.5625246784925;1439.8992070555053;1472.7938088667588;1477.4828809916528;1489.1139771835758;7547.05661792276;7806.180351063142;7843;8221.383396245275;8240.91759082409;7039.296070392958;8017.959910200456;8276.462029968048;8282.006159260882;8485.0666426693;1944;2268.4669102760836;2304.2396009316926;2355.870427126516;2355.9175428859994;2069;2139.0374331550865;2321.5356928614347;2359.8702071386065;2368.1356304948654;2245.8764767937755;2317.6523521471745;2321.779430954065;2344.4138965258685;2348.542034303303;2317.9304620861403;2325.418645338657;2327.662488939103;2341.4263505441104;2348.859068455898;2275.7724227577273;2284.314705588317;2330.8135349172194;2334.1130370459286;2356.045801450415;7164.928350716478;8525.872111918326;8534.274586660142;8562.529060901648;8582.785430364229;5171.146760784466;5287.391949925769;5304.787808487656;5306.283651707009;5309.681419114865;3207.747609753701;3343.498475228735;3415.0096472023083;3430.2110514581536;3483.4426491761196;1108;1137.8805225451374;1172.8709841917382;1183;1184.2657552317532;10958.56165753369;10978.231523793258;11009.61466348674;11046.834297485544;11058.391788451632;4411.735295882258;4704.69419487739;4767.880802979953;4791.95208047922;4795.160846851787;9582.089701478348;9627.181689556326;9672.903270967341;9712.465814380135;9712.465814380135;5218.112920803473;5283.947160528345;5297.099493086184;5309.230161626542;5311.681299122065;3908.882733517999;4029.294873397144;4719.669623126349;4722.881927951828;4824.541668541483;3291.440455122635;3871.380579107329;3909.4948444848937;3944.9605503944595;3976.741511801724;1082.9675109746563;1192.6839387562302;1236.042067397756;1270.790319597256;1308.1431662261234;896.3546246702364;913.4519288427024;913.7578541686458;921.1295226964291;930.4510338900081;5568.136938774496;5573.749181286852;5725.255716756878;5768.3366412862215;5829.591928565044;812.29736278119;844.9197326253995;854.5385491834464;1009.5658866687395;1035.9896401036044;3769.3780526212868;3791.7724936503896;3801.2967600993907;3873;3917.804109794499;9781.41311521311;10143.847842282372;10146.289759716896;10146.985301469795;10176.287659863887;10066;10094.242931780145;10113.898861011443;10117.038881306264;10127.7468063299;7399.778006659809;7587.355074818703;7802.765917022499;7888.329491993245;8018;5501.614886958035;5678.154599755014;5776.537876969916;5792.82621521355;5890.14592884038;7935;9094.408863423856;9096.499692516974;9182.540872956191;9299.628014879328;8528.317734581342;8554.91445085541;8905;9260.166585007382;9332.580033898355;6649.202095748541;7356.154042285206;7367.079115110611;7368.926310736824;7369.63151842395;7908.723194688158;7936;7947.205279472007;7999.9600001999615;8039.075506316614;5840.269966254218;6163.322034576104;6377.585456945284;7206.027186329988;7241.203467618452;7046.647667616701;7106.2017039824;7127.144742630918;7186.676599552928;7202.279772022755;6351.555391122577;6379.489640828815;6459;6474.223093228654;6482;4867.659263851497;4960.7767650456535;4996.3005179274205;5028.874278142929;5134.5378915897745;4376.343548467756;5037.924431133462;5060.544550990428;5125.256837758429;5214.739263036909 04:48:40 INFO - PROCESS | 1927 | |4;prototype.html;315.771648288159;331.74455669134767;351.94192958161904;368.2744992365041;370.47947633574836;5123.718195499246;5167.379914410267;5323.60072994525;5493.148561972896;5542.5565954723625;4190.266703326921;4675.812967481296;5297.814576489824;5315.627906046579;5397.946020539794;3635.546006445652;3828.3109040372724;3955.9318983874336;4249.915001699964;4276.828926842931;3867.38121900496;4282.616732781099;4287.678424118188;4303.91392172158;4309.245881970658;3230.6284777250567;4011.6991225658194;4012.228505205098;4039.939400908989;4062.53280872699;3427.7600567960253;3873.612638736132;3911.178652482982;4080.6939479538855;4156.404709925922;2979.8808047678067;3184.7738620630994;3543.875964341236;3569.839357228931;3574.1422058705984;4396.670249731251;4783.760811959388;4969.527894849984;5280.471952804727;5298.36419629643;3897.8830635080994;4478.955210447886;4482.5069242383315;4684.906301873977;4703.952960470386;4830.975845120752;5696;5732.971335143339;5775.826725198251;5803.448672376117;3334.966160490256;3469.2367679110557;3596.964030359689;3619.0952261934517;3857.228554289153;5211.478852114796;5617.9157312640755;5700.857478563028;5923.001973667757;5964.821055368346;3434.9484757728655;3450.7584469086923;3760.623937606272;3888.202918401721;3904.8047597620007;3176.3806057818965;3340.682635149657;3949;3955.5253369595257;3967.3652215645357;3430.6569343065494;4557.5442455754155;4597.161135545742;4634.884127896762;4653.162430762428;5098.362704661918;5293.788248470018;5783.71081445926;5794.82615521535;5795.7391917362975;1150.3787954504473;1172.1384782185087;1225.583301677434;1263.8988880889506;1281.923084614926;872.4024043530202;872.5768002518773;876.237673224299;889.564113584339;896.9506677132821;5125.79496820123;5146.202338637517;5159.58723302135;5168.640383750592;5193.844184674465;4772.570468657768;5556.083246264321;5621.578381621394;5624.775008999594;5643.068893632505;4400.625946794495;5059.190529515334;5098.413682426494;5104;5147.485251474823;2543.9491210175693;2564.3845477085365;2699.3926366567757;2750.8624568771484;2990.925226869346;4789.353437285991;4801.830791677476;5230.14329912883;5325.920111198335;5835.44563266489;4904.743817843761;5006.571812670852;5494.835154945358;5845.561582881301;5860.2381690380835;5289.523942845085;5655.1799989001365;5893.734781934823;5916.526677865846;5936.5844390892225;5066.9359434519165;5153.639245252796;5447.618666693294;5833.387494313206;5905.763769449174;4431.889202769957;5624.328443989236;5864.413558644102;5877.177195192591;5911.497522710618;5272.9435520147945;5748.281464816898;5843.912341314969;6188.752449902134;6211.503079753699;3471.5660542432197;3638.7270954677974;3824.7489633811037;3918.5101862267215;3958.247932892741;5257.316548848626;5446.891062178814;5955.970220148872;5985.610935289193;5990.274425136639;5323.600729945192;5532.861678458071;5712.742926568398;5732.70691723314;5750.6262092963825;5063.544281014726;5686.203931449518;5721.485066343883;5752.884942301047;5761.477240366293;4524.751138687272;4792.969511555032;5104.770285337243;5441.428649991853;5724.656520608777;4710.396546641753;5132.409772876018;5441.4291994723035;5809.419058094157;5862.062070068769;4370.934435983399;4528.049109687003;4807.663463557517;5724.771009159587;5735.483806457439 04:48:40 INFO - PROCESS | 1927 | |5;yui.html;670.875887960727;687.3435868745348;718.6514540447882;759.7540034343677;837.8282452097319;14634;15511.53465396037;15631.452899148531;15677.059376437408;15702.979306345094;13948.930255348734;14538.927305363484;14666.63333416665;14674.706505869875;14690;862.6808081010035;873.829603227371;876.2201640539924;879.0462348352046;879.2394578689419;683.0300972618893;702.0241863809283;848.6096395658004;869.3045563549167;869.5912920927154;719.7157122936437;933.3746389918737;934.5233930695326;935.3546053223254;937.2736129499658;919.31511024287;927.20260575905;929.1266209762802;930.4231376546517;931.0038259062806;686.2656957055952;690.526989012528;690.6857379892188;692.1140939597324;694.1392673085338;14896.776548351783;15396.384144634201;15438.536843894588;15519.301631426613;15576.376944922189;14189.716205675828;14658.70682586353;14763.852361476354;14951.177685227309;15246.923765381212;11613;11734.823977640342;11863;11866.881331186663;11909.821352679717;3731;3736.346139425617;3759.830807613637;3768.434734789776;3790.166163444038;86676;108214.91785082126;108608;109278.45360773224;109813;11071.612493562849;11331.886681133248;11396.316221026766;11422.200445968703;11594.942025289989;19472.318468853522;20250.493737656678;24307.513849723262;24450;24718;7870.4884182529095;7926.603669816486;7928.127905930343;7936.920630793618;8029.8394032120195;3771.7548359356556;4275.251830929575;4276.636485898734;4332.783360832009;4369.628581570539;3590.1563132663805;3619.8914032579064;3636.4363523653874;3668.614795446493;3674.797886116236;687.0347162237065;690.1166506871213;725.1805459830358;725.8874874394477;735.2280105888796;578.2742657964312;677.6340775981014;698.671624336561;700.2332445971653;709.4785332780402;9566.856497152548;10215.23385746072;10236.232282578836;10387.84418233727;10517.948205179573;549.598792881199;565.9858503537362;568.2635235313625;610.7618028968706;644.6100109433819;4948.4309304429735;5052.823151189691;5078.822241221539;5112.156494178494;5134.512221338967;59093.704531477066;77525.61237193778;99262;103226;106304.46847765712;90265;91945;93824.06175938153;94638.52680736552;95266;59517;76815.61592192115;81007.59496202481;82502.58748706339;83034;9732.513374331114;9766.46284454362;9799.11807937289;9802.166815820736;9844.753881153028;48155;48424.757876210395;48914;49429.01141977212;49566.50433495619;68418;70542.64728676324;71218;71250.28749712436;73150.63424682843;11738.237014594024;11801.468933897824;11828.34944078084;11935.820962685393;12025.398730063287;13072.281024543361;13079.366343731808;13223.471061157446;13229.933850330686;13793.86206137966;11957.820632690691;11983.26442700454;12026.639200823989;12078.396080196131;12094;11835.234197722899;11918.901085717602;11932.463039163085;11979.640610781691;12036.458359373673;12021.81967270509;12045.156839021534;12077.818832717341;12091.39543022863;12114.091443141624;9760;9775.902240977784;9865.161461275871;9998.350107243006;10344.37933723979;9944.900550994398;9995.800083998134;10014.64948726791;10033;10033.19734421259 04:48:40 INFO - PROCESS | 1927 | __end_tp_report 04:48:40 INFO - PROCESS | 1927 | __start_cc_report 04:48:40 INFO - PROCESS | 1927 | _x_x_mozilla_cycle_collect,369 04:48:40 INFO - PROCESS | 1927 | __end_cc_report 04:48:40 INFO - PROCESS | 1927 | __startTimestamp1484225320257__endTimestamp 04:48:40 INFO - PROCESS | 1927 | 04:48:40 INFO - PROCESS | 1927 | ------- Summary: start ------- 04:48:40 INFO - PROCESS | 1927 | Number of tests: 6 04:48:40 INFO - PROCESS | 1927 | 04:48:40 INFO - PROCESS | 1927 | [#0] dojo.html Cycles:165 Average:5757.44 Median:5980.31 stddev:660.15 (11.0%) stddev-sans-first:632.81 04:48:40 INFO - PROCESS | 1927 | Values: 3275.7 3325.7 3343.9 3379.6 3398.3 5131.4 5595.4 5634.8 5644.1 5746.5 5618.8 5668.8 5698.9 5735.1 5802.8 5156.9 5160.0 5241.6 5273.7 5340.7 5238.9 5335.6 5382.7 5399.5 5425.2 5146.9 5211.2 5216.1 5308.8 5320.1 5101.9 5180.0 5200.0 5250.5 5254.1 4208.6 5130.8 5165.9 5204.5 5226.8 5537.3 5600.4 5657.2 5695.1 5709.7 6102.7 6121.4 6134.0 6144.6 6177.8 6215.3 6236.2 6241.2 6242.6 6248.0 5766.5 5779.8 5792.3 5802.6 5853.9 5229.7 6160.8 6275.9 6299.1 6329.2 6185.0 6203.0 6229.0 6242.7 6251.2 6116.0 6282.6 6288.3 6298.0 6298.6 5608.7 5748.9 6136.9 6169.9 6262.5 6247.7 6256.7 6271.2 6272.3 6275.3 4343.5 4987.2 5058.6 5078.4 5121.0 4451.0 4484.1 4518.3 4589.0 4608.1 5815.8 5839.4 5861.6 5935.2 5945.2 6235.3 6270.4 6273.1 6288.7 6293.1 5852.1 5896.6 5925.9 5928.2 5940.3 5401.3 5467.7 5475.2 5528.5 5531.3 6447.0 6498.2 6501.1 6513.8 6528.1 4862.8 6265.0 6266.4 6295.8 6314.2 6229.9 6284.3 6477.1 6514.1 6526.6 6146.3 6158.0 6192.8 6245.0 6260.5 6264.8 6270.2 6275.5 6279.2 6287.1 6071.4 6075.2 6080.5 6173.2 6211.6 5752.4 6104.4 6104.8 6119.6 6214.9 5957.0 5980.3 6062.6 6074.2 6087.6 5931.6 6071.5 6097.7 6112.1 6138.3 6097.6 6121.8 6134.7 6135.7 6139.5 04:48:40 INFO - PROCESS | 1927 | 04:48:40 INFO - PROCESS | 1927 | [#1] ext.html Cycles:180 Average:72645.24 Median:34399.07 stddev:171319.62 (498.0%) stddev-sans-first:171714.43 04:48:40 INFO - PROCESS | 1927 | Values: 435.9 459.5 467.2 504.7 515.6 39420.8 41161.2 41241.4 41259.0 41495.0 42286.8 42668.0 42762.4 42828.9 42856.6 9271.0 9456.9 9492.0 9492.6 10808.1 10903.6 11082.8 11115.0 11118.4 11125.8 10164.5 10685.8 11122.7 11148.5 11166.3 9964.3 10436.7 11091.4 11133.9 11176.8 9706.9 10449.7 10955.0 10989.3 10993.7 36669.6 36912.8 36926.0 37222.8 37272.4 3158.7 3985.8 4021.0 4028.5 4165.0 27883.0 28571.1 28845.3 28882.4 29142.1 3107.5 3167.8 3186.3 3276.1 3284.4 441891.0 448903.0 449409.0 451197.0 451351.0 56014.2 56074.7 56300.4 56408.4 56462.4 47367.0 55655.7 59046.4 59249.0 59529.4 15164.5 15279.9 15442.4 15472.5 15481.4 10689.2 11018.6 11679.6 11882.2 12034.9 17841.7 17939.2 17986.9 18013.3 18044.0 6833.6 6887.4 7858.0 7966.6 8367.4 887.2 896.0 899.2 920.2 931.4 30101.9 30256.1 30427.5 30652.4 30751.0 1078.8 1100.9 1103.1 1130.5 1260.3 12068.5 15722.9 15737.8 15981.2 16009.9 599205.0 858986.0 876022.0 878973.0 880791.0 52901.5 53199.5 53478.5 53537.2 54528.5 1423.9 1440.2 1466.2 1466.3 1479.7 28442.9 28527.7 28688.7 28898.3 28970.3 45360.0 45985.9 46082.0 46093.1 46351.3 568937.0 572178.0 575376.0 575678.0 577412.0 25160.6 25220.7 25409.0 25586.4 25592.4 34722.0 35337.0 41270.6 41595.6 41728.0 34320.3 34477.8 35119.0 36342.6 36376.8 32638.7 34577.7 34668.5 34839.0 36329.5 33418.8 33685.5 33741.8 33857.3 35680.8 23859.3 30094.8 31312.7 32128.8 32639.3 20039.4 24083.6 24806.5 24842.0 24978.4 04:48:40 INFO - PROCESS | 1927 | 04:48:40 INFO - PROCESS | 1927 | [#2] jquery.html Cycles:180 Average:37610.05 Median:391.43 stddev:113069.01 (28885.8%) stddev-sans-first:113351.57 04:48:40 INFO - PROCESS | 1927 | Values: 341.4 385.7 397.2 399.3 412.5 19886.6 20000.5 20887.8 21228.5 21342.9 12334.8 12457.4 14186.5 21147.9 21846.7 5263.6 5489.5 5493.8 5498.9 5569.4 5863.7 5985.1 6015.3 6038.5 6040.7 5324.1 5449.1 5991.9 5992.6 5994.8 5379.8 5396.3 5400.4 5432.5 5435.0 4537.6 4988.8 5298.0 5312.4 5338.3 13649.5 13685.4 13734.5 13784.1 24666.6 2694.9 2858.7 3003.2 3018.3 3132.8 9297.3 9593.9 9972.2 10037.3 10627.0 1782.7 1878.9 1880.3 1903.8 1907.9 107620.0 108690.5 125094.0 133484.0 136022.0 11638.2 11857.8 12024.9 12065.2 12092.3 15634.5 15740.8 15778.9 15793.8 15819.2 8087.5 8768.6 8908.5 8999.3 9024.7 7184.7 7368.9 8694.5 8819.9 8898.6 4801.4 4813.9 4854.4 4908.8 4911.4 1684.5 2055.7 2057.4 2058.6 2068.8 946.8 956.3 962.8 972.2 1019.9 13529.2 13702.3 13925.0 14105.0 14223.6 958.4 970.1 977.1 993.7 1006.0 7106.9 7135.3 7274.3 7299.7 7342.2 664397.0 673639.0 677213.0 681755.0 689229.0 118251.0 138135.0 138884.3 139554.0 167352.2 972.0 997.6 1029.8 1030.4 1033.5 17534.7 19870.9 20402.6 20539.2 20791.4 59516.7 59696.8 60844.4 60962.4 62095.4 85792.0 88747.2 89780.6 90002.0 90509.5 7156.9 8197.1 8409.6 8428.9 8560.3 8864.1 13082.9 13160.8 13163.7 13647.0 11319.8 11519.9 11573.8 11699.0 11795.1 9939.3 11334.7 11605.2 11636.5 11820.8 10096.9 11047.2 11461.9 11466.8 11743.5 8196.5 8209.0 8399.2 8582.5 8619.7 8253.4 10538.6 10835.9 11078.2 11177.9 04:48:40 INFO - PROCESS | 1927 | 04:48:40 INFO - PROCESS | 1927 | [#3] mootools.html Cycles:180 Average:5420.43 Median:5192.94 stddev:2960.25 (57.0%) stddev-sans-first:2951.54 04:48:40 INFO - PROCESS | 1927 | Values: 1198.6 1439.9 1472.8 1477.5 1489.1 7547.1 7806.2 7843.0 8221.4 8240.9 7039.3 8018.0 8276.5 8282.0 8485.1 1944.0 2268.5 2304.2 2355.9 2355.9 2069.0 2139.0 2321.5 2359.9 2368.1 2245.9 2317.7 2321.8 2344.4 2348.5 2317.9 2325.4 2327.7 2341.4 2348.9 2275.8 2284.3 2330.8 2334.1 2356.0 7164.9 8525.9 8534.3 8562.5 8582.8 5171.1 5287.4 5304.8 5306.3 5309.7 3207.7 3343.5 3415.0 3430.2 3483.4 1108.0 1137.9 1172.9 1183.0 1184.3 10958.6 10978.2 11009.6 11046.8 11058.4 4411.7 4704.7 4767.9 4792.0 4795.2 9582.1 9627.2 9672.9 9712.5 9712.5 5218.1 5283.9 5297.1 5309.2 5311.7 3908.9 4029.3 4719.7 4722.9 4824.5 3291.4 3871.4 3909.5 3945.0 3976.7 1083.0 1192.7 1236.0 1270.8 1308.1 896.4 913.5 913.8 921.1 930.5 5568.1 5573.7 5725.3 5768.3 5829.6 812.3 844.9 854.5 1009.6 1036.0 3769.4 3791.8 3801.3 3873.0 3917.8 9781.4 10143.8 10146.3 10147.0 10176.3 10066.0 10094.2 10113.9 10117.0 10127.7 7399.8 7587.4 7802.8 7888.3 8018.0 5501.6 5678.2 5776.5 5792.8 5890.1 7935.0 9094.4 9096.5 9182.5 9299.6 8528.3 8554.9 8905.0 9260.2 9332.6 6649.2 7356.2 7367.1 7368.9 7369.6 7908.7 7936.0 7947.2 8000.0 8039.1 5840.3 6163.3 6377.6 7206.0 7241.2 7046.6 7106.2 7127.1 7186.7 7202.3 6351.6 6379.5 6459.0 6474.2 6482.0 4867.7 4960.8 4996.3 5028.9 5134.5 4376.3 5037.9 5060.5 5125.3 5214.7 04:48:40 INFO - PROCESS | 1927 | 04:48:40 INFO - PROCESS | 1927 | [#4] prototype.html Cycles:180 Average:4481.89 Median:5065.24 stddev:1412.86 (27.9%) stddev-sans-first:1381.78 04:48:40 INFO - PROCESS | 1927 | Values: 315.8 331.7 351.9 368.3 370.5 5123.7 5167.4 5323.6 5493.1 5542.6 4190.3 4675.8 5297.8 5315.6 5397.9 3635.5 3828.3 3955.9 4249.9 4276.8 3867.4 4282.6 4287.7 4303.9 4309.2 3230.6 4011.7 4012.2 4039.9 4062.5 3427.8 3873.6 3911.2 4080.7 4156.4 2979.9 3184.8 3543.9 3569.8 3574.1 4396.7 4783.8 4969.5 5280.5 5298.4 3897.9 4479.0 4482.5 4684.9 4704.0 4831.0 5696.0 5733.0 5775.8 5803.4 3335.0 3469.2 3597.0 3619.1 3857.2 5211.5 5617.9 5700.9 5923.0 5964.8 3434.9 3450.8 3760.6 3888.2 3904.8 3176.4 3340.7 3949.0 3955.5 3967.4 3430.7 4557.5 4597.2 4634.9 4653.2 5098.4 5293.8 5783.7 5794.8 5795.7 1150.4 1172.1 1225.6 1263.9 1281.9 872.4 872.6 876.2 889.6 897.0 5125.8 5146.2 5159.6 5168.6 5193.8 4772.6 5556.1 5621.6 5624.8 5643.1 4400.6 5059.2 5098.4 5104.0 5147.5 2543.9 2564.4 2699.4 2750.9 2990.9 4789.4 4801.8 5230.1 5325.9 5835.4 4904.7 5006.6 5494.8 5845.6 5860.2 5289.5 5655.2 5893.7 5916.5 5936.6 5066.9 5153.6 5447.6 5833.4 5905.8 4431.9 5624.3 5864.4 5877.2 5911.5 5272.9 5748.3 5843.9 6188.8 6211.5 3471.6 3638.7 3824.7 3918.5 3958.2 5257.3 5446.9 5956.0 5985.6 5990.3 5323.6 5532.9 5712.7 5732.7 5750.6 5063.5 5686.2 5721.5 5752.9 5761.5 4524.8 4793.0 5104.8 5441.4 5724.7 4710.4 5132.4 5441.4 5809.4 5862.1 4370.9 4528.0 4807.7 5724.8 5735.5 04:48:40 INFO - PROCESS | 1927 | 04:48:40 INFO - PROCESS | 1927 | [#5] yui.html Cycles:180 Average:20045.64 Median:48669.38 stddev:28865.37 (59.3%) stddev-sans-first:28909.68 04:48:40 INFO - PROCESS | 1927 | Values: 670.9 687.3 718.7 759.8 837.8 14634.0 15511.5 15631.5 15677.1 15703.0 13948.9 14538.9 14666.6 14674.7 14690.0 862.7 873.8 876.2 879.0 879.2 683.0 702.0 848.6 869.3 869.6 719.7 933.4 934.5 935.4 937.3 919.3 927.2 929.1 930.4 931.0 686.3 690.5 690.7 692.1 694.1 14896.8 15396.4 15438.5 15519.3 15576.4 14189.7 14658.7 14763.9 14951.2 15246.9 11613.0 11734.8 11863.0 11866.9 11909.8 3731.0 3736.3 3759.8 3768.4 3790.2 86676.0 108214.9 108608.0 109278.5 109813.0 11071.6 11331.9 11396.3 11422.2 11594.9 19472.3 20250.5 24307.5 24450.0 24718.0 7870.5 7926.6 7928.1 7936.9 8029.8 3771.8 4275.3 4276.6 4332.8 4369.6 3590.2 3619.9 3636.4 3668.6 3674.8 687.0 690.1 725.2 725.9 735.2 578.3 677.6 698.7 700.2 709.5 9566.9 10215.2 10236.2 10387.8 10517.9 549.6 566.0 568.3 610.8 644.6 4948.4 5052.8 5078.8 5112.2 5134.5 59093.7 77525.6 99262.0 103226.0 106304.5 90265.0 91945.0 93824.1 94638.5 95266.0 59517.0 76815.6 81007.6 82502.6 83034.0 9732.5 9766.5 9799.1 9802.2 9844.8 48155.0 48424.8 48914.0 49429.0 49566.5 68418.0 70542.6 71218.0 71250.3 73150.6 11738.2 11801.5 11828.3 11935.8 12025.4 13072.3 13079.4 13223.5 13229.9 13793.9 11957.8 11983.3 12026.6 12078.4 12094.0 11835.2 11918.9 11932.5 11979.6 12036.5 12021.8 12045.2 12077.8 12091.4 12114.1 9760.0 9775.9 9865.2 9998.4 10344.4 9944.9 9995.8 10014.6 10033.0 10033.2 04:48:40 INFO - PROCESS | 1927 | -------- Summary: end -------- 04:48:40 INFO - PROCESS | 1927 | 04:48:40 INFO - TEST-INFO | 1927: exit 0 04:48:40 INFO - TEST-OK | dromaeo_css | took 1115823ms 04:48:40 INFO - TEST-START | kraken 04:48:40 INFO - operating with platform_type : mac_ 04:48:40 INFO - Initialising browser for kraken test... 04:48:40 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpx4XimU/profile http://localhost:55658/getInfo.html) 04:48:43 INFO - PROCESS | 1933 | 2017-01-12 04:48:43.662 plugin-container[1935:22579] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 04:48:43 INFO - PROCESS | 1933 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:43 INFO - PROCESS | 1933 | 2017-01-12 04:48:43.662 plugin-container[1935:22579] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0f, name = 'com.apple.CFPasteboardClient' 04:48:43 INFO - PROCESS | 1933 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:43 INFO - PROCESS | 1933 | 2017-01-12 04:48:43.663 plugin-container[1935:22579] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:48:43 INFO - PROCESS | 1933 | __metrics Screen width/height:1600/1200 04:48:43 INFO - PROCESS | 1933 | colorDepth:24 04:48:43 INFO - PROCESS | 1933 | Browser inner width/height: 1024/689 04:48:43 INFO - PROCESS | 1933 | __metrics 04:48:43 ERROR - PROCESS | 1933 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3242: TypeError: this.tabs is undefined 04:48:44 INFO - TEST-INFO | 1933: exit 0 04:48:44 INFO - Browser initialized. 04:48:44 INFO - Running cycle 1/1 for kraken test... 04:48:44 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpx4XimU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:47 INFO - PROCESS | 1936 | 2017-01-12 04:48:47.047 plugin-container[1937:22698] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 04:48:47 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:47 INFO - PROCESS | 1936 | 2017-01-12 04:48:47.048 plugin-container[1937:22698] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1b, name = 'com.apple.CFPasteboardClient' 04:48:47 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:48:47 INFO - PROCESS | 1936 | 2017-01-12 04:48:47.048 plugin-container[1937:22698] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:48:56 INFO - PROCESS | 1936 | RSS: Main: 233414656 04:48:56 INFO - PROCESS | 1936 | 04:48:59 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55658/tests/kraken/driver.html?testName=audio-beat-detection) 04:49:00 INFO - PROCESS | 1936 | RSS: Main: 246136832 04:49:00 INFO - PROCESS | 1936 | 04:49:03 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55658/tests/kraken/driver.html?testName=audio-dft) 04:49:03 INFO - PROCESS | 1936 | RSS: Main: 254287872 04:49:03 INFO - PROCESS | 1936 | 04:49:07 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55658/tests/kraken/driver.html?testName=audio-fft) 04:49:07 INFO - PROCESS | 1936 | RSS: Main: 256917504 04:49:07 INFO - PROCESS | 1936 | 04:49:09 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55658/tests/kraken/driver.html?testName=audio-oscillator) 04:49:10 INFO - PROCESS | 1936 | RSS: Main: 259182592 04:49:10 INFO - PROCESS | 1936 | 04:49:12 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55658/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:49:13 INFO - PROCESS | 1936 | RSS: Main: 259829760 04:49:13 INFO - PROCESS | 1936 | 04:49:16 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55658/tests/kraken/driver.html?testName=imaging-darkroom) 04:49:16 INFO - PROCESS | 1936 | RSS: Main: 265891840 04:49:16 INFO - PROCESS | 1936 | 04:49:19 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55658/tests/kraken/driver.html?testName=imaging-desaturate) 04:49:20 INFO - PROCESS | 1936 | RSS: Main: 264884224 04:49:20 INFO - PROCESS | 1936 | 04:49:23 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55658/tests/kraken/driver.html?testName=json-parse-financial) 04:49:24 INFO - PROCESS | 1936 | RSS: Main: 264589312 04:49:24 INFO - PROCESS | 1936 | 04:49:25 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55658/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:49:26 INFO - PROCESS | 1936 | RSS: Main: 264224768 04:49:26 INFO - PROCESS | 1936 | 04:49:28 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55658/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:49:28 INFO - PROCESS | 1936 | RSS: Main: 264310784 04:49:28 INFO - PROCESS | 1936 | 04:49:31 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55658/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:49:31 INFO - PROCESS | 1936 | RSS: Main: 264331264 04:49:31 INFO - PROCESS | 1936 | 04:49:34 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55658/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:49:35 INFO - PROCESS | 1936 | RSS: Main: 264765440 04:49:35 INFO - PROCESS | 1936 | 04:49:38 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55658/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:49:39 INFO - PROCESS | 1936 | RSS: Main: 264642560 04:49:39 INFO - PROCESS | 1936 | 04:49:40 INFO - PROCESS | 1936 | Cycle 1(1): loaded http://localhost:55658/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55658/tests/kraken/driver.html?testName=ai-astar) 04:49:41 INFO - PROCESS | 1936 | RSS: Main: 266227712 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | __start_tp_report 04:49:41 INFO - PROCESS | 1936 | _x_x_mozilla_page_load 04:49:41 INFO - PROCESS | 1936 | _x_x_mozilla_page_load_details 04:49:41 INFO - PROCESS | 1936 | |i|pagename|runs| 04:49:41 INFO - PROCESS | 1936 | |0;ai-astar;103;116;115;118;115;116;119;117;116;115 04:49:41 INFO - PROCESS | 1936 | |1;audio-beat-detection;133;113;112;155;114;140;103;138;166;148 04:49:41 INFO - PROCESS | 1936 | |2;audio-dft;195;200;204;231;286;215;195;198;197;198 04:49:41 INFO - PROCESS | 1936 | |3;audio-fft;78;57;59;86;62;65;74;84;61;51 04:49:41 INFO - PROCESS | 1936 | |4;audio-oscillator;90;70;83;66;75;82;119;99;71;85 04:49:41 INFO - PROCESS | 1936 | |5;imaging-gaussian-blur;92;89;90;90;92;89;129;89;110;111 04:49:41 INFO - PROCESS | 1936 | |6;imaging-darkroom;127;96;98;122;118;97;97;116;155;111 04:49:41 INFO - PROCESS | 1936 | |7;imaging-desaturate;92;92;92;93;97;89;82;154;153;98 04:49:41 INFO - PROCESS | 1936 | |8;json-parse-financial;63;62;61;62;62;65;62;61;62;61 04:49:41 INFO - PROCESS | 1936 | |9;json-stringify-tinderbox;71;75;84;76;74;69;70;73;70;92 04:49:41 INFO - PROCESS | 1936 | |10;stanford-crypto-aes;88;86;88;86;79;98;110;110;93;75 04:49:41 INFO - PROCESS | 1936 | |11;stanford-crypto-ccm;159;176;151;159;132;148;153;146;162;188 04:49:41 INFO - PROCESS | 1936 | |12;stanford-crypto-pbkdf2;175;176;174;177;175;179;175;174;171;170 04:49:41 INFO - PROCESS | 1936 | |13;stanford-crypto-sha256-iterative;67;71;69;66;72;65;64;71;86;60 04:49:41 INFO - PROCESS | 1936 | __end_tp_report 04:49:41 INFO - PROCESS | 1936 | __start_cc_report 04:49:41 INFO - PROCESS | 1936 | _x_x_mozilla_cycle_collect,921 04:49:41 INFO - PROCESS | 1936 | __end_cc_report 04:49:41 INFO - PROCESS | 1936 | __startTimestamp1484225381093__endTimestamp 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | ------- Summary: start ------- 04:49:41 INFO - PROCESS | 1936 | Number of tests: 14 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#0] ai-astar Cycles:10 Average:115.00 Median:116.00 stddev:4.42 (3.8%) stddev-sans-first:1.41 04:49:41 INFO - PROCESS | 1936 | Values: 103.0 116.0 115.0 118.0 115.0 116.0 119.0 117.0 116.0 115.0 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:139.00 stddev:21.00 (15.1%) stddev-sans-first:22.27 04:49:41 INFO - PROCESS | 1936 | Values: 133.0 113.0 112.0 155.0 114.0 140.0 103.0 138.0 166.0 148.0 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#2] audio-dft Cycles:10 Average:211.90 Median:202.00 stddev:28.38 (14.0%) stddev-sans-first:29.44 04:49:41 INFO - PROCESS | 1936 | Values: 195.0 200.0 204.0 231.0 286.0 215.0 195.0 198.0 197.0 198.0 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#3] audio-fft Cycles:10 Average:67.70 Median:69.50 stddev:12.02 (17.3%) stddev-sans-first:12.16 04:49:41 INFO - PROCESS | 1936 | Values: 78.0 57.0 59.0 86.0 62.0 65.0 74.0 84.0 61.0 51.0 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#4] audio-oscillator Cycles:10 Average:84.00 Median:82.50 stddev:15.85 (19.2%) stddev-sans-first:16.67 04:49:41 INFO - PROCESS | 1936 | Values: 90.0 70.0 83.0 66.0 75.0 82.0 119.0 99.0 71.0 85.0 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#5] imaging-gaussian-blur Cycles:10 Average:98.10 Median:89.50 stddev:13.81 (15.4%) stddev-sans-first:14.47 04:49:41 INFO - PROCESS | 1936 | Values: 92.0 89.0 90.0 90.0 92.0 89.0 129.0 89.0 110.0 111.0 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#6] imaging-darkroom Cycles:10 Average:113.70 Median:125.50 stddev:18.56 (14.8%) stddev-sans-first:19.05 04:49:41 INFO - PROCESS | 1936 | Values: 127.0 96.0 98.0 122.0 118.0 97.0 97.0 116.0 155.0 111.0 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#7] imaging-desaturate Cycles:10 Average:104.20 Median:92.00 stddev:26.35 (28.6%) stddev-sans-first:27.57 04:49:41 INFO - PROCESS | 1936 | Values: 92.0 92.0 92.0 93.0 97.0 89.0 82.0 154.0 153.0 98.0 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:62.00 stddev:1.20 (1.9%) stddev-sans-first:1.22 04:49:41 INFO - PROCESS | 1936 | Values: 63.0 62.0 61.0 62.0 62.0 65.0 62.0 61.0 62.0 61.0 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#9] json-stringify-tinderbox Cycles:10 Average:75.40 Median:74.50 stddev:7.28 (9.8%) stddev-sans-first:7.54 04:49:41 INFO - PROCESS | 1936 | Values: 71.0 75.0 84.0 76.0 74.0 69.0 70.0 73.0 70.0 92.0 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#10] stanford-crypto-aes Cycles:10 Average:91.30 Median:87.00 stddev:11.75 (13.5%) stddev-sans-first:12.40 04:49:41 INFO - PROCESS | 1936 | Values: 88.0 86.0 88.0 86.0 79.0 98.0 110.0 110.0 93.0 75.0 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#11] stanford-crypto-ccm Cycles:10 Average:157.40 Median:159.00 stddev:15.75 (9.9%) stddev-sans-first:16.69 04:49:41 INFO - PROCESS | 1936 | Values: 159.0 176.0 151.0 159.0 132.0 148.0 153.0 146.0 162.0 188.0 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.60 Median:175.00 stddev:2.63 (1.5%) stddev-sans-first:2.79 04:49:41 INFO - PROCESS | 1936 | Values: 175.0 176.0 174.0 177.0 175.0 179.0 175.0 174.0 171.0 170.0 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - PROCESS | 1936 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:70.00 stddev:7.00 (10.0%) stddev-sans-first:7.38 04:49:41 INFO - PROCESS | 1936 | Values: 67.0 71.0 69.0 66.0 72.0 65.0 64.0 71.0 86.0 60.0 04:49:41 INFO - PROCESS | 1936 | -------- Summary: end -------- 04:49:41 INFO - PROCESS | 1936 | 04:49:41 INFO - TEST-INFO | 1936: exit 0 04:49:41 INFO - TEST-OK | kraken | took 61072ms 04:49:41 INFO - SUITE-END | took 1176s 04:49:42 INFO - Completed test suite (00:19:37) 04:49:42 INFO - javascript benchmark 04:49:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7196.9710620954, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3275.721563667088, 3325.7006869381753, 3343.8996830095093, 3379.5944486661606, 3398.269372085002, 5131.384233891934, 5595.38450770415, 5634.830955071355, 5644.096944488888, 5746.511546518541, 5618.775248990036, 5668.773249070032, 5698.886022279562, 5735.139729040646, 5802.796902108427, 5156.948430515685, 5159.974200128994, 5241.633085684004, 5273.683578985273, 5340.652857564246, 5238.947610523903, 5335.599830012745, 5382.703951282678, 5399.460053994609, 5425.186222066682, 5146.922796158061, 5211.24436956643, 5216.113260745682, 5308.761105750251, 5320.068987927098, 5101.897962040738, 5180.041692286902, 5200.037992971313, 5250.501202385767, 5254.054270231357, 4208.621224089816, 5130.819401754276, 5165.896682066337, 5204.505571970694, 5226.843194704165, 5537.280153580009, 5600.383957764683, 5657.1514272858985, 5695.14572814077, 5709.74306156224, 6102.701334035014, 6121.422488707842, 6134.04922237054, 6144.631322120687, 6177.845553861144, 6215.2541694996435, 6236.158118653969, 6241.188645476104, 6242.5942313749465, 6248.0, 5766.509846663039, 5779.797707080232, 5792.304923409218, 5802.564807639402, 5853.882922341572, 5229.712365819841, 6160.83715312499, 6275.937240627627, 6299.055141728777, 6329.177206963065, 6184.969075154595, 6203.038529028008, 6229.0, 6242.719077641517, 6251.156093927352, 6115.970584430425, 6282.591631543935, 6288.308286088527, 6297.96851015742, 6298.559100862895, 5608.747606357723, 5748.942510574924, 6136.917362303815, 6169.907451388232, 6262.46769024638, 6247.656378899204, 6256.6871656417, 6271.247450305901, 6272.278687950944, 6275.278342990523, 4343.457067866517, 4987.202047672355, 5058.620603454756, 5078.415982162025, 5121.0, 4451.0430257494145, 4484.148011877733, 4518.322251662211, 4588.990422107131, 4608.124456353282, 5815.796447124415, 5839.445252700986, 5861.6189947653265, 5935.198748168942, 5945.227120474311, 6235.282942461584, 6270.372962703785, 6273.059041143865, 6288.748450062038, 6293.056041593706, 5852.122181672783, 5896.587238893237, 5925.940740592625, 5928.198407497858, 5940.34656187819, 5401.3248343957, 5467.699276539749, 5475.151351540518, 5528.502434780889, 5531.3362396511875, 6446.967765161145, 6498.155239818888, 6501.05734668461, 6513.837154071091, 6528.118703975093, 4862.781174847069, 6264.968675156777, 6266.373362663697, 6295.779647712308, 6314.242290925118, 6229.937700622936, 6284.308726040041, 6477.125588045554, 6514.120593719977, 6526.575772574767, 6146.293176284785, 6158.0147176451555, 6192.783252586138, 6244.969580019246, 6260.467860231931, 6264.843378915563, 6270.184875966095, 6275.497960163085, 6279.246490421178, 6287.088372186099, 6071.423214794588, 6075.2405949256345, 6080.513558915188, 6173.166622505904, 6211.596246243979, 5752.367239603724, 6104.368208815833, 6104.7926496150985, 6119.602225855305, 6214.875702486015, 5956.97021514907, 5980.312264089685, 6062.575619706755, 6074.205865301834, 6087.57386982907, 5931.584789064724, 6071.483923866521, 6097.743237184312, 6112.113743507255, 6138.296437970497, 6097.634141951497, 6121.8493044937295, 6134.7427470591965, 6135.722664536972, 6139.522803442411], "value": 5715.454615435866, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.8866694659388, 459.45554517896284, 467.201086142696, 504.67952849940286, 515.556621305677, 39420.802895985515, 41161.176776464454, 41241.38137927934, 41259.0, 41494.962625934364, 42286.78856605721, 42668.0, 42762.35856462156, 42828.929276768096, 42856.571434285645, 9271.026542213054, 9456.905430945671, 9491.95254023729, 9492.620295188184, 10808.081313088396, 10903.618373356934, 11082.778344433107, 11114.999650031496, 11118.444077796119, 11125.777484450306, 10164.54259558318, 10685.78628427431, 11122.721931951684, 11148.491589392403, 11166.274192177523, 9964.302498825085, 10436.739081522946, 11091.445427728662, 11133.944330278378, 11176.776464470746, 9706.883708373556, 10449.68650940473, 10955.0, 10989.340639561651, 10993.725156871062, 36669.63330366689, 36912.81543592265, 36926.0, 37222.81388593067, 37272.44091338632, 3158.652548219694, 3985.820638071265, 4020.959790402088, 4028.516578010628, 4165.000399904001, 27883.0, 28571.142865713853, 28845.278868028257, 28882.42235155306, 29142.125736227736, 3107.456195165837, 3167.762417818673, 3186.314942287396, 3276.0990727550115, 3284.359549887749, 441891.0, 448903.0, 449409.0, 451197.0, 451350.99999999674, 56014.15978760322, 56074.71962640161, 56300.43699562952, 56408.43591564114, 56462.43537564572, 47367.0, 55655.721721391135, 59046.40953590409, 59249.0, 59529.40470595239, 15164.469243576423, 15279.92360038215, 15442.382304707686, 15472.458463953708, 15481.380744770084, 10689.198310126772, 11018.614348497924, 11679.649610511698, 11882.16824822254, 12034.879651203375, 17841.7323740144, 17939.192736326895, 17986.91006544959, 18013.27946882136, 18044.007579582987, 6833.6241506716615, 6887.380135787803, 7858.0, 7966.561839098905, 8367.372447066495, 887.2325438495784, 895.9641614335484, 899.1727610598265, 920.1902325953118, 931.3992474853817, 30101.946431874778, 30256.09231723052, 30427.543586846215, 30652.38695226083, 30751.0, 1078.8435676826973, 1100.9394483303495, 1103.089950790601, 1130.5477808876515, 1260.2627462934104, 12068.517259309705, 15722.921385393, 15737.842621573867, 15981.200939952956, 16009.919950400174, 599205.0, 858986.0, 876022.0, 878973.0, 880791.0, 52901.47098529043, 53199.468005320225, 53478.46521534813, 53537.1969420459, 54528.45471545313, 1423.8647328503775, 1440.1503113163285, 1466.2448838848002, 1466.2741942738412, 1479.6522817137964, 28442.857785710938, 28527.714722853336, 28688.713112868605, 28898.27754306159, 28970.27574310659, 45360.0, 45985.85035374142, 46082.0, 46093.07813843771, 46351.3047304284, 568937.0, 572178.0, 575376.0, 575678.0, 577412.0, 25160.622590661522, 25220.74779252184, 25408.983640654165, 25586.360340991625, 25592.360190995376, 34721.9583412498, 35337.0, 41270.58729412667, 41595.58404415917, 41728.0, 34320.313593727486, 34477.82761086179, 35119.0, 36342.63657363393, 36376.81811590925, 32638.673613263563, 34577.65422345845, 34668.47997279993, 34838.9548313551, 36329.46633411574, 33418.83290583531, 33685.49471757877, 33741.83129084339, 33857.322853543286, 35680.7511737088, 23859.284221473383, 30094.84952575223, 31312.686873130977, 32128.839355804008, 32639.34721305608, 20039.398818035483, 24083.554986700856, 24806.50386992286, 24842.0, 24978.37554061163], "value": 19604.04012435632, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.3769869987273, 385.6567654787238, 397.21153510282096, 399.26535175277485, 412.4802748536845, 19886.602267954633, 20000.4999875003, 20887.791122088776, 21228.469288267803, 21342.89328553355, 12334.81497777534, 12457.439415226314, 14186.503472378443, 21147.894260528716, 21846.672299915474, 5263.578913686905, 5489.451054894498, 5493.752781124859, 5498.917516237259, 5569.443055694439, 5863.677497737623, 5985.132155837401, 6015.3383127855905, 6038.516918646497, 6040.697965101727, 5324.094903866333, 5449.100898351767, 5991.910121348184, 5992.640441573498, 5994.790182343619, 5379.784808607651, 5396.325459317585, 5400.351957765054, 5432.511074003319, 5435.0, 4537.614302784267, 4988.802687355045, 5298.0, 5312.442193569658, 5338.27933229013, 13649.454021839114, 13685.384157712828, 13734.45062197511, 13784.104033237907, 24666.630000550005, 2694.8517831519257, 2858.7141285871453, 3003.234175285288, 3018.2756138526815, 3132.7650426218124, 9297.349185557014, 9593.856092158623, 9972.15236704881, 10037.297389182688, 10627.0, 1782.6880295948158, 1878.8872667639985, 1880.2666959885655, 1903.7810651775085, 1907.9332223372255, 107620.0, 108690.45654771675, 125094.0, 133484.0, 136022.0, 11638.185327027028, 11857.822132668018, 12024.939875300743, 12065.155439119353, 12092.334921579222, 15634.45279415215, 15740.842591574166, 15778.92110539463, 15793.842061579237, 15819.209039547977, 8087.47431416956, 8768.605412756442, 8908.510031948304, 8999.280057595377, 9024.729258122266, 7184.676689548878, 7368.926310736932, 8694.478466268378, 8819.867701984474, 8898.59956301968, 4801.375821143229, 4813.879653008633, 4854.368932038883, 4908.828191013369, 4911.386076740408, 1684.5114916674027, 2055.660815965379, 2057.403353027635, 2058.5779915117364, 2068.803463670949, 946.7633091727068, 956.31623389277, 962.764122789921, 972.1713476750051, 1019.90820826126, 13529.18824870511, 13702.314884255748, 13925.025248232527, 14105.0, 14223.573292801233, 958.4249450329886, 970.1421777296539, 977.1044174301832, 993.6969224386631, 1006.0332050840589, 7106.928930710626, 7135.286471352823, 7274.345308922222, 7299.708011679473, 7342.155652100077, 664397.0, 673639.0, 677213.0, 681755.0, 689229.0, 118251.0, 138135.0, 138884.30557847145, 139554.0, 167352.16323918302, 971.965981190669, 997.5535473563217, 1029.7528593137592, 1030.3972176276811, 1033.534909290822, 17534.668413001487, 19870.90064549668, 20402.591948160658, 20539.178432863115, 20791.376258712262, 59516.70241648764, 59696.80606387935, 60844.39155608387, 60962.390376095675, 62095.37904620896, 85792.0, 88747.22505549982, 89780.5510972441, 90002.0, 90509.54745226231, 7156.928430715834, 8197.098319184766, 8409.603263069996, 8428.873566896624, 8560.315174785877, 8864.113588641085, 13082.934585327013, 13160.80258796138, 13163.67090822737, 13647.044706870423, 11319.830202546804, 11519.88480115188, 11573.826392603949, 11699.0, 11795.115366347387, 9939.254555908192, 11334.659960201207, 11605.245659032136, 11636.476358563716, 11820.82268765952, 10096.949515252376, 11047.226694131656, 11461.942690286494, 11466.77066458683, 11743.530258789553, 8196.508209507449, 8208.958955205424, 8399.160083991552, 8582.527960962207, 8619.741407757778, 8253.380996425172, 10538.57845686164, 10835.891641083488, 11078.224524283223, 11177.888221117684], "value": 9426.434878304019, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1198.5625246784925, 1439.8992070555053, 1472.7938088667588, 1477.4828809916528, 1489.1139771835758, 7547.05661792276, 7806.180351063142, 7843.0, 8221.383396245275, 8240.91759082409, 7039.296070392958, 8017.959910200456, 8276.462029968048, 8282.006159260882, 8485.0666426693, 1944.0, 2268.4669102760836, 2304.2396009316926, 2355.870427126516, 2355.9175428859994, 2069.0, 2139.0374331550865, 2321.5356928614347, 2359.8702071386065, 2368.1356304948654, 2245.8764767937755, 2317.6523521471745, 2321.779430954065, 2344.4138965258685, 2348.542034303303, 2317.9304620861403, 2325.418645338657, 2327.662488939103, 2341.4263505441104, 2348.859068455898, 2275.7724227577273, 2284.314705588317, 2330.8135349172194, 2334.1130370459286, 2356.045801450415, 7164.928350716478, 8525.872111918326, 8534.274586660142, 8562.529060901648, 8582.785430364229, 5171.146760784466, 5287.391949925769, 5304.787808487656, 5306.283651707009, 5309.681419114865, 3207.747609753701, 3343.498475228735, 3415.0096472023083, 3430.2110514581536, 3483.4426491761196, 1108.0, 1137.8805225451374, 1172.8709841917382, 1183.0, 1184.2657552317532, 10958.56165753369, 10978.231523793258, 11009.61466348674, 11046.834297485544, 11058.391788451632, 4411.735295882258, 4704.69419487739, 4767.880802979953, 4791.95208047922, 4795.160846851787, 9582.089701478348, 9627.181689556326, 9672.903270967341, 9712.465814380135, 9712.465814380135, 5218.112920803473, 5283.947160528345, 5297.099493086184, 5309.230161626542, 5311.681299122065, 3908.882733517999, 4029.294873397144, 4719.669623126349, 4722.881927951828, 4824.541668541483, 3291.440455122635, 3871.380579107329, 3909.4948444848937, 3944.9605503944595, 3976.741511801724, 1082.9675109746563, 1192.6839387562302, 1236.042067397756, 1270.790319597256, 1308.1431662261234, 896.3546246702364, 913.4519288427024, 913.7578541686458, 921.1295226964291, 930.4510338900081, 5568.136938774496, 5573.749181286852, 5725.255716756878, 5768.3366412862215, 5829.591928565044, 812.29736278119, 844.9197326253995, 854.5385491834464, 1009.5658866687395, 1035.9896401036044, 3769.3780526212868, 3791.7724936503896, 3801.2967600993907, 3873.0, 3917.804109794499, 9781.41311521311, 10143.847842282372, 10146.289759716896, 10146.985301469795, 10176.287659863887, 10066.0, 10094.242931780145, 10113.898861011443, 10117.038881306264, 10127.7468063299, 7399.778006659809, 7587.355074818703, 7802.765917022499, 7888.329491993245, 8018.0, 5501.614886958035, 5678.154599755014, 5776.537876969916, 5792.82621521355, 5890.14592884038, 7935.0, 9094.408863423856, 9096.499692516974, 9182.540872956191, 9299.628014879328, 8528.317734581342, 8554.91445085541, 8905.0, 9260.166585007382, 9332.580033898355, 6649.202095748541, 7356.154042285206, 7367.079115110611, 7368.926310736824, 7369.63151842395, 7908.723194688158, 7936.0, 7947.205279472007, 7999.9600001999615, 8039.075506316614, 5840.269966254218, 6163.322034576104, 6377.585456945284, 7206.027186329988, 7241.203467618452, 7046.647667616701, 7106.2017039824, 7127.144742630918, 7186.676599552928, 7202.279772022755, 6351.555391122577, 6379.489640828815, 6459.0, 6474.223093228654, 6482.0, 4867.659263851497, 4960.7767650456535, 4996.3005179274205, 5028.874278142929, 5134.5378915897745, 4376.343548467756, 5037.924431133462, 5060.544550990428, 5125.256837758429, 5214.739263036909], "value": 4401.876645544423, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.771648288159, 331.74455669134767, 351.94192958161904, 368.2744992365041, 370.47947633574836, 5123.718195499246, 5167.379914410267, 5323.60072994525, 5493.148561972896, 5542.5565954723625, 4190.266703326921, 4675.812967481296, 5297.814576489824, 5315.627906046579, 5397.946020539794, 3635.546006445652, 3828.3109040372724, 3955.9318983874336, 4249.915001699964, 4276.828926842931, 3867.38121900496, 4282.616732781099, 4287.678424118188, 4303.91392172158, 4309.245881970658, 3230.6284777250567, 4011.6991225658194, 4012.228505205098, 4039.939400908989, 4062.53280872699, 3427.7600567960253, 3873.612638736132, 3911.178652482982, 4080.6939479538855, 4156.404709925922, 2979.8808047678067, 3184.7738620630994, 3543.875964341236, 3569.839357228931, 3574.1422058705984, 4396.670249731251, 4783.760811959388, 4969.527894849984, 5280.471952804727, 5298.36419629643, 3897.8830635080994, 4478.955210447886, 4482.5069242383315, 4684.906301873977, 4703.952960470386, 4830.975845120752, 5696.0, 5732.971335143339, 5775.826725198251, 5803.448672376117, 3334.966160490256, 3469.2367679110557, 3596.964030359689, 3619.0952261934517, 3857.228554289153, 5211.478852114796, 5617.9157312640755, 5700.857478563028, 5923.001973667757, 5964.821055368346, 3434.9484757728655, 3450.7584469086923, 3760.623937606272, 3888.202918401721, 3904.8047597620007, 3176.3806057818965, 3340.682635149657, 3949.0, 3955.5253369595257, 3967.3652215645357, 3430.6569343065494, 4557.5442455754155, 4597.161135545742, 4634.884127896762, 4653.162430762428, 5098.362704661918, 5293.788248470018, 5783.71081445926, 5794.82615521535, 5795.7391917362975, 1150.3787954504473, 1172.1384782185087, 1225.583301677434, 1263.8988880889506, 1281.923084614926, 872.4024043530202, 872.5768002518773, 876.237673224299, 889.564113584339, 896.9506677132821, 5125.79496820123, 5146.202338637517, 5159.58723302135, 5168.640383750592, 5193.844184674465, 4772.570468657768, 5556.083246264321, 5621.578381621394, 5624.775008999594, 5643.068893632505, 4400.625946794495, 5059.190529515334, 5098.413682426494, 5104.0, 5147.485251474823, 2543.9491210175693, 2564.3845477085365, 2699.3926366567757, 2750.8624568771484, 2990.925226869346, 4789.353437285991, 4801.830791677476, 5230.14329912883, 5325.920111198335, 5835.44563266489, 4904.743817843761, 5006.571812670852, 5494.835154945358, 5845.561582881301, 5860.2381690380835, 5289.523942845085, 5655.1799989001365, 5893.734781934823, 5916.526677865846, 5936.5844390892225, 5066.9359434519165, 5153.639245252796, 5447.618666693294, 5833.387494313206, 5905.763769449174, 4431.889202769957, 5624.328443989236, 5864.413558644102, 5877.177195192591, 5911.497522710618, 5272.9435520147945, 5748.281464816898, 5843.912341314969, 6188.752449902134, 6211.503079753699, 3471.5660542432197, 3638.7270954677974, 3824.7489633811037, 3918.5101862267215, 3958.247932892741, 5257.316548848626, 5446.891062178814, 5955.970220148872, 5985.610935289193, 5990.274425136639, 5323.600729945192, 5532.861678458071, 5712.742926568398, 5732.70691723314, 5750.6262092963825, 5063.544281014726, 5686.203931449518, 5721.485066343883, 5752.884942301047, 5761.477240366293, 4524.751138687272, 4792.969511555032, 5104.770285337243, 5441.428649991853, 5724.656520608777, 4710.396546641753, 5132.409772876018, 5441.4291994723035, 5809.419058094157, 5862.062070068769, 4370.934435983399, 4528.049109687003, 4807.663463557517, 5724.771009159587, 5735.483806457439], "value": 4045.4012579874016, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.875887960727, 687.3435868745348, 718.6514540447882, 759.7540034343677, 837.8282452097319, 14634.0, 15511.53465396037, 15631.452899148531, 15677.059376437408, 15702.979306345094, 13948.930255348734, 14538.927305363484, 14666.63333416665, 14674.706505869875, 14690.0, 862.6808081010035, 873.829603227371, 876.2201640539924, 879.0462348352046, 879.2394578689419, 683.0300972618893, 702.0241863809283, 848.6096395658004, 869.3045563549167, 869.5912920927154, 719.7157122936437, 933.3746389918737, 934.5233930695326, 935.3546053223254, 937.2736129499658, 919.31511024287, 927.20260575905, 929.1266209762802, 930.4231376546517, 931.0038259062806, 686.2656957055952, 690.526989012528, 690.6857379892188, 692.1140939597324, 694.1392673085338, 14896.776548351783, 15396.384144634201, 15438.536843894588, 15519.301631426613, 15576.376944922189, 14189.716205675828, 14658.70682586353, 14763.852361476354, 14951.177685227309, 15246.923765381212, 11613.0, 11734.823977640342, 11863.0, 11866.881331186663, 11909.821352679717, 3731.0, 3736.346139425617, 3759.830807613637, 3768.434734789776, 3790.166163444038, 86676.0, 108214.91785082126, 108608.0, 109278.45360773224, 109813.0, 11071.612493562849, 11331.886681133248, 11396.316221026766, 11422.200445968703, 11594.942025289989, 19472.318468853522, 20250.493737656678, 24307.513849723262, 24450.0, 24718.0, 7870.4884182529095, 7926.603669816486, 7928.127905930343, 7936.920630793618, 8029.8394032120195, 3771.7548359356556, 4275.251830929575, 4276.636485898734, 4332.783360832009, 4369.628581570539, 3590.1563132663805, 3619.8914032579064, 3636.4363523653874, 3668.614795446493, 3674.797886116236, 687.0347162237065, 690.1166506871213, 725.1805459830358, 725.8874874394477, 735.2280105888796, 578.2742657964312, 677.6340775981014, 698.671624336561, 700.2332445971653, 709.4785332780402, 9566.856497152548, 10215.23385746072, 10236.232282578836, 10387.84418233727, 10517.948205179573, 549.598792881199, 565.9858503537362, 568.2635235313625, 610.7618028968706, 644.6100109433819, 4948.4309304429735, 5052.823151189691, 5078.822241221539, 5112.156494178494, 5134.512221338967, 59093.704531477066, 77525.61237193778, 99262.0, 103226.0, 106304.46847765712, 90265.0, 91945.0, 93824.06175938153, 94638.52680736552, 95266.0, 59517.0, 76815.61592192115, 81007.59496202481, 82502.58748706339, 83034.0, 9732.513374331114, 9766.46284454362, 9799.11807937289, 9802.166815820736, 9844.753881153028, 48155.0, 48424.757876210395, 48914.0, 49429.01141977212, 49566.50433495619, 68418.0, 70542.64728676324, 71218.0, 71250.28749712436, 73150.63424682843, 11738.237014594024, 11801.468933897824, 11828.34944078084, 11935.820962685393, 12025.398730063287, 13072.281024543361, 13079.366343731808, 13223.471061157446, 13229.933850330686, 13793.86206137966, 11957.820632690691, 11983.26442700454, 12026.639200823989, 12078.396080196131, 12094.0, 11835.234197722899, 11918.901085717602, 11932.463039163085, 11979.640610781691, 12036.458359373673, 12021.81967270509, 12045.156839021534, 12077.818832717341, 12091.39543022863, 12114.091443141624, 9760.0, 9775.902240977784, 9865.161461275871, 9998.350107243006, 10344.37933723979, 9944.900550994398, 9995.800083998134, 10014.64948726791, 10033.0, 10033.19734421259], "value": 7387.683717959583, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1556.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 116.0, 115.0, 118.0, 115.0, 116.0, 119.0, 117.0, 116.0, 115.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 113.0, 112.0, 155.0, 114.0, 140.0, 103.0, 138.0, 166.0, 148.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 200.0, 204.0, 231.0, 286.0, 215.0, 195.0, 198.0, 197.0, 198.0], "value": 211.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 59.0, 86.0, 62.0, 65.0, 74.0, 84.0, 61.0, 51.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 83.0, 66.0, 75.0, 82.0, 119.0, 99.0, 71.0, 85.0], "value": 84.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 90.0, 90.0, 92.0, 89.0, 129.0, 89.0, 110.0, 111.0], "value": 98.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 96.0, 98.0, 122.0, 118.0, 97.0, 97.0, 116.0, 155.0, 111.0], "value": 113.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 93.0, 97.0, 89.0, 82.0, 154.0, 153.0, 98.0], "value": 104.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 61.0, 62.0, 62.0, 65.0, 62.0, 61.0, 62.0, 61.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 84.0, 76.0, 74.0, 69.0, 70.0, 73.0, 70.0, 92.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 86.0, 79.0, 98.0, 110.0, 110.0, 93.0, 75.0], "value": 91.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 176.0, 151.0, 159.0, 132.0, 148.0, 153.0, 146.0, 162.0, 188.0], "value": 157.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 174.0, 177.0, 175.0, 179.0, 175.0, 174.0, 171.0, 170.0], "value": 174.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 69.0, 66.0, 72.0, 65.0, 64.0, 71.0, 86.0, 60.0], "value": 69.1, "unit": "score"}]}]} 04:49:42 INFO - Return code: 0 04:49:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:49:42 ERROR - # TBPL SUCCESS # 04:49:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:49:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:49:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:49:42 INFO - 2017-01-12 04:49:42.393 screenresolution[1941:23083] starting screenresolution argv=screenresolution get 04:49:42 INFO - 2017-01-12 04:49:42.396 screenresolution[1941:23083] Display 0: 1600x1200x32@60 04:49:42 INFO - 2017-01-12 04:49:42.402 screenresolution[1942:23087] starting screenresolution argv=screenresolution list 04:49:42 INFO - Available Modes on Display 0 04:49:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:49:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:49:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:49:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:49:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:49:42 INFO - Intel Iris: 04:49:42 INFO - Chipset Model: Intel Iris 04:49:42 INFO - Type: GPU 04:49:42 INFO - Bus: Built-In 04:49:42 INFO - VRAM (Dynamic, Max): 1536 MB 04:49:42 INFO - Vendor: Intel (0x8086) 04:49:42 INFO - Device ID: 0x0a2e 04:49:42 INFO - Revision ID: 0x0009 04:49:42 INFO - Displays: 04:49:42 INFO - Display: 04:49:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:49:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:49:42 INFO - Display Serial Number: 1600x1200 60 04:49:42 INFO - Main Display: Yes 04:49:42 INFO - Mirror: Off 04:49:42 INFO - Online: Yes 04:49:42 INFO - Rotation: Supported 04:49:42 INFO - Return code: 0 04:49:42 INFO - Running post-action listener: _resource_record_post_action 04:49:42 INFO - [mozharness: 2017-01-12 12:49:42.639195Z] Finished run-tests step (success) 04:49:42 INFO - Running post-run listener: _resource_record_post_run 04:49:42 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 1327104; Write bytes: 555120640; Read time: 16; Write time: 1783 04:49:42 INFO - TinderboxPrint: CPU usage
54.2% 04:49:42 INFO - TinderboxPrint: I/O read bytes / time
1,327,104 / 16 04:49:42 INFO - TinderboxPrint: I/O write bytes / time
555,120,640 / 1,783 04:49:42 INFO - TinderboxPrint: CPU idle
2,197.7 (45.8%) 04:49:42 INFO - TinderboxPrint: CPU system
966.1 (20.1%) 04:49:42 INFO - TinderboxPrint: CPU user
1,637.9 (34.1%) 04:49:42 INFO - TinderboxPrint: Swap in / out
252,805,120 / 0 04:49:42 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245301760; Write bytes: 240363008; Read time: 17833; Write time: 481 04:49:42 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1290240; Write bytes: 299278848; Read time: 15; Write time: 1264 04:49:43 INFO - Running post-run listener: _upload_blobber_files 04:49:43 INFO - Blob upload gear active. 04:49:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:49:43 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 04:49:43 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:49:43 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:49:43 INFO - (blobuploader) - INFO - Open directory for files ... 04:49:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:49:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:49:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:44 INFO - (blobuploader) - INFO - Done attempting. 04:49:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:49:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:49:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:45 INFO - (blobuploader) - INFO - Done attempting. 04:49:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:49:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:49:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:48 INFO - (blobuploader) - INFO - Done attempting. 04:49:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:49:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:49:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:49:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:49:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:49:51 INFO - (blobuploader) - INFO - Done attempting. 04:49:51 INFO - (blobuploader) - INFO - Iteration through files over. 04:49:51 INFO - Return code: 0 04:49:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:49:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:49:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c474cb0cbfc75f1578688204805c0136e6d4324d94c28d7f02f95460639a86bb2c3980ed16221737713f20e694555fa88d8be293c94230666ab723e9e4dd9d9d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adcc7ce05e1a5ab2de0e3f7322cadeb944255025cc06e70df6ee5266e0c49fa6901adbfd5edd38b761bf928a52515b1fac0351a35293e287606198fa12596cba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c36c7cb61094d57d5f56134653a4d534de2f36e51a7ce32c282ab6d8ca014f8d8e32b30ea942c2a29639fa8c1f71bc91cf85dc2e96666ce30d2b816fb769c392", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8c00ee1f4a1a7a63adce82bc764dd915c867f3d848c90da8bb03bc0a6d8abf93965f0ad9d4f656252ce3c58029862244ea2788074091e8c8b9f3e43d54e4b70"} 04:49:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:49:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:49:51 INFO - Contents: 04:49:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c474cb0cbfc75f1578688204805c0136e6d4324d94c28d7f02f95460639a86bb2c3980ed16221737713f20e694555fa88d8be293c94230666ab723e9e4dd9d9d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adcc7ce05e1a5ab2de0e3f7322cadeb944255025cc06e70df6ee5266e0c49fa6901adbfd5edd38b761bf928a52515b1fac0351a35293e287606198fa12596cba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c36c7cb61094d57d5f56134653a4d534de2f36e51a7ce32c282ab6d8ca014f8d8e32b30ea942c2a29639fa8c1f71bc91cf85dc2e96666ce30d2b816fb769c392", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8c00ee1f4a1a7a63adce82bc764dd915c867f3d848c90da8bb03bc0a6d8abf93965f0ad9d4f656252ce3c58029862244ea2788074091e8c8b9f3e43d54e4b70"} 04:49:51 INFO - Running post-run listener: copy_logs_to_upload_dir 04:49:51 INFO - Copying logs to upload dir... 04:49:51 INFO - mkdir: /builds/slave/test/build/upload/logs 04:49:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.900256 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-01-12 04:49:51.151169) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 04:49:51.154440) ========= 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.HMZ83PQT3H/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.37NCcWN44g/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c474cb0cbfc75f1578688204805c0136e6d4324d94c28d7f02f95460639a86bb2c3980ed16221737713f20e694555fa88d8be293c94230666ab723e9e4dd9d9d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adcc7ce05e1a5ab2de0e3f7322cadeb944255025cc06e70df6ee5266e0c49fa6901adbfd5edd38b761bf928a52515b1fac0351a35293e287606198fa12596cba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c36c7cb61094d57d5f56134653a4d534de2f36e51a7ce32c282ab6d8ca014f8d8e32b30ea942c2a29639fa8c1f71bc91cf85dc2e96666ce30d2b816fb769c392", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8c00ee1f4a1a7a63adce82bc764dd915c867f3d848c90da8bb03bc0a6d8abf93965f0ad9d4f656252ce3c58029862244ea2788074091e8c8b9f3e43d54e4b70"} build_url:https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011411 build_url: 'https://queue.taskcluster.net/v1/task/NgUg3YRRTVCJ9diFfqNf2A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c474cb0cbfc75f1578688204805c0136e6d4324d94c28d7f02f95460639a86bb2c3980ed16221737713f20e694555fa88d8be293c94230666ab723e9e4dd9d9d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adcc7ce05e1a5ab2de0e3f7322cadeb944255025cc06e70df6ee5266e0c49fa6901adbfd5edd38b761bf928a52515b1fac0351a35293e287606198fa12596cba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c36c7cb61094d57d5f56134653a4d534de2f36e51a7ce32c282ab6d8ca014f8d8e32b30ea942c2a29639fa8c1f71bc91cf85dc2e96666ce30d2b816fb769c392", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8c00ee1f4a1a7a63adce82bc764dd915c867f3d848c90da8bb03bc0a6d8abf93965f0ad9d4f656252ce3c58029862244ea2788074091e8c8b9f3e43d54e4b70"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 04:49:51.190152) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:49:51.190703) ========= 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.HMZ83PQT3H/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.37NCcWN44g/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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.005299 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 04:49:51.239700) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 04:49:51.240021) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 04:49:51.240451) ========= ========= Total master_lag: 0.27 =========