builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0385 starttime: 1490898116.94 results: success (0) buildid: 20170330095205 builduid: 02b9edf15d4a45adb6e7dedd1735ea50 revision: 39cc9278d2fab01442a3349169f0f86805a152e9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:56.940752) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:56.941214) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:56.941490) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:56.966925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:56.967358) ========= 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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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-03-30 11:21:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 5.69M=0.002s 2017-03-30 11:21:57 (5.69 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123565 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:57.115797) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:57.116115) ========= 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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.020254 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:57.164431) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:57.166048) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 39cc9278d2fab01442a3349169f0f86805a152e9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 39cc9278d2fab01442a3349169f0f86805a152e9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8pbyYwWBpY/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.yYj4dZNpsg/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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-03-30 11:21:57,266 truncating revision to first 12 chars 2017-03-30 11:21:57,267 Setting DEBUG logging. 2017-03-30 11:21:57,267 attempt 1/10 2017-03-30 11:21:57,267 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/39cc9278d2fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-30 11:21:57,853 unpacking tar archive at: mozilla-aurora-39cc9278d2fa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.845710 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:58.050500) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:58.050839) ========= script_repo_revision: 39cc9278d2fab01442a3349169f0f86805a152e9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:58.051243) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:58.051511) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 11:21:58.071899) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-03-30 11:21:58.072312) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8pbyYwWBpY/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.yYj4dZNpsg/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 11:21:58 INFO - MultiFileLogger online at 20170330 11:21:58 in /builds/slave/test 11:21:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 11:21:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:21:58 INFO - {'append_to_log': False, 11:21:58 INFO - 'base_work_dir': '/builds/slave/test', 11:21:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:21:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:21:58 INFO - 'branch': 'Mozilla-Aurora', 11:21:58 INFO - 'buildbot_json_path': 'buildprops.json', 11:21:58 INFO - 'config_files': ('talos/mac_config.py',), 11:21:58 INFO - 'default_actions': ('clobber', 11:21:58 INFO - 'read-buildbot-config', 11:21:58 INFO - 'download-and-extract', 11:21:58 INFO - 'populate-webroot', 11:21:58 INFO - 'create-virtualenv', 11:21:58 INFO - 'install', 11:21:58 INFO - 'run-tests'), 11:21:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:21:58 INFO - 'download_minidump_stackwalk': True, 11:21:58 INFO - 'download_symbols': 'ondemand', 11:21:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:21:58 INFO - 'tooltool.py': '/tools/tooltool.py', 11:21:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:21:58 INFO - '/tools/misc-python/virtualenv.py')}, 11:21:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:21:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:21:58 INFO - 'gecko_profile': False, 11:21:58 INFO - 'gecko_profile_interval': 0, 11:21:58 INFO - 'installer_path': 'installer.exe', 11:21:58 INFO - 'log_level': 'info', 11:21:58 INFO - 'log_name': 'talos', 11:21:58 INFO - 'log_to_console': True, 11:21:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:21:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:21:58 INFO - 'opt_config_files': (), 11:21:58 INFO - 'pip_index': False, 11:21:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:21:58 INFO - 'cmd': ('bash', 11:21:58 INFO - '-c', 11:21:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:21:58 INFO - 'enabled': True, 11:21:58 INFO - 'halt_on_failure': False, 11:21:58 INFO - 'name': 'check_screen_resolution'},), 11:21:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:21:58 INFO - 'cmd': ('bash', 11:21:58 INFO - '-c', 11:21:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:21:58 INFO - 'enabled': True, 11:21:58 INFO - 'halt_on_failure': False, 11:21:58 INFO - 'name': 'check_screen_resolution'},), 11:21:58 INFO - 'run_cmd_checks_enabled': True, 11:21:58 INFO - 'suite': 'dromaeojs-e10s', 11:21:58 INFO - 'system_bits': '32', 11:21:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:21:58 INFO - 'title': 't-yosemite-r7-0385', 11:21:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:21:58 INFO - 'use_talos_json': True, 11:21:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:21:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:21:58 INFO - 'work_dir': 'build'} 11:21:58 INFO - [mozharness: 2017-03-30 18:21:58.292106Z] Running clobber step. 11:21:58 INFO - Running pre-action listener: _resource_record_pre_action 11:21:58 INFO - Running main action method: clobber 11:21:58 INFO - rmtree: /builds/slave/test/build 11:21:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:21:58 INFO - Running post-action listener: _resource_record_post_action 11:21:58 INFO - [mozharness: 2017-03-30 18:21:58.617276Z] Finished clobber step (success) 11:21:58 INFO - [mozharness: 2017-03-30 18:21:58.617465Z] Running read-buildbot-config step. 11:21:58 INFO - Running pre-action listener: _resource_record_pre_action 11:21:58 INFO - Running main action method: read_buildbot_config 11:21:58 INFO - Using buildbot properties: 11:21:58 INFO - { 11:21:58 INFO - "project": "", 11:21:58 INFO - "product": "firefox", 11:21:58 INFO - "script_repo_revision": "production", 11:21:58 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 11:21:58 INFO - "repository": "", 11:21:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 11:21:58 INFO - "buildid": "20170330095205", 11:21:58 INFO - "pgo_build": "False", 11:21:58 INFO - "basedir": "/builds/slave/test", 11:21:58 INFO - "buildnumber": 11, 11:21:58 INFO - "slavename": "t-yosemite-r7-0385", 11:21:58 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:21:58 INFO - "platform": "yosemite_r7", 11:21:58 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:21:58 INFO - "branch": "mozilla-aurora", 11:21:58 INFO - "repo_path": "releases/mozilla-aurora", 11:21:58 INFO - "revision": "39cc9278d2fab01442a3349169f0f86805a152e9", 11:21:58 INFO - "stage_platform": "macosx64", 11:21:58 INFO - "builduid": "02b9edf15d4a45adb6e7dedd1735ea50", 11:21:58 INFO - "slavebuilddir": "test" 11:21:58 INFO - } 11:21:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 11:21:58 INFO - Running post-action listener: _resource_record_post_action 11:21:58 INFO - [mozharness: 2017-03-30 18:21:58.621444Z] Finished read-buildbot-config step (success) 11:21:58 INFO - [mozharness: 2017-03-30 18:21:58.621571Z] Running download-and-extract step. 11:21:58 INFO - Running pre-action listener: _resource_record_pre_action 11:21:58 INFO - Running main action method: download_and_extract 11:21:58 INFO - mkdir: /builds/slave/test/build/tests 11:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:21:58 INFO - trying https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 11:21:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 11:21:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 11:21:59 INFO - Downloaded 1199 bytes. 11:21:59 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 11:21:59 INFO - Using the following test package requirements: 11:21:59 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 11:21:59 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:21:59 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 11:21:59 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:21:59 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 11:21:59 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:21:59 INFO - u'jsshell-mac.zip'], 11:21:59 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:21:59 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 11:21:59 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 11:21:59 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:21:59 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 11:21:59 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:21:59 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 11:21:59 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:21:59 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 11:21:59 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:21:59 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 11:21:59 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:21:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 11:21:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:21:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 11:22:00 INFO - Expected file size: 51787958 11:22:00 INFO - Obtained file size: 51787958 11:22:16 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 11:22:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 11:22:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:22:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 11:22:18 INFO - Expected file size: 51787958 11:22:18 INFO - Obtained file size: 51787958 11:22:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 11:22:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:22:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 11:22:34 INFO - Expected file size: 14404438 11:22:34 INFO - Obtained file size: 14404438 11:22:34 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')]} 11:22:34 INFO - trying https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 11:22:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 11:22:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 11:22:36 INFO - Downloaded 74686053 bytes. 11:22:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 11:22:36 INFO - mkdir: /builds/slave/test/properties 11:22:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:22:36 INFO - Writing to file /builds/slave/test/properties/build_url 11:22:36 INFO - Contents: 11:22:36 INFO - build_url:https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 11:22:37 INFO - Running post-action listener: _resource_record_post_action 11:22:37 INFO - Running post-action listener: set_extra_try_arguments 11:22:37 INFO - [mozharness: 2017-03-30 18:22:37.898340Z] Finished download-and-extract step (success) 11:22:37 INFO - [mozharness: 2017-03-30 18:22:37.898492Z] Running populate-webroot step. 11:22:37 INFO - Running pre-action listener: _resource_record_pre_action 11:22:37 INFO - Running main action method: populate_webroot 11:22:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:22:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:22:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:22:37 INFO - '--tppagecycles', 11:22:37 INFO - '7'], 11:22:37 INFO - 'tests': ['tsvgm']}}, 11:22:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:22:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:22:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:22:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:22:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:22:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:22:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:22:37 INFO - 'talos_options': ['--disable-e10s'], 11:22:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:22:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:22:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:22:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:22:37 INFO - 'talos_options': ['--disable-e10s'], 11:22:37 INFO - 'tests': ['damp', 'tps']}, 11:22:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:22:37 INFO - 'tests': ['damp', 'tps']}, 11:22:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:22:37 INFO - 'tests': ['dromaeo_dom']}, 11:22:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:22:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:22:37 INFO - 'tests': ['basic_compositor_video']}, 11:22:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:22:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:22:37 INFO - 'tests': ['a11yr', 11:22:37 INFO - 'ts_paint', 11:22:37 INFO - 'tpaint', 11:22:37 INFO - 'sessionrestore', 11:22:37 INFO - 'sessionrestore_no_auto_restore', 11:22:37 INFO - 'tabpaint']}, 11:22:37 INFO - 'other-e10s': {'tests': ['a11yr', 11:22:37 INFO - 'ts_paint', 11:22:37 INFO - 'tpaint', 11:22:37 INFO - 'sessionrestore', 11:22:37 INFO - 'sessionrestore_no_auto_restore', 11:22:37 INFO - 'tabpaint']}, 11:22:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:22:37 INFO - 'ts_paint', 11:22:37 INFO - 'tpaint', 11:22:37 INFO - 'sessionrestore', 11:22:37 INFO - 'sessionrestore_no_auto_restore', 11:22:37 INFO - 'tabpaint']}, 11:22:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:22:37 INFO - 'ts_paint', 11:22:37 INFO - 'tpaint', 11:22:37 INFO - 'sessionrestore', 11:22:37 INFO - 'sessionrestore_no_auto_restore', 11:22:37 INFO - 'tabpaint']}, 11:22:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:22:37 INFO - 'tests': ['a11yr', 11:22:37 INFO - 'ts_paint', 11:22:37 INFO - 'tpaint', 11:22:37 INFO - 'sessionrestore', 11:22:37 INFO - 'sessionrestore_no_auto_restore', 11:22:37 INFO - 'tabpaint']}, 11:22:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:22:37 INFO - 'tests': ['a11yr', 11:22:37 INFO - 'ts_paint', 11:22:37 INFO - 'tpaint', 11:22:37 INFO - 'sessionrestore', 11:22:37 INFO - 'sessionrestore_no_auto_restore', 11:22:37 INFO - 'tabpaint']}, 11:22:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:22:37 INFO - 'tests': ['tsvgx', 11:22:37 INFO - 'tsvgr_opacity', 11:22:37 INFO - 'tart', 11:22:37 INFO - 'tscrollx', 11:22:37 INFO - 'cart', 11:22:37 INFO - 'tsvg_static']}, 11:22:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:22:37 INFO - 'tsvgr_opacity', 11:22:37 INFO - 'tart', 11:22:37 INFO - 'tscrollx', 11:22:37 INFO - 'cart', 11:22:37 INFO - 'tsvg_static']}, 11:22:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:22:37 INFO - 'talos_options': ['--disable-e10s'], 11:22:37 INFO - 'tests': ['tp5o']}, 11:22:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:22:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:22:37 INFO - 'talos_options': ['--disable-e10s', 11:22:37 INFO - '--xperf_path', 11:22:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:22:37 INFO - 'tests': ['tp5n']}, 11:22:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:22:37 INFO - 'talos_options': ['--xperf_path', 11:22:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:22:37 INFO - 'tests': ['tp5n']}}, 11:22:37 INFO - 'talos.zip': {'path': '', 11:22:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:22:37 INFO - Running post-action listener: _resource_record_post_action 11:22:37 INFO - [mozharness: 2017-03-30 18:22:37.917078Z] Finished populate-webroot step (success) 11:22:37 INFO - [mozharness: 2017-03-30 18:22:37.917191Z] Running create-virtualenv step. 11:22:37 INFO - Running pre-action listener: _resource_record_pre_action 11:22:37 INFO - Running main action method: create_virtualenv 11:22:37 INFO - Creating virtualenv /builds/slave/test/build/venv 11:22:37 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 11:22:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:22:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:22:38 INFO - Using real prefix '/tools/python27' 11:22:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:22:38 INFO - Installing distribute.............................................................................................................................................................................................done. 11:22:41 INFO - Installing pip.................done. 11:22:41 INFO - Return code: 0 11:22:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:22:41 INFO - Reading from file tmpfile_stdout 11:22:41 INFO - Output received: 11:22:41 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) 11:22:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:22: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')]} 11:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22: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')]} 11:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f2492a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10196cd30>, '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 0x101893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101961be8>, '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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 11:22: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', 'pip>=1.5'] in /builds/slave/test/build 11:22: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 pip>=1.5 11:22:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8pbyYwWBpY/Render', 11:22:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:41 INFO - 'HOME': '/Users/cltbld', 11:22:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:41 INFO - 'LOGNAME': 'cltbld', 11:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:41 INFO - 'MOZ_NO_REMOTE': '1', 11:22:41 INFO - 'NO_EM_RESTART': '1', 11:22:41 INFO - 'PAGER': '/bin/cat', 11:22:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:41 INFO - 'PWD': '/builds/slave/test', 11:22:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:41 INFO - 'SHELL': '/bin/bash', 11:22:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYj4dZNpsg/Listeners', 11:22:41 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 11:22:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:41 INFO - 'USER': 'cltbld', 11:22:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:41 INFO - 'XPC_FLAGS': '0x0', 11:22:41 INFO - 'XPC_SERVICE_NAME': '0', 11:22:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:42 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 11:22:42 INFO - Cleaning up... 11:22:42 INFO - Return code: 0 11:22:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f2492a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10196cd30>, '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 0x101893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101961be8>, '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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 11:22:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:22:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8pbyYwWBpY/Render', 11:22:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:42 INFO - 'HOME': '/Users/cltbld', 11:22:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:42 INFO - 'LOGNAME': 'cltbld', 11:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:42 INFO - 'MOZ_NO_REMOTE': '1', 11:22:42 INFO - 'NO_EM_RESTART': '1', 11:22:42 INFO - 'PAGER': '/bin/cat', 11:22:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:42 INFO - 'PWD': '/builds/slave/test', 11:22:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:42 INFO - 'SHELL': '/bin/bash', 11:22:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYj4dZNpsg/Listeners', 11:22:42 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 11:22:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:42 INFO - 'USER': 'cltbld', 11:22:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:42 INFO - 'XPC_FLAGS': '0x0', 11:22:42 INFO - 'XPC_SERVICE_NAME': '0', 11:22:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:42 INFO - Downloading/unpacking psutil>=3.1.1 11:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:22:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:22:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:22:46 INFO - Installing collected packages: psutil 11:22:46 INFO - Running setup.py install for psutil 11:22:46 INFO - building 'psutil._psutil_osx' extension 11:22:46 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 11:22:46 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 11:22:46 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 11:22:46 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 11:22:46 INFO - building 'psutil._psutil_posix' extension 11:22:46 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 11:22:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:22:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:22:46 INFO - ^ 11:22:46 INFO - 1 warning generated. 11:22:46 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 11:22:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:22:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:22:46 INFO - Successfully installed psutil 11:22:46 INFO - Cleaning up... 11:22:46 INFO - Return code: 0 11:22:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:22:46 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')]} 11:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:46 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')]} 11:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:46 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 0x101b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f2492a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10196cd30>, '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 0x101893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101961be8>, '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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 11:22:46 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 11:22:46 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 11:22:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8pbyYwWBpY/Render', 11:22:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:46 INFO - 'HOME': '/Users/cltbld', 11:22:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:46 INFO - 'LOGNAME': 'cltbld', 11:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:46 INFO - 'MOZ_NO_REMOTE': '1', 11:22:46 INFO - 'NO_EM_RESTART': '1', 11:22:46 INFO - 'PAGER': '/bin/cat', 11:22:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:46 INFO - 'PWD': '/builds/slave/test', 11:22:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:46 INFO - 'SHELL': '/bin/bash', 11:22:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYj4dZNpsg/Listeners', 11:22:46 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 11:22:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:46 INFO - 'USER': 'cltbld', 11:22:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:46 INFO - 'XPC_FLAGS': '0x0', 11:22:46 INFO - 'XPC_SERVICE_NAME': '0', 11:22:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:22:47 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 11:22:47 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 11:22:47 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 11:22:47 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 11:22:47 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 11:22:47 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 11:22:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:22:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:22:49 INFO - Installing collected packages: mozsystemmonitor 11:22:49 INFO - Running setup.py install for mozsystemmonitor 11:22:50 INFO - Successfully installed mozsystemmonitor 11:22:50 INFO - Cleaning up... 11:22:50 INFO - Return code: 0 11:22:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:22:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:51 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 0x101b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f2492a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10196cd30>, '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 0x101893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101961be8>, '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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 11:22:51 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 11:22:51 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 11:22:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8pbyYwWBpY/Render', 11:22:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:51 INFO - 'HOME': '/Users/cltbld', 11:22:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:51 INFO - 'LOGNAME': 'cltbld', 11:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:51 INFO - 'MOZ_NO_REMOTE': '1', 11:22:51 INFO - 'NO_EM_RESTART': '1', 11:22:51 INFO - 'PAGER': '/bin/cat', 11:22:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:51 INFO - 'PWD': '/builds/slave/test', 11:22:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:51 INFO - 'SHELL': '/bin/bash', 11:22:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYj4dZNpsg/Listeners', 11:22:51 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 11:22:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:51 INFO - 'USER': 'cltbld', 11:22:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:51 INFO - 'XPC_FLAGS': '0x0', 11:22:51 INFO - 'XPC_SERVICE_NAME': '0', 11:22:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:51 INFO - Downloading/unpacking jsonschema==2.5.1 11:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:22:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:54 INFO - Downloading functools32-3.2.3-2.tar.gz 11:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:22:54 INFO - warning: no files found matching '*.txt' 11:22:54 INFO - no previously-included directories found matching 'build' 11:22:54 INFO - no previously-included directories found matching 'dist' 11:22:54 INFO - no previously-included directories found matching '.git*' 11:22:54 INFO - Installing collected packages: jsonschema, functools32 11:22:54 INFO - Running setup.py install for functools32 11:22:54 INFO - warning: no files found matching '*.txt' 11:22:54 INFO - no previously-included directories found matching 'build' 11:22:54 INFO - no previously-included directories found matching 'dist' 11:22:54 INFO - no previously-included directories found matching '.git*' 11:22:54 INFO - Successfully installed jsonschema functools32 11:22:54 INFO - Cleaning up... 11:22:54 INFO - Return code: 0 11:22:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:22:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f2492a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10196cd30>, '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 0x101893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101961be8>, '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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 11:22:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:22:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:22:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8pbyYwWBpY/Render', 11:22:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:54 INFO - 'HOME': '/Users/cltbld', 11:22:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:54 INFO - 'LOGNAME': 'cltbld', 11:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:54 INFO - 'MOZ_NO_REMOTE': '1', 11:22:54 INFO - 'NO_EM_RESTART': '1', 11:22:54 INFO - 'PAGER': '/bin/cat', 11:22:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:54 INFO - 'PWD': '/builds/slave/test', 11:22:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:54 INFO - 'SHELL': '/bin/bash', 11:22:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYj4dZNpsg/Listeners', 11:22:54 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 11:22:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:54 INFO - 'USER': 'cltbld', 11:22:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:54 INFO - 'XPC_FLAGS': '0x0', 11:22:54 INFO - 'XPC_SERVICE_NAME': '0', 11:22:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:22:55 INFO - Cleaning up... 11:22:55 INFO - Return code: 0 11:22:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:22:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:22:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:22:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:22:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f2492a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10196cd30>, '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 0x101893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101961be8>, '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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 11:22:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:22:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:22:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8pbyYwWBpY/Render', 11:22:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:22:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:22:55 INFO - 'HOME': '/Users/cltbld', 11:22:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:22:55 INFO - 'LOGNAME': 'cltbld', 11:22:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:22:55 INFO - 'MOZ_NO_REMOTE': '1', 11:22:55 INFO - 'NO_EM_RESTART': '1', 11:22:55 INFO - 'PAGER': '/bin/cat', 11:22:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:22:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:22:55 INFO - 'PWD': '/builds/slave/test', 11:22:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:22:55 INFO - 'SHELL': '/bin/bash', 11:22:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYj4dZNpsg/Listeners', 11:22:55 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 11:22:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:22:55 INFO - 'USER': 'cltbld', 11:22:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:22:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:22:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:22:55 INFO - 'XPC_FLAGS': '0x0', 11:22:55 INFO - 'XPC_SERVICE_NAME': '0', 11:22:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:22:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:22:55 INFO - Downloading/unpacking blobuploader==1.2.4 11:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:58 INFO - Downloading blobuploader-1.2.4.tar.gz 11:22:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:22:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:22:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:22:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:22:59 INFO - Downloading docopt-0.6.1.tar.gz 11:22:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:22:59 INFO - Installing collected packages: blobuploader, requests, docopt 11:22:59 INFO - Running setup.py install for blobuploader 11:22:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:22:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:22:59 INFO - Running setup.py install for requests 11:23:00 INFO - Running setup.py install for docopt 11:23:00 INFO - Successfully installed blobuploader requests docopt 11:23:00 INFO - Cleaning up... 11:23:00 INFO - Return code: 0 11:23:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:00 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 0x101b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f2492a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10196cd30>, '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 0x101893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101961be8>, '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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 11:23:00 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 11:23:00 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 11:23:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8pbyYwWBpY/Render', 11:23:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:00 INFO - 'HOME': '/Users/cltbld', 11:23:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:00 INFO - 'LOGNAME': 'cltbld', 11:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:00 INFO - 'MOZ_NO_REMOTE': '1', 11:23:00 INFO - 'NO_EM_RESTART': '1', 11:23:00 INFO - 'PAGER': '/bin/cat', 11:23:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:00 INFO - 'PWD': '/builds/slave/test', 11:23:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:00 INFO - 'SHELL': '/bin/bash', 11:23:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYj4dZNpsg/Listeners', 11:23:00 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 11:23:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:00 INFO - 'USER': 'cltbld', 11:23:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:00 INFO - 'XPC_FLAGS': '0x0', 11:23:00 INFO - 'XPC_SERVICE_NAME': '0', 11:23:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:23:00 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-dinEja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:23:00 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-tBUBJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:23:00 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-rAHUVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:23:00 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-hG7Bqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:23:01 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-C2l2Ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:23:01 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-roLP8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:23:01 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-DcNqbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:23:01 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-6TQElx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:23:01 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-zhfp_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:23:01 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-xJdLg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:23:01 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-hIeHzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:23:01 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-MsybEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:23:01 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-v27mxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:23:01 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-yCYvag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:02 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-MukjUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:23:02 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-IS_CX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:23:02 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-rOsucT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:23:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:23:02 INFO - Running setup.py install for manifestparser 11:23:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:23:02 INFO - Running setup.py install for mozcrash 11:23:02 INFO - Running setup.py install for mozdebug 11:23:02 INFO - Running setup.py install for mozdevice 11:23:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:23:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:23:02 INFO - Running setup.py install for mozfile 11:23:03 INFO - Running setup.py install for mozhttpd 11:23:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:23:03 INFO - Running setup.py install for mozinfo 11:23:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:23:03 INFO - Running setup.py install for mozInstall 11:23:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:23:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:23:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:23:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:23:03 INFO - Running setup.py install for mozleak 11:23:03 INFO - Running setup.py install for mozlog 11:23:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:23:03 INFO - Running setup.py install for moznetwork 11:23:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:23:04 INFO - Running setup.py install for mozprocess 11:23:04 INFO - Running setup.py install for mozprofile 11:23:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:23:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:23:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:23:04 INFO - Running setup.py install for mozrunner 11:23:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:23:04 INFO - Running setup.py install for mozscreenshot 11:23:04 INFO - Running setup.py install for moztest 11:23:04 INFO - Running setup.py install for mozversion 11:23:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:23:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:23:04 INFO - Cleaning up... 11:23:04 INFO - Return code: 0 11:23:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:23: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')]} 11:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23: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')]} 11:23:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:04 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 0x101b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f2492a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10196cd30>, '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 0x101893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101961be8>, '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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 11:23:04 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 11:23:04 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 11:23:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8pbyYwWBpY/Render', 11:23:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:04 INFO - 'HOME': '/Users/cltbld', 11:23:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:04 INFO - 'LOGNAME': 'cltbld', 11:23:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:04 INFO - 'MOZ_NO_REMOTE': '1', 11:23:04 INFO - 'NO_EM_RESTART': '1', 11:23:04 INFO - 'PAGER': '/bin/cat', 11:23:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:04 INFO - 'PWD': '/builds/slave/test', 11:23:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:04 INFO - 'SHELL': '/bin/bash', 11:23:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYj4dZNpsg/Listeners', 11:23:04 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 11:23:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:04 INFO - 'USER': 'cltbld', 11:23:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:04 INFO - 'XPC_FLAGS': '0x0', 11:23:04 INFO - 'XPC_SERVICE_NAME': '0', 11:23:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:23:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-BD4u2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:23:05 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)) 11:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:23:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-A0Tpzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:23:05 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)) 11:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:23:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-CFSWZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:23:05 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)) 11:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:23:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-IjXcOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:23:05 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)) 11:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:23:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-ySGFiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:23:05 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)) 11:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:23:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-VMs8T7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:23:05 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)) 11:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:23:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-vOaIV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:23:05 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)) 11:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:23:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-CmPCE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:23:06 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)) 11:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:23:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-DjlI0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:23:06 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)) 11:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:23:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-36IwAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:23:06 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)) 11:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:23:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-ZR8gxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:23:06 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)) 11:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:23:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-EXFGlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:23:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 11:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:23:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-p3RHnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:23:06 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)) 11:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:23:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-KOJd8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:23:06 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)) 11:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-3xAQDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:06 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)) 11:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:23:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-cJ29Um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:23:06 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)) 11:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:23:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Uommmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:23:07 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)) 11:23:07 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)) 11:23:07 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)) 11:23:07 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)) 11:23:07 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)) 11:23:07 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)) 11:23:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:23:07 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 11:23:07 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 11:23:07 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 11:23:07 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 11:23:07 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 11:23:07 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 11:23:09 INFO - Downloading blessings-1.6.tar.gz 11:23:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:23:09 INFO - Installing collected packages: blessings 11:23:09 INFO - Running setup.py install for blessings 11:23:09 INFO - Successfully installed blessings 11:23:09 INFO - Cleaning up... 11:23:10 INFO - Return code: 0 11:23:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:23:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:23:10 INFO - Reading from file tmpfile_stdout 11:23:10 INFO - Current package versions: 11:23:10 INFO - blessings == 1.6 11:23:10 INFO - blobuploader == 1.2.4 11:23:10 INFO - docopt == 0.6.1 11:23:10 INFO - functools32 == 3.2.3-2 11:23:10 INFO - jsonschema == 2.5.1 11:23:10 INFO - manifestparser == 1.1 11:23:10 INFO - mozInstall == 1.12 11:23:10 INFO - mozcrash == 1.0 11:23:10 INFO - mozdebug == 0.1 11:23:10 INFO - mozdevice == 0.49 11:23:10 INFO - mozfile == 1.2 11:23:10 INFO - mozhttpd == 0.7 11:23:10 INFO - mozinfo == 0.9 11:23:10 INFO - mozleak == 0.1 11:23:10 INFO - mozlog == 3.4 11:23:10 INFO - moznetwork == 0.27 11:23:10 INFO - mozprocess == 0.25 11:23:10 INFO - mozprofile == 0.28 11:23:10 INFO - mozrunner == 6.13 11:23:10 INFO - mozscreenshot == 0.1 11:23:10 INFO - mozsystemmonitor == 0.3 11:23:10 INFO - moztest == 0.8 11:23:10 INFO - mozversion == 1.4 11:23:10 INFO - psutil == 3.1.1 11:23:10 INFO - requests == 1.2.3 11:23:10 INFO - wsgiref == 0.1.2 11:23:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:23: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')]} 11:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23: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')]} 11:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:10 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 0x101b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f2492a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10196cd30>, '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 0x101893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101961be8>, '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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 11:23:10 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 11:23:10 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 11:23:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8pbyYwWBpY/Render', 11:23:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:10 INFO - 'HOME': '/Users/cltbld', 11:23:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:10 INFO - 'LOGNAME': 'cltbld', 11:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:10 INFO - 'MOZ_NO_REMOTE': '1', 11:23:10 INFO - 'NO_EM_RESTART': '1', 11:23:10 INFO - 'PAGER': '/bin/cat', 11:23:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:10 INFO - 'PWD': '/builds/slave/test', 11:23:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:10 INFO - 'SHELL': '/bin/bash', 11:23:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYj4dZNpsg/Listeners', 11:23:10 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 11:23:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:10 INFO - 'USER': 'cltbld', 11:23:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:10 INFO - 'XPC_FLAGS': '0x0', 11:23:10 INFO - 'XPC_SERVICE_NAME': '0', 11:23:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:10 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)) 11:23:10 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)) 11:23:10 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)) 11:23:10 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)) 11:23:10 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)) 11:23:10 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)) 11:23:10 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)) 11:23:10 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)) 11:23:10 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)) 11:23:10 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)) 11:23:10 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)) 11:23:10 INFO - Cleaning up... 11:23:10 INFO - Return code: 0 11:23:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:23: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')]} 11:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23: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')]} 11:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5f2492a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10196cd30>, '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 0x101893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101961be8>, '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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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 11:23: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', 'jsonschema'] in /builds/slave/test/build 11:23: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 jsonschema 11:23:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8pbyYwWBpY/Render', 11:23:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:10 INFO - 'HOME': '/Users/cltbld', 11:23:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:10 INFO - 'LOGNAME': 'cltbld', 11:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:10 INFO - 'MOZ_NO_REMOTE': '1', 11:23:10 INFO - 'NO_EM_RESTART': '1', 11:23:10 INFO - 'PAGER': '/bin/cat', 11:23:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:10 INFO - 'PWD': '/builds/slave/test', 11:23:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:10 INFO - 'SHELL': '/bin/bash', 11:23:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYj4dZNpsg/Listeners', 11:23:10 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 11:23:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:10 INFO - 'USER': 'cltbld', 11:23:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:10 INFO - 'XPC_FLAGS': '0x0', 11:23:10 INFO - 'XPC_SERVICE_NAME': '0', 11:23:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:23:10 INFO - Cleaning up... 11:23:10 INFO - Return code: 0 11:23:10 INFO - Running post-action listener: _resource_record_post_action 11:23:10 INFO - Running post-action listener: _start_resource_monitoring 11:23:10 INFO - Starting resource monitoring. 11:23:10 INFO - [mozharness: 2017-03-30 18:23:10.848595Z] Finished create-virtualenv step (success) 11:23:10 INFO - [mozharness: 2017-03-30 18:23:10.849584Z] Running install step. 11:23:10 INFO - Running pre-action listener: _resource_record_pre_action 11:23:10 INFO - Running main action method: install 11:23:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:23:11 INFO - Reading from file tmpfile_stdout 11:23:11 INFO - Detecting whether we're running mozinstall >=1.0... 11:23:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:23:11 INFO - Reading from file tmpfile_stdout 11:23:11 INFO - Output received: 11:23:11 INFO - Usage: mozinstall [options] installer 11:23:11 INFO - Options: 11:23:11 INFO - -h, --help show this help message and exit 11:23:11 INFO - -d DEST, --destination=DEST 11:23:11 INFO - Directory to install application into. [default: 11:23:11 INFO - "/builds/slave/test"] 11:23:11 INFO - --app=APP Application being installed. [default: firefox] 11:23:11 INFO - mkdir: /builds/slave/test/build/application 11:23:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 11:23:31 INFO - Reading from file tmpfile_stdout 11:23:31 INFO - Output received: 11:23:31 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:23:31 INFO - Running post-action listener: _resource_record_post_action 11:23:31 INFO - [mozharness: 2017-03-30 18:23:31.224118Z] Finished install step (success) 11:23:31 INFO - [mozharness: 2017-03-30 18:23:31.224264Z] Running run-tests step. 11:23:31 INFO - Running pre-action listener: _resource_record_pre_action 11:23:31 INFO - Running main action method: run_tests 11:23:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:23:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:23:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:23:31 INFO - 2017-03-30 11:23:31.238 screenresolution[1990:19783] starting screenresolution argv=screenresolution get 11:23:31 INFO - 2017-03-30 11:23:31.241 screenresolution[1990:19783] Display 0: 1600x1200x32@60 11:23:31 INFO - 2017-03-30 11:23:31.246 screenresolution[1991:19786] starting screenresolution argv=screenresolution list 11:23:31 INFO - Available Modes on Display 0 11:23:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:23:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:23:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:23:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:23:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:23:31 INFO - Intel Iris: 11:23:31 INFO - Chipset Model: Intel Iris 11:23:31 INFO - Type: GPU 11:23:31 INFO - Bus: Built-In 11:23:31 INFO - VRAM (Dynamic, Max): 1536 MB 11:23:31 INFO - Vendor: Intel (0x8086) 11:23:31 INFO - Device ID: 0x0a2e 11:23:31 INFO - Revision ID: 0x0009 11:23:31 INFO - Displays: 11:23:31 INFO - Display: 11:23:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:23:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:23:31 INFO - Display Serial Number: 1600x1200 60 11:23:31 INFO - Main Display: Yes 11:23:31 INFO - Mirror: Off 11:23:31 INFO - Online: Yes 11:23:31 INFO - Rotation: Supported 11:23:31 INFO - Return code: 0 11:23:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:23:31 INFO - Python 2.7.3 11:23:31 INFO - Return code: 0 11:23:31 INFO - grabbing minidump binary from tooltool 11:23: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')]} 11:23:31 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 0x10196cd30>, '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 0x101893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101961be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:23:31 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 11:23:31 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 11:23:31 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 11:23:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:23:31 INFO - Return code: 0 11:23:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:23:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:23:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:23:31 INFO - ENV: RUST_BACKTRACE is now 1 11:23:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:23:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:23:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--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 11:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0385 --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 11:23:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8pbyYwWBpY/Render', 11:23:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:31 INFO - 'HOME': '/Users/cltbld', 11:23:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:31 INFO - 'LOGNAME': 'cltbld', 11:23:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:23:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:31 INFO - 'MOZ_NO_REMOTE': '1', 11:23:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:23:31 INFO - 'NO_EM_RESTART': '1', 11:23:31 INFO - 'PAGER': '/bin/cat', 11:23:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:31 INFO - 'PWD': '/builds/slave/test', 11:23:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:23:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:31 INFO - 'RUST_BACKTRACE': '1', 11:23:31 INFO - 'SHELL': '/bin/bash', 11:23:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yYj4dZNpsg/Listeners', 11:23:31 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 11:23:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:31 INFO - 'USER': 'cltbld', 11:23:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:31 INFO - 'XPC_FLAGS': '0x0', 11:23:31 INFO - 'XPC_SERVICE_NAME': '0', 11:23:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--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 11:23:31 INFO - mozversion application_buildid: 20170330095205 11:23:31 INFO - mozversion application_changeset: 39cc9278d2fab01442a3349169f0f86805a152e9 11:23:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:23:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:23:31 INFO - mozversion application_name: Firefox 11:23:31 INFO - mozversion application_remotingname: firefox-dev 11:23:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:23:31 INFO - mozversion application_vendor: Mozilla 11:23:31 INFO - mozversion application_version: 54.0a2 11:23:31 INFO - mozversion platform_buildid: 20170330095205 11:23:31 INFO - mozversion platform_changeset: 39cc9278d2fab01442a3349169f0f86805a152e9 11:23:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:23:31 INFO - mozversion platform_version: 54.0a2 11:23:31 INFO - using testdate: 1490898211 11:23:31 INFO - actual date: 1490898211 11:23:31 INFO - starting webserver on 'localhost:49297' 11:23:31 INFO - SUITE-START | Running 2 tests 11:23:31 INFO - TEST-START | dromaeo_css 11:23:31 INFO - operating with platform_type : mac_ 11:23:31 INFO - Initialising browser for dromaeo_css test... 11:23:31 INFO - TEST-INFO | started process 2001 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpZxHNOA/profile http://localhost:49297/getInfo.html) 11:23:34 INFO - PROCESS | 2001 | 2017-03-30 11:23:34.806 plugin-container[2003:19951] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4953, name = 'com.apple.tsm.portname' 11:23:34 INFO - PROCESS | 2001 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:23:34 INFO - PROCESS | 2001 | 2017-03-30 11:23:34.810 plugin-container[2003:19951] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 11:23:34 INFO - PROCESS | 2001 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:23:34 INFO - PROCESS | 2001 | 2017-03-30 11:23:34.810 plugin-container[2003:19951] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:23:34 INFO - PROCESS | 2001 | __metrics Screen width/height:1600/1200 11:23:34 INFO - PROCESS | 2001 | colorDepth:24 11:23:34 INFO - PROCESS | 2001 | Browser inner width/height: 1024/663 11:23:34 INFO - PROCESS | 2001 | __metrics 11:23:34 ERROR - PROCESS | 2001 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 11:23:35 INFO - TEST-INFO | 2001: exit 0 11:23:35 INFO - Browser initialized. 11:23:35 INFO - Running cycle 1/1 for dromaeo_css test... 11:23:35 INFO - TEST-INFO | started process 2005 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpZxHNOA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:23:38 INFO - PROCESS | 2005 | 2017-03-30 11:23:38.128 plugin-container[2006:20086] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b43, name = 'com.apple.tsm.portname' 11:23:38 INFO - PROCESS | 2005 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:23:38 INFO - PROCESS | 2005 | 2017-03-30 11:23:38.129 plugin-container[2006:20086] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450b, name = 'com.apple.CFPasteboardClient' 11:23:38 INFO - PROCESS | 2005 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:23:38 INFO - PROCESS | 2005 | 2017-03-30 11:23:38.129 plugin-container[2006:20086] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:23:47 INFO - PROCESS | 2005 | RSS: Main: 230719488 11:23:47 INFO - PROCESS | 2005 | 11:26:50 INFO - PROCESS | 2005 | Cycle 1(1): loaded http://localhost:49297/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49297/tests/dromaeo/cssquery-ext.html) 11:26:52 INFO - PROCESS | 2005 | RSS: Main: 246140928 11:26:52 INFO - PROCESS | 2005 | 11:28:36 INFO - PROCESS | 2005 | ************************* 11:28:36 INFO - PROCESS | 2005 | A coding exception was thrown and uncaught in a Task. 11:28:36 INFO - PROCESS | 2005 | 11:28:36 ERROR - PROCESS | 2005 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:28:36 INFO - PROCESS | 2005 | Full stack: 11:28:36 INFO - PROCESS | 2005 | ************************* 11:29:54 INFO - PROCESS | 2005 | Cycle 1(1): loaded http://localhost:49297/tests/dromaeo/cssquery-ext.html (next: http://localhost:49297/tests/dromaeo/cssquery-jquery.html) 11:29:55 INFO - PROCESS | 2005 | RSS: Main: 254902272 11:29:55 INFO - PROCESS | 2005 | 11:32:57 INFO - PROCESS | 2005 | Cycle 1(1): loaded http://localhost:49297/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49297/tests/dromaeo/cssquery-mootools.html) 11:32:58 INFO - PROCESS | 2005 | RSS: Main: 260956160 11:32:58 INFO - PROCESS | 2005 | 11:36:00 INFO - PROCESS | 2005 | Cycle 1(1): loaded http://localhost:49297/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49297/tests/dromaeo/cssquery-prototype.html) 11:36:00 INFO - PROCESS | 2005 | RSS: Main: 257273856 11:36:00 INFO - PROCESS | 2005 | 11:39:03 INFO - PROCESS | 2005 | Cycle 1(1): loaded http://localhost:49297/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49297/tests/dromaeo/cssquery-yui.html) 11:39:04 INFO - PROCESS | 2005 | RSS: Main: 262242304 11:39:04 INFO - PROCESS | 2005 | 11:42:07 INFO - PROCESS | 2005 | Cycle 1(1): loaded http://localhost:49297/tests/dromaeo/cssquery-yui.html (next: http://localhost:49297/tests/dromaeo/cssquery-dojo.html) 11:42:07 INFO - PROCESS | 2005 | RSS: Main: 258383872 11:42:07 INFO - PROCESS | 2005 | 11:42:07 INFO - PROCESS | 2005 | __start_tp_report 11:42:07 INFO - PROCESS | 2005 | _x_x_mozilla_page_load 11:42:07 INFO - PROCESS | 2005 | _x_x_mozilla_page_load_details 11:42:07 INFO - PROCESS | 2005 | |i|pagename|runs| 11:42:07 INFO - PROCESS | 2005 | |0;dojo.html;2720.279126031602;3386.373520898634;3401.761876668633;3411.539442175306;3441.0365097772615;5912.379200183977;5944.108383742441;5949.613275137119;5968.253968253968;5970.641761494303;5904.763809447617;5942.168096466498;5955.927932972063;5969.1046343048565;5987.880242395149;4415.763586195871;5320.707361095138;5397.136458166694;5411.998780225651;5432.13085906255;4472.776361181945;5425.9186112208345;5431.266778984846;5438.483344082326;5447.319085114361;5237.3453318335205;5245.868853278679;5416.91874621881;5422.53908417783;5422.6475279106735;4640.976795116003;5355.357357117151;5367.543758780508;5393.2719082923695;5413.918791218135;5239.685618862881;5319.468053194688;5328;5414.810481633163;5435.918461223085;5541.722913854332;5937.52499800015;5978.252718410199;6042.728077236491;6069.119977603267;5665.178549110397;5699.515541179005;6176.567640265183;6259.280182778948;6288.151099601541;6318.494520438354;6344.270408902989;6345.809625711236;6356.745730170787;6364.872702545969;6071.817845464643;6084.178635884187;6088.482478989291;6114.877702445926;6116.755329786848;6249.625022498573;6255.155554000152;6281.120643109968;6414.037894315797;6416.518761092937;6255.218097737783;6296.874062518724;6359.9364006360265;6371.585846919936;6399.392057754506;6309.053641953744;6330.905036424458;6337.4613157882095;6343.968280158662;6363.840903977345;6201.534884883562;6233.34549872266;6335.68321583919;6336.841578960563;6350.523710721622;6223.844403889849;6257.937420625735;6292.276388215322;6295.968520157461;6317.210348706411;5046;5083.186690129562;5086.618503612244;5116.232565115188;5131.897362052738;4572.428446444194;4575.725456472623;4593.035462552834;4594.862154135381;4642.280446530725;5745.913811292834;5821.301443826683;5941.257342832146;6015.428534289235;6017.338092809788;6254.843628909313;6276.46650034743;6278.874422511524;6329.905051424233;6341.175647165839;6006.789762358297;6021;6024.668168670671;6041.154238406627;6045.093236014633;5427.0773968425465;5593.049181639131;5595.468430499096;5609.943900561024;5617.8595535111135;6322.936770632326;6373.235211774617;6384.968075159781;6421.550491465552;6446.419822216023;6343.111964324906;6362.109304697438;6363.268224154282;6373.235211774617;6374.426301632875;5010.525687155688;6462.321456247214;6512.5766825156215;6513.381228783258;6560.114384558215;5431.88646327494;5725.971370143123;6259.937400625936;6299.338569450142;6318.210223722035;6215;6350.841228969128;6360.077788720702;6363.268224154096;6383.170187875547;5191.442567229891;6325.936740632535;6342.778002769881;6343.9682801585695;6382.106505089198;6097.481714054177;6105.358937311696;6111.083337499233;6113.694315284307;6269.96865015672;6003.129546215861;6033.728482218223;6071.2714474263375;6159.384061593805;6167.074938759222;6112.786052488142;6245;6297.275813281532;6319.494440444845;6331.018692102732;5974.731137098754;6052.03167493199;6122.855605810045;6145.1564530639735;6147.569670123048 11:42:07 INFO - PROCESS | 2005 | |1;ext.html;810.1655295046103;832.1470492744936;861.8836457078296;943.1652987106409;944.1596978688964;36157.638423615754;37875;37910.43134352987;37957.861264162086;38034.42948355773;36356.72751453699;36636.63363366365;36642.8167859161;36643;36742.81628591853;7301.780946571611;7465.104187497508;7660.080790305157;7808.960955195216;10907.781844363108;9198.954005229965;10865.782684346308;10927.125829933586;10968.23222374434;11015.339079655247;11006.009459148676;11024.118070554336;11025.062869656089;11037;11046.668599942015;10952.616658416917;10979;11032.172587056002;11034.282771619819;11052.778944421147;10761.354318740901;10810.027097561257;10811.18916081297;10815.134789216843;10846.783064338748;32139.678603214135;37508.62491375106;37817.81091094555;37981;38027.04932376685;3822.4266360045935;3834.578196398394;3842.538895332578;3876.9806150969066;3923.3330333843314;26797.866010670015;26955.595666065026;27249.45501089987;27517.449651007068;28349.57475637867;3068.585740924991;3078.245829771698;3121.172889184368;3170.3183815479783;3201.471757160066;344187;345788;346520;347207;347846;40997.59002409938;41727;49652;50831.983360333325;50876.74561627242;46545;51180.48819511832;53191.20213196805;53221.733891330296;53391.73304133455;14437.422503099759;14484.493042743454;14518.491852785102;14520.056196347203;14567.854321456862;12516;12519.43662535188;12519.93740031294;12524.248545087325;12749.617511474671;11901.226420282654;11992.940035299767;12003.339816310187;12067.155299128977;12097.2136811107;5401.081816091274;5404.91892621611;5426.158945363475;5428.67427954324;5434.510894019557;968.5544649461186;986.8519722041751;1014.91373233276;1034.2811745836723;1037.2065369991963;24320.635190472156;25987.61018584723;26729.33176670599;26795.86602066977;27394.726052739617;980.5048450532436;1033.788073444942;1123.612353737959;1141.5833220874488;1142.9257098288585;13980.790288145687;15129.773053404208;15491.450854914418;15699.68600627981;15898.60253493672;502937;506410;507010;508277;508697;54491;54562.72718636461;54714.72642636761;54855.451445485036;54861.1770823438;1409.5348534983432;1458.6717988452513;1461.4081297074702;1461.5615315405337;1463.3780643226798;28227.294317642223;28333.716662833933;28983.5652465209;28988.041712414622;29320.26699332534;43386.48147314829;43424.78287608542;43523.78238108789;43549.346759798;44911.10177796491;423755;468996;471005;471276.6436167797;472893;31455.52816707797;32385.838070810438;33538.496922545695;34149.29253537363;34296.65703343035;37511.062223444635;37794.43308350432;38283;38476.615233848424;38574.2285154301;22210;22544.985475653964;27617.920776328094;27819.721802781714;28284.010059647815;26955.595666065416;27175.72824271732;27343.453130936872;27738.72261277442;28030.018949336674;31329.373412531168;31346.529802053443;31641.36717265596;32000;32656.203908785246;23009.769902301432;23023;24762.50474990526;26814.329641758333;27854.860725696242;19899.701504477733;22527.88736056309;23859.522809544058;23890.52218955646;24410.633840492763 11:42:07 INFO - PROCESS | 2005 | |2;jquery.html;390.9804509774511;412.9752214867106;413.29739442947;425.4809132857914;455.44435788338217;19295.807041929576;20359.898200508997;20989.580208395862;21713.67429488559;21726.782732172695;17203.22585483656;21742.673859892075;22152.88923555384;22650.54698906025;22849;6344.143540622026;6656.866862662744;6667.033280174372;6695.56478828877;6711.9664401678165;6247.531435142359;6423.940049891762;6477.676116194195;6487.124238227826;6509.772157974472;5534;6498.090267362573;6502.122213501164;6506.642134682591;6510.121133646944;7181.8922716159295;7510.887336689927;7663.846723065563;7682.654280557388;7687.5387476751575;7238.52949558277;7363.742269020612;7390.187079421259;7415.036045314128;7466.85066298671;20493.590128197502;26201.475970480675;26587.867060664765;26610.6008409874;26754.331141721417;2737.7399147080996;2768.750812426891;2819.9295017624518;2826.78799090067;2846.274200078987;15433.691326173526;16223.675526489522;16522.926009809325;17010.829891701047;17379.478615641423;2011.4669612552684;2040.1382573033886;2098.4334229758033;2101.3289862131373;2119.0676102515;167321;172708;174064;174320;176488.11755941395;21111.78888211129;21389.893050534647;21483.248086316904;21697.45756356072;21707.782922170576;31521.684783152334;31777.364452710815;31796.682033179834;31823.84088079591;32151.678483215335;8879.801226834425;10361.792764144675;10659.040686338267;10739.194560407845;10880.945595271973;8909.287257019423;8938.240249578728;8949.552522373855;9064.138906803975;9087.681931132378;9601.183899368632;9630.951845240868;9691.722445225367;9791.853122203172;9855.655052073287;4024.597540245952;4073.837046518165;4113.814878330482;4118.938215926763;4132.2561938850695;715.6529083394549;885.5350940756026;898.6854600889636;937.9108984646448;940.351157701184;20212.494687632927;21370.57258854814;21487.355379338645;21505.462363441038;21590.92045397724;903.3631289940577;908.9954550227206;922.7739203894954;938.5166639180827;957.3155194036298;10531.368117912949;10987.780244395068;11105.944470277596;11126.554937802397;11153.888461115284;722788;751729;760625;777219.2278077147;778503;127389.08916366262;148417;154365;160803.19598401934;169440.30559694246;1152.2049785647876;1268.0933132810085;1299.4802079168408;1310.4299629661127;1327.1108357400565;18738.6252274951;19943.20227190954;19970.60058798787;19971.500712482306;20067.899660501604;61950;62068.37931620626;62642.74714505776;64199.35800641934;65324.34675653183;88377.55811220902;89179.66230506418;89360.10639893518;89752.10247897437;90072.54963725139;11214.271072380268;11418.143639227257;11464.656060318204;11721;11768.705442401159;12483.251004939733;12540;12794.232346058894;12960.481580737041;13513.729725405241;11210.43947802623;11594.826077609012;11818.172727908965;11873.228240164362;11898.940505297418;10315.381077135396;10462.372257664565;10516.421596811942;10517.684469465928;10948.835767463335;10262.897371026194;11643;11784.82322765142;11895.583654572049;11984.520619175135;11004.174686898354;11587;11717.824232636347;11968.46141923598;11970.22193557416;9858.950705246429;11136.721581960515;11307.321560706385;11527.76944461123;11549.076073913899 11:42:07 INFO - PROCESS | 2005 | |3;mootools.html;1312.4881296294452;1520.9239538023103;1546.907185568866;1548.589623749706;1558.0418042903614;8625.611847466864;9519.333646644729;10991.395473248987;11171.553137874485;11204.21570490064;9376.57805398757;9562.474063926482;11201.71995700108;11207;11217.495212715427;2386.4033991502124;2431.8297719159664;2446.6085426331792;2448.963265551018;2449.35092200567;2343.3907184132077;2399.1123284384803;2412.4089598048504;2420.491696743686;2425.527022230666;2348.612478940973;2406.494636126407;2418.093215044358;2419.177479656916;2431.3313838694326;2333.218371845434;2389.8924548395275;2394.1859767678898;2394.4851856850773;2411.614141737331;2389.8207634427486;2395.113807891074;2408.735413907695;2422.47916697909;2425.38152771044;10958.123350131971;11168.050715689176;11179.217454778169;11182.832257516144;11201.831972520418;5649.887002259932;5781.046127388976;5822.097574875901;5825.271841019872;5849.736761845727;3257.120577444077;3391.8134502602347;3535.8762443314617;3661.7986010769396;3746.6440688134585;1220.2190598017276;1229.440604524939;1254.084518301645;1268.3911722373311;1273.4778740716263;13086.541971031063;13688.52090176839;14774.48289309869;15402.845971540366;15568.37726490939;4613.66203800894;4663.370444989951;4808.2547205183255;4819.927701084486;4821.349117869113;10024.84714257855;11857.407129643483;11943.223690460092;11969.760604787856;12015.639530814089;5834.416558344132;5841.299044114733;5846.210761547221;5851.795187168514;5884.529237660977;4337.37108119321;5112.923306150411;5152.665076770073;5249.501297376743;5256.9737151314;3758.0792705787258;3871.283812494641;3928.5482169550296;4003.679705623543;4007.8196481158416;1049.569676432659;1053.657561292583;1053.7787064716342;1065.8507808906759;1068.8610480637467;767.9155292917775;799.3205775091126;821.1337039423346;823.0987069159381;829;6636.270010298863;6651.16860392451;6739.427148692416;6772.8984065239065;6886.655667216619;959.6113574002541;961.7980224153006;986.2061040862175;995.8207522645848;1013.0730381700808;3725.776453412804;4110.403991421227;4180.059486615487;4233.872983810491;4352.782360882006;13406.93296533511;13971.371288292052;14005.789913151311;14014.71970560583;14164.504242351675;11867.821982670266;13074.738505229841;13366.93316533411;13742.65643358904;13769.724605507834;8667.999999999874;10167;10211.132053775364;10319;10363.844542331872;6119.296280927572;6406;6742.718883412136;6951.44388448933;7006.544574602634;11771;11977.820332694842;12270.202436841577;12323.75352492963;12498.625041248413;12319.076069295019;12458.501659933501;12475.251484910934;12552.811707824207;12608.747825043265;9306.208972237166;9399.013103624295;9445.386049906734;9470.289728270269;9485.620575176916;10220.080192782685;10312.742181445525;10401.375917444977;10408.843867341844;10413.843792343272;8226.341892648454;8647.56762161902;8669.436486628349;8869.157430044135;8869.778255543662;8270.83458330842;8645.092265312303;8677;8696.304295656459;8712.91287087121;6865.622390768555;7865.016872890888;8034.63844127004;8035.397345199251;8057.355411567178;5761.107028410603;5805.216295800013;5832.241808565029;5987.6108052976415;6045.546584006129;6014.699265036818;6015.518758499222;6116.969415152896;6185.969070154621;6238.594491358047 11:42:07 INFO - PROCESS | 2005 | |4;prototype.html;376.10862256452197;378.33790865984525;383.6623771081448;390.81826950468036;391.692521370724;5212.635115541913;5214.400343960446;5319.388270348906;5328.255948186682;5407.297051383314;4977.7262250576205;5098.643094983353;5140.871478213046;5160.92258616121;5221.660592061513;4179.20595086934;4321.611055005057;4375.221734198775;4378.1462614790125;4384.618845063811;4166.854160104397;4387.254166791653;4387.978060109695;4397.526828512437;4417.447819022622;4208.684348673846;4366.8034938427845;4397.296432570789;4419.624331931774;4439.400680908085;3449.482577613353;3658.7804731715914;4160.687948403882;4394.384786129945;4398.802053907566;3471.427214509603;3561.465780132975;3957.6042395760483;4010.455974449829;4045.7167998240134;5077.949220507785;5100.0929470202;5194.168932970744;5204.219367094928;5289.841304760864;4570.451545814491;4753;4776.8566942991765;5068.3917929848285;5072.188449848007;4941.20940649498;5595.71298601324;5649.8587535311535;5672.483060502474;5705.914411283834;3700.85196592136;4043.433919251307;4493.955060449419;4503.639708823319;4506.662000349954;5160.8193713220235;5574.60977731559;5722.227499287626;5854.795082172103;6043.305019922721;4009.7794621295525;4490.842820501267;4610.976945115253;4612.169809434267;4667.789949452283;4249.151619045663;4295.334223195409;4727.196376615915;4768.976155119202;4783.851875549843;4850.733209673502;4929.630277729185;4976.576990955737;5011.699298042056;5077.009983053269;4510.639148868083;5551.944480555224;5581.413951535112;5617.915731264035;5656.236408084856;1441.3802065112102;1459.839417664056;1465.1648560320727;1575.4954018911912;1597.8162511311116;1102.32207192576;1107.7119948813368;1188.8216767484946;1202.5370232460566;1222.10175520992;4509.706869053501;4976.701397916064;5101.642884998088;5302.091247151023;5304.416514183436;5366.490183432567;5442.857000030038;5454.481824226771;5516.91724624131;5761.168822732836;4783.938279501122;4967.155583550732;5304.170061328836;5308.283381743492;5336.839894803162;2732.8223665461683;3047.725704686589;3057.3121047764075;3071.078676397072;3083.152133163353;5525.613207075467;5573.442655734394;5963.7337811631305;6025.457708806229;6148.7557041679465;5035.651291890925;5072;5462.399136095027;5620.887582248332;6080.115164298993;5132.538071573576;5381.946180538144;5852.356240813507;5938.287405511366;6035.577509574287;5189.896202075862;5215.973920130375;5488.807891723771;5740.540756739534;5952.672603006876;4711.623070154448;5752.741126649227;5771.336296325976;5783.797567085132;5790.565707572033;5097.898042039213;5433.402325744244;5763.7982670606325;5833.366657335952;6002.219711437485;3987.5414327351564;4410.933835992527;4596.356510088657;4644.814207431665;4658.767061646972;5062.544371006627;5127.46161653021;5770.413136387527;5866.6480011199465;5876.647401155944;4551.362809206647;4671.486136524912;5226.346706661668;5405.756740946588;5634.718264086698;4803.3428467178765;5100.923486147785;5104.2343648453025;5235.4502777207845;5683.857903552279;4343.457067866517;4965.453800081916;5175.275461435327;5176.101383225406;5430.104032834538;4782.324369387223;5083.729067733067;5306.752913065504;5512.2558454608115;5705.942940570707;4744.833930812405;5045.2936588876855;5514.61397702157;5530.1978566362795;5562.972185139049 11:42:07 INFO - PROCESS | 2005 | |5;yui.html;652.7030201258428;707.9575225486471;770.3490550484842;796.9003874515685;851.8424091543067;14928.925355373221;15706.293216805243;16379.508614741548;16598.66802663946;16604.750928736077;14988.25058747061;15121.243937803121;15154.848451515481;15195.696086078271;15267.312970916308;881.3962435731545;916.9083091690844;925.5973651461602;944.9196818270456;946.4794363100302;902.8284625921054;946.5125460387906;948.4025064209538;949.9667511637059;950.092661508258;979.3634137810409;992.0377234082964;993.5330394714508;994.4928086675811;994.7314225159203;983.5672304186171;986.8174387738255;989.1196834817018;990.2672022703184;991.5092029445399;733.2630706140349;734.372111844376;735.3455424672046;736.2637362637363;737.3695490355723;14526.128432294096;15846;16396.344146234136;16484.6703065938;16532.83467165325;14195.290235488184;14280.07179533327;14310.64223394413;14321.212333321662;14419.639509012359;11638.76722465546;11756;11814.17300788945;11816.7786905651;11835.644930652094;3655.214128962286;3664.8146950712994;3683.7605555639066;3709.9072523186865;3720.2931443025736;69952.3004769951;97283;106645;116592.41703791427;118628.22057669141;10888.945555272174;11145.306989759481;11152.944235278934;11190.38452885083;11302.32186068822;19128.04359782033;22151.33545993623;22196.556068878534;22198.112075516798;22211.66682499764;7337.669804858794;7578.09062912454;7821.452498325063;8189.385796065199;8241.711540096068;4266.2960611499375;4285.935710964338;4316.870493885189;4344.283193273074;4381.802818873159;2680.5309070912513;3118.547810567455;3149.937001259962;3175.142711467891;3189.1389324882152;629.3832044596321;633.7591715148214;634.308603622045;637.6014990630856;644.4650939720021;618.9783357582463;626.5238418801744;634.4511997122456;647.608197040793;672.7611697847292;9624.66313679018;10073.093421592092;10079.941606131251;10085.798284034425;10204.846927296096;619.3744318238612;639.9712012959428;651.205529254309;652.5693042591867;675.7330854312519;4238.352504934107;4638.2115040443205;4803.639727020487;4844.858178058978;4847.442544107403;75692.62153689197;82091;82544.58727706443;102813.48593256986;107533;65043;67783.32216677869;71307.21731956639;84013.15986840053;86086.13913860782;50047.74976125169;56873.43126568764;56877.7248910039;59719.701401493585;64353.356466434736;10350.482475876326;10358.274920755475;10959.452027398438;11115.388653624128;11289.548418063185;44745.77627111843;45782;46698.76650616725;46861.12555497742;48902.77743056452;54594.72702636461;62699.37300626935;64718.35281647123;69327;71217.6439117801;9955.900440995787;12667.30329831868;12671.859532641738;12751;12912.483500659868;14728.337224825123;14747.305733083393;14777.808036045679;14953.775693364825;15215.391384344945;13424.127431716906;13540.255285959367;13925.582232533041;14232.857671423568;14717.484888028868;13318.062827225376;13430.865691343352;13776.724465510833;13976.091554048955;14133.717325653224;11316.321020738782;13075.738485230433;13388.397522111723;13800.447982080605;14075.718485630436;8083.393745469231;9159.388049993002;9550.522473876139;9708.75728106803;9771;9897.901020989699;10619.734506637396;10954.378318132854;11553.82669259945;11602.129840261845 11:42:07 INFO - PROCESS | 2005 | __end_tp_report 11:42:07 INFO - PROCESS | 2005 | __start_cc_report 11:42:07 INFO - PROCESS | 2005 | _x_x_mozilla_cycle_collect,422 11:42:07 INFO - PROCESS | 2005 | __end_cc_report 11:42:07 INFO - PROCESS | 2005 | __startTimestamp1490899327428__endTimestamp 11:42:07 INFO - PROCESS | 2005 | 11:42:07 INFO - PROCESS | 2005 | ------- Summary: start ------- 11:42:07 INFO - PROCESS | 2005 | Number of tests: 6 11:42:07 INFO - PROCESS | 2005 | 11:42:07 INFO - PROCESS | 2005 | [#0] dojo.html Cycles:165 Average:5847.59 Median:6084.18 stddev:675.37 (11.1%) stddev-sans-first:631.32 11:42:07 INFO - PROCESS | 2005 | Values: 2720.3 3386.4 3401.8 3411.5 3441.0 5912.4 5944.1 5949.6 5968.3 5970.6 5904.8 5942.2 5955.9 5969.1 5987.9 4415.8 5320.7 5397.1 5412.0 5432.1 4472.8 5425.9 5431.3 5438.5 5447.3 5237.3 5245.9 5416.9 5422.5 5422.6 4641.0 5355.4 5367.5 5393.3 5413.9 5239.7 5319.5 5328.0 5414.8 5435.9 5541.7 5937.5 5978.3 6042.7 6069.1 5665.2 5699.5 6176.6 6259.3 6288.2 6318.5 6344.3 6345.8 6356.7 6364.9 6071.8 6084.2 6088.5 6114.9 6116.8 6249.6 6255.2 6281.1 6414.0 6416.5 6255.2 6296.9 6359.9 6371.6 6399.4 6309.1 6330.9 6337.5 6344.0 6363.8 6201.5 6233.3 6335.7 6336.8 6350.5 6223.8 6257.9 6292.3 6296.0 6317.2 5046.0 5083.2 5086.6 5116.2 5131.9 4572.4 4575.7 4593.0 4594.9 4642.3 5745.9 5821.3 5941.3 6015.4 6017.3 6254.8 6276.5 6278.9 6329.9 6341.2 6006.8 6021.0 6024.7 6041.2 6045.1 5427.1 5593.0 5595.5 5609.9 5617.9 6322.9 6373.2 6385.0 6421.6 6446.4 6343.1 6362.1 6363.3 6373.2 6374.4 5010.5 6462.3 6512.6 6513.4 6560.1 5431.9 5726.0 6259.9 6299.3 6318.2 6215.0 6350.8 6360.1 6363.3 6383.2 5191.4 6325.9 6342.8 6344.0 6382.1 6097.5 6105.4 6111.1 6113.7 6270.0 6003.1 6033.7 6071.3 6159.4 6167.1 6112.8 6245.0 6297.3 6319.5 6331.0 5974.7 6052.0 6122.9 6145.2 6147.6 11:42:07 INFO - PROCESS | 2005 | 11:42:07 INFO - PROCESS | 2005 | [#1] ext.html Cycles:180 Average:56435.63 Median:31337.95 stddev:118068.00 (376.8%) stddev-sans-first:118325.34 11:42:07 INFO - PROCESS | 2005 | Values: 810.2 832.1 861.9 943.2 944.2 36157.6 37875.0 37910.4 37957.9 38034.4 36356.7 36636.6 36642.8 36643.0 36742.8 7301.8 7465.1 7660.1 7809.0 10907.8 9199.0 10865.8 10927.1 10968.2 11015.3 11006.0 11024.1 11025.1 11037.0 11046.7 10952.6 10979.0 11032.2 11034.3 11052.8 10761.4 10810.0 10811.2 10815.1 10846.8 32139.7 37508.6 37817.8 37981.0 38027.0 3822.4 3834.6 3842.5 3877.0 3923.3 26797.9 26955.6 27249.5 27517.4 28349.6 3068.6 3078.2 3121.2 3170.3 3201.5 344187.0 345788.0 346520.0 347207.0 347846.0 40997.6 41727.0 49652.0 50832.0 50876.7 46545.0 51180.5 53191.2 53221.7 53391.7 14437.4 14484.5 14518.5 14520.1 14567.9 12516.0 12519.4 12519.9 12524.2 12749.6 11901.2 11992.9 12003.3 12067.2 12097.2 5401.1 5404.9 5426.2 5428.7 5434.5 968.6 986.9 1014.9 1034.3 1037.2 24320.6 25987.6 26729.3 26795.9 27394.7 980.5 1033.8 1123.6 1141.6 1142.9 13980.8 15129.8 15491.5 15699.7 15898.6 502937.0 506410.0 507010.0 508277.0 508697.0 54491.0 54562.7 54714.7 54855.5 54861.2 1409.5 1458.7 1461.4 1461.6 1463.4 28227.3 28333.7 28983.6 28988.0 29320.3 43386.5 43424.8 43523.8 43549.3 44911.1 423755.0 468996.0 471005.0 471276.6 472893.0 31455.5 32385.8 33538.5 34149.3 34296.7 37511.1 37794.4 38283.0 38476.6 38574.2 22210.0 22545.0 27617.9 27819.7 28284.0 26955.6 27175.7 27343.5 27738.7 28030.0 31329.4 31346.5 31641.4 32000.0 32656.2 23009.8 23023.0 24762.5 26814.3 27854.9 19899.7 22527.9 23859.5 23890.5 24410.6 11:42:07 INFO - PROCESS | 2005 | 11:42:07 INFO - PROCESS | 2005 | [#2] jquery.html Cycles:180 Average:43934.34 Median:21947.78 stddev:126925.03 (578.3%) stddev-sans-first:127238.98 11:42:07 INFO - PROCESS | 2005 | Values: 391.0 413.0 413.3 425.5 455.4 19295.8 20359.9 20989.6 21713.7 21726.8 17203.2 21742.7 22152.9 22650.5 22849.0 6344.1 6656.9 6667.0 6695.6 6712.0 6247.5 6423.9 6477.7 6487.1 6509.8 5534.0 6498.1 6502.1 6506.6 6510.1 7181.9 7510.9 7663.8 7682.7 7687.5 7238.5 7363.7 7390.2 7415.0 7466.9 20493.6 26201.5 26587.9 26610.6 26754.3 2737.7 2768.8 2819.9 2826.8 2846.3 15433.7 16223.7 16522.9 17010.8 17379.5 2011.5 2040.1 2098.4 2101.3 2119.1 167321.0 172708.0 174064.0 174320.0 176488.1 21111.8 21389.9 21483.2 21697.5 21707.8 31521.7 31777.4 31796.7 31823.8 32151.7 8879.8 10361.8 10659.0 10739.2 10880.9 8909.3 8938.2 8949.6 9064.1 9087.7 9601.2 9631.0 9691.7 9791.9 9855.7 4024.6 4073.8 4113.8 4118.9 4132.3 715.7 885.5 898.7 937.9 940.4 20212.5 21370.6 21487.4 21505.5 21590.9 903.4 909.0 922.8 938.5 957.3 10531.4 10987.8 11105.9 11126.6 11153.9 722788.0 751729.0 760625.0 777219.2 778503.0 127389.1 148417.0 154365.0 160803.2 169440.3 1152.2 1268.1 1299.5 1310.4 1327.1 18738.6 19943.2 19970.6 19971.5 20067.9 61950.0 62068.4 62642.7 64199.4 65324.3 88377.6 89179.7 89360.1 89752.1 90072.5 11214.3 11418.1 11464.7 11721.0 11768.7 12483.3 12540.0 12794.2 12960.5 13513.7 11210.4 11594.8 11818.2 11873.2 11898.9 10315.4 10462.4 10516.4 10517.7 10948.8 10262.9 11643.0 11784.8 11895.6 11984.5 11004.2 11587.0 11717.8 11968.5 11970.2 9859.0 11136.7 11307.3 11527.8 11549.1 11:42:07 INFO - PROCESS | 2005 | 11:42:07 INFO - PROCESS | 2005 | [#3] mootools.html Cycles:180 Average:6679.51 Median:2853.24 stddev:4147.33 (145.4%) stddev-sans-first:4139.36 11:42:07 INFO - PROCESS | 2005 | Values: 1312.5 1520.9 1546.9 1548.6 1558.0 8625.6 9519.3 10991.4 11171.6 11204.2 9376.6 9562.5 11201.7 11207.0 11217.5 2386.4 2431.8 2446.6 2449.0 2449.4 2343.4 2399.1 2412.4 2420.5 2425.5 2348.6 2406.5 2418.1 2419.2 2431.3 2333.2 2389.9 2394.2 2394.5 2411.6 2389.8 2395.1 2408.7 2422.5 2425.4 10958.1 11168.1 11179.2 11182.8 11201.8 5649.9 5781.0 5822.1 5825.3 5849.7 3257.1 3391.8 3535.9 3661.8 3746.6 1220.2 1229.4 1254.1 1268.4 1273.5 13086.5 13688.5 14774.5 15402.8 15568.4 4613.7 4663.4 4808.3 4819.9 4821.3 10024.8 11857.4 11943.2 11969.8 12015.6 5834.4 5841.3 5846.2 5851.8 5884.5 4337.4 5112.9 5152.7 5249.5 5257.0 3758.1 3871.3 3928.5 4003.7 4007.8 1049.6 1053.7 1053.8 1065.9 1068.9 767.9 799.3 821.1 823.1 829.0 6636.3 6651.2 6739.4 6772.9 6886.7 959.6 961.8 986.2 995.8 1013.1 3725.8 4110.4 4180.1 4233.9 4352.8 13406.9 13971.4 14005.8 14014.7 14164.5 11867.8 13074.7 13366.9 13742.7 13769.7 8668.0 10167.0 10211.1 10319.0 10363.8 6119.3 6406.0 6742.7 6951.4 7006.5 11771.0 11977.8 12270.2 12323.8 12498.6 12319.1 12458.5 12475.3 12552.8 12608.7 9306.2 9399.0 9445.4 9470.3 9485.6 10220.1 10312.7 10401.4 10408.8 10413.8 8226.3 8647.6 8669.4 8869.2 8869.8 8270.8 8645.1 8677.0 8696.3 8712.9 6865.6 7865.0 8034.6 8035.4 8057.4 5761.1 5805.2 5832.2 5987.6 6045.5 6014.7 6015.5 6117.0 6186.0 6238.6 11:42:07 INFO - PROCESS | 2005 | 11:42:07 INFO - PROCESS | 2005 | [#4] prototype.html Cycles:180 Average:4650.35 Median:5070.20 stddev:1289.22 (25.4%) stddev-sans-first:1252.29 11:42:07 INFO - PROCESS | 2005 | Values: 376.1 378.3 383.7 390.8 391.7 5212.6 5214.4 5319.4 5328.3 5407.3 4977.7 5098.6 5140.9 5160.9 5221.7 4179.2 4321.6 4375.2 4378.1 4384.6 4166.9 4387.3 4388.0 4397.5 4417.4 4208.7 4366.8 4397.3 4419.6 4439.4 3449.5 3658.8 4160.7 4394.4 4398.8 3471.4 3561.5 3957.6 4010.5 4045.7 5077.9 5100.1 5194.2 5204.2 5289.8 4570.5 4753.0 4776.9 5068.4 5072.2 4941.2 5595.7 5649.9 5672.5 5705.9 3700.9 4043.4 4494.0 4503.6 4506.7 5160.8 5574.6 5722.2 5854.8 6043.3 4009.8 4490.8 4611.0 4612.2 4667.8 4249.2 4295.3 4727.2 4769.0 4783.9 4850.7 4929.6 4976.6 5011.7 5077.0 4510.6 5551.9 5581.4 5617.9 5656.2 1441.4 1459.8 1465.2 1575.5 1597.8 1102.3 1107.7 1188.8 1202.5 1222.1 4509.7 4976.7 5101.6 5302.1 5304.4 5366.5 5442.9 5454.5 5516.9 5761.2 4783.9 4967.2 5304.2 5308.3 5336.8 2732.8 3047.7 3057.3 3071.1 3083.2 5525.6 5573.4 5963.7 6025.5 6148.8 5035.7 5072.0 5462.4 5620.9 6080.1 5132.5 5381.9 5852.4 5938.3 6035.6 5189.9 5216.0 5488.8 5740.5 5952.7 4711.6 5752.7 5771.3 5783.8 5790.6 5097.9 5433.4 5763.8 5833.4 6002.2 3987.5 4410.9 4596.4 4644.8 4658.8 5062.5 5127.5 5770.4 5866.6 5876.6 4551.4 4671.5 5226.3 5405.8 5634.7 4803.3 5100.9 5104.2 5235.5 5683.9 4343.5 4965.5 5175.3 5176.1 5430.1 4782.3 5083.7 5306.8 5512.3 5705.9 4744.8 5045.3 5514.6 5530.2 5563.0 11:42:07 INFO - PROCESS | 2005 | 11:42:07 INFO - PROCESS | 2005 | [#5] yui.html Cycles:180 Average:18921.31 Median:4330.58 stddev:26221.54 (605.5%) stddev-sans-first:26259.22 11:42:07 INFO - PROCESS | 2005 | Values: 652.7 708.0 770.3 796.9 851.8 14928.9 15706.3 16379.5 16598.7 16604.8 14988.3 15121.2 15154.8 15195.7 15267.3 881.4 916.9 925.6 944.9 946.5 902.8 946.5 948.4 950.0 950.1 979.4 992.0 993.5 994.5 994.7 983.6 986.8 989.1 990.3 991.5 733.3 734.4 735.3 736.3 737.4 14526.1 15846.0 16396.3 16484.7 16532.8 14195.3 14280.1 14310.6 14321.2 14419.6 11638.8 11756.0 11814.2 11816.8 11835.6 3655.2 3664.8 3683.8 3709.9 3720.3 69952.3 97283.0 106645.0 116592.4 118628.2 10888.9 11145.3 11152.9 11190.4 11302.3 19128.0 22151.3 22196.6 22198.1 22211.7 7337.7 7578.1 7821.5 8189.4 8241.7 4266.3 4285.9 4316.9 4344.3 4381.8 2680.5 3118.5 3149.9 3175.1 3189.1 629.4 633.8 634.3 637.6 644.5 619.0 626.5 634.5 647.6 672.8 9624.7 10073.1 10079.9 10085.8 10204.8 619.4 640.0 651.2 652.6 675.7 4238.4 4638.2 4803.6 4844.9 4847.4 75692.6 82091.0 82544.6 102813.5 107533.0 65043.0 67783.3 71307.2 84013.2 86086.1 50047.7 56873.4 56877.7 59719.7 64353.4 10350.5 10358.3 10959.5 11115.4 11289.5 44745.8 45782.0 46698.8 46861.1 48902.8 54594.7 62699.4 64718.4 69327.0 71217.6 9955.9 12667.3 12671.9 12751.0 12912.5 14728.3 14747.3 14777.8 14953.8 15215.4 13424.1 13540.3 13925.6 14232.9 14717.5 13318.1 13430.9 13776.7 13976.1 14133.7 11316.3 13075.7 13388.4 13800.4 14075.7 8083.4 9159.4 9550.5 9708.8 9771.0 9897.9 10619.7 10954.4 11553.8 11602.1 11:42:07 INFO - PROCESS | 2005 | -------- Summary: end -------- 11:42:07 INFO - PROCESS | 2005 | 11:42:08 INFO - TEST-INFO | 2005: exit 0 11:42:08 INFO - TEST-OK | dromaeo_css | took 1116276ms 11:42:08 INFO - TEST-START | kraken 11:42:08 INFO - operating with platform_type : mac_ 11:42:08 INFO - Initialising browser for kraken test... 11:42:08 INFO - TEST-INFO | started process 2013 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpsGKbJC/profile http://localhost:49297/getInfo.html) 11:42:10 INFO - PROCESS | 2013 | 2017-03-30 11:42:10.969 plugin-container[2014:22327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 11:42:10 INFO - PROCESS | 2013 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:10 INFO - PROCESS | 2013 | 2017-03-30 11:42:10.972 plugin-container[2014:22327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e17, name = 'com.apple.CFPasteboardClient' 11:42:10 INFO - PROCESS | 2013 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:10 INFO - PROCESS | 2013 | 2017-03-30 11:42:10.972 plugin-container[2014:22327] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:42:11 INFO - PROCESS | 2013 | __metrics Screen width/height:1600/1200 11:42:11 INFO - PROCESS | 2013 | colorDepth:24 11:42:11 INFO - PROCESS | 2013 | Browser inner width/height: 1024/663 11:42:11 INFO - PROCESS | 2013 | __metrics 11:42:11 ERROR - PROCESS | 2013 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 11:42:12 INFO - TEST-INFO | 2013: exit 0 11:42:12 INFO - Browser initialized. 11:42:12 INFO - Running cycle 1/1 for kraken test... 11:42:12 INFO - TEST-INFO | started process 2015 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpsGKbJC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:42:14 INFO - PROCESS | 2015 | 2017-03-30 11:42:14.477 plugin-container[2016:22448] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 11:42:14 INFO - PROCESS | 2015 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:14 INFO - PROCESS | 2015 | 2017-03-30 11:42:14.478 plugin-container[2016:22448] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 11:42:14 INFO - PROCESS | 2015 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:14 INFO - PROCESS | 2015 | 2017-03-30 11:42:14.478 plugin-container[2016:22448] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:42:24 INFO - PROCESS | 2015 | RSS: Main: 231067648 11:42:24 INFO - PROCESS | 2015 | 11:42:27 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49297/tests/kraken/driver.html?testName=audio-beat-detection) 11:42:28 INFO - PROCESS | 2015 | RSS: Main: 243003392 11:42:28 INFO - PROCESS | 2015 | 11:42:30 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49297/tests/kraken/driver.html?testName=audio-dft) 11:42:31 INFO - PROCESS | 2015 | RSS: Main: 250802176 11:42:31 INFO - PROCESS | 2015 | 11:42:34 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49297/tests/kraken/driver.html?testName=audio-fft) 11:42:35 INFO - PROCESS | 2015 | RSS: Main: 252932096 11:42:35 INFO - PROCESS | 2015 | 11:42:37 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49297/tests/kraken/driver.html?testName=audio-oscillator) 11:42:37 INFO - PROCESS | 2015 | RSS: Main: 252796928 11:42:37 INFO - PROCESS | 2015 | 11:42:39 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49297/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:42:40 INFO - PROCESS | 2015 | RSS: Main: 252809216 11:42:40 INFO - PROCESS | 2015 | 11:42:43 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49297/tests/kraken/driver.html?testName=imaging-darkroom) 11:42:44 INFO - PROCESS | 2015 | RSS: Main: 256376832 11:42:44 INFO - PROCESS | 2015 | 11:42:47 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49297/tests/kraken/driver.html?testName=imaging-desaturate) 11:42:47 INFO - PROCESS | 2015 | RSS: Main: 256028672 11:42:47 INFO - PROCESS | 2015 | 11:42:51 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49297/tests/kraken/driver.html?testName=json-parse-financial) 11:42:51 INFO - PROCESS | 2015 | RSS: Main: 255836160 11:42:51 INFO - PROCESS | 2015 | 11:42:53 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49297/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:42:54 INFO - PROCESS | 2015 | RSS: Main: 255410176 11:42:54 INFO - PROCESS | 2015 | 11:42:56 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49297/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:42:56 INFO - PROCESS | 2015 | RSS: Main: 255467520 11:42:56 INFO - PROCESS | 2015 | 11:42:59 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49297/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:42:59 INFO - PROCESS | 2015 | RSS: Main: 255782912 11:42:59 INFO - PROCESS | 2015 | 11:43:02 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49297/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:43:03 INFO - PROCESS | 2015 | RSS: Main: 256147456 11:43:03 INFO - PROCESS | 2015 | 11:43:06 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49297/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:43:07 INFO - PROCESS | 2015 | RSS: Main: 255926272 11:43:07 INFO - PROCESS | 2015 | 11:43:08 INFO - PROCESS | 2015 | Cycle 1(1): loaded http://localhost:49297/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49297/tests/kraken/driver.html?testName=ai-astar) 11:43:09 INFO - PROCESS | 2015 | RSS: Main: 257806336 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | __start_tp_report 11:43:09 INFO - PROCESS | 2015 | _x_x_mozilla_page_load 11:43:09 INFO - PROCESS | 2015 | _x_x_mozilla_page_load_details 11:43:09 INFO - PROCESS | 2015 | |i|pagename|runs| 11:43:09 INFO - PROCESS | 2015 | |0;ai-astar;111;121;119;122;118;119;118;120;119;120 11:43:09 INFO - PROCESS | 2015 | |1;audio-beat-detection;142;110;110;143;113;113;109;129;133;113 11:43:09 INFO - PROCESS | 2015 | |2;audio-dft;196;202;230;222;196;203;241;256;197;195 11:43:09 INFO - PROCESS | 2015 | |3;audio-fft;86;61;75;81;59;74;88;65;67;71 11:43:09 INFO - PROCESS | 2015 | |4;audio-oscillator;84;72;85;71;71;71;81;71;71;83 11:43:09 INFO - PROCESS | 2015 | |5;imaging-gaussian-blur;91;94;91;89;90;91;110;85;136;135 11:43:09 INFO - PROCESS | 2015 | |6;imaging-darkroom;98;114;114;97;97;98;99;97;105;97 11:43:09 INFO - PROCESS | 2015 | |7;imaging-desaturate;143;141;94;145;95;139;78;140;146;91 11:43:09 INFO - PROCESS | 2015 | |8;json-parse-financial;67;66;66;70;68;68;68;81;91;79 11:43:09 INFO - PROCESS | 2015 | |9;json-stringify-tinderbox;58;65;60;72;69;51;61;83;84;86 11:43:09 INFO - PROCESS | 2015 | |10;stanford-crypto-aes;104;78;93;101;105;99;85;86;107;101 11:43:09 INFO - PROCESS | 2015 | |11;stanford-crypto-ccm;141;133;158;126;143;153;152;167;206;163 11:43:09 INFO - PROCESS | 2015 | |12;stanford-crypto-pbkdf2;165;167;164;166;218;183;169;169;167;166 11:43:09 INFO - PROCESS | 2015 | |13;stanford-crypto-sha256-iterative;64;65;70;67;65;67;66;69;70;62 11:43:09 INFO - PROCESS | 2015 | __end_tp_report 11:43:09 INFO - PROCESS | 2015 | __start_cc_report 11:43:09 INFO - PROCESS | 2015 | _x_x_mozilla_cycle_collect,1030 11:43:09 INFO - PROCESS | 2015 | __end_cc_report 11:43:09 INFO - PROCESS | 2015 | __startTimestamp1490899389158__endTimestamp 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | ------- Summary: start ------- 11:43:09 INFO - PROCESS | 2015 | Number of tests: 14 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#0] ai-astar Cycles:10 Average:118.70 Median:119.50 stddev:2.98 (2.5%) stddev-sans-first:1.33 11:43:09 INFO - PROCESS | 2015 | Values: 111.0 121.0 119.0 122.0 118.0 119.0 118.0 120.0 119.0 120.0 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#1] audio-beat-detection Cycles:10 Average:121.50 Median:121.00 stddev:13.78 (11.4%) stddev-sans-first:12.46 11:43:09 INFO - PROCESS | 2015 | Values: 142.0 110.0 110.0 143.0 113.0 113.0 109.0 129.0 133.0 113.0 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#2] audio-dft Cycles:10 Average:213.80 Median:212.50 stddev:22.05 (10.4%) stddev-sans-first:22.43 11:43:09 INFO - PROCESS | 2015 | Values: 196.0 202.0 230.0 222.0 196.0 203.0 241.0 256.0 197.0 195.0 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#3] audio-fft Cycles:10 Average:72.70 Median:74.50 stddev:10.03 (13.5%) stddev-sans-first:9.42 11:43:09 INFO - PROCESS | 2015 | Values: 86.0 61.0 75.0 81.0 59.0 74.0 88.0 65.0 67.0 71.0 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:76.50 stddev:6.32 (8.3%) stddev-sans-first:6.01 11:43:09 INFO - PROCESS | 2015 | Values: 84.0 72.0 85.0 71.0 71.0 71.0 81.0 71.0 71.0 83.0 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#5] imaging-gaussian-blur Cycles:10 Average:101.20 Median:90.50 stddev:19.24 (21.3%) stddev-sans-first:20.05 11:43:09 INFO - PROCESS | 2015 | Values: 91.0 94.0 91.0 89.0 90.0 91.0 110.0 85.0 136.0 135.0 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:97.00 stddev:6.96 (7.2%) stddev-sans-first:7.26 11:43:09 INFO - PROCESS | 2015 | Values: 98.0 114.0 114.0 97.0 97.0 98.0 99.0 97.0 105.0 97.0 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#7] imaging-desaturate Cycles:10 Average:121.20 Median:112.00 stddev:27.74 (24.8%) stddev-sans-first:28.27 11:43:09 INFO - PROCESS | 2015 | Values: 143.0 141.0 94.0 145.0 95.0 139.0 78.0 140.0 146.0 91.0 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#8] json-parse-financial Cycles:10 Average:72.40 Median:69.00 stddev:8.42 (12.2%) stddev-sans-first:8.70 11:43:09 INFO - PROCESS | 2015 | Values: 67.0 66.0 66.0 70.0 68.0 68.0 68.0 81.0 91.0 79.0 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:70.50 stddev:12.13 (17.2%) stddev-sans-first:12.21 11:43:09 INFO - PROCESS | 2015 | Values: 58.0 65.0 60.0 72.0 69.0 51.0 61.0 83.0 84.0 86.0 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#10] stanford-crypto-aes Cycles:10 Average:95.90 Median:81.50 stddev:9.88 (12.1%) stddev-sans-first:10.04 11:43:09 INFO - PROCESS | 2015 | Values: 104.0 78.0 93.0 101.0 105.0 99.0 85.0 86.0 107.0 101.0 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#11] stanford-crypto-ccm Cycles:10 Average:154.20 Median:155.50 stddev:22.33 (14.4%) stddev-sans-first:23.17 11:43:09 INFO - PROCESS | 2015 | Values: 141.0 133.0 158.0 126.0 143.0 153.0 152.0 167.0 206.0 163.0 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:168.00 stddev:16.57 (9.9%) stddev-sans-first:17.29 11:43:09 INFO - PROCESS | 2015 | Values: 165.0 167.0 164.0 166.0 218.0 183.0 169.0 169.0 167.0 166.0 11:43:09 INFO - PROCESS | 2015 | 11:43:09 INFO - PROCESS | 2015 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:2.64 (3.9%) stddev-sans-first:2.64 11:43:09 INFO - PROCESS | 2015 | Values: 64.0 65.0 70.0 67.0 65.0 67.0 66.0 69.0 70.0 62.0 11:43:09 INFO - PROCESS | 2015 | -------- Summary: end -------- 11:43:09 INFO - PROCESS | 2015 | 11:43:10 INFO - TEST-INFO | 2015: exit 0 11:43:10 INFO - TEST-OK | kraken | took 61970ms 11:43:10 INFO - SUITE-END | took 1178s 11:43:10 INFO - Completed test suite (00:19:38) 11:43:10 INFO - javascript benchmark 11:43:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7647.213985934219, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2720.279126031602, 3386.373520898634, 3401.761876668633, 3411.539442175306, 3441.0365097772615, 5912.379200183977, 5944.108383742441, 5949.613275137119, 5968.253968253968, 5970.641761494303, 5904.763809447617, 5942.168096466498, 5955.927932972063, 5969.1046343048565, 5987.880242395149, 4415.763586195871, 5320.707361095138, 5397.136458166694, 5411.998780225651, 5432.13085906255, 4472.776361181945, 5425.9186112208345, 5431.266778984846, 5438.483344082326, 5447.319085114361, 5237.3453318335205, 5245.868853278679, 5416.91874621881, 5422.53908417783, 5422.6475279106735, 4640.976795116003, 5355.357357117151, 5367.543758780508, 5393.2719082923695, 5413.918791218135, 5239.685618862881, 5319.468053194688, 5328.0, 5414.810481633163, 5435.918461223085, 5541.722913854332, 5937.52499800015, 5978.252718410199, 6042.728077236491, 6069.119977603267, 5665.178549110397, 5699.515541179005, 6176.567640265183, 6259.280182778948, 6288.151099601541, 6318.494520438354, 6344.270408902989, 6345.809625711236, 6356.745730170787, 6364.872702545969, 6071.817845464643, 6084.178635884187, 6088.482478989291, 6114.877702445926, 6116.755329786848, 6249.625022498573, 6255.155554000152, 6281.120643109968, 6414.037894315797, 6416.518761092937, 6255.218097737783, 6296.874062518724, 6359.9364006360265, 6371.585846919936, 6399.392057754506, 6309.053641953744, 6330.905036424458, 6337.4613157882095, 6343.968280158662, 6363.840903977345, 6201.534884883562, 6233.34549872266, 6335.68321583919, 6336.841578960563, 6350.523710721622, 6223.844403889849, 6257.937420625735, 6292.276388215322, 6295.968520157461, 6317.210348706411, 5046.0, 5083.186690129562, 5086.618503612244, 5116.232565115188, 5131.897362052738, 4572.428446444194, 4575.725456472623, 4593.035462552834, 4594.862154135381, 4642.280446530725, 5745.913811292834, 5821.301443826683, 5941.257342832146, 6015.428534289235, 6017.338092809788, 6254.843628909313, 6276.46650034743, 6278.874422511524, 6329.905051424233, 6341.175647165839, 6006.789762358297, 6021.0, 6024.668168670671, 6041.154238406627, 6045.093236014633, 5427.0773968425465, 5593.049181639131, 5595.468430499096, 5609.943900561024, 5617.8595535111135, 6322.936770632326, 6373.235211774617, 6384.968075159781, 6421.550491465552, 6446.419822216023, 6343.111964324906, 6362.109304697438, 6363.268224154282, 6373.235211774617, 6374.426301632875, 5010.525687155688, 6462.321456247214, 6512.5766825156215, 6513.381228783258, 6560.114384558215, 5431.88646327494, 5725.971370143123, 6259.937400625936, 6299.338569450142, 6318.210223722035, 6215.0, 6350.841228969128, 6360.077788720702, 6363.268224154096, 6383.170187875547, 5191.442567229891, 6325.936740632535, 6342.778002769881, 6343.9682801585695, 6382.106505089198, 6097.481714054177, 6105.358937311696, 6111.083337499233, 6113.694315284307, 6269.96865015672, 6003.129546215861, 6033.728482218223, 6071.2714474263375, 6159.384061593805, 6167.074938759222, 6112.786052488142, 6245.0, 6297.275813281532, 6319.494440444845, 6331.018692102732, 5974.731137098754, 6052.03167493199, 6122.855605810045, 6145.1564530639735, 6147.569670123048], "value": 5804.103397800817, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.1655295046103, 832.1470492744936, 861.8836457078296, 943.1652987106409, 944.1596978688964, 36157.638423615754, 37875.0, 37910.43134352987, 37957.861264162086, 38034.42948355773, 36356.72751453699, 36636.63363366365, 36642.8167859161, 36643.0, 36742.81628591853, 7301.780946571611, 7465.104187497508, 7660.080790305157, 7808.960955195216, 10907.781844363108, 9198.954005229965, 10865.782684346308, 10927.125829933586, 10968.23222374434, 11015.339079655247, 11006.009459148676, 11024.118070554336, 11025.062869656089, 11037.0, 11046.668599942015, 10952.616658416917, 10979.0, 11032.172587056002, 11034.282771619819, 11052.778944421147, 10761.354318740901, 10810.027097561257, 10811.18916081297, 10815.134789216843, 10846.783064338748, 32139.678603214135, 37508.62491375106, 37817.81091094555, 37981.0, 38027.04932376685, 3822.4266360045935, 3834.578196398394, 3842.538895332578, 3876.9806150969066, 3923.3330333843314, 26797.866010670015, 26955.595666065026, 27249.45501089987, 27517.449651007068, 28349.57475637867, 3068.585740924991, 3078.245829771698, 3121.172889184368, 3170.3183815479783, 3201.471757160066, 344187.0, 345788.0, 346520.0, 347207.0, 347846.0, 40997.59002409938, 41727.0, 49652.0, 50831.983360333325, 50876.74561627242, 46545.0, 51180.48819511832, 53191.20213196805, 53221.733891330296, 53391.73304133455, 14437.422503099759, 14484.493042743454, 14518.491852785102, 14520.056196347203, 14567.854321456862, 12516.0, 12519.43662535188, 12519.93740031294, 12524.248545087325, 12749.617511474671, 11901.226420282654, 11992.940035299767, 12003.339816310187, 12067.155299128977, 12097.2136811107, 5401.081816091274, 5404.91892621611, 5426.158945363475, 5428.67427954324, 5434.510894019557, 968.5544649461186, 986.8519722041751, 1014.91373233276, 1034.2811745836723, 1037.2065369991963, 24320.635190472156, 25987.61018584723, 26729.33176670599, 26795.86602066977, 27394.726052739617, 980.5048450532436, 1033.788073444942, 1123.612353737959, 1141.5833220874488, 1142.9257098288585, 13980.790288145687, 15129.773053404208, 15491.450854914418, 15699.68600627981, 15898.60253493672, 502937.0, 506410.0, 507010.0, 508277.0, 508697.0, 54491.0, 54562.72718636461, 54714.72642636761, 54855.451445485036, 54861.1770823438, 1409.5348534983432, 1458.6717988452513, 1461.4081297074702, 1461.5615315405337, 1463.3780643226798, 28227.294317642223, 28333.716662833933, 28983.5652465209, 28988.041712414622, 29320.26699332534, 43386.48147314829, 43424.78287608542, 43523.78238108789, 43549.346759798, 44911.10177796491, 423755.0, 468996.0, 471005.0, 471276.6436167797, 472893.0, 31455.52816707797, 32385.838070810438, 33538.496922545695, 34149.29253537363, 34296.65703343035, 37511.062223444635, 37794.43308350432, 38283.0, 38476.615233848424, 38574.2285154301, 22210.0, 22544.985475653964, 27617.920776328094, 27819.721802781714, 28284.010059647815, 26955.595666065416, 27175.72824271732, 27343.453130936872, 27738.72261277442, 28030.018949336674, 31329.373412531168, 31346.529802053443, 31641.36717265596, 32000.0, 32656.203908785246, 23009.769902301432, 23023.0, 24762.50474990526, 26814.329641758333, 27854.860725696242, 19899.701504477733, 22527.88736056309, 23859.522809544058, 23890.52218955646, 24410.633840492763], "value": 18295.08703398828, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.9804509774511, 412.9752214867106, 413.29739442947, 425.4809132857914, 455.44435788338217, 19295.807041929576, 20359.898200508997, 20989.580208395862, 21713.67429488559, 21726.782732172695, 17203.22585483656, 21742.673859892075, 22152.88923555384, 22650.54698906025, 22849.0, 6344.143540622026, 6656.866862662744, 6667.033280174372, 6695.56478828877, 6711.9664401678165, 6247.531435142359, 6423.940049891762, 6477.676116194195, 6487.124238227826, 6509.772157974472, 5534.0, 6498.090267362573, 6502.122213501164, 6506.642134682591, 6510.121133646944, 7181.8922716159295, 7510.887336689927, 7663.846723065563, 7682.654280557388, 7687.5387476751575, 7238.52949558277, 7363.742269020612, 7390.187079421259, 7415.036045314128, 7466.85066298671, 20493.590128197502, 26201.475970480675, 26587.867060664765, 26610.6008409874, 26754.331141721417, 2737.7399147080996, 2768.750812426891, 2819.9295017624518, 2826.78799090067, 2846.274200078987, 15433.691326173526, 16223.675526489522, 16522.926009809325, 17010.829891701047, 17379.478615641423, 2011.4669612552684, 2040.1382573033886, 2098.4334229758033, 2101.3289862131373, 2119.0676102515, 167321.0, 172708.0, 174064.0, 174320.0, 176488.11755941395, 21111.78888211129, 21389.893050534647, 21483.248086316904, 21697.45756356072, 21707.782922170576, 31521.684783152334, 31777.364452710815, 31796.682033179834, 31823.84088079591, 32151.678483215335, 8879.801226834425, 10361.792764144675, 10659.040686338267, 10739.194560407845, 10880.945595271973, 8909.287257019423, 8938.240249578728, 8949.552522373855, 9064.138906803975, 9087.681931132378, 9601.183899368632, 9630.951845240868, 9691.722445225367, 9791.853122203172, 9855.655052073287, 4024.597540245952, 4073.837046518165, 4113.814878330482, 4118.938215926763, 4132.2561938850695, 715.6529083394549, 885.5350940756026, 898.6854600889636, 937.9108984646448, 940.351157701184, 20212.494687632927, 21370.57258854814, 21487.355379338645, 21505.462363441038, 21590.92045397724, 903.3631289940577, 908.9954550227206, 922.7739203894954, 938.5166639180827, 957.3155194036298, 10531.368117912949, 10987.780244395068, 11105.944470277596, 11126.554937802397, 11153.888461115284, 722788.0, 751729.0, 760625.0, 777219.2278077147, 778503.0, 127389.08916366262, 148417.0, 154365.0, 160803.19598401934, 169440.30559694246, 1152.2049785647876, 1268.0933132810085, 1299.4802079168408, 1310.4299629661127, 1327.1108357400565, 18738.6252274951, 19943.20227190954, 19970.60058798787, 19971.500712482306, 20067.899660501604, 61950.0, 62068.37931620626, 62642.74714505776, 64199.35800641934, 65324.34675653183, 88377.55811220902, 89179.66230506418, 89360.10639893518, 89752.10247897437, 90072.54963725139, 11214.271072380268, 11418.143639227257, 11464.656060318204, 11721.0, 11768.705442401159, 12483.251004939733, 12540.0, 12794.232346058894, 12960.481580737041, 13513.729725405241, 11210.43947802623, 11594.826077609012, 11818.172727908965, 11873.228240164362, 11898.940505297418, 10315.381077135396, 10462.372257664565, 10516.421596811942, 10517.684469465928, 10948.835767463335, 10262.897371026194, 11643.0, 11784.82322765142, 11895.583654572049, 11984.520619175135, 11004.174686898354, 11587.0, 11717.824232636347, 11968.46141923598, 11970.22193557416, 9858.950705246429, 11136.721581960515, 11307.321560706385, 11527.76944461123, 11549.076073913899], "value": 11654.904914319091, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1312.4881296294452, 1520.9239538023103, 1546.907185568866, 1548.589623749706, 1558.0418042903614, 8625.611847466864, 9519.333646644729, 10991.395473248987, 11171.553137874485, 11204.21570490064, 9376.57805398757, 9562.474063926482, 11201.71995700108, 11207.0, 11217.495212715427, 2386.4033991502124, 2431.8297719159664, 2446.6085426331792, 2448.963265551018, 2449.35092200567, 2343.3907184132077, 2399.1123284384803, 2412.4089598048504, 2420.491696743686, 2425.527022230666, 2348.612478940973, 2406.494636126407, 2418.093215044358, 2419.177479656916, 2431.3313838694326, 2333.218371845434, 2389.8924548395275, 2394.1859767678898, 2394.4851856850773, 2411.614141737331, 2389.8207634427486, 2395.113807891074, 2408.735413907695, 2422.47916697909, 2425.38152771044, 10958.123350131971, 11168.050715689176, 11179.217454778169, 11182.832257516144, 11201.831972520418, 5649.887002259932, 5781.046127388976, 5822.097574875901, 5825.271841019872, 5849.736761845727, 3257.120577444077, 3391.8134502602347, 3535.8762443314617, 3661.7986010769396, 3746.6440688134585, 1220.2190598017276, 1229.440604524939, 1254.084518301645, 1268.3911722373311, 1273.4778740716263, 13086.541971031063, 13688.52090176839, 14774.48289309869, 15402.845971540366, 15568.37726490939, 4613.66203800894, 4663.370444989951, 4808.2547205183255, 4819.927701084486, 4821.349117869113, 10024.84714257855, 11857.407129643483, 11943.223690460092, 11969.760604787856, 12015.639530814089, 5834.416558344132, 5841.299044114733, 5846.210761547221, 5851.795187168514, 5884.529237660977, 4337.37108119321, 5112.923306150411, 5152.665076770073, 5249.501297376743, 5256.9737151314, 3758.0792705787258, 3871.283812494641, 3928.5482169550296, 4003.679705623543, 4007.8196481158416, 1049.569676432659, 1053.657561292583, 1053.7787064716342, 1065.8507808906759, 1068.8610480637467, 767.9155292917775, 799.3205775091126, 821.1337039423346, 823.0987069159381, 829.0, 6636.270010298863, 6651.16860392451, 6739.427148692416, 6772.8984065239065, 6886.655667216619, 959.6113574002541, 961.7980224153006, 986.2061040862175, 995.8207522645848, 1013.0730381700808, 3725.776453412804, 4110.403991421227, 4180.059486615487, 4233.872983810491, 4352.782360882006, 13406.93296533511, 13971.371288292052, 14005.789913151311, 14014.71970560583, 14164.504242351675, 11867.821982670266, 13074.738505229841, 13366.93316533411, 13742.65643358904, 13769.724605507834, 8667.999999999874, 10167.0, 10211.132053775364, 10319.0, 10363.844542331872, 6119.296280927572, 6406.0, 6742.718883412136, 6951.44388448933, 7006.544574602634, 11771.0, 11977.820332694842, 12270.202436841577, 12323.75352492963, 12498.625041248413, 12319.076069295019, 12458.501659933501, 12475.251484910934, 12552.811707824207, 12608.747825043265, 9306.208972237166, 9399.013103624295, 9445.386049906734, 9470.289728270269, 9485.620575176916, 10220.080192782685, 10312.742181445525, 10401.375917444977, 10408.843867341844, 10413.843792343272, 8226.341892648454, 8647.56762161902, 8669.436486628349, 8869.157430044135, 8869.778255543662, 8270.83458330842, 8645.092265312303, 8677.0, 8696.304295656459, 8712.91287087121, 6865.622390768555, 7865.016872890888, 8034.63844127004, 8035.397345199251, 8057.355411567178, 5761.107028410603, 5805.216295800013, 5832.241808565029, 5987.6108052976415, 6045.546584006129, 6014.699265036818, 6015.518758499222, 6116.969415152896, 6185.969070154621, 6238.594491358047], "value": 5099.84016841476, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.10862256452197, 378.33790865984525, 383.6623771081448, 390.81826950468036, 391.692521370724, 5212.635115541913, 5214.400343960446, 5319.388270348906, 5328.255948186682, 5407.297051383314, 4977.7262250576205, 5098.643094983353, 5140.871478213046, 5160.92258616121, 5221.660592061513, 4179.20595086934, 4321.611055005057, 4375.221734198775, 4378.1462614790125, 4384.618845063811, 4166.854160104397, 4387.254166791653, 4387.978060109695, 4397.526828512437, 4417.447819022622, 4208.684348673846, 4366.8034938427845, 4397.296432570789, 4419.624331931774, 4439.400680908085, 3449.482577613353, 3658.7804731715914, 4160.687948403882, 4394.384786129945, 4398.802053907566, 3471.427214509603, 3561.465780132975, 3957.6042395760483, 4010.455974449829, 4045.7167998240134, 5077.949220507785, 5100.0929470202, 5194.168932970744, 5204.219367094928, 5289.841304760864, 4570.451545814491, 4753.0, 4776.8566942991765, 5068.3917929848285, 5072.188449848007, 4941.20940649498, 5595.71298601324, 5649.8587535311535, 5672.483060502474, 5705.914411283834, 3700.85196592136, 4043.433919251307, 4493.955060449419, 4503.639708823319, 4506.662000349954, 5160.8193713220235, 5574.60977731559, 5722.227499287626, 5854.795082172103, 6043.305019922721, 4009.7794621295525, 4490.842820501267, 4610.976945115253, 4612.169809434267, 4667.789949452283, 4249.151619045663, 4295.334223195409, 4727.196376615915, 4768.976155119202, 4783.851875549843, 4850.733209673502, 4929.630277729185, 4976.576990955737, 5011.699298042056, 5077.009983053269, 4510.639148868083, 5551.944480555224, 5581.413951535112, 5617.915731264035, 5656.236408084856, 1441.3802065112102, 1459.839417664056, 1465.1648560320727, 1575.4954018911912, 1597.8162511311116, 1102.32207192576, 1107.7119948813368, 1188.8216767484946, 1202.5370232460566, 1222.10175520992, 4509.706869053501, 4976.701397916064, 5101.642884998088, 5302.091247151023, 5304.416514183436, 5366.490183432567, 5442.857000030038, 5454.481824226771, 5516.91724624131, 5761.168822732836, 4783.938279501122, 4967.155583550732, 5304.170061328836, 5308.283381743492, 5336.839894803162, 2732.8223665461683, 3047.725704686589, 3057.3121047764075, 3071.078676397072, 3083.152133163353, 5525.613207075467, 5573.442655734394, 5963.7337811631305, 6025.457708806229, 6148.7557041679465, 5035.651291890925, 5072.0, 5462.399136095027, 5620.887582248332, 6080.115164298993, 5132.538071573576, 5381.946180538144, 5852.356240813507, 5938.287405511366, 6035.577509574287, 5189.896202075862, 5215.973920130375, 5488.807891723771, 5740.540756739534, 5952.672603006876, 4711.623070154448, 5752.741126649227, 5771.336296325976, 5783.797567085132, 5790.565707572033, 5097.898042039213, 5433.402325744244, 5763.7982670606325, 5833.366657335952, 6002.219711437485, 3987.5414327351564, 4410.933835992527, 4596.356510088657, 4644.814207431665, 4658.767061646972, 5062.544371006627, 5127.46161653021, 5770.413136387527, 5866.6480011199465, 5876.647401155944, 4551.362809206647, 4671.486136524912, 5226.346706661668, 5405.756740946588, 5634.718264086698, 4803.3428467178765, 5100.923486147785, 5104.2343648453025, 5235.4502777207845, 5683.857903552279, 4343.457067866517, 4965.453800081916, 5175.275461435327, 5176.101383225406, 5430.104032834538, 4782.324369387223, 5083.729067733067, 5306.752913065504, 5512.2558454608115, 5705.942940570707, 4744.833930812405, 5045.2936588876855, 5514.61397702157, 5530.1978566362795, 5562.972185139049], "value": 4273.8655404130495, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.7030201258428, 707.9575225486471, 770.3490550484842, 796.9003874515685, 851.8424091543067, 14928.925355373221, 15706.293216805243, 16379.508614741548, 16598.66802663946, 16604.750928736077, 14988.25058747061, 15121.243937803121, 15154.848451515481, 15195.696086078271, 15267.312970916308, 881.3962435731545, 916.9083091690844, 925.5973651461602, 944.9196818270456, 946.4794363100302, 902.8284625921054, 946.5125460387906, 948.4025064209538, 949.9667511637059, 950.092661508258, 979.3634137810409, 992.0377234082964, 993.5330394714508, 994.4928086675811, 994.7314225159203, 983.5672304186171, 986.8174387738255, 989.1196834817018, 990.2672022703184, 991.5092029445399, 733.2630706140349, 734.372111844376, 735.3455424672046, 736.2637362637363, 737.3695490355723, 14526.128432294096, 15846.0, 16396.344146234136, 16484.6703065938, 16532.83467165325, 14195.290235488184, 14280.07179533327, 14310.64223394413, 14321.212333321662, 14419.639509012359, 11638.76722465546, 11756.0, 11814.17300788945, 11816.7786905651, 11835.644930652094, 3655.214128962286, 3664.8146950712994, 3683.7605555639066, 3709.9072523186865, 3720.2931443025736, 69952.3004769951, 97283.0, 106645.0, 116592.41703791427, 118628.22057669141, 10888.945555272174, 11145.306989759481, 11152.944235278934, 11190.38452885083, 11302.32186068822, 19128.04359782033, 22151.33545993623, 22196.556068878534, 22198.112075516798, 22211.66682499764, 7337.669804858794, 7578.09062912454, 7821.452498325063, 8189.385796065199, 8241.711540096068, 4266.2960611499375, 4285.935710964338, 4316.870493885189, 4344.283193273074, 4381.802818873159, 2680.5309070912513, 3118.547810567455, 3149.937001259962, 3175.142711467891, 3189.1389324882152, 629.3832044596321, 633.7591715148214, 634.308603622045, 637.6014990630856, 644.4650939720021, 618.9783357582463, 626.5238418801744, 634.4511997122456, 647.608197040793, 672.7611697847292, 9624.66313679018, 10073.093421592092, 10079.941606131251, 10085.798284034425, 10204.846927296096, 619.3744318238612, 639.9712012959428, 651.205529254309, 652.5693042591867, 675.7330854312519, 4238.352504934107, 4638.2115040443205, 4803.639727020487, 4844.858178058978, 4847.442544107403, 75692.62153689197, 82091.0, 82544.58727706443, 102813.48593256986, 107533.0, 65043.0, 67783.32216677869, 71307.21731956639, 84013.15986840053, 86086.13913860782, 50047.74976125169, 56873.43126568764, 56877.7248910039, 59719.701401493585, 64353.356466434736, 10350.482475876326, 10358.274920755475, 10959.452027398438, 11115.388653624128, 11289.548418063185, 44745.77627111843, 45782.0, 46698.76650616725, 46861.12555497742, 48902.77743056452, 54594.72702636461, 62699.37300626935, 64718.35281647123, 69327.0, 71217.6439117801, 9955.900440995787, 12667.30329831868, 12671.859532641738, 12751.0, 12912.483500659868, 14728.337224825123, 14747.305733083393, 14777.808036045679, 14953.775693364825, 15215.391384344945, 13424.127431716906, 13540.255285959367, 13925.582232533041, 14232.857671423568, 14717.484888028868, 13318.062827225376, 13430.865691343352, 13776.724465510833, 13976.091554048955, 14133.717325653224, 11316.321020738782, 13075.738485230433, 13388.397522111723, 13800.447982080605, 14075.718485630436, 8083.393745469231, 9159.388049993002, 9550.522473876139, 9708.75728106803, 9771.0, 9897.901020989699, 10619.734506637396, 10954.378318132854, 11553.82669259945, 11602.129840261845], "value": 7413.499403380879, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.0000000000005, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 121.0, 119.0, 122.0, 118.0, 119.0, 118.0, 120.0, 119.0, 120.0], "value": 118.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 110.0, 110.0, 143.0, 113.0, 113.0, 109.0, 129.0, 133.0, 113.0], "value": 121.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 202.0, 230.0, 222.0, 196.0, 203.0, 241.0, 256.0, 197.0, 195.0], "value": 213.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 61.0, 75.0, 81.0, 59.0, 74.0, 88.0, 65.0, 67.0, 71.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 85.0, 71.0, 71.0, 71.0, 81.0, 71.0, 71.0, 83.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 91.0, 89.0, 90.0, 91.0, 110.0, 85.0, 136.0, 135.0], "value": 101.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 114.0, 114.0, 97.0, 97.0, 98.0, 99.0, 97.0, 105.0, 97.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 141.0, 94.0, 145.0, 95.0, 139.0, 78.0, 140.0, 146.0, 91.0], "value": 121.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 70.0, 68.0, 68.0, 68.0, 81.0, 91.0, 79.0], "value": 72.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 65.0, 60.0, 72.0, 69.0, 51.0, 61.0, 83.0, 84.0, 86.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 78.0, 93.0, 101.0, 105.0, 99.0, 85.0, 86.0, 107.0, 101.0], "value": 95.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 133.0, 158.0, 126.0, 143.0, 153.0, 152.0, 167.0, 206.0, 163.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 164.0, 166.0, 218.0, 183.0, 169.0, 169.0, 167.0, 166.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 70.0, 67.0, 65.0, 67.0, 66.0, 69.0, 70.0, 62.0], "value": 66.5, "unit": "score"}]}]} 11:43:10 INFO - Return code: 0 11:43:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:43:10 ERROR - # TBPL SUCCESS # 11:43:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:43:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:43:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:43:10 INFO - 2017-03-30 11:43:10.487 screenresolution[2020:22837] starting screenresolution argv=screenresolution get 11:43:10 INFO - 2017-03-30 11:43:10.489 screenresolution[2020:22837] Display 0: 1600x1200x32@60 11:43:10 INFO - 2017-03-30 11:43:10.495 screenresolution[2021:22840] starting screenresolution argv=screenresolution list 11:43:10 INFO - Available Modes on Display 0 11:43:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:43:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:43:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:43:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:43:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:43:10 INFO - Intel Iris: 11:43:10 INFO - Chipset Model: Intel Iris 11:43:10 INFO - Type: GPU 11:43:10 INFO - Bus: Built-In 11:43:10 INFO - VRAM (Dynamic, Max): 1536 MB 11:43:10 INFO - Vendor: Intel (0x8086) 11:43:10 INFO - Device ID: 0x0a2e 11:43:10 INFO - Revision ID: 0x0009 11:43:10 INFO - Displays: 11:43:10 INFO - Display: 11:43:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:43:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:43:10 INFO - Display Serial Number: 1600x1200 60 11:43:10 INFO - Main Display: Yes 11:43:10 INFO - Mirror: Off 11:43:10 INFO - Online: Yes 11:43:10 INFO - Rotation: Supported 11:43:10 INFO - Return code: 0 11:43:10 INFO - Running post-action listener: _resource_record_post_action 11:43:10 INFO - [mozharness: 2017-03-30 18:43:10.729859Z] Finished run-tests step (success) 11:43:10 INFO - Running post-run listener: _resource_record_post_run 11:43:10 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 31187456; Write bytes: 514957312; Read time: 185; Write time: 1642 11:43:10 INFO - TinderboxPrint: CPU usage
54.8% 11:43:10 INFO - TinderboxPrint: I/O read bytes / time
31,187,456 / 185 11:43:10 INFO - TinderboxPrint: I/O write bytes / time
514,957,312 / 1,642 11:43:10 INFO - TinderboxPrint: CPU idle
2,167.7 (45.2%) 11:43:10 INFO - TinderboxPrint: CPU system
970.7 (20.2%) 11:43:10 INFO - TinderboxPrint: CPU user
1,659.3 (34.6%) 11:43:10 INFO - TinderboxPrint: Swap in / out
233,615,360 / 0 11:43:10 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205629952; Write bytes: 201963008; Read time: 15772; Write time: 447 11:43:10 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 30941696; Write bytes: 301943296; Read time: 181; Write time: 1160 11:43:11 INFO - Running post-run listener: _upload_blobber_files 11:43:11 INFO - Blob upload gear active. 11:43:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:43:11 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 11:43:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:43:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:43:11 INFO - (blobuploader) - INFO - Open directory for files ... 11:43:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:43:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:43:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:12 INFO - (blobuploader) - INFO - Done attempting. 11:43:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:43:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:43:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:14 INFO - (blobuploader) - INFO - Done attempting. 11:43:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:43:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:43:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:15 INFO - (blobuploader) - INFO - Done attempting. 11:43:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:43:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:43:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:17 INFO - (blobuploader) - INFO - Done attempting. 11:43:17 INFO - (blobuploader) - INFO - Iteration through files over. 11:43:17 INFO - Return code: 0 11:43:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:43:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:43:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df9652fa17ec0d6711618bc27138eaa3858e0cca7a38c0b0301b050eb4e3ac597fd6e897fbe4506a04f3064abd54a5ba75d4ee8e73e127f7e4d8b33849d3c510", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a5072921d2043e2a1152e6eff78e9ec37375d9b999e7ded8c4e1fad02c37e60a58443ccdb09ee2753f1d7ac653efbf45a2ed76e5657fbb2d75d11526a72c54d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1776e0b77b35eef5509d5ddb398b78d3271029bd17546d5a1866b233f9f2429c1f30baade9d18c8b3825d00f8007752e5d27f10a6d8ce1dd9fa66f735c83755f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b5421009d2e3a01c41cbc4be5df3e2ad960abf44a7472ecfd87b0782063cffd1c064592a202812aac3f5e01d9ee61eadec7bbce6cec832c95396825d5be3ba7"} 11:43:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:43:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:43:17 INFO - Contents: 11:43:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df9652fa17ec0d6711618bc27138eaa3858e0cca7a38c0b0301b050eb4e3ac597fd6e897fbe4506a04f3064abd54a5ba75d4ee8e73e127f7e4d8b33849d3c510", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a5072921d2043e2a1152e6eff78e9ec37375d9b999e7ded8c4e1fad02c37e60a58443ccdb09ee2753f1d7ac653efbf45a2ed76e5657fbb2d75d11526a72c54d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1776e0b77b35eef5509d5ddb398b78d3271029bd17546d5a1866b233f9f2429c1f30baade9d18c8b3825d00f8007752e5d27f10a6d8ce1dd9fa66f735c83755f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b5421009d2e3a01c41cbc4be5df3e2ad960abf44a7472ecfd87b0782063cffd1c064592a202812aac3f5e01d9ee61eadec7bbce6cec832c95396825d5be3ba7"} 11:43:17 INFO - Running post-run listener: copy_logs_to_upload_dir 11:43:17 INFO - Copying logs to upload dir... 11:43:17 INFO - mkdir: /builds/slave/test/build/upload/logs 11:43:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.038361 ========= master_lag: 0.16 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-03-30 11:43:17.271202) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 11:43:17.275046) ========= 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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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/Mozilla-Aurora/sha512/df9652fa17ec0d6711618bc27138eaa3858e0cca7a38c0b0301b050eb4e3ac597fd6e897fbe4506a04f3064abd54a5ba75d4ee8e73e127f7e4d8b33849d3c510", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a5072921d2043e2a1152e6eff78e9ec37375d9b999e7ded8c4e1fad02c37e60a58443ccdb09ee2753f1d7ac653efbf45a2ed76e5657fbb2d75d11526a72c54d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1776e0b77b35eef5509d5ddb398b78d3271029bd17546d5a1866b233f9f2429c1f30baade9d18c8b3825d00f8007752e5d27f10a6d8ce1dd9fa66f735c83755f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b5421009d2e3a01c41cbc4be5df3e2ad960abf44a7472ecfd87b0782063cffd1c064592a202812aac3f5e01d9ee61eadec7bbce6cec832c95396825d5be3ba7"} build_url:https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011215 build_url: 'https://queue.taskcluster.net/v1/task/Y2MhDcEHQEewYntagHX5nQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df9652fa17ec0d6711618bc27138eaa3858e0cca7a38c0b0301b050eb4e3ac597fd6e897fbe4506a04f3064abd54a5ba75d4ee8e73e127f7e4d8b33849d3c510", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a5072921d2043e2a1152e6eff78e9ec37375d9b999e7ded8c4e1fad02c37e60a58443ccdb09ee2753f1d7ac653efbf45a2ed76e5657fbb2d75d11526a72c54d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1776e0b77b35eef5509d5ddb398b78d3271029bd17546d5a1866b233f9f2429c1f30baade9d18c8b3825d00f8007752e5d27f10a6d8ce1dd9fa66f735c83755f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b5421009d2e3a01c41cbc4be5df3e2ad960abf44a7472ecfd87b0782063cffd1c064592a202812aac3f5e01d9ee61eadec7bbce6cec832c95396825d5be3ba7"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 11:43:17.312120) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 11:43:17.312461) ========= 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.8pbyYwWBpY/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.yYj4dZNpsg/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.005277 ========= master_lag: 0.79 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 11:43:18.107829) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 11:43:18.108155) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 11:43:18.110362) ========= ========= Total master_lag: 1.07 =========