builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0372 starttime: 1491490857.58 results: success (0) buildid: 20170406071933 builduid: 2cf52192536141cca0762961ca740b8e revision: e03c56f3a839d1575e87dbd782638ad4894429e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 08:00:57.576861) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 08:00:57.577643) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 08:00:57.578181) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 08:00:57.605986) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:00:57.606541) ========= 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.sBViLxpXA9/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.SyLQsAb5pG/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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-04-06 08:00:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 155M=0s 2017-04-06 08:00:57 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144586 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:00:57.775460) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:00:57.775843) ========= 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.sBViLxpXA9/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.SyLQsAb5pG/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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.021980 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:00:57.824659) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 08:00:57.825078) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e03c56f3a839d1575e87dbd782638ad4894429e8 --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 e03c56f3a839d1575e87dbd782638ad4894429e8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sBViLxpXA9/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.SyLQsAb5pG/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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-04-06 08:00:57,956 truncating revision to first 12 chars 2017-04-06 08:00:57,956 Setting DEBUG logging. 2017-04-06 08:00:57,956 attempt 1/10 2017-04-06 08:00:57,956 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e03c56f3a839?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 08:00:58,710 unpacking tar archive at: mozilla-aurora-e03c56f3a839/testing/mozharness/ program finished with exit code 0 elapsedTime=1.016925 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 08:00:58.866214) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 08:00:58.866598) ========= script_repo_revision: e03c56f3a839d1575e87dbd782638ad4894429e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 08:00:58.867035) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 08:00:58.867357) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 08:00:58.884503) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-06 08:00:58.884816) ========= /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.sBViLxpXA9/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.SyLQsAb5pG/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:00:59 INFO - MultiFileLogger online at 20170406 08:00:59 in /builds/slave/test 08:00:59 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 08:00:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:00:59 INFO - {'append_to_log': False, 08:00:59 INFO - 'base_work_dir': '/builds/slave/test', 08:00:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:00:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:00:59 INFO - 'branch': 'Mozilla-Aurora', 08:00:59 INFO - 'buildbot_json_path': 'buildprops.json', 08:00:59 INFO - 'config_files': ('talos/mac_config.py',), 08:00:59 INFO - 'default_actions': ('clobber', 08:00:59 INFO - 'read-buildbot-config', 08:00:59 INFO - 'download-and-extract', 08:00:59 INFO - 'populate-webroot', 08:00:59 INFO - 'create-virtualenv', 08:00:59 INFO - 'install', 08:00:59 INFO - 'run-tests'), 08:00:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:00:59 INFO - 'download_minidump_stackwalk': True, 08:00:59 INFO - 'download_symbols': 'ondemand', 08:00:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:00:59 INFO - 'tooltool.py': '/tools/tooltool.py', 08:00:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:00:59 INFO - '/tools/misc-python/virtualenv.py')}, 08:00:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:00:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:00:59 INFO - 'gecko_profile': False, 08:00:59 INFO - 'gecko_profile_interval': 0, 08:00:59 INFO - 'installer_path': 'installer.exe', 08:00:59 INFO - 'log_level': 'info', 08:00:59 INFO - 'log_name': 'talos', 08:00:59 INFO - 'log_to_console': True, 08:00:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:00:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:00:59 INFO - 'opt_config_files': (), 08:00:59 INFO - 'pip_index': False, 08:00:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:00:59 INFO - 'cmd': ('bash', 08:00:59 INFO - '-c', 08:00:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:00:59 INFO - 'enabled': True, 08:00:59 INFO - 'halt_on_failure': False, 08:00:59 INFO - 'name': 'check_screen_resolution'},), 08:00:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:00:59 INFO - 'cmd': ('bash', 08:00:59 INFO - '-c', 08:00:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:00:59 INFO - 'enabled': True, 08:00:59 INFO - 'halt_on_failure': False, 08:00:59 INFO - 'name': 'check_screen_resolution'},), 08:00:59 INFO - 'run_cmd_checks_enabled': True, 08:00:59 INFO - 'suite': 'dromaeojs-e10s', 08:00:59 INFO - 'system_bits': '32', 08:00:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:00:59 INFO - 'title': 't-yosemite-r7-0372', 08:00:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:00:59 INFO - 'use_talos_json': True, 08:00:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:00:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:00:59 INFO - 'work_dir': 'build'} 08:00:59 INFO - [mozharness: 2017-04-06 15:00:59.132323Z] Running clobber step. 08:00:59 INFO - Running pre-action listener: _resource_record_pre_action 08:00:59 INFO - Running main action method: clobber 08:00:59 INFO - rmtree: /builds/slave/test/build 08:00:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:01:00 INFO - Running post-action listener: _resource_record_post_action 08:01:00 INFO - [mozharness: 2017-04-06 15:01:00.962602Z] Finished clobber step (success) 08:01:00 INFO - [mozharness: 2017-04-06 15:01:00.962749Z] Running read-buildbot-config step. 08:01:00 INFO - Running pre-action listener: _resource_record_pre_action 08:01:00 INFO - Running main action method: read_buildbot_config 08:01:00 INFO - Using buildbot properties: 08:01:00 INFO - { 08:01:00 INFO - "project": "", 08:01:00 INFO - "product": "firefox", 08:01:00 INFO - "script_repo_revision": "production", 08:01:00 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:01:00 INFO - "repository": "", 08:01:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:01:00 INFO - "buildid": "20170406071933", 08:01:00 INFO - "pgo_build": "False", 08:01:00 INFO - "basedir": "/builds/slave/test", 08:01:00 INFO - "buildnumber": 11, 08:01:00 INFO - "slavename": "t-yosemite-r7-0372", 08:01:00 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:01:00 INFO - "platform": "yosemite_r7", 08:01:00 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:01:00 INFO - "branch": "mozilla-aurora", 08:01:00 INFO - "repo_path": "releases/mozilla-aurora", 08:01:00 INFO - "revision": "e03c56f3a839d1575e87dbd782638ad4894429e8", 08:01:00 INFO - "stage_platform": "macosx64", 08:01:00 INFO - "builduid": "2cf52192536141cca0762961ca740b8e", 08:01:00 INFO - "slavebuilddir": "test" 08:01:00 INFO - } 08:01:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 08:01:00 INFO - Running post-action listener: _resource_record_post_action 08:01:00 INFO - [mozharness: 2017-04-06 15:01:00.966131Z] Finished read-buildbot-config step (success) 08:01:00 INFO - [mozharness: 2017-04-06 15:01:00.966270Z] Running download-and-extract step. 08:01:00 INFO - Running pre-action listener: _resource_record_pre_action 08:01:00 INFO - Running main action method: download_and_extract 08:01:00 INFO - mkdir: /builds/slave/test/build/tests 08:01: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')]} 08:01:00 INFO - trying https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 08:01:00 INFO - Downloading https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/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 08:01:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/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 08:01:02 INFO - Downloaded 1199 bytes. 08:01:02 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 08:01:02 INFO - Using the following test package requirements: 08:01:02 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:01:02 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:01:02 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 08:01:02 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:01:02 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 08:01:02 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:01:02 INFO - u'jsshell-mac.zip'], 08:01:02 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:01:02 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 08:01:02 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:01:02 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:01:02 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 08:01:02 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:01:02 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 08:01:02 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:01:02 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 08:01:02 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:01:02 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 08:01:02 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:01:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:01:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:01:02 INFO - Fetch https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:01:03 INFO - Expected file size: 51790423 08:01:03 INFO - Obtained file size: 51790423 08:01:19 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 08:01:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:01:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:01:19 INFO - Fetch https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:01:21 INFO - Expected file size: 51790423 08:01:21 INFO - Obtained file size: 51790423 08:01:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 08:01:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:01:36 INFO - Fetch https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 08:01:37 INFO - Expected file size: 14404438 08:01:37 INFO - Obtained file size: 14404438 08:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:38 INFO - trying https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:01:38 INFO - Downloading https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 08:01:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/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 08:01:40 INFO - Downloaded 74687087 bytes. 08:01:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:01:40 INFO - mkdir: /builds/slave/test/properties 08:01:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:01:40 INFO - Writing to file /builds/slave/test/properties/build_url 08:01:40 INFO - Contents: 08:01:40 INFO - build_url:https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:01:40 INFO - Running post-action listener: _resource_record_post_action 08:01:40 INFO - Running post-action listener: set_extra_try_arguments 08:01:40 INFO - [mozharness: 2017-04-06 15:01:40.816028Z] Finished download-and-extract step (success) 08:01:40 INFO - [mozharness: 2017-04-06 15:01:40.816140Z] Running populate-webroot step. 08:01:40 INFO - Running pre-action listener: _resource_record_pre_action 08:01:40 INFO - Running main action method: populate_webroot 08:01:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:01:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:01:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:01:40 INFO - '--tppagecycles', 08:01:40 INFO - '7'], 08:01:40 INFO - 'tests': ['tsvgm']}}, 08:01:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:01:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:01:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:01:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:01:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:01:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:01:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:01:40 INFO - 'talos_options': ['--disable-e10s'], 08:01:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:01:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:01:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:01:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:01:40 INFO - 'talos_options': ['--disable-e10s'], 08:01:40 INFO - 'tests': ['damp', 'tps']}, 08:01:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:01:40 INFO - 'tests': ['damp', 'tps']}, 08:01:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:01:40 INFO - 'tests': ['dromaeo_dom']}, 08:01:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:01:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:01:40 INFO - 'tests': ['basic_compositor_video']}, 08:01:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:01:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:01:40 INFO - 'tests': ['a11yr', 08:01:40 INFO - 'ts_paint', 08:01:40 INFO - 'tpaint', 08:01:40 INFO - 'sessionrestore', 08:01:40 INFO - 'sessionrestore_no_auto_restore', 08:01:40 INFO - 'tabpaint']}, 08:01:40 INFO - 'other-e10s': {'tests': ['a11yr', 08:01:40 INFO - 'ts_paint', 08:01:40 INFO - 'tpaint', 08:01:40 INFO - 'sessionrestore', 08:01:40 INFO - 'sessionrestore_no_auto_restore', 08:01:40 INFO - 'tabpaint']}, 08:01:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:01:40 INFO - 'ts_paint', 08:01:40 INFO - 'tpaint', 08:01:40 INFO - 'sessionrestore', 08:01:40 INFO - 'sessionrestore_no_auto_restore', 08:01:40 INFO - 'tabpaint']}, 08:01:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:01:40 INFO - 'ts_paint', 08:01:40 INFO - 'tpaint', 08:01:40 INFO - 'sessionrestore', 08:01:40 INFO - 'sessionrestore_no_auto_restore', 08:01:40 INFO - 'tabpaint']}, 08:01:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:01:40 INFO - 'tests': ['a11yr', 08:01:40 INFO - 'ts_paint', 08:01:40 INFO - 'tpaint', 08:01:40 INFO - 'sessionrestore', 08:01:40 INFO - 'sessionrestore_no_auto_restore', 08:01:40 INFO - 'tabpaint']}, 08:01:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:01:40 INFO - 'tests': ['a11yr', 08:01:40 INFO - 'ts_paint', 08:01:40 INFO - 'tpaint', 08:01:40 INFO - 'sessionrestore', 08:01:40 INFO - 'sessionrestore_no_auto_restore', 08:01:40 INFO - 'tabpaint']}, 08:01:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:01:40 INFO - 'tests': ['tsvgx', 08:01:40 INFO - 'tsvgr_opacity', 08:01:40 INFO - 'tart', 08:01:40 INFO - 'tscrollx', 08:01:40 INFO - 'cart', 08:01:40 INFO - 'tsvg_static']}, 08:01:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:01:40 INFO - 'tsvgr_opacity', 08:01:40 INFO - 'tart', 08:01:40 INFO - 'tscrollx', 08:01:40 INFO - 'cart', 08:01:40 INFO - 'tsvg_static']}, 08:01:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:01:40 INFO - 'talos_options': ['--disable-e10s'], 08:01:40 INFO - 'tests': ['tp5o']}, 08:01:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:01:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:01:40 INFO - 'talos_options': ['--disable-e10s', 08:01:40 INFO - '--xperf_path', 08:01:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:01:40 INFO - 'tests': ['tp5n']}, 08:01:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:01:40 INFO - 'talos_options': ['--xperf_path', 08:01:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:01:40 INFO - 'tests': ['tp5n']}}, 08:01:40 INFO - 'talos.zip': {'path': '', 08:01:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:01:40 INFO - Running post-action listener: _resource_record_post_action 08:01:40 INFO - [mozharness: 2017-04-06 15:01:40.834619Z] Finished populate-webroot step (success) 08:01:40 INFO - [mozharness: 2017-04-06 15:01:40.834748Z] Running create-virtualenv step. 08:01:40 INFO - Running pre-action listener: _resource_record_pre_action 08:01:40 INFO - Running main action method: create_virtualenv 08:01:40 INFO - Creating virtualenv /builds/slave/test/build/venv 08:01:40 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 08:01:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:01:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:01:40 INFO - Using real prefix '/tools/python27' 08:01:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:01:41 INFO - Installing distribute.............................................................................................................................................................................................done. 08:01:44 INFO - Installing pip.................done. 08:01:44 INFO - Return code: 0 08:01:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:01:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:01:44 INFO - Reading from file tmpfile_stdout 08:01:44 INFO - Output received: 08:01:44 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) 08:01:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10da40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f1cbd0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81ed30>, '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 0x10d745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d813be8>, '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.sBViLxpXA9/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.SyLQsAb5pG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:01:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:01:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:01:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBViLxpXA9/Render', 08:01:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:01:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:01:45 INFO - 'HOME': '/Users/cltbld', 08:01:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:01:45 INFO - 'LOGNAME': 'cltbld', 08:01:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:45 INFO - 'MOZ_NO_REMOTE': '1', 08:01:45 INFO - 'NO_EM_RESTART': '1', 08:01:45 INFO - 'PAGER': '/bin/cat', 08:01:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:01:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:45 INFO - 'PWD': '/builds/slave/test', 08:01:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:01:45 INFO - 'SHELL': '/bin/bash', 08:01:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyLQsAb5pG/Listeners', 08:01:45 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:01:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:01:45 INFO - 'USER': 'cltbld', 08:01:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:01:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:01:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:45 INFO - 'XPC_FLAGS': '0x0', 08:01:45 INFO - 'XPC_SERVICE_NAME': '0', 08:01:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:01:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:45 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 08:01:45 INFO - Cleaning up... 08:01:45 INFO - Return code: 0 08:01:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:01:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10da40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f1cbd0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81ed30>, '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 0x10d745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d813be8>, '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.sBViLxpXA9/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.SyLQsAb5pG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:01:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:01:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:01:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBViLxpXA9/Render', 08:01:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:01:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:01:45 INFO - 'HOME': '/Users/cltbld', 08:01:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:01:45 INFO - 'LOGNAME': 'cltbld', 08:01:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:45 INFO - 'MOZ_NO_REMOTE': '1', 08:01:45 INFO - 'NO_EM_RESTART': '1', 08:01:45 INFO - 'PAGER': '/bin/cat', 08:01:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:01:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:45 INFO - 'PWD': '/builds/slave/test', 08:01:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:01:45 INFO - 'SHELL': '/bin/bash', 08:01:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyLQsAb5pG/Listeners', 08:01:45 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:01:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:01:45 INFO - 'USER': 'cltbld', 08:01:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:01:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:01:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:45 INFO - 'XPC_FLAGS': '0x0', 08:01:45 INFO - 'XPC_SERVICE_NAME': '0', 08:01:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:46 INFO - Downloading/unpacking psutil>=3.1.1 08:01:46 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 08:01:46 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 08:01:46 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 08:01:46 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 08:01:46 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 08:01:46 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 08:01:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:01:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:01:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:01:49 INFO - Installing collected packages: psutil 08:01:49 INFO - Running setup.py install for psutil 08:01:49 INFO - building 'psutil._psutil_osx' extension 08:01:49 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 08:01:50 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 08:01:50 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 08:01:50 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 08:01:50 INFO - building 'psutil._psutil_posix' extension 08:01:50 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 08:01:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:01:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:01:50 INFO - ^ 08:01:50 INFO - 1 warning generated. 08:01:50 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 08:01:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:01:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:01:50 INFO - Successfully installed psutil 08:01:50 INFO - Cleaning up... 08:01:50 INFO - Return code: 0 08:01:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:01: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')]} 08:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01: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')]} 08:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:50 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 0x10da40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f1cbd0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81ed30>, '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 0x10d745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d813be8>, '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.sBViLxpXA9/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.SyLQsAb5pG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:01:50 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 08:01:50 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 08:01:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBViLxpXA9/Render', 08:01:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:01:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:01:50 INFO - 'HOME': '/Users/cltbld', 08:01:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:01:50 INFO - 'LOGNAME': 'cltbld', 08:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:50 INFO - 'MOZ_NO_REMOTE': '1', 08:01:50 INFO - 'NO_EM_RESTART': '1', 08:01:50 INFO - 'PAGER': '/bin/cat', 08:01:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:50 INFO - 'PWD': '/builds/slave/test', 08:01:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:01:50 INFO - 'SHELL': '/bin/bash', 08:01:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyLQsAb5pG/Listeners', 08:01:50 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:01:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:01:50 INFO - 'USER': 'cltbld', 08:01:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:01:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:50 INFO - 'XPC_FLAGS': '0x0', 08:01:50 INFO - 'XPC_SERVICE_NAME': '0', 08:01:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:01:50 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 08:01:50 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 08:01:50 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 08:01:50 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 08:01:50 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 08:01:50 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 08:01:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:01:53 INFO - Installing collected packages: mozsystemmonitor 08:01:53 INFO - Running setup.py install for mozsystemmonitor 08:01:53 INFO - Successfully installed mozsystemmonitor 08:01:53 INFO - Cleaning up... 08:01:53 INFO - Return code: 0 08:01:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:01:53 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')]} 08:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:53 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')]} 08:01:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:53 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 0x10da40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f1cbd0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81ed30>, '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 0x10d745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d813be8>, '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.sBViLxpXA9/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.SyLQsAb5pG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:01:53 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 08:01:53 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 08:01:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBViLxpXA9/Render', 08:01:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:01:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:01:53 INFO - 'HOME': '/Users/cltbld', 08:01:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:01:53 INFO - 'LOGNAME': 'cltbld', 08:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:53 INFO - 'MOZ_NO_REMOTE': '1', 08:01:53 INFO - 'NO_EM_RESTART': '1', 08:01:53 INFO - 'PAGER': '/bin/cat', 08:01:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:53 INFO - 'PWD': '/builds/slave/test', 08:01:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:01:53 INFO - 'SHELL': '/bin/bash', 08:01:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyLQsAb5pG/Listeners', 08:01:53 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:01:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:01:53 INFO - 'USER': 'cltbld', 08:01:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:01:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:53 INFO - 'XPC_FLAGS': '0x0', 08:01:53 INFO - 'XPC_SERVICE_NAME': '0', 08:01:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:54 INFO - Downloading/unpacking jsonschema==2.5.1 08:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:01:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:57 INFO - Downloading functools32-3.2.3-2.tar.gz 08:01:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:01:57 INFO - warning: no files found matching '*.txt' 08:01:57 INFO - no previously-included directories found matching 'build' 08:01:57 INFO - no previously-included directories found matching 'dist' 08:01:57 INFO - no previously-included directories found matching '.git*' 08:01:57 INFO - Installing collected packages: jsonschema, functools32 08:01:57 INFO - Running setup.py install for functools32 08:01:57 INFO - warning: no files found matching '*.txt' 08:01:57 INFO - no previously-included directories found matching 'build' 08:01:57 INFO - no previously-included directories found matching 'dist' 08:01:57 INFO - no previously-included directories found matching '.git*' 08:01:57 INFO - Successfully installed jsonschema functools32 08:01:57 INFO - Cleaning up... 08:01:57 INFO - Return code: 0 08:01:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:01:57 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')]} 08:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:57 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')]} 08:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:57 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 0x10da40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f1cbd0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81ed30>, '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 0x10d745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d813be8>, '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.sBViLxpXA9/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.SyLQsAb5pG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:01:57 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 08:01:57 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 08:01:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBViLxpXA9/Render', 08:01:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:01:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:01:57 INFO - 'HOME': '/Users/cltbld', 08:01:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:01:57 INFO - 'LOGNAME': 'cltbld', 08:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:57 INFO - 'MOZ_NO_REMOTE': '1', 08:01:57 INFO - 'NO_EM_RESTART': '1', 08:01:57 INFO - 'PAGER': '/bin/cat', 08:01:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:57 INFO - 'PWD': '/builds/slave/test', 08:01:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:01:57 INFO - 'SHELL': '/bin/bash', 08:01:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyLQsAb5pG/Listeners', 08:01:57 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:01:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:01:57 INFO - 'USER': 'cltbld', 08:01:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:01:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:57 INFO - 'XPC_FLAGS': '0x0', 08:01:57 INFO - 'XPC_SERVICE_NAME': '0', 08:01:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:01:57 INFO - Cleaning up... 08:01:57 INFO - Return code: 0 08:01:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:01:57 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')]} 08:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:57 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')]} 08:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:57 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 0x10da40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f1cbd0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81ed30>, '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 0x10d745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d813be8>, '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.sBViLxpXA9/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.SyLQsAb5pG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:01:57 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 08:01:57 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 08:01:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBViLxpXA9/Render', 08:01:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:01:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:01:57 INFO - 'HOME': '/Users/cltbld', 08:01:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:01:57 INFO - 'LOGNAME': 'cltbld', 08:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:57 INFO - 'MOZ_NO_REMOTE': '1', 08:01:57 INFO - 'NO_EM_RESTART': '1', 08:01:57 INFO - 'PAGER': '/bin/cat', 08:01:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:01:57 INFO - 'PWD': '/builds/slave/test', 08:01:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:01:57 INFO - 'SHELL': '/bin/bash', 08:01:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyLQsAb5pG/Listeners', 08:01:57 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:01:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:01:57 INFO - 'USER': 'cltbld', 08:01:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:01:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:57 INFO - 'XPC_FLAGS': '0x0', 08:01:57 INFO - 'XPC_SERVICE_NAME': '0', 08:01:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:58 INFO - Downloading/unpacking blobuploader==1.2.4 08:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:00 INFO - Downloading blobuploader-1.2.4.tar.gz 08:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:02:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:02:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:01 INFO - Downloading docopt-0.6.1.tar.gz 08:02:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:02:02 INFO - Installing collected packages: blobuploader, requests, docopt 08:02:02 INFO - Running setup.py install for blobuploader 08:02:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:02:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:02:02 INFO - Running setup.py install for requests 08:02:02 INFO - Running setup.py install for docopt 08:02:02 INFO - Successfully installed blobuploader requests docopt 08:02:02 INFO - Cleaning up... 08:02:02 INFO - Return code: 0 08:02:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:02 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 0x10da40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f1cbd0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81ed30>, '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 0x10d745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d813be8>, '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.sBViLxpXA9/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.SyLQsAb5pG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:02:02 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 08:02:02 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 08:02:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBViLxpXA9/Render', 08:02:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:02 INFO - 'HOME': '/Users/cltbld', 08:02:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:02 INFO - 'LOGNAME': 'cltbld', 08:02:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:02 INFO - 'MOZ_NO_REMOTE': '1', 08:02:02 INFO - 'NO_EM_RESTART': '1', 08:02:02 INFO - 'PAGER': '/bin/cat', 08:02:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:02 INFO - 'PWD': '/builds/slave/test', 08:02:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:02 INFO - 'SHELL': '/bin/bash', 08:02:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyLQsAb5pG/Listeners', 08:02:02 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:02:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:02 INFO - 'USER': 'cltbld', 08:02:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:02 INFO - 'XPC_FLAGS': '0x0', 08:02:02 INFO - 'XPC_SERVICE_NAME': '0', 08:02:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:03 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-MAQ644-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:03 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-7VvCPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:03 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-xEY_JB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:03 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-rnL2UG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:03 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-J71gtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:03 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-pEIu8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:03 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-wj0lKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:03 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-ljUQ3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-6L9772-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-AGdL7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-WVSX4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-S5hX8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-qBqxfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-p8LuXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-mPOyLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-QwWBxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-XbwF7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:02:04 INFO - Running setup.py install for manifestparser 08:02:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:02:05 INFO - Running setup.py install for mozcrash 08:02:05 INFO - Running setup.py install for mozdebug 08:02:05 INFO - Running setup.py install for mozdevice 08:02:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:02:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:02:05 INFO - Running setup.py install for mozfile 08:02:05 INFO - Running setup.py install for mozhttpd 08:02:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:02:05 INFO - Running setup.py install for mozinfo 08:02:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:02:05 INFO - Running setup.py install for mozInstall 08:02:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:02:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:02:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:02:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:02:06 INFO - Running setup.py install for mozleak 08:02:06 INFO - Running setup.py install for mozlog 08:02:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:02:06 INFO - Running setup.py install for moznetwork 08:02:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:02:06 INFO - Running setup.py install for mozprocess 08:02:06 INFO - Running setup.py install for mozprofile 08:02:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:02:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:02:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:02:06 INFO - Running setup.py install for mozrunner 08:02:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:02:07 INFO - Running setup.py install for mozscreenshot 08:02:07 INFO - Running setup.py install for moztest 08:02:07 INFO - Running setup.py install for mozversion 08:02:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:02:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:02:07 INFO - Cleaning up... 08:02:07 INFO - Return code: 0 08:02:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:07 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')]} 08:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:07 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')]} 08:02:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:07 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 0x10da40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f1cbd0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81ed30>, '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 0x10d745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d813be8>, '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.sBViLxpXA9/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.SyLQsAb5pG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:02:07 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 08:02:07 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 08:02:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBViLxpXA9/Render', 08:02:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:07 INFO - 'HOME': '/Users/cltbld', 08:02:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:07 INFO - 'LOGNAME': 'cltbld', 08:02:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:07 INFO - 'MOZ_NO_REMOTE': '1', 08:02:07 INFO - 'NO_EM_RESTART': '1', 08:02:07 INFO - 'PAGER': '/bin/cat', 08:02:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:07 INFO - 'PWD': '/builds/slave/test', 08:02:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:07 INFO - 'SHELL': '/bin/bash', 08:02:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyLQsAb5pG/Listeners', 08:02:07 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:02:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:07 INFO - 'USER': 'cltbld', 08:02:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:07 INFO - 'XPC_FLAGS': '0x0', 08:02:07 INFO - 'XPC_SERVICE_NAME': '0', 08:02:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:07 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-GEXDbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02:07 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)) 08:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:07 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-bCdKcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02:08 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)) 08:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:08 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-XPM8DI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02:08 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)) 08:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:08 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-jAaPWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02:08 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)) 08:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:08 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-YMf5r2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02:08 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)) 08:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:08 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-4CWFbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02:08 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)) 08:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:08 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-hXSHS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:08 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)) 08:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:08 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-I06QiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:08 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)) 08:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:08 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-yEZpWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:08 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)) 08:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:08 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-xnrs4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:08 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)) 08:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:08 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-RccrAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02:08 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)) 08:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:08 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-IRTJyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02:09 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)) 08:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:09 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-qANqTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02:09 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)) 08:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:09 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-uSWeNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02:09 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)) 08:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:09 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-jqL7BI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:09 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)) 08:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:09 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-QvJUSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02:09 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)) 08:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:09 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-7rUUap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:09 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)) 08:02:09 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)) 08:02:09 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)) 08:02:09 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)) 08:02:09 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)) 08:02:09 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)) 08:02:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:02:09 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 08:02:09 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 08:02:09 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 08:02:09 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 08:02:09 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 08:02:09 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 08:02:12 INFO - Downloading blessings-1.6.tar.gz 08:02:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:02:12 INFO - Installing collected packages: blessings 08:02:12 INFO - Running setup.py install for blessings 08:02:12 INFO - Successfully installed blessings 08:02:12 INFO - Cleaning up... 08:02:12 INFO - Return code: 0 08:02:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:02:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:12 INFO - Reading from file tmpfile_stdout 08:02:12 INFO - Current package versions: 08:02:12 INFO - blessings == 1.6 08:02:12 INFO - blobuploader == 1.2.4 08:02:12 INFO - docopt == 0.6.1 08:02:12 INFO - functools32 == 3.2.3-2 08:02:12 INFO - jsonschema == 2.5.1 08:02:12 INFO - manifestparser == 1.1 08:02:12 INFO - mozInstall == 1.12 08:02:12 INFO - mozcrash == 1.0 08:02:12 INFO - mozdebug == 0.1 08:02:12 INFO - mozdevice == 0.49 08:02:12 INFO - mozfile == 1.2 08:02:12 INFO - mozhttpd == 0.7 08:02:12 INFO - mozinfo == 0.9 08:02:12 INFO - mozleak == 0.1 08:02:12 INFO - mozlog == 3.4 08:02:12 INFO - moznetwork == 0.27 08:02:12 INFO - mozprocess == 0.25 08:02:12 INFO - mozprofile == 0.28 08:02:12 INFO - mozrunner == 6.13 08:02:12 INFO - mozscreenshot == 0.1 08:02:12 INFO - mozsystemmonitor == 0.3 08:02:12 INFO - moztest == 0.8 08:02:12 INFO - mozversion == 1.4 08:02:12 INFO - psutil == 3.1.1 08:02:12 INFO - requests == 1.2.3 08:02:12 INFO - wsgiref == 0.1.2 08:02:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:12 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')]} 08:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:12 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')]} 08:02:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:12 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 0x10da40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f1cbd0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81ed30>, '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 0x10d745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d813be8>, '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.sBViLxpXA9/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.SyLQsAb5pG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:02:12 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 08:02:12 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 08:02:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBViLxpXA9/Render', 08:02:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:12 INFO - 'HOME': '/Users/cltbld', 08:02:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:12 INFO - 'LOGNAME': 'cltbld', 08:02:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:12 INFO - 'MOZ_NO_REMOTE': '1', 08:02:12 INFO - 'NO_EM_RESTART': '1', 08:02:12 INFO - 'PAGER': '/bin/cat', 08:02:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:12 INFO - 'PWD': '/builds/slave/test', 08:02:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:12 INFO - 'SHELL': '/bin/bash', 08:02:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyLQsAb5pG/Listeners', 08:02:12 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:02:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:12 INFO - 'USER': 'cltbld', 08:02:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:12 INFO - 'XPC_FLAGS': '0x0', 08:02:12 INFO - 'XPC_SERVICE_NAME': '0', 08:02:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:13 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)) 08:02:13 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)) 08:02:13 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)) 08:02:13 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)) 08:02:13 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)) 08:02:13 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)) 08:02:13 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)) 08:02:13 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)) 08:02:13 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)) 08:02:13 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)) 08:02:13 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)) 08:02:13 INFO - Cleaning up... 08:02:13 INFO - Return code: 0 08:02:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:02:13 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')]} 08:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:13 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')]} 08:02:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:13 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 0x10da40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f1cbd0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81ed30>, '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 0x10d745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d813be8>, '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.sBViLxpXA9/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.SyLQsAb5pG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:02:13 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 08:02:13 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 08:02:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBViLxpXA9/Render', 08:02:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:13 INFO - 'HOME': '/Users/cltbld', 08:02:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:13 INFO - 'LOGNAME': 'cltbld', 08:02:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:13 INFO - 'MOZ_NO_REMOTE': '1', 08:02:13 INFO - 'NO_EM_RESTART': '1', 08:02:13 INFO - 'PAGER': '/bin/cat', 08:02:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:13 INFO - 'PWD': '/builds/slave/test', 08:02:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:13 INFO - 'SHELL': '/bin/bash', 08:02:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyLQsAb5pG/Listeners', 08:02:13 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:02:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:13 INFO - 'USER': 'cltbld', 08:02:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:13 INFO - 'XPC_FLAGS': '0x0', 08:02:13 INFO - 'XPC_SERVICE_NAME': '0', 08:02:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:02:13 INFO - Cleaning up... 08:02:13 INFO - Return code: 0 08:02:13 INFO - Running post-action listener: _resource_record_post_action 08:02:13 INFO - Running post-action listener: _start_resource_monitoring 08:02:13 INFO - Starting resource monitoring. 08:02:13 INFO - [mozharness: 2017-04-06 15:02:13.430157Z] Finished create-virtualenv step (success) 08:02:13 INFO - [mozharness: 2017-04-06 15:02:13.431135Z] Running install step. 08:02:13 INFO - Running pre-action listener: _resource_record_pre_action 08:02:13 INFO - Running main action method: install 08:02:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:13 INFO - Reading from file tmpfile_stdout 08:02:13 INFO - Detecting whether we're running mozinstall >=1.0... 08:02:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:02:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:02:13 INFO - Reading from file tmpfile_stdout 08:02:13 INFO - Output received: 08:02:13 INFO - Usage: mozinstall [options] installer 08:02:13 INFO - Options: 08:02:13 INFO - -h, --help show this help message and exit 08:02:13 INFO - -d DEST, --destination=DEST 08:02:13 INFO - Directory to install application into. [default: 08:02:13 INFO - "/builds/slave/test"] 08:02:13 INFO - --app=APP Application being installed. [default: firefox] 08:02:13 INFO - mkdir: /builds/slave/test/build/application 08:02:13 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'] 08:02:13 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 08:02:33 INFO - Reading from file tmpfile_stdout 08:02:33 INFO - Output received: 08:02:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:02:33 INFO - Running post-action listener: _resource_record_post_action 08:02:33 INFO - [mozharness: 2017-04-06 15:02:33.821273Z] Finished install step (success) 08:02:33 INFO - [mozharness: 2017-04-06 15:02:33.821416Z] Running run-tests step. 08:02:33 INFO - Running pre-action listener: _resource_record_pre_action 08:02:33 INFO - Running main action method: run_tests 08:02:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:02:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:02:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:02:33 INFO - 2017-04-06 08:02:33.835 screenresolution[1920:17025] starting screenresolution argv=screenresolution get 08:02:33 INFO - 2017-04-06 08:02:33.838 screenresolution[1920:17025] Display 0: 1600x1200x32@60 08:02:33 INFO - 2017-04-06 08:02:33.844 screenresolution[1921:17027] starting screenresolution argv=screenresolution list 08:02:33 INFO - Available Modes on Display 0 08:02:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:02:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:02:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:02:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:02:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:02:34 INFO - Intel Iris: 08:02:34 INFO - Chipset Model: Intel Iris 08:02:34 INFO - Type: GPU 08:02:34 INFO - Bus: Built-In 08:02:34 INFO - VRAM (Dynamic, Max): 1536 MB 08:02:34 INFO - Vendor: Intel (0x8086) 08:02:34 INFO - Device ID: 0x0a2e 08:02:34 INFO - Revision ID: 0x0009 08:02:34 INFO - Displays: 08:02:34 INFO - Display: 08:02:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:02:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:02:34 INFO - Display Serial Number: 1600x1200 60 08:02:34 INFO - Main Display: Yes 08:02:34 INFO - Mirror: Off 08:02:34 INFO - Online: Yes 08:02:34 INFO - Rotation: Supported 08:02:34 INFO - Return code: 0 08:02:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:02:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:02:34 INFO - Python 2.7.3 08:02:34 INFO - Return code: 0 08:02:34 INFO - grabbing minidump binary from tooltool 08:02: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')]} 08:02:34 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 0x10d81ed30>, '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 0x10d745db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d813be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:02:34 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 08:02:34 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 08:02:34 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 08:02:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:02:34 INFO - Return code: 0 08:02:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:02:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:02:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:02:34 INFO - ENV: RUST_BACKTRACE is now 1 08:02:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:02:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:02:34 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/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0372', '--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 08:02:34 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/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0372 --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 08:02:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sBViLxpXA9/Render', 08:02:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:34 INFO - 'HOME': '/Users/cltbld', 08:02:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:34 INFO - 'LOGNAME': 'cltbld', 08:02:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:02:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:02:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:34 INFO - 'MOZ_NO_REMOTE': '1', 08:02:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:02:34 INFO - 'NO_EM_RESTART': '1', 08:02:34 INFO - 'PAGER': '/bin/cat', 08:02:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:34 INFO - 'PWD': '/builds/slave/test', 08:02:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:02:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:34 INFO - 'RUST_BACKTRACE': '1', 08:02:34 INFO - 'SHELL': '/bin/bash', 08:02:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyLQsAb5pG/Listeners', 08:02:34 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:02:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:34 INFO - 'USER': 'cltbld', 08:02:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:34 INFO - 'XPC_FLAGS': '0x0', 08:02:34 INFO - 'XPC_SERVICE_NAME': '0', 08:02:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:34 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/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0372', '--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 08:02:34 INFO - mozversion application_buildid: 20170406071933 08:02:34 INFO - mozversion application_changeset: e03c56f3a839d1575e87dbd782638ad4894429e8 08:02:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:02:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:02:34 INFO - mozversion application_name: Firefox 08:02:34 INFO - mozversion application_remotingname: firefox-dev 08:02:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:02:34 INFO - mozversion application_vendor: Mozilla 08:02:34 INFO - mozversion application_version: 54.0a2 08:02:34 INFO - mozversion platform_buildid: 20170406071933 08:02:34 INFO - mozversion platform_changeset: e03c56f3a839d1575e87dbd782638ad4894429e8 08:02:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:02:34 INFO - mozversion platform_version: 54.0a2 08:02:34 INFO - using testdate: 1491490954 08:02:34 INFO - actual date: 1491490954 08:02:34 INFO - starting webserver on 'localhost:53439' 08:02:34 INFO - SUITE-START | Running 2 tests 08:02:34 INFO - TEST-START | dromaeo_css 08:02:34 INFO - operating with platform_type : mac_ 08:02:34 INFO - Initialising browser for dromaeo_css test... 08:02:34 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpQxTOSK/profile http://localhost:53439/getInfo.html) 08:02:37 INFO - PROCESS | 1931 | 2017-04-06 08:02:37.178 plugin-container[1933:17196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f37, name = 'com.apple.tsm.portname' 08:02:37 INFO - PROCESS | 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:02:37 INFO - PROCESS | 1931 | 2017-04-06 08:02:37.181 plugin-container[1933:17196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e5f, name = 'com.apple.CFPasteboardClient' 08:02:37 INFO - PROCESS | 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:02:37 INFO - PROCESS | 1931 | 2017-04-06 08:02:37.181 plugin-container[1933:17196] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:02:37 INFO - PROCESS | 1931 | __metrics Screen width/height:1600/1200 08:02:37 INFO - PROCESS | 1931 | colorDepth:24 08:02:37 INFO - PROCESS | 1931 | Browser inner width/height: 1024/663 08:02:37 INFO - PROCESS | 1931 | __metrics 08:02:37 ERROR - PROCESS | 1931 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 08:02:38 INFO - TEST-INFO | 1931: exit 0 08:02:38 INFO - Browser initialized. 08:02:38 INFO - Running cycle 1/1 for dromaeo_css test... 08:02:38 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpQxTOSK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:02:40 INFO - PROCESS | 1934 | 2017-04-06 08:02:40.495 plugin-container[1935:17328] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 08:02:40 INFO - PROCESS | 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:02:40 INFO - PROCESS | 1934 | 2017-04-06 08:02:40.495 plugin-container[1935:17328] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:02:40 INFO - PROCESS | 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:02:40 INFO - PROCESS | 1934 | 2017-04-06 08:02:40.495 plugin-container[1935:17328] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:02:50 INFO - PROCESS | 1934 | RSS: Main: 230981632 08:02:50 INFO - PROCESS | 1934 | 08:05:54 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53439/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53439/tests/dromaeo/cssquery-ext.html) 08:05:56 INFO - PROCESS | 1934 | RSS: Main: 249470976 08:05:56 INFO - PROCESS | 1934 | 08:07:38 INFO - PROCESS | 1934 | ************************* 08:07:38 INFO - PROCESS | 1934 | A coding exception was thrown and uncaught in a Task. 08:07:38 INFO - PROCESS | 1934 | 08:07:38 ERROR - PROCESS | 1934 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:07:38 INFO - PROCESS | 1934 | Full stack: 08:07:38 INFO - PROCESS | 1934 | ************************* 08:07:38 INFO - PROCESS | 1934 | ************************* 08:07:38 INFO - PROCESS | 1934 | A coding exception was thrown and uncaught in a Task. 08:07:38 INFO - PROCESS | 1934 | 08:07:38 ERROR - PROCESS | 1934 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:07:38 INFO - PROCESS | 1934 | Full stack: 08:07:38 INFO - PROCESS | 1934 | ************************* 08:07:38 INFO - PROCESS | 1934 | ************************* 08:07:38 INFO - PROCESS | 1934 | A coding exception was thrown and uncaught in a Task. 08:07:38 INFO - PROCESS | 1934 | 08:07:38 ERROR - PROCESS | 1934 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:07:38 INFO - PROCESS | 1934 | Full stack: 08:07:38 INFO - PROCESS | 1934 | ************************* 08:07:38 INFO - PROCESS | 1934 | ************************* 08:07:38 INFO - PROCESS | 1934 | A coding exception was thrown and uncaught in a Task. 08:07:38 INFO - PROCESS | 1934 | 08:07:38 ERROR - PROCESS | 1934 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:07:38 INFO - PROCESS | 1934 | Full stack: 08:07:38 INFO - PROCESS | 1934 | ************************* 08:08:58 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53439/tests/dromaeo/cssquery-ext.html (next: http://localhost:53439/tests/dromaeo/cssquery-jquery.html) 08:08:59 INFO - PROCESS | 1934 | RSS: Main: 254894080 08:08:59 INFO - PROCESS | 1934 | 08:12:00 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53439/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53439/tests/dromaeo/cssquery-mootools.html) 08:12:01 INFO - PROCESS | 1934 | RSS: Main: 261373952 08:12:01 INFO - PROCESS | 1934 | 08:15:03 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53439/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53439/tests/dromaeo/cssquery-prototype.html) 08:15:03 INFO - PROCESS | 1934 | RSS: Main: 258109440 08:15:03 INFO - PROCESS | 1934 | 08:18:07 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53439/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53439/tests/dromaeo/cssquery-yui.html) 08:18:07 INFO - PROCESS | 1934 | RSS: Main: 263360512 08:18:07 INFO - PROCESS | 1934 | 08:21:10 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53439/tests/dromaeo/cssquery-yui.html (next: http://localhost:53439/tests/dromaeo/cssquery-dojo.html) 08:21:11 INFO - PROCESS | 1934 | RSS: Main: 259346432 08:21:11 INFO - PROCESS | 1934 | 08:21:11 INFO - PROCESS | 1934 | __start_tp_report 08:21:11 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 08:21:11 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 08:21:11 INFO - PROCESS | 1934 | |i|pagename|runs| 08:21:11 INFO - PROCESS | 1934 | |0;dojo.html;3197.792143510672;3430.708389786868;3444.586649602048;3450.706689931356;3463.1342164458883;5987.520998320135;5998.490128339091;6020.217371741673;6027.306859711134;6047.425494578008;5955.642661440316;6026.035834266519;6039.456448919596;6046.3349031606485;6071.3625069367745;5350.224217488462;5429.592780541475;5443.482869127427;5467.5625949923915;5573.693446860421;5509.228707980866;5513.889722205554;5521.917171242435;5530.5022547970675;5533.00405926935;5294.311739473863;5341.0653135701095;5461.5357694596005;5508.944910550883;5510.834674959738;5411.512963833255;5412.161115027177;5415.404305526408;5464.398916119224;5538.723063846832;5296.25852380667;5459;5472.890542189174;5476.315460567429;5520.447955204488;6033.306169790487;6043.8186854394435;6069.57512974092;6089.543284253698;6106.694665266719;6209.813705588795;6274.466670333027;6283.6858157091965;6294.968525157345;6425.58233714807;5277.577793776489;6538.607683538955;6546.214454265471;6547.214334279869;6574.605523668595;6149.09239038636;6205.720742566596;6208.161898143693;6210.1305817186085;6239.500839932794;6330;6397.4562162216735;6573.1783527059115;6594.604323740591;6662.500312476487;4744.312074749142;6239.78160764371;6278.215223097113;6280.434760871543;6290.968545157337;6449.129367535323;6450.290468048511;6460.3849037740565;6477.4494167996245;6512.902306465407;6229.501639868799;6362.872742545123;6366.745330186741;6418;6438.098666186737;6281.277653069864;6292.748290068346;6304.842378940563;6442.8389290268115;6458.22501299847;4962.057209130254;5005.1491246488185;5031.371078615173;5049.293098966147;5164.251183578435;4537.500874903758;4566.70316429431;4576.748278844696;4613.054323863667;4635.443746750411;5997.100434934795;6011.3086994995265;6083.96572582662;6093.8476538086015;6107.938920610826;6245.75016999324;6251.062340648939;6497.967510162419;6529.412352888168;6544.312847151075;5947.286325640951;5980.85047873798;5983.880322393527;5999.280086389661;6042.305134909541;5674.687892165888;5684.63049901755;5690.2033715279895;5697.914531281951;5699.031164702011;6288.874222515433;6718.059471674067;6730.899036514456;6738.225104113089;6741.988701694785;5604.355499117653;5717.685527296038;6275.717592708431;6278.4349408551625;6375.840603984938;6654.467642588678;6667.133272674521;6680;6683.198016238082;6692.431143352869;6308.053791931064;6351.904721429275;6361.681915904279;6364.204474440695;6398.5201109916015;5623.1846382273525;6169.383061693795;6206.441420272197;6228.377162283917;6397.168368112302;6277.424554490918;6302.968485157545;6310.400511951358;6321.747130114928;6322.525810564318;5221.869453263699;6306.684665766602;6310.021946598285;6323.810285691437;6330.01884707871;5436.565074793928;5762.84743051383;5995.370486098896;5996.5502587305255;6122.154209354628;5124.5131712487255;6188.1027251049245;6286.308506064238;6292.905606415999;6301.6534090625455;5555.500004999408;5900.5574581907385;6002.129691194665;6255.812325630239;6273.090401891792 08:21:11 INFO - PROCESS | 1934 | |1;ext.html;698.594815007296;756.4364548411435;785.1520358013346;960.4333443268474;968.278632418848;34032.31935361295;37937.62062379375;38664;39319.820405387814;39887.202255954864;38777.8061109694;38972.80513597428;38973.41539876897;38996;39036.60963390365;7702.691892324315;7851.5681637509915;10285.177185825154;10588.3646981181;10754.516046777913;10485;10545.261831671785;10581.576736930514;10587.258891877571;10595.682129536126;10556.577736890515;10569.365838049704;10640.733981650443;10641.201909856793;10668.35989840608;10561.683149505527;10577.259591828499;10590.470476476185;10597.62908298206;10666.200034997368;10336.793264134676;10412.479376031244;10412.583496660125;10414.218933580012;10423.374597524173;24069.87965060181;34131.31737365264;34378.82810585956;34453.48319775205;36012.279754404764;3169.31859650174;3702.9444558331647;3740.4389341598708;3766.190269092131;3920.647141757227;24825.145277306936;24991;25620.615690764655;26398.472030559282;26408.86795566029;2511.660925775015;2754.7107553706774;2867.4551835151256;2890;2921.6932222116798;348015;350476.49523504695;357802;358237.20881395426;359155;45578.316325255146;45725.54274457279;45770.771146144056;45813;45885.31172032422;41447.13487893052;47397.81505462391;47602.80992975203;47793.52206477891;47811;13061.477540898448;13787.310634468236;13838.792418113737;13947.511837085856;13976.720465590632;11807.586734464252;12050.457729402198;12075.27548347102;12084.879151208552;12097;9646.987066358071;11933.105017123751;11944.761104777856;11966.58116965902;12117.969972552431;5580.804671836466;5696.3449203341315;5705.828825135252;5788.5369170466265;5802.854928626818;1009.9798004039878;1058.9309461900368;1102.8125218712678;1120.5517792882913;1185.8458400407892;23235.07059717626;26405.735942640327;26938;26951.326216844343;27090.5936410954;979.5836769372988;1051.8842927277992;1211.8061110222498;1241.5530409052733;1250.9562165324353;9059.873167730177;14857.331420086122;15723;15955.441559545361;16262.430814921421;494737;495360.5231973817;499266;499297;499807;49209.50790492121;49696.509104726916;56167.43832561704;56333;56643.43356566464;1465.8754005909404;1485.7251408489465;1490.9925450372896;1500.699860027977;1503.3911265937313;28667.713322866504;30194.2451438707;30571;30717.23206919845;30850.382992340477;48737.75631121822;48835.02329953452;49016;49096;49293.75353123211;365938;375361;434870;435520;438566;35854.64145358513;36308.81845590755;36501.45247821338;36594;36596;44085.55914440943;44407;45239.77380113078;45779.54220457886;45782.85542861455;30256.54615180818;30431.695683042886;30621.23446913845;30713;31767.205819853763;30464.390712186076;30897.53653695241;30964.84517577398;31427.214319642193;31997.360052798347;36136;37252;37341.81329093337;37349.6265037357;37379;26705.599416009165;28946.27634309159;29475.263118421353;30122.49387530571;31355;22582.66126008144;22667.093316267164;23192.536149277257;23198.652020220048;23339.76660233376 08:21:11 INFO - PROCESS | 1934 | |2;jquery.html;364.30236097872574;409.0816117815503;409.30213985155314;417.4760675352432;447.5345640533845;16889.324427022933;20209.191632334707;20673.896630516825;21315.786842131572;21608.24371147008;21513.784862151413;21584;21788;21845;22164.445888852788;6519.315471875444;6823.488238382116;6828.214755303129;6872.862542749142;6882.002109694091;6469.1590093287805;6674.232463266713;6692.263850976388;6721.8319542011595;6723;6705.597664140143;6716.1269035025625;6720.764773232938;6725.529212955095;6743.5953842769595;7023.7541686040995;7359.300866417681;7509.173990861001;7512.661930213154;7520.5487670739385;6092;7094.71621135154;7291.854162916712;7333;7351.117865856078;25650;25953.22140335793;26330;26500.86749566259;26633;2852.8288302701903;2958.156925276286;2972.5689774982725;2976.538636511344;2979.746721528673;16011.19944002807;16073.276702548297;16498.67002659952;16510.42213522521;17150.399736009305;1673.280489389562;2017.5057111007898;2028;2031;2056.50643845476;156750;173500.1324993367;179579.10210448995;181057;181555;20922.953852307324;21200.681989770164;21716.50260220063;21816.672749908765;21868;32352.67647323544;32698;33225;33331.333373332396;33396.499052514235;10212.438315892698;10349.275550711529;10523.84214236787;10576.101031412416;10578.57685692579;8734.475931444134;8762.956185219033;8774.25418839393;8896.59965301563;8901.465912045298;9763.170130538983;9798;9806.950965245129;9942.005799420145;9979.451130187765;3260.3316320154313;3595.964040359563;4208.516020657602;4305.794377574284;4329.19909816685;898.925783688499;994.7314225159166;1036.7356324137297;1064.3932958213898;1101.3502033800096;15449.459268925533;19130.660853740384;20575.58848823015;21021.264255750975;21279.78720212809;956.9043095690375;1067.8611780468489;1083.4528563075596;1103.25530267071;1107.7895199912152;10171.745706357253;10997.6150834722;11021.944890275658;11037.724056898642;11039.282446640942;725267;738424;743414;751935;759874;115792;149128.50871491362;159692;163676;167540;1173.7359094203737;1261.4638778519093;1277.150694787974;1277.4251586785983;1311.8294621699308;17533.912330438267;18251.634967300844;18481.630367392307;18509.907450462662;18565;64211.35788642054;64800;65497.36256593623;65517;66402.00396993953;91339.88474431238;91543;91648.54175729079;93072.53463732637;94161;11256.099512039182;11301.886981130083;11330.490127944098;11339.773204536028;11723.534558180227;12378.566750163702;12678.619641410773;12835;12846.678833028875;12871;10699.8930010702;10733.624323148964;10795.892041079804;10931.945340273247;10970.945145274223;9979.900200997898;10089.949550252202;10236.181105511392;10304.381737095799;10310.484475776331;10871.29336593119;10977.506012229629;11080.501377438195;11331.943340283246;11345.751967283757;11289.37908415045;11374.772504550028;11618;11646.94176529112;11863.406829658656;10983;11060.225784195029;11229.157813164209;11259.211855170059;11284.548618055185 08:21:11 INFO - PROCESS | 1934 | |3;mootools.html;1412.5409241996351;1485.1089346392173;1508.645468314946;1526.1987456585284;1530.6249968757656;10715.035646791786;11163.83254251187;11309.15181361398;11465.025472834819;11487.770244595104;9473.763155921088;11220.551177952872;11406.54373825048;11408.372539510323;11432.771344573104;2432.622943443769;2467.6551279552596;2469.7283298837115;2470.6170543565777;2473.3445636906235;2357.3244021224846;2413.9637905431346;2451.497443024176;2456.3981824453035;2457.4347899983013;2381.2856143156982;2418.1294733895784;2422.806175505964;2463.4949835283815;2468.4816188600416;2385.4155731845635;2428.5992811186134;2439.621858611918;2441.56051910656;2449.2040086971806;2413.553492603874;2433.2578563538113;2438.5001074779807;2450.1057114153205;2453.0555736041574;11119.666410007712;11313.773724525463;11370.147238957112;11378.60174893883;11397.202195846294;4802.4237091548885;5799.18811366409;5873.765049398019;5983.940160598426;5985.16207730918;3402.659734026602;3449.6722811332884;3491.3366460372704;3586.5158203642436;3635.581908080578;1157.2593540134321;1164.895159435655;1169.2224670594044;1188.2335893348827;1211.4548453196096;13391.665208369772;13542.661433464144;15187.240637968167;15518;15638.921805391014;4033.274010678106;4666.859994200179;4749.168895443284;4888.242322440027;4910.607151427877;11816.231944923553;11838.171328006956;11846.644600661994;11874.406279685982;11874.940625296818;4831.565159135695;5834.416558344216;5889.882202356014;5892.027815410495;5916.467517923407;4354.911272181955;4731.432228132646;5251.317328747238;5323.095073837457;5342;4018.7990600470443;4041.7574945503366;4051.736637118578;4140.37894315855;4140.648044916155;977.6285011695652;983.090641156927;989.5645915797027;994.1450352696675;994.9900500995042;828.8093738440192;914.9085091490798;924.9676261330956;938.6010945348199;946.2477330522114;6328.398802113792;6390.680465976682;6537.182852143482;6544.247411547733;6653.567518111307;860.3117505995178;973.4597298499259;1021.6628512590827;1027.4092396871679;1032.2877214721818;3412.1502694716114;4104.548499665035;4229.661627069827;4338.132373525307;4381.496127945264;13676.863231367757;14259.857401426061;14270;14273.78589321161;14310.427582896777;13664.795028074586;13887;14176.85823141776;14253.287335633177;14273.072250303698;8493.660253589787;10238.641647542301;10349.275550711378;10357.378557286587;10389.896101039045;5927.911081333697;6497.837554061186;6523.445507131947;6525.282218956006;6674.799756007327;10615.787684246427;12132.818007730068;12238.571649992207;12326.938365308117;12332.938335308267;11488.965993060665;12518;12525.686857828263;12754.87245127574;12819.551315703906;7019.894701579583;8699.478031318142;8707.259882910024;8732.301415886841;8821.426607270507;10220.97380145787;10291.22815788804;10324.019218174262;10383.94808025955;10487.790244195226;8678.609462574326;8793.780155495908;8877.778055548662;8880.200781929658;8906.421082629608;7014.964925175545;8283.420160588701;8334.583270836556;8379.95810020946;8392.580370981548;7950.64222110018;8031.076426211061;8032.357411407191;8069.47548409375;8138.267555919995;5865.589408741518;5879.764809407576;5895;6074;6093.086037094471;5921.733521991435;5967.254093238345;6034.607750496204;6064.120702498201;6231.75072997075 08:21:11 INFO - PROCESS | 1934 | |4;prototype.html;367.75176755689904;370.21329674441813;372.46365234062966;387.66467006039784;389.9025243689077;4585.6560757943125;5172.250023746564;5311.229871668611;5447.210489000732;5475.178723191523;4614.831029416169;5181.948180518203;5254.842354729364;5263.289455923459;5321.201819727043;3699.0395090602024;4550.977245113778;4584.358189853407;4618.422697162855;4647.302904564309;3531.3579185678655;4226;4595.839145629903;4624.838130665427;4636.09596128755;3541.3979623463943;3795.356457800515;3821.6942644588507;4334.263175260197;4366.995591014069;4025.335819589764;4349.716833534083;4355.477342718862;4366.406261714483;4381.53993830648;3243.659415761358;3454.481827725836;3513.8770143045126;3826.827792749333;4034.4755181826467;4105.325733468529;4262.509811371701;4658.5775396793815;4913.901721965576;5010.498950104997;4084.7957602119777;4502.527234640381;4681.32120842476;4917.852464426073;5086.287919691246;4785.784639691222;4940.789506570877;5005.3721796057;5562.610617256793;5576.358718747356;3967.504061992251;4411.757353345564;4542.4782697795945;4835.806567737286;4881.6338774591695;5645.810117283721;5744.511716504102;5945.64326140433;5965.403459654043;6043.829757467333;4128.422020917074;4390.626796722315;4499.910001800011;5045.94035252601;5091.516305950929;4621.69958952667;5180.740962951837;5197.402298735618;5204.76578553966;5238.68567885928;5074.908894587681;5126.001718659451;5282.498162674617;5301.496357845998;5394.78420863161;4535.523770004168;5080.441151473335;5758.625689330264;5780.364159942403;5811.3316968548315;1607.541850572581;1783.4025601423668;1786.0799640386635;1788.9873891871644;1793.4350679535905;1279.4242590833976;1348.3460521646994;1405.4659229492727;1441.978370324446;1443.9205843678699;4442.511758560919;4457.732536047847;4613.523672424859;4850.175470170079;5042.168042273057;4707.31743897133;5475.06923822951;5562.745793710725;5593.440655934374;5726.5683579105225;4569.794359253841;4950.975245123751;5014.774335154927;5175.7153356564995;5213.983273261678;2950.2771820904018;2996.2471953906775;3008.172752493082;3016.2308611304416;3054.2975115723507;5434.380776340079;5663.631863928831;5846.41535846412;6017.669028203403;6044.304904935989;5252.111338763665;5519.43346343952;5769;5935.40645935403;5936.130119012497;5023.623228257895;5237.8428647140645;5473.644213126134;6005.68781019302;6076.490292692011;5060.661002642248;5496.131315352799;5758.452946970031;5778.87311974161;5819.748754017906;4772.618190544678;5139.89492259166;5501.752421141138;5712.314522257356;5743.167240750151;4977.253411988231;5340.546053585489;5408.215288954714;5524.994379075139;5903.594599859201;4332;4356.86721452431;4985.177445721415;5033.75322916724;5057.291979122852;4907.97546012282;5656.60403771732;5763.567732420001;5779.884402312015;5788.044972579477;4870.9269360960325;5063.645544811828;5108.347531514984;5213.08770965072;5639.8698722209565;5283.365996080495;5582.748776304995;5663.32040155184;5666.065099258576;5669.744861481161;4841.927371089507;5214.452482489284;5313.654612450178;5539.836634306841;5611.971940140274;4513.796879140381;4894.853154405374;5724.484796368347;5766.798162064308;5771.249737534094;4893.828715994923;4929.718273248909;5294.97352513235;5583.581231407579;5590.329160500603 08:21:11 INFO - PROCESS | 1934 | |5;yui.html;710.7228181009406;721.7978965889546;723.9131304243491;791.3273717340262;874.7332063720564;13225.20648761075;15110.168940708256;15778.684426311467;16391.4263000795;16437.09595972221;12875.806862897063;12996;13015.93492032541;13027.80458293124;13070.085094043421;927.6196759328693;961.4327546747418;963.5519483440199;963.9855402168973;966.1256562810668;920.3235621817959;952.8427809411473;960.1118964957421;962.2206013129388;963.7638778499278;1005.140604782911;1005.9949700251489;1007.6573964851985;1008.0725732326277;1009.0514915978993;987.8221920054388;999.6351331763926;1000.0599436529639;1002.6891663584275;1003.0471052500118;746.4700062955309;749.505326484523;750.107372227048;751.2337415835817;751.6918063593955;16032.599185020352;16062.196890155445;16368.672626547403;16738.16309184536;16775.161241937854;14161.85838141626;14363.281835908267;14475.855241447556;14501.274936253145;14584.927075364662;11555;11590.884091159149;11627.825582616268;11841.644750657493;12379.257244565264;3362.159460134966;3536.522569453117;3709.8145092745526;3756.5116534850567;3799.9620003799882;92844;110747.9999999992;116766;121085.39457302746;121351;9284.907150928404;9489.145976862115;9564.947855735863;9585;9622.37454565451;17850.821491785176;18540.629187416445;18551.90724046371;18634.627307453775;18674.719879201824;7720.498167619086;7754.9224507755325;7775.727849525239;7889.487183333065;7968.482048666818;3793.7534060285993;4274.829006839754;4327.76197309151;4352.2818734909015;4416.66874984371;3454.5854497460464;3500;3501.9474707879403;3533;3623.7644553103964;651.6806764685365;652.8563715982476;660.3891400454656;662.9900551491731;665.55075324156;668.548729607513;687.8280429892527;690.2510775808204;695.3672158335891;714.6193831810965;9873.555689993967;10147.34042287249;10225.693229203136;10463.267571270091;10559.366438013743;670.1924181361447;693.19935474527;707.7416742888809;714.3749219432996;717.2218143314458;4921.286413470027;5073.289739436482;5087.618428617869;5087.974560127176;5091.974540127349;107613;111031;111241;111721;113959.43020285011;88961;89360;91503;92760;93094;60764.69617651883;61812.381876181564;79317;81288.99999999882;81350.59324703338;8586.914130858611;9018.774530636787;9514.762130946505;9798.706038818847;9946.950265248628;45469.77265113653;45893;48573.757131214115;51216.74391628018;51794.48205517897;55231.619209518474;71869.6406517964;72021.27978720146;72152;73957.63021184859;10989.61536346224;11031.669049928514;11135.109191264517;11208.831867522156;11550.364729939934;12147.453364598437;12439.440225190068;12584;12681.873181268069;12957.963362931132;9944;11791.29252244868;11843.170978031456;11947.283163010246;12326.383680816103;11542.212694750624;11906.940465297617;11943.940280298542;11949.940250298692;12492.25046497213;11496.425178741198;11786.58746943853;11805.881941180478;12243;12289.56986505468;7417.554946703215;8615.439996400213;9013.369064165445;9117.407368521024;9250.167484926389;8883.466991980244;8927.732168034969;9879.753006174904;9942.850857237292;9953.402795832273 08:21:11 INFO - PROCESS | 1934 | __end_tp_report 08:21:11 INFO - PROCESS | 1934 | __start_cc_report 08:21:11 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,420 08:21:11 INFO - PROCESS | 1934 | __end_cc_report 08:21:11 INFO - PROCESS | 1934 | __startTimestamp1491492071029__endTimestamp 08:21:11 INFO - PROCESS | 1934 | 08:21:11 INFO - PROCESS | 1934 | ------- Summary: start ------- 08:21:11 INFO - PROCESS | 1934 | Number of tests: 6 08:21:11 INFO - PROCESS | 1934 | 08:21:11 INFO - PROCESS | 1934 | [#0] dojo.html Cycles:165 Average:5915.22 Median:6106.69 stddev:671.02 (11.0%) stddev-sans-first:638.32 08:21:11 INFO - PROCESS | 1934 | Values: 3197.8 3430.7 3444.6 3450.7 3463.1 5987.5 5998.5 6020.2 6027.3 6047.4 5955.6 6026.0 6039.5 6046.3 6071.4 5350.2 5429.6 5443.5 5467.6 5573.7 5509.2 5513.9 5521.9 5530.5 5533.0 5294.3 5341.1 5461.5 5508.9 5510.8 5411.5 5412.2 5415.4 5464.4 5538.7 5296.3 5459.0 5472.9 5476.3 5520.4 6033.3 6043.8 6069.6 6089.5 6106.7 6209.8 6274.5 6283.7 6295.0 6425.6 5277.6 6538.6 6546.2 6547.2 6574.6 6149.1 6205.7 6208.2 6210.1 6239.5 6330.0 6397.5 6573.2 6594.6 6662.5 4744.3 6239.8 6278.2 6280.4 6291.0 6449.1 6450.3 6460.4 6477.4 6512.9 6229.5 6362.9 6366.7 6418.0 6438.1 6281.3 6292.7 6304.8 6442.8 6458.2 4962.1 5005.1 5031.4 5049.3 5164.3 4537.5 4566.7 4576.7 4613.1 4635.4 5997.1 6011.3 6084.0 6093.8 6107.9 6245.8 6251.1 6498.0 6529.4 6544.3 5947.3 5980.9 5983.9 5999.3 6042.3 5674.7 5684.6 5690.2 5697.9 5699.0 6288.9 6718.1 6730.9 6738.2 6742.0 5604.4 5717.7 6275.7 6278.4 6375.8 6654.5 6667.1 6680.0 6683.2 6692.4 6308.1 6351.9 6361.7 6364.2 6398.5 5623.2 6169.4 6206.4 6228.4 6397.2 6277.4 6303.0 6310.4 6321.7 6322.5 5221.9 6306.7 6310.0 6323.8 6330.0 5436.6 5762.8 5995.4 5996.6 6122.2 5124.5 6188.1 6286.3 6292.9 6301.7 5555.5 5900.6 6002.1 6255.8 6273.1 08:21:11 INFO - PROCESS | 1934 | 08:21:11 INFO - PROCESS | 1934 | [#1] ext.html Cycles:180 Average:55596.64 Median:30931.19 stddev:112937.95 (365.1%) stddev-sans-first:113179.55 08:21:11 INFO - PROCESS | 1934 | Values: 698.6 756.4 785.2 960.4 968.3 34032.3 37937.6 38664.0 39319.8 39887.2 38777.8 38972.8 38973.4 38996.0 39036.6 7702.7 7851.6 10285.2 10588.4 10754.5 10485.0 10545.3 10581.6 10587.3 10595.7 10556.6 10569.4 10640.7 10641.2 10668.4 10561.7 10577.3 10590.5 10597.6 10666.2 10336.8 10412.5 10412.6 10414.2 10423.4 24069.9 34131.3 34378.8 34453.5 36012.3 3169.3 3702.9 3740.4 3766.2 3920.6 24825.1 24991.0 25620.6 26398.5 26408.9 2511.7 2754.7 2867.5 2890.0 2921.7 348015.0 350476.5 357802.0 358237.2 359155.0 45578.3 45725.5 45770.8 45813.0 45885.3 41447.1 47397.8 47602.8 47793.5 47811.0 13061.5 13787.3 13838.8 13947.5 13976.7 11807.6 12050.5 12075.3 12084.9 12097.0 9647.0 11933.1 11944.8 11966.6 12118.0 5580.8 5696.3 5705.8 5788.5 5802.9 1010.0 1058.9 1102.8 1120.6 1185.8 23235.1 26405.7 26938.0 26951.3 27090.6 979.6 1051.9 1211.8 1241.6 1251.0 9059.9 14857.3 15723.0 15955.4 16262.4 494737.0 495360.5 499266.0 499297.0 499807.0 49209.5 49696.5 56167.4 56333.0 56643.4 1465.9 1485.7 1491.0 1500.7 1503.4 28667.7 30194.2 30571.0 30717.2 30850.4 48737.8 48835.0 49016.0 49096.0 49293.8 365938.0 375361.0 434870.0 435520.0 438566.0 35854.6 36308.8 36501.5 36594.0 36596.0 44085.6 44407.0 45239.8 45779.5 45782.9 30256.5 30431.7 30621.2 30713.0 31767.2 30464.4 30897.5 30964.8 31427.2 31997.4 36136.0 37252.0 37341.8 37349.6 37379.0 26705.6 28946.3 29475.3 30122.5 31355.0 22582.7 22667.1 23192.5 23198.7 23339.8 08:21:11 INFO - PROCESS | 1934 | 08:21:11 INFO - PROCESS | 1934 | [#2] jquery.html Cycles:180 Average:43612.34 Median:21297.79 stddev:124744.03 (585.7%) stddev-sans-first:125051.70 08:21:11 INFO - PROCESS | 1934 | Values: 364.3 409.1 409.3 417.5 447.5 16889.3 20209.2 20673.9 21315.8 21608.2 21513.8 21584.0 21788.0 21845.0 22164.4 6519.3 6823.5 6828.2 6872.9 6882.0 6469.2 6674.2 6692.3 6721.8 6723.0 6705.6 6716.1 6720.8 6725.5 6743.6 7023.8 7359.3 7509.2 7512.7 7520.5 6092.0 7094.7 7291.9 7333.0 7351.1 25650.0 25953.2 26330.0 26500.9 26633.0 2852.8 2958.2 2972.6 2976.5 2979.7 16011.2 16073.3 16498.7 16510.4 17150.4 1673.3 2017.5 2028.0 2031.0 2056.5 156750.0 173500.1 179579.1 181057.0 181555.0 20923.0 21200.7 21716.5 21816.7 21868.0 32352.7 32698.0 33225.0 33331.3 33396.5 10212.4 10349.3 10523.8 10576.1 10578.6 8734.5 8763.0 8774.3 8896.6 8901.5 9763.2 9798.0 9807.0 9942.0 9979.5 3260.3 3596.0 4208.5 4305.8 4329.2 898.9 994.7 1036.7 1064.4 1101.4 15449.5 19130.7 20575.6 21021.3 21279.8 956.9 1067.9 1083.5 1103.3 1107.8 10171.7 10997.6 11021.9 11037.7 11039.3 725267.0 738424.0 743414.0 751935.0 759874.0 115792.0 149128.5 159692.0 163676.0 167540.0 1173.7 1261.5 1277.2 1277.4 1311.8 17533.9 18251.6 18481.6 18509.9 18565.0 64211.4 64800.0 65497.4 65517.0 66402.0 91339.9 91543.0 91648.5 93072.5 94161.0 11256.1 11301.9 11330.5 11339.8 11723.5 12378.6 12678.6 12835.0 12846.7 12871.0 10699.9 10733.6 10795.9 10931.9 10970.9 9979.9 10089.9 10236.2 10304.4 10310.5 10871.3 10977.5 11080.5 11331.9 11345.8 11289.4 11374.8 11618.0 11646.9 11863.4 10983.0 11060.2 11229.2 11259.2 11284.5 08:21:11 INFO - PROCESS | 1934 | 08:21:11 INFO - PROCESS | 1934 | [#3] mootools.html Cycles:180 Average:6711.60 Median:2938.00 stddev:4230.75 (144.0%) stddev-sans-first:4223.88 08:21:11 INFO - PROCESS | 1934 | Values: 1412.5 1485.1 1508.6 1526.2 1530.6 10715.0 11163.8 11309.2 11465.0 11487.8 9473.8 11220.6 11406.5 11408.4 11432.8 2432.6 2467.7 2469.7 2470.6 2473.3 2357.3 2414.0 2451.5 2456.4 2457.4 2381.3 2418.1 2422.8 2463.5 2468.5 2385.4 2428.6 2439.6 2441.6 2449.2 2413.6 2433.3 2438.5 2450.1 2453.1 11119.7 11313.8 11370.1 11378.6 11397.2 4802.4 5799.2 5873.8 5983.9 5985.2 3402.7 3449.7 3491.3 3586.5 3635.6 1157.3 1164.9 1169.2 1188.2 1211.5 13391.7 13542.7 15187.2 15518.0 15638.9 4033.3 4666.9 4749.2 4888.2 4910.6 11816.2 11838.2 11846.6 11874.4 11874.9 4831.6 5834.4 5889.9 5892.0 5916.5 4354.9 4731.4 5251.3 5323.1 5342.0 4018.8 4041.8 4051.7 4140.4 4140.6 977.6 983.1 989.6 994.1 995.0 828.8 914.9 925.0 938.6 946.2 6328.4 6390.7 6537.2 6544.2 6653.6 860.3 973.5 1021.7 1027.4 1032.3 3412.2 4104.5 4229.7 4338.1 4381.5 13676.9 14259.9 14270.0 14273.8 14310.4 13664.8 13887.0 14176.9 14253.3 14273.1 8493.7 10238.6 10349.3 10357.4 10389.9 5927.9 6497.8 6523.4 6525.3 6674.8 10615.8 12132.8 12238.6 12326.9 12332.9 11489.0 12518.0 12525.7 12754.9 12819.6 7019.9 8699.5 8707.3 8732.3 8821.4 10221.0 10291.2 10324.0 10383.9 10487.8 8678.6 8793.8 8877.8 8880.2 8906.4 7015.0 8283.4 8334.6 8380.0 8392.6 7950.6 8031.1 8032.4 8069.5 8138.3 5865.6 5879.8 5895.0 6074.0 6093.1 5921.7 5967.3 6034.6 6064.1 6231.8 08:21:11 INFO - PROCESS | 1934 | 08:21:11 INFO - PROCESS | 1934 | [#4] prototype.html Cycles:180 Average:4670.27 Median:5037.96 stddev:1269.28 (25.2%) stddev-sans-first:1231.07 08:21:11 INFO - PROCESS | 1934 | Values: 367.8 370.2 372.5 387.7 389.9 4585.7 5172.3 5311.2 5447.2 5475.2 4614.8 5181.9 5254.8 5263.3 5321.2 3699.0 4551.0 4584.4 4618.4 4647.3 3531.4 4226.0 4595.8 4624.8 4636.1 3541.4 3795.4 3821.7 4334.3 4367.0 4025.3 4349.7 4355.5 4366.4 4381.5 3243.7 3454.5 3513.9 3826.8 4034.5 4105.3 4262.5 4658.6 4913.9 5010.5 4084.8 4502.5 4681.3 4917.9 5086.3 4785.8 4940.8 5005.4 5562.6 5576.4 3967.5 4411.8 4542.5 4835.8 4881.6 5645.8 5744.5 5945.6 5965.4 6043.8 4128.4 4390.6 4499.9 5045.9 5091.5 4621.7 5180.7 5197.4 5204.8 5238.7 5074.9 5126.0 5282.5 5301.5 5394.8 4535.5 5080.4 5758.6 5780.4 5811.3 1607.5 1783.4 1786.1 1789.0 1793.4 1279.4 1348.3 1405.5 1442.0 1443.9 4442.5 4457.7 4613.5 4850.2 5042.2 4707.3 5475.1 5562.7 5593.4 5726.6 4569.8 4951.0 5014.8 5175.7 5214.0 2950.3 2996.2 3008.2 3016.2 3054.3 5434.4 5663.6 5846.4 6017.7 6044.3 5252.1 5519.4 5769.0 5935.4 5936.1 5023.6 5237.8 5473.6 6005.7 6076.5 5060.7 5496.1 5758.5 5778.9 5819.7 4772.6 5139.9 5501.8 5712.3 5743.2 4977.3 5340.5 5408.2 5525.0 5903.6 4332.0 4356.9 4985.2 5033.8 5057.3 4908.0 5656.6 5763.6 5779.9 5788.0 4870.9 5063.6 5108.3 5213.1 5639.9 5283.4 5582.7 5663.3 5666.1 5669.7 4841.9 5214.5 5313.7 5539.8 5612.0 4513.8 4894.9 5724.5 5766.8 5771.2 4893.8 4929.7 5295.0 5583.6 5590.3 08:21:11 INFO - PROCESS | 1934 | 08:21:11 INFO - PROCESS | 1934 | [#5] yui.html Cycles:180 Average:20363.44 Median:45681.39 stddev:30706.63 (67.2%) stddev-sans-first:30757.31 08:21:11 INFO - PROCESS | 1934 | Values: 710.7 721.8 723.9 791.3 874.7 13225.2 15110.2 15778.7 16391.4 16437.1 12875.8 12996.0 13015.9 13027.8 13070.1 927.6 961.4 963.6 964.0 966.1 920.3 952.8 960.1 962.2 963.8 1005.1 1006.0 1007.7 1008.1 1009.1 987.8 999.6 1000.1 1002.7 1003.0 746.5 749.5 750.1 751.2 751.7 16032.6 16062.2 16368.7 16738.2 16775.2 14161.9 14363.3 14475.9 14501.3 14584.9 11555.0 11590.9 11627.8 11841.6 12379.3 3362.2 3536.5 3709.8 3756.5 3800.0 92844.0 110748.0 116766.0 121085.4 121351.0 9284.9 9489.1 9564.9 9585.0 9622.4 17850.8 18540.6 18551.9 18634.6 18674.7 7720.5 7754.9 7775.7 7889.5 7968.5 3793.8 4274.8 4327.8 4352.3 4416.7 3454.6 3500.0 3501.9 3533.0 3623.8 651.7 652.9 660.4 663.0 665.6 668.5 687.8 690.3 695.4 714.6 9873.6 10147.3 10225.7 10463.3 10559.4 670.2 693.2 707.7 714.4 717.2 4921.3 5073.3 5087.6 5088.0 5092.0 107613.0 111031.0 111241.0 111721.0 113959.4 88961.0 89360.0 91503.0 92760.0 93094.0 60764.7 61812.4 79317.0 81289.0 81350.6 8586.9 9018.8 9514.8 9798.7 9947.0 45469.8 45893.0 48573.8 51216.7 51794.5 55231.6 71869.6 72021.3 72152.0 73957.6 10989.6 11031.7 11135.1 11208.8 11550.4 12147.5 12439.4 12584.0 12681.9 12958.0 9944.0 11791.3 11843.2 11947.3 12326.4 11542.2 11906.9 11943.9 11949.9 12492.3 11496.4 11786.6 11805.9 12243.0 12289.6 7417.6 8615.4 9013.4 9117.4 9250.2 8883.5 8927.7 9879.8 9942.9 9953.4 08:21:11 INFO - PROCESS | 1934 | -------- Summary: end -------- 08:21:11 INFO - PROCESS | 1934 | 08:21:11 INFO - TEST-INFO | 1934: exit 0 08:21:11 INFO - TEST-OK | dromaeo_css | took 1117241ms 08:21:11 INFO - TEST-START | kraken 08:21:11 INFO - operating with platform_type : mac_ 08:21:11 INFO - Initialising browser for kraken test... 08:21:11 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmplS36iX/profile http://localhost:53439/getInfo.html) 08:21:14 INFO - PROCESS | 1939 | 2017-04-06 08:21:14.538 plugin-container[1940:19489] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 08:21:14 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:14 INFO - PROCESS | 1939 | 2017-04-06 08:21:14.541 plugin-container[1940:19489] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 08:21:14 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:14 INFO - PROCESS | 1939 | 2017-04-06 08:21:14.541 plugin-container[1940:19489] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:21:14 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 08:21:14 INFO - PROCESS | 1939 | colorDepth:24 08:21:14 INFO - PROCESS | 1939 | Browser inner width/height: 1024/663 08:21:14 INFO - PROCESS | 1939 | __metrics 08:21:14 ERROR - PROCESS | 1939 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 08:21:15 INFO - TEST-INFO | 1939: exit 0 08:21:15 INFO - Browser initialized. 08:21:15 INFO - Running cycle 1/1 for kraken test... 08:21:15 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmplS36iX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:21:18 INFO - PROCESS | 1941 | 2017-04-06 08:21:18.143 plugin-container[1942:19613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 08:21:18 INFO - PROCESS | 1941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:18 INFO - PROCESS | 1941 | 2017-04-06 08:21:18.144 plugin-container[1942:19613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 08:21:18 INFO - PROCESS | 1941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:18 INFO - PROCESS | 1941 | 2017-04-06 08:21:18.144 plugin-container[1942:19613] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:21:27 INFO - PROCESS | 1941 | RSS: Main: 232259584 08:21:27 INFO - PROCESS | 1941 | 08:21:31 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53439/tests/kraken/driver.html?testName=audio-beat-detection) 08:21:32 INFO - PROCESS | 1941 | RSS: Main: 244514816 08:21:32 INFO - PROCESS | 1941 | 08:21:34 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53439/tests/kraken/driver.html?testName=audio-dft) 08:21:35 INFO - PROCESS | 1941 | RSS: Main: 251924480 08:21:35 INFO - PROCESS | 1941 | 08:21:38 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53439/tests/kraken/driver.html?testName=audio-fft) 08:21:39 INFO - PROCESS | 1941 | RSS: Main: 252715008 08:21:39 INFO - PROCESS | 1941 | 08:21:41 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53439/tests/kraken/driver.html?testName=audio-oscillator) 08:21:41 INFO - PROCESS | 1941 | RSS: Main: 252669952 08:21:41 INFO - PROCESS | 1941 | 08:21:43 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53439/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:21:44 INFO - PROCESS | 1941 | RSS: Main: 252653568 08:21:44 INFO - PROCESS | 1941 | 08:21:47 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53439/tests/kraken/driver.html?testName=imaging-darkroom) 08:21:47 INFO - PROCESS | 1941 | RSS: Main: 256389120 08:21:47 INFO - PROCESS | 1941 | 08:21:51 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53439/tests/kraken/driver.html?testName=imaging-desaturate) 08:21:51 INFO - PROCESS | 1941 | RSS: Main: 255860736 08:21:51 INFO - PROCESS | 1941 | 08:21:54 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53439/tests/kraken/driver.html?testName=json-parse-financial) 08:21:55 INFO - PROCESS | 1941 | RSS: Main: 256212992 08:21:55 INFO - PROCESS | 1941 | 08:21:57 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53439/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:21:57 INFO - PROCESS | 1941 | RSS: Main: 255422464 08:21:57 INFO - PROCESS | 1941 | 08:21:59 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53439/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:22:00 INFO - PROCESS | 1941 | RSS: Main: 255410176 08:22:00 INFO - PROCESS | 1941 | 08:22:02 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53439/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:22:03 INFO - PROCESS | 1941 | RSS: Main: 255467520 08:22:03 INFO - PROCESS | 1941 | 08:22:06 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53439/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:22:07 INFO - PROCESS | 1941 | RSS: Main: 256114688 08:22:07 INFO - PROCESS | 1941 | 08:22:10 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53439/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:22:10 INFO - PROCESS | 1941 | RSS: Main: 255516672 08:22:10 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:53439/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53439/tests/kraken/driver.html?testName=ai-astar) 08:22:12 INFO - PROCESS | 1941 | RSS: Main: 257400832 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | __start_tp_report 08:22:12 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 08:22:12 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 08:22:12 INFO - PROCESS | 1941 | |i|pagename|runs| 08:22:12 INFO - PROCESS | 1941 | |0;ai-astar;156;183;135;123;123;120;120;130;148;120 08:22:12 INFO - PROCESS | 1941 | |1;audio-beat-detection;131;105;115;146;116;109;112;125;159;126 08:22:12 INFO - PROCESS | 1941 | |2;audio-dft;197;203;197;200;202;200;203;194;199;198 08:22:12 INFO - PROCESS | 1941 | |3;audio-fft;79;60;60;92;92;88;61;65;73;62 08:22:12 INFO - PROCESS | 1941 | |4;audio-oscillator;86;65;120;102;87;71;83;71;69;83 08:22:12 INFO - PROCESS | 1941 | |5;imaging-gaussian-blur;90;88;89;88;88;89;87;90;106;88 08:22:12 INFO - PROCESS | 1941 | |6;imaging-darkroom;108;107;96;97;97;131;156;96;96;99 08:22:12 INFO - PROCESS | 1941 | |7;imaging-desaturate;91;91;91;108;78;126;149;120;92;90 08:22:12 INFO - PROCESS | 1941 | |8;json-parse-financial;66;64;64;64;69;70;64;69;64;67 08:22:12 INFO - PROCESS | 1941 | |9;json-stringify-tinderbox;57;67;63;66;83;85;84;85;58;59 08:22:12 INFO - PROCESS | 1941 | |10;stanford-crypto-aes;85;82;80;87;86;83;90;104;106;103 08:22:12 INFO - PROCESS | 1941 | |11;stanford-crypto-ccm;156;157;149;138;137;140;142;146;204;135 08:22:12 INFO - PROCESS | 1941 | |12;stanford-crypto-pbkdf2;171;172;174;235;231;170;167;151;223;174 08:22:12 INFO - PROCESS | 1941 | |13;stanford-crypto-sha256-iterative;67;66;64;73;92;89;86;74;67;66 08:22:12 INFO - PROCESS | 1941 | __end_tp_report 08:22:12 INFO - PROCESS | 1941 | __start_cc_report 08:22:12 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,1020 08:22:12 INFO - PROCESS | 1941 | __end_cc_report 08:22:12 INFO - PROCESS | 1941 | __startTimestamp1491492132810__endTimestamp 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | ------- Summary: start ------- 08:22:12 INFO - PROCESS | 1941 | Number of tests: 14 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#0] ai-astar Cycles:10 Average:135.80 Median:132.50 stddev:20.80 (15.7%) stddev-sans-first:20.74 08:22:12 INFO - PROCESS | 1941 | Values: 156.0 183.0 135.0 123.0 123.0 120.0 120.0 130.0 148.0 120.0 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#1] audio-beat-detection Cycles:10 Average:124.40 Median:125.50 stddev:17.12 (13.6%) stddev-sans-first:17.99 08:22:12 INFO - PROCESS | 1941 | Values: 131.0 105.0 115.0 146.0 116.0 109.0 112.0 125.0 159.0 126.0 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#2] audio-dft Cycles:10 Average:199.30 Median:200.00 stddev:2.91 (1.5%) stddev-sans-first:2.96 08:22:12 INFO - PROCESS | 1941 | Values: 197.0 203.0 197.0 200.0 202.0 200.0 203.0 194.0 199.0 198.0 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#3] audio-fft Cycles:10 Average:73.20 Median:76.00 stddev:13.54 (17.8%) stddev-sans-first:14.20 08:22:12 INFO - PROCESS | 1941 | Values: 79.0 60.0 60.0 92.0 92.0 88.0 61.0 65.0 73.0 62.0 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#4] audio-oscillator Cycles:10 Average:83.70 Median:77.00 stddev:16.86 (21.9%) stddev-sans-first:17.86 08:22:12 INFO - PROCESS | 1941 | Values: 86.0 65.0 120.0 102.0 87.0 71.0 83.0 71.0 69.0 83.0 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:88.50 stddev:5.60 (6.3%) stddev-sans-first:5.94 08:22:12 INFO - PROCESS | 1941 | Values: 90.0 88.0 89.0 88.0 88.0 89.0 87.0 90.0 106.0 88.0 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#6] imaging-darkroom Cycles:10 Average:108.30 Median:96.00 stddev:19.97 (20.8%) stddev-sans-first:21.18 08:22:12 INFO - PROCESS | 1941 | Values: 108.0 107.0 96.0 97.0 97.0 131.0 156.0 96.0 96.0 99.0 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#7] imaging-desaturate Cycles:10 Average:103.60 Median:90.50 stddev:21.86 (24.2%) stddev-sans-first:22.71 08:22:12 INFO - PROCESS | 1941 | Values: 91.0 91.0 91.0 108.0 78.0 126.0 149.0 120.0 92.0 90.0 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#8] json-parse-financial Cycles:10 Average:66.10 Median:66.50 stddev:2.47 (3.7%) stddev-sans-first:2.62 08:22:12 INFO - PROCESS | 1941 | Values: 66.0 64.0 64.0 64.0 69.0 70.0 64.0 69.0 64.0 67.0 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:75.00 stddev:12.10 (16.1%) stddev-sans-first:11.78 08:22:12 INFO - PROCESS | 1941 | Values: 57.0 67.0 63.0 66.0 83.0 85.0 84.0 85.0 58.0 59.0 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:84.00 stddev:9.89 (11.8%) stddev-sans-first:10.28 08:22:12 INFO - PROCESS | 1941 | Values: 85.0 82.0 80.0 87.0 86.0 83.0 90.0 104.0 106.0 103.0 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:147.50 stddev:20.33 (13.8%) stddev-sans-first:21.46 08:22:12 INFO - PROCESS | 1941 | Values: 156.0 157.0 149.0 138.0 137.0 140.0 142.0 146.0 204.0 135.0 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.80 Median:174.00 stddev:30.44 (17.5%) stddev-sans-first:31.75 08:22:12 INFO - PROCESS | 1941 | Values: 171.0 172.0 174.0 235.0 231.0 170.0 167.0 151.0 223.0 174.0 08:22:12 INFO - PROCESS | 1941 | 08:22:12 INFO - PROCESS | 1941 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:73.50 stddev:10.64 (14.5%) stddev-sans-first:10.94 08:22:12 INFO - PROCESS | 1941 | Values: 67.0 66.0 64.0 73.0 92.0 89.0 86.0 74.0 67.0 66.0 08:22:12 INFO - PROCESS | 1941 | -------- Summary: end -------- 08:22:12 INFO - PROCESS | 1941 | 08:22:13 INFO - TEST-INFO | 1941: exit 0 08:22:13 INFO - TEST-OK | kraken | took 62087ms 08:22:13 INFO - SUITE-END | took 1179s 08:22:13 INFO - Completed test suite (00:19:39) 08:22:13 INFO - javascript benchmark 08:22:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7690.668399974869, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3197.792143510672, 3430.708389786868, 3444.586649602048, 3450.706689931356, 3463.1342164458883, 5987.520998320135, 5998.490128339091, 6020.217371741673, 6027.306859711134, 6047.425494578008, 5955.642661440316, 6026.035834266519, 6039.456448919596, 6046.3349031606485, 6071.3625069367745, 5350.224217488462, 5429.592780541475, 5443.482869127427, 5467.5625949923915, 5573.693446860421, 5509.228707980866, 5513.889722205554, 5521.917171242435, 5530.5022547970675, 5533.00405926935, 5294.311739473863, 5341.0653135701095, 5461.5357694596005, 5508.944910550883, 5510.834674959738, 5411.512963833255, 5412.161115027177, 5415.404305526408, 5464.398916119224, 5538.723063846832, 5296.25852380667, 5459.0, 5472.890542189174, 5476.315460567429, 5520.447955204488, 6033.306169790487, 6043.8186854394435, 6069.57512974092, 6089.543284253698, 6106.694665266719, 6209.813705588795, 6274.466670333027, 6283.6858157091965, 6294.968525157345, 6425.58233714807, 5277.577793776489, 6538.607683538955, 6546.214454265471, 6547.214334279869, 6574.605523668595, 6149.09239038636, 6205.720742566596, 6208.161898143693, 6210.1305817186085, 6239.500839932794, 6330.0, 6397.4562162216735, 6573.1783527059115, 6594.604323740591, 6662.500312476487, 4744.312074749142, 6239.78160764371, 6278.215223097113, 6280.434760871543, 6290.968545157337, 6449.129367535323, 6450.290468048511, 6460.3849037740565, 6477.4494167996245, 6512.902306465407, 6229.501639868799, 6362.872742545123, 6366.745330186741, 6418.0, 6438.098666186737, 6281.277653069864, 6292.748290068346, 6304.842378940563, 6442.8389290268115, 6458.22501299847, 4962.057209130254, 5005.1491246488185, 5031.371078615173, 5049.293098966147, 5164.251183578435, 4537.500874903758, 4566.70316429431, 4576.748278844696, 4613.054323863667, 4635.443746750411, 5997.100434934795, 6011.3086994995265, 6083.96572582662, 6093.8476538086015, 6107.938920610826, 6245.75016999324, 6251.062340648939, 6497.967510162419, 6529.412352888168, 6544.312847151075, 5947.286325640951, 5980.85047873798, 5983.880322393527, 5999.280086389661, 6042.305134909541, 5674.687892165888, 5684.63049901755, 5690.2033715279895, 5697.914531281951, 5699.031164702011, 6288.874222515433, 6718.059471674067, 6730.899036514456, 6738.225104113089, 6741.988701694785, 5604.355499117653, 5717.685527296038, 6275.717592708431, 6278.4349408551625, 6375.840603984938, 6654.467642588678, 6667.133272674521, 6680.0, 6683.198016238082, 6692.431143352869, 6308.053791931064, 6351.904721429275, 6361.681915904279, 6364.204474440695, 6398.5201109916015, 5623.1846382273525, 6169.383061693795, 6206.441420272197, 6228.377162283917, 6397.168368112302, 6277.424554490918, 6302.968485157545, 6310.400511951358, 6321.747130114928, 6322.525810564318, 5221.869453263699, 6306.684665766602, 6310.021946598285, 6323.810285691437, 6330.01884707871, 5436.565074793928, 5762.84743051383, 5995.370486098896, 5996.5502587305255, 6122.154209354628, 5124.5131712487255, 6188.1027251049245, 6286.308506064238, 6292.905606415999, 6301.6534090625455, 5555.500004999408, 5900.5574581907385, 6002.129691194665, 6255.812325630239, 6273.090401891792], "value": 5873.737417721367, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.594815007296, 756.4364548411435, 785.1520358013346, 960.4333443268474, 968.278632418848, 34032.31935361295, 37937.62062379375, 38664.0, 39319.820405387814, 39887.202255954864, 38777.8061109694, 38972.80513597428, 38973.41539876897, 38996.0, 39036.60963390365, 7702.691892324315, 7851.5681637509915, 10285.177185825154, 10588.3646981181, 10754.516046777913, 10485.0, 10545.261831671785, 10581.576736930514, 10587.258891877571, 10595.682129536126, 10556.577736890515, 10569.365838049704, 10640.733981650443, 10641.201909856793, 10668.35989840608, 10561.683149505527, 10577.259591828499, 10590.470476476185, 10597.62908298206, 10666.200034997368, 10336.793264134676, 10412.479376031244, 10412.583496660125, 10414.218933580012, 10423.374597524173, 24069.87965060181, 34131.31737365264, 34378.82810585956, 34453.48319775205, 36012.279754404764, 3169.31859650174, 3702.9444558331647, 3740.4389341598708, 3766.190269092131, 3920.647141757227, 24825.145277306936, 24991.0, 25620.615690764655, 26398.472030559282, 26408.86795566029, 2511.660925775015, 2754.7107553706774, 2867.4551835151256, 2890.0, 2921.6932222116798, 348015.0, 350476.49523504695, 357802.0, 358237.20881395426, 359155.0, 45578.316325255146, 45725.54274457279, 45770.771146144056, 45813.0, 45885.31172032422, 41447.13487893052, 47397.81505462391, 47602.80992975203, 47793.52206477891, 47811.0, 13061.477540898448, 13787.310634468236, 13838.792418113737, 13947.511837085856, 13976.720465590632, 11807.586734464252, 12050.457729402198, 12075.27548347102, 12084.879151208552, 12097.0, 9646.987066358071, 11933.105017123751, 11944.761104777856, 11966.58116965902, 12117.969972552431, 5580.804671836466, 5696.3449203341315, 5705.828825135252, 5788.5369170466265, 5802.854928626818, 1009.9798004039878, 1058.9309461900368, 1102.8125218712678, 1120.5517792882913, 1185.8458400407892, 23235.07059717626, 26405.735942640327, 26938.0, 26951.326216844343, 27090.5936410954, 979.5836769372988, 1051.8842927277992, 1211.8061110222498, 1241.5530409052733, 1250.9562165324353, 9059.873167730177, 14857.331420086122, 15723.0, 15955.441559545361, 16262.430814921421, 494737.0, 495360.5231973817, 499266.0, 499297.0, 499807.0, 49209.50790492121, 49696.509104726916, 56167.43832561704, 56333.0, 56643.43356566464, 1465.8754005909404, 1485.7251408489465, 1490.9925450372896, 1500.699860027977, 1503.3911265937313, 28667.713322866504, 30194.2451438707, 30571.0, 30717.23206919845, 30850.382992340477, 48737.75631121822, 48835.02329953452, 49016.0, 49096.0, 49293.75353123211, 365938.0, 375361.0, 434870.0, 435520.0, 438566.0, 35854.64145358513, 36308.81845590755, 36501.45247821338, 36594.0, 36596.0, 44085.55914440943, 44407.0, 45239.77380113078, 45779.54220457886, 45782.85542861455, 30256.54615180818, 30431.695683042886, 30621.23446913845, 30713.0, 31767.205819853763, 30464.390712186076, 30897.53653695241, 30964.84517577398, 31427.214319642193, 31997.360052798347, 36136.0, 37252.0, 37341.81329093337, 37349.6265037357, 37379.0, 26705.599416009165, 28946.27634309159, 29475.263118421353, 30122.49387530571, 31355.0, 22582.66126008144, 22667.093316267164, 23192.536149277257, 23198.652020220048, 23339.76660233376], "value": 18542.03292213905, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.30236097872574, 409.0816117815503, 409.30213985155314, 417.4760675352432, 447.5345640533845, 16889.324427022933, 20209.191632334707, 20673.896630516825, 21315.786842131572, 21608.24371147008, 21513.784862151413, 21584.0, 21788.0, 21845.0, 22164.445888852788, 6519.315471875444, 6823.488238382116, 6828.214755303129, 6872.862542749142, 6882.002109694091, 6469.1590093287805, 6674.232463266713, 6692.263850976388, 6721.8319542011595, 6723.0, 6705.597664140143, 6716.1269035025625, 6720.764773232938, 6725.529212955095, 6743.5953842769595, 7023.7541686040995, 7359.300866417681, 7509.173990861001, 7512.661930213154, 7520.5487670739385, 6092.0, 7094.71621135154, 7291.854162916712, 7333.0, 7351.117865856078, 25650.0, 25953.22140335793, 26330.0, 26500.86749566259, 26633.0, 2852.8288302701903, 2958.156925276286, 2972.5689774982725, 2976.538636511344, 2979.746721528673, 16011.19944002807, 16073.276702548297, 16498.67002659952, 16510.42213522521, 17150.399736009305, 1673.280489389562, 2017.5057111007898, 2028.0, 2031.0, 2056.50643845476, 156750.0, 173500.1324993367, 179579.10210448995, 181057.0, 181555.0, 20922.953852307324, 21200.681989770164, 21716.50260220063, 21816.672749908765, 21868.0, 32352.67647323544, 32698.0, 33225.0, 33331.333373332396, 33396.499052514235, 10212.438315892698, 10349.275550711529, 10523.84214236787, 10576.101031412416, 10578.57685692579, 8734.475931444134, 8762.956185219033, 8774.25418839393, 8896.59965301563, 8901.465912045298, 9763.170130538983, 9798.0, 9806.950965245129, 9942.005799420145, 9979.451130187765, 3260.3316320154313, 3595.964040359563, 4208.516020657602, 4305.794377574284, 4329.19909816685, 898.925783688499, 994.7314225159166, 1036.7356324137297, 1064.3932958213898, 1101.3502033800096, 15449.459268925533, 19130.660853740384, 20575.58848823015, 21021.264255750975, 21279.78720212809, 956.9043095690375, 1067.8611780468489, 1083.4528563075596, 1103.25530267071, 1107.7895199912152, 10171.745706357253, 10997.6150834722, 11021.944890275658, 11037.724056898642, 11039.282446640942, 725267.0, 738424.0, 743414.0, 751935.0, 759874.0, 115792.0, 149128.50871491362, 159692.0, 163676.0, 167540.0, 1173.7359094203737, 1261.4638778519093, 1277.150694787974, 1277.4251586785983, 1311.8294621699308, 17533.912330438267, 18251.634967300844, 18481.630367392307, 18509.907450462662, 18565.0, 64211.35788642054, 64800.0, 65497.36256593623, 65517.0, 66402.00396993953, 91339.88474431238, 91543.0, 91648.54175729079, 93072.53463732637, 94161.0, 11256.099512039182, 11301.886981130083, 11330.490127944098, 11339.773204536028, 11723.534558180227, 12378.566750163702, 12678.619641410773, 12835.0, 12846.678833028875, 12871.0, 10699.8930010702, 10733.624323148964, 10795.892041079804, 10931.945340273247, 10970.945145274223, 9979.900200997898, 10089.949550252202, 10236.181105511392, 10304.381737095799, 10310.484475776331, 10871.29336593119, 10977.506012229629, 11080.501377438195, 11331.943340283246, 11345.751967283757, 11289.37908415045, 11374.772504550028, 11618.0, 11646.94176529112, 11863.406829658656, 10983.0, 11060.225784195029, 11229.157813164209, 11259.211855170059, 11284.548618055185], "value": 11667.92116618077, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1412.5409241996351, 1485.1089346392173, 1508.645468314946, 1526.1987456585284, 1530.6249968757656, 10715.035646791786, 11163.83254251187, 11309.15181361398, 11465.025472834819, 11487.770244595104, 9473.763155921088, 11220.551177952872, 11406.54373825048, 11408.372539510323, 11432.771344573104, 2432.622943443769, 2467.6551279552596, 2469.7283298837115, 2470.6170543565777, 2473.3445636906235, 2357.3244021224846, 2413.9637905431346, 2451.497443024176, 2456.3981824453035, 2457.4347899983013, 2381.2856143156982, 2418.1294733895784, 2422.806175505964, 2463.4949835283815, 2468.4816188600416, 2385.4155731845635, 2428.5992811186134, 2439.621858611918, 2441.56051910656, 2449.2040086971806, 2413.553492603874, 2433.2578563538113, 2438.5001074779807, 2450.1057114153205, 2453.0555736041574, 11119.666410007712, 11313.773724525463, 11370.147238957112, 11378.60174893883, 11397.202195846294, 4802.4237091548885, 5799.18811366409, 5873.765049398019, 5983.940160598426, 5985.16207730918, 3402.659734026602, 3449.6722811332884, 3491.3366460372704, 3586.5158203642436, 3635.581908080578, 1157.2593540134321, 1164.895159435655, 1169.2224670594044, 1188.2335893348827, 1211.4548453196096, 13391.665208369772, 13542.661433464144, 15187.240637968167, 15518.0, 15638.921805391014, 4033.274010678106, 4666.859994200179, 4749.168895443284, 4888.242322440027, 4910.607151427877, 11816.231944923553, 11838.171328006956, 11846.644600661994, 11874.406279685982, 11874.940625296818, 4831.565159135695, 5834.416558344216, 5889.882202356014, 5892.027815410495, 5916.467517923407, 4354.911272181955, 4731.432228132646, 5251.317328747238, 5323.095073837457, 5342.0, 4018.7990600470443, 4041.7574945503366, 4051.736637118578, 4140.37894315855, 4140.648044916155, 977.6285011695652, 983.090641156927, 989.5645915797027, 994.1450352696675, 994.9900500995042, 828.8093738440192, 914.9085091490798, 924.9676261330956, 938.6010945348199, 946.2477330522114, 6328.398802113792, 6390.680465976682, 6537.182852143482, 6544.247411547733, 6653.567518111307, 860.3117505995178, 973.4597298499259, 1021.6628512590827, 1027.4092396871679, 1032.2877214721818, 3412.1502694716114, 4104.548499665035, 4229.661627069827, 4338.132373525307, 4381.496127945264, 13676.863231367757, 14259.857401426061, 14270.0, 14273.78589321161, 14310.427582896777, 13664.795028074586, 13887.0, 14176.85823141776, 14253.287335633177, 14273.072250303698, 8493.660253589787, 10238.641647542301, 10349.275550711378, 10357.378557286587, 10389.896101039045, 5927.911081333697, 6497.837554061186, 6523.445507131947, 6525.282218956006, 6674.799756007327, 10615.787684246427, 12132.818007730068, 12238.571649992207, 12326.938365308117, 12332.938335308267, 11488.965993060665, 12518.0, 12525.686857828263, 12754.87245127574, 12819.551315703906, 7019.894701579583, 8699.478031318142, 8707.259882910024, 8732.301415886841, 8821.426607270507, 10220.97380145787, 10291.22815788804, 10324.019218174262, 10383.94808025955, 10487.790244195226, 8678.609462574326, 8793.780155495908, 8877.778055548662, 8880.200781929658, 8906.421082629608, 7014.964925175545, 8283.420160588701, 8334.583270836556, 8379.95810020946, 8392.580370981548, 7950.64222110018, 8031.076426211061, 8032.357411407191, 8069.47548409375, 8138.267555919995, 5865.589408741518, 5879.764809407576, 5895.0, 6074.0, 6093.086037094471, 5921.733521991435, 5967.254093238345, 6034.607750496204, 6064.120702498201, 6231.75072997075], "value": 5108.569708083097, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.75176755689904, 370.21329674441813, 372.46365234062966, 387.66467006039784, 389.9025243689077, 4585.6560757943125, 5172.250023746564, 5311.229871668611, 5447.210489000732, 5475.178723191523, 4614.831029416169, 5181.948180518203, 5254.842354729364, 5263.289455923459, 5321.201819727043, 3699.0395090602024, 4550.977245113778, 4584.358189853407, 4618.422697162855, 4647.302904564309, 3531.3579185678655, 4226.0, 4595.839145629903, 4624.838130665427, 4636.09596128755, 3541.3979623463943, 3795.356457800515, 3821.6942644588507, 4334.263175260197, 4366.995591014069, 4025.335819589764, 4349.716833534083, 4355.477342718862, 4366.406261714483, 4381.53993830648, 3243.659415761358, 3454.481827725836, 3513.8770143045126, 3826.827792749333, 4034.4755181826467, 4105.325733468529, 4262.509811371701, 4658.5775396793815, 4913.901721965576, 5010.498950104997, 4084.7957602119777, 4502.527234640381, 4681.32120842476, 4917.852464426073, 5086.287919691246, 4785.784639691222, 4940.789506570877, 5005.3721796057, 5562.610617256793, 5576.358718747356, 3967.504061992251, 4411.757353345564, 4542.4782697795945, 4835.806567737286, 4881.6338774591695, 5645.810117283721, 5744.511716504102, 5945.64326140433, 5965.403459654043, 6043.829757467333, 4128.422020917074, 4390.626796722315, 4499.910001800011, 5045.94035252601, 5091.516305950929, 4621.69958952667, 5180.740962951837, 5197.402298735618, 5204.76578553966, 5238.68567885928, 5074.908894587681, 5126.001718659451, 5282.498162674617, 5301.496357845998, 5394.78420863161, 4535.523770004168, 5080.441151473335, 5758.625689330264, 5780.364159942403, 5811.3316968548315, 1607.541850572581, 1783.4025601423668, 1786.0799640386635, 1788.9873891871644, 1793.4350679535905, 1279.4242590833976, 1348.3460521646994, 1405.4659229492727, 1441.978370324446, 1443.9205843678699, 4442.511758560919, 4457.732536047847, 4613.523672424859, 4850.175470170079, 5042.168042273057, 4707.31743897133, 5475.06923822951, 5562.745793710725, 5593.440655934374, 5726.5683579105225, 4569.794359253841, 4950.975245123751, 5014.774335154927, 5175.7153356564995, 5213.983273261678, 2950.2771820904018, 2996.2471953906775, 3008.172752493082, 3016.2308611304416, 3054.2975115723507, 5434.380776340079, 5663.631863928831, 5846.41535846412, 6017.669028203403, 6044.304904935989, 5252.111338763665, 5519.43346343952, 5769.0, 5935.40645935403, 5936.130119012497, 5023.623228257895, 5237.8428647140645, 5473.644213126134, 6005.68781019302, 6076.490292692011, 5060.661002642248, 5496.131315352799, 5758.452946970031, 5778.87311974161, 5819.748754017906, 4772.618190544678, 5139.89492259166, 5501.752421141138, 5712.314522257356, 5743.167240750151, 4977.253411988231, 5340.546053585489, 5408.215288954714, 5524.994379075139, 5903.594599859201, 4332.0, 4356.86721452431, 4985.177445721415, 5033.75322916724, 5057.291979122852, 4907.97546012282, 5656.60403771732, 5763.567732420001, 5779.884402312015, 5788.044972579477, 4870.9269360960325, 5063.645544811828, 5108.347531514984, 5213.08770965072, 5639.8698722209565, 5283.365996080495, 5582.748776304995, 5663.32040155184, 5666.065099258576, 5669.744861481161, 4841.927371089507, 5214.452482489284, 5313.654612450178, 5539.836634306841, 5611.971940140274, 4513.796879140381, 4894.853154405374, 5724.484796368347, 5766.798162064308, 5771.249737534094, 4893.828715994923, 4929.718273248909, 5294.97352513235, 5583.581231407579, 5590.329160500603], "value": 4315.752869270977, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.7228181009406, 721.7978965889546, 723.9131304243491, 791.3273717340262, 874.7332063720564, 13225.20648761075, 15110.168940708256, 15778.684426311467, 16391.4263000795, 16437.09595972221, 12875.806862897063, 12996.0, 13015.93492032541, 13027.80458293124, 13070.085094043421, 927.6196759328693, 961.4327546747418, 963.5519483440199, 963.9855402168973, 966.1256562810668, 920.3235621817959, 952.8427809411473, 960.1118964957421, 962.2206013129388, 963.7638778499278, 1005.140604782911, 1005.9949700251489, 1007.6573964851985, 1008.0725732326277, 1009.0514915978993, 987.8221920054388, 999.6351331763926, 1000.0599436529639, 1002.6891663584275, 1003.0471052500118, 746.4700062955309, 749.505326484523, 750.107372227048, 751.2337415835817, 751.6918063593955, 16032.599185020352, 16062.196890155445, 16368.672626547403, 16738.16309184536, 16775.161241937854, 14161.85838141626, 14363.281835908267, 14475.855241447556, 14501.274936253145, 14584.927075364662, 11555.0, 11590.884091159149, 11627.825582616268, 11841.644750657493, 12379.257244565264, 3362.159460134966, 3536.522569453117, 3709.8145092745526, 3756.5116534850567, 3799.9620003799882, 92844.0, 110747.9999999992, 116766.0, 121085.39457302746, 121351.0, 9284.907150928404, 9489.145976862115, 9564.947855735863, 9585.0, 9622.37454565451, 17850.821491785176, 18540.629187416445, 18551.90724046371, 18634.627307453775, 18674.719879201824, 7720.498167619086, 7754.9224507755325, 7775.727849525239, 7889.487183333065, 7968.482048666818, 3793.7534060285993, 4274.829006839754, 4327.76197309151, 4352.2818734909015, 4416.66874984371, 3454.5854497460464, 3500.0, 3501.9474707879403, 3533.0, 3623.7644553103964, 651.6806764685365, 652.8563715982476, 660.3891400454656, 662.9900551491731, 665.55075324156, 668.548729607513, 687.8280429892527, 690.2510775808204, 695.3672158335891, 714.6193831810965, 9873.555689993967, 10147.34042287249, 10225.693229203136, 10463.267571270091, 10559.366438013743, 670.1924181361447, 693.19935474527, 707.7416742888809, 714.3749219432996, 717.2218143314458, 4921.286413470027, 5073.289739436482, 5087.618428617869, 5087.974560127176, 5091.974540127349, 107613.0, 111031.0, 111241.0, 111721.0, 113959.43020285011, 88961.0, 89360.0, 91503.0, 92760.0, 93094.0, 60764.69617651883, 61812.381876181564, 79317.0, 81288.99999999882, 81350.59324703338, 8586.914130858611, 9018.774530636787, 9514.762130946505, 9798.706038818847, 9946.950265248628, 45469.77265113653, 45893.0, 48573.757131214115, 51216.74391628018, 51794.48205517897, 55231.619209518474, 71869.6406517964, 72021.27978720146, 72152.0, 73957.63021184859, 10989.61536346224, 11031.669049928514, 11135.109191264517, 11208.831867522156, 11550.364729939934, 12147.453364598437, 12439.440225190068, 12584.0, 12681.873181268069, 12957.963362931132, 9944.0, 11791.29252244868, 11843.170978031456, 11947.283163010246, 12326.383680816103, 11542.212694750624, 11906.940465297617, 11943.940280298542, 11949.940250298692, 12492.25046497213, 11496.425178741198, 11786.58746943853, 11805.881941180478, 12243.0, 12289.56986505468, 7417.554946703215, 8615.439996400213, 9013.369064165445, 9117.407368521024, 9250.167484926389, 8883.466991980244, 8927.732168034969, 9879.753006174904, 9942.850857237292, 9953.402795832273], "value": 7384.5334133708575, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1557.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 183.0, 135.0, 123.0, 123.0, 120.0, 120.0, 130.0, 148.0, 120.0], "value": 135.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 105.0, 115.0, 146.0, 116.0, 109.0, 112.0, 125.0, 159.0, 126.0], "value": 124.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 203.0, 197.0, 200.0, 202.0, 200.0, 203.0, 194.0, 199.0, 198.0], "value": 199.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 60.0, 92.0, 92.0, 88.0, 61.0, 65.0, 73.0, 62.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 65.0, 120.0, 102.0, 87.0, 71.0, 83.0, 71.0, 69.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 88.0, 88.0, 89.0, 87.0, 90.0, 106.0, 88.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 107.0, 96.0, 97.0, 97.0, 131.0, 156.0, 96.0, 96.0, 99.0], "value": 108.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 108.0, 78.0, 126.0, 149.0, 120.0, 92.0, 90.0], "value": 103.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 64.0, 69.0, 70.0, 64.0, 69.0, 64.0, 67.0], "value": 66.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 67.0, 63.0, 66.0, 83.0, 85.0, 84.0, 85.0, 58.0, 59.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 80.0, 87.0, 86.0, 83.0, 90.0, 104.0, 106.0, 103.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 157.0, 149.0, 138.0, 137.0, 140.0, 142.0, 146.0, 204.0, 135.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 174.0, 235.0, 231.0, 170.0, 167.0, 151.0, 223.0, 174.0], "value": 186.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 64.0, 73.0, 92.0, 89.0, 86.0, 74.0, 67.0, 66.0], "value": 74.4, "unit": "score"}]}]} 08:22:14 INFO - Return code: 0 08:22:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:22:14 ERROR - # TBPL SUCCESS # 08:22:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:22:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:22:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:22:14 INFO - 2017-04-06 08:22:14.144 screenresolution[1946:20000] starting screenresolution argv=screenresolution get 08:22:14 INFO - 2017-04-06 08:22:14.146 screenresolution[1946:20000] Display 0: 1600x1200x32@60 08:22:14 INFO - 2017-04-06 08:22:14.153 screenresolution[1947:20003] starting screenresolution argv=screenresolution list 08:22:14 INFO - Available Modes on Display 0 08:22:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:22:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:22:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:22:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:22:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:22:14 INFO - Intel Iris: 08:22:14 INFO - Chipset Model: Intel Iris 08:22:14 INFO - Type: GPU 08:22:14 INFO - Bus: Built-In 08:22:14 INFO - VRAM (Dynamic, Max): 1536 MB 08:22:14 INFO - Vendor: Intel (0x8086) 08:22:14 INFO - Device ID: 0x0a2e 08:22:14 INFO - Revision ID: 0x0009 08:22:14 INFO - Displays: 08:22:14 INFO - Display: 08:22:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:22:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:22:14 INFO - Display Serial Number: 1600x1200 60 08:22:14 INFO - Main Display: Yes 08:22:14 INFO - Mirror: Off 08:22:14 INFO - Online: Yes 08:22:14 INFO - Rotation: Supported 08:22:14 INFO - Return code: 0 08:22:14 INFO - Running post-action listener: _resource_record_post_action 08:22:14 INFO - [mozharness: 2017-04-06 15:22:14.387809Z] Finished run-tests step (success) 08:22:14 INFO - Running post-run listener: _resource_record_post_run 08:22:14 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 655360; Write bytes: 514005504; Read time: 7; Write time: 1647 08:22:14 INFO - TinderboxPrint: CPU usage
54.5% 08:22:14 INFO - TinderboxPrint: I/O read bytes / time
655,360 / 7 08:22:14 INFO - TinderboxPrint: I/O write bytes / time
514,005,504 / 1,647 08:22:14 INFO - TinderboxPrint: CPU idle
2,182.6 (45.5%) 08:22:14 INFO - TinderboxPrint: CPU system
963.0 (20.1%) 08:22:14 INFO - TinderboxPrint: CPU user
1,656.0 (34.5%) 08:22:14 INFO - TinderboxPrint: Swap in / out
208,470,016 / 0 08:22:14 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205711872; Write bytes: 202520576; Read time: 15840; Write time: 448 08:22:14 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 655360; Write bytes: 300499456; Read time: 7; Write time: 1157 08:22:14 INFO - Running post-run listener: _upload_blobber_files 08:22:14 INFO - Blob upload gear active. 08:22:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:22:14 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 08:22:14 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'] 08:22:14 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 08:22:15 INFO - (blobuploader) - INFO - Open directory for files ... 08:22:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:22:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:22:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:16 INFO - (blobuploader) - INFO - Done attempting. 08:22:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:22:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:22:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:17 INFO - (blobuploader) - INFO - Done attempting. 08:22:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:22:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:22:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:18 INFO - (blobuploader) - INFO - Done attempting. 08:22:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:22:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:22:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:20 INFO - (blobuploader) - INFO - Done attempting. 08:22:20 INFO - (blobuploader) - INFO - Iteration through files over. 08:22:20 INFO - Return code: 0 08:22:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:22:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:22:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/463680925f2889a25336ff85e8bcd77551380c3bfabe64c79c069a60cd7f53bb70c269d24bede070ebd3b23046dff18121d2d49564f82be3b6b385c210526d70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/623a6d83992d58a40174c24d459b37a53844ca652d6156f086d11af3eb72d3d5a68b22de2677501eb7e106ef3f6d896e413f4a1553dde1d604b517ddb2ea0af3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37151c7ec449aff914853bd71a97bae4e1b624f9cca8a4cc70d3668bea182ca2b42a337f5c5ec6359979f7fc8c3a9b2d0012b3d5a458ce463eb9845bf1523a7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0de650b355d10ed4d9bafe598185263c17e5e908f169dd316b6d4551e3153239f9c164ba04699413314d74057da615b8a866957fc09709a328ecc017a91cf348"} 08:22:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:22:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:22:20 INFO - Contents: 08:22:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/463680925f2889a25336ff85e8bcd77551380c3bfabe64c79c069a60cd7f53bb70c269d24bede070ebd3b23046dff18121d2d49564f82be3b6b385c210526d70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/623a6d83992d58a40174c24d459b37a53844ca652d6156f086d11af3eb72d3d5a68b22de2677501eb7e106ef3f6d896e413f4a1553dde1d604b517ddb2ea0af3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37151c7ec449aff914853bd71a97bae4e1b624f9cca8a4cc70d3668bea182ca2b42a337f5c5ec6359979f7fc8c3a9b2d0012b3d5a458ce463eb9845bf1523a7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0de650b355d10ed4d9bafe598185263c17e5e908f169dd316b6d4551e3153239f9c164ba04699413314d74057da615b8a866957fc09709a328ecc017a91cf348"} 08:22:20 INFO - Running post-run listener: copy_logs_to_upload_dir 08:22:20 INFO - Copying logs to upload dir... 08:22:20 INFO - mkdir: /builds/slave/test/build/upload/logs 08:22:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.351823 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-06 08:22:20.280059) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 08:22:20.283728) ========= 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.sBViLxpXA9/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.SyLQsAb5pG/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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/463680925f2889a25336ff85e8bcd77551380c3bfabe64c79c069a60cd7f53bb70c269d24bede070ebd3b23046dff18121d2d49564f82be3b6b385c210526d70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/623a6d83992d58a40174c24d459b37a53844ca652d6156f086d11af3eb72d3d5a68b22de2677501eb7e106ef3f6d896e413f4a1553dde1d604b517ddb2ea0af3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37151c7ec449aff914853bd71a97bae4e1b624f9cca8a4cc70d3668bea182ca2b42a337f5c5ec6359979f7fc8c3a9b2d0012b3d5a458ce463eb9845bf1523a7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0de650b355d10ed4d9bafe598185263c17e5e908f169dd316b6d4551e3153239f9c164ba04699413314d74057da615b8a866957fc09709a328ecc017a91cf348"} build_url:https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011265 build_url: 'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/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/463680925f2889a25336ff85e8bcd77551380c3bfabe64c79c069a60cd7f53bb70c269d24bede070ebd3b23046dff18121d2d49564f82be3b6b385c210526d70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/623a6d83992d58a40174c24d459b37a53844ca652d6156f086d11af3eb72d3d5a68b22de2677501eb7e106ef3f6d896e413f4a1553dde1d604b517ddb2ea0af3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/37151c7ec449aff914853bd71a97bae4e1b624f9cca8a4cc70d3668bea182ca2b42a337f5c5ec6359979f7fc8c3a9b2d0012b3d5a458ce463eb9845bf1523a7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0de650b355d10ed4d9bafe598185263c17e5e908f169dd316b6d4551e3153239f9c164ba04699413314d74057da615b8a866957fc09709a328ecc017a91cf348"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 08:22:20.323238) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:22:20.324021) ========= 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.sBViLxpXA9/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.SyLQsAb5pG/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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.005149 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:22:20.378518) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 08:22:20.379169) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 08:22:20.380091) ========= ========= Total master_lag: 0.20 =========