builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0129 starttime: 1491475921.83 results: success (0) buildid: 20170406021021 builduid: 229d938eed51444fa5cb385fa4494ac2 revision: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:01.829411) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:01.829904) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:01.830258) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:01.857151) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:01.857856) ========= 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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 03:52:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.4M=0.001s 2017-04-06 03:52:02 (10.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137241 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:02.020194) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:02.020631) ========= 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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.029283 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:02.071895) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-06 03:52:02.072285) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 --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 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 03:52:02,182 truncating revision to first 12 chars 2017-04-06 03:52:02,182 Setting DEBUG logging. 2017-04-06 03:52:02,182 attempt 1/10 2017-04-06 03:52:02,182 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/632a3c9e2b4b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 03:52:03,906 unpacking tar archive at: mozilla-aurora-632a3c9e2b4b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.997396 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-06 03:52:04.095610) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:04.095969) ========= script_repo_revision: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:04.096432) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:04.096769) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 03:52:04.117077) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-06 03:52:04.117548) ========= /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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 03:52:04 INFO - MultiFileLogger online at 20170406 03:52:04 in /builds/slave/test 03:52:04 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 03:52:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:52:04 INFO - {'append_to_log': False, 03:52:04 INFO - 'base_work_dir': '/builds/slave/test', 03:52:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:52:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:52:04 INFO - 'branch': 'Mozilla-Aurora', 03:52:04 INFO - 'buildbot_json_path': 'buildprops.json', 03:52:04 INFO - 'config_files': ('talos/mac_config.py',), 03:52:04 INFO - 'default_actions': ('clobber', 03:52:04 INFO - 'read-buildbot-config', 03:52:04 INFO - 'download-and-extract', 03:52:04 INFO - 'populate-webroot', 03:52:04 INFO - 'create-virtualenv', 03:52:04 INFO - 'install', 03:52:04 INFO - 'run-tests'), 03:52:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:52:04 INFO - 'download_minidump_stackwalk': True, 03:52:04 INFO - 'download_symbols': 'ondemand', 03:52:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:52:04 INFO - 'tooltool.py': '/tools/tooltool.py', 03:52:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:52:04 INFO - '/tools/misc-python/virtualenv.py')}, 03:52:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:52:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:52:04 INFO - 'gecko_profile': False, 03:52:04 INFO - 'gecko_profile_interval': 0, 03:52:04 INFO - 'installer_path': 'installer.exe', 03:52:04 INFO - 'log_level': 'info', 03:52:04 INFO - 'log_name': 'talos', 03:52:04 INFO - 'log_to_console': True, 03:52:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:52:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:52:04 INFO - 'opt_config_files': (), 03:52:04 INFO - 'pip_index': False, 03:52:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:52:04 INFO - 'cmd': ('bash', 03:52:04 INFO - '-c', 03:52:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:52:04 INFO - 'enabled': True, 03:52:04 INFO - 'halt_on_failure': False, 03:52:04 INFO - 'name': 'check_screen_resolution'},), 03:52:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:52:04 INFO - 'cmd': ('bash', 03:52:04 INFO - '-c', 03:52:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:52:04 INFO - 'enabled': True, 03:52:04 INFO - 'halt_on_failure': False, 03:52:04 INFO - 'name': 'check_screen_resolution'},), 03:52:04 INFO - 'run_cmd_checks_enabled': True, 03:52:04 INFO - 'suite': 'dromaeojs-e10s', 03:52:04 INFO - 'system_bits': '32', 03:52:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:52:04 INFO - 'title': 't-yosemite-r7-0129', 03:52:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:52:04 INFO - 'use_talos_json': True, 03:52:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:52:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:52:04 INFO - 'work_dir': 'build'} 03:52:04 INFO - [mozharness: 2017-04-06 10:52:04.335624Z] Running clobber step. 03:52:04 INFO - Running pre-action listener: _resource_record_pre_action 03:52:04 INFO - Running main action method: clobber 03:52:04 INFO - rmtree: /builds/slave/test/build 03:52:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:52:06 INFO - Running post-action listener: _resource_record_post_action 03:52:06 INFO - [mozharness: 2017-04-06 10:52:06.879242Z] Finished clobber step (success) 03:52:06 INFO - [mozharness: 2017-04-06 10:52:06.879372Z] Running read-buildbot-config step. 03:52:06 INFO - Running pre-action listener: _resource_record_pre_action 03:52:06 INFO - Running main action method: read_buildbot_config 03:52:06 INFO - Using buildbot properties: 03:52:06 INFO - { 03:52:06 INFO - "project": "", 03:52:06 INFO - "product": "firefox", 03:52:06 INFO - "script_repo_revision": "production", 03:52:06 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:52:06 INFO - "repository": "", 03:52:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:52:06 INFO - "buildid": "20170406021021", 03:52:06 INFO - "pgo_build": "False", 03:52:06 INFO - "basedir": "/builds/slave/test", 03:52:06 INFO - "buildnumber": 10, 03:52:06 INFO - "slavename": "t-yosemite-r7-0129", 03:52:06 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:52:06 INFO - "platform": "yosemite_r7", 03:52:06 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:52:06 INFO - "branch": "mozilla-aurora", 03:52:06 INFO - "repo_path": "releases/mozilla-aurora", 03:52:06 INFO - "revision": "632a3c9e2b4b0dda89ca5924c616568af8c0dd85", 03:52:06 INFO - "stage_platform": "macosx64", 03:52:06 INFO - "builduid": "229d938eed51444fa5cb385fa4494ac2", 03:52:06 INFO - "slavebuilddir": "test" 03:52:06 INFO - } 03:52:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 03:52:06 INFO - Running post-action listener: _resource_record_post_action 03:52:06 INFO - [mozharness: 2017-04-06 10:52:06.882697Z] Finished read-buildbot-config step (success) 03:52:06 INFO - [mozharness: 2017-04-06 10:52:06.882828Z] Running download-and-extract step. 03:52:06 INFO - Running pre-action listener: _resource_record_pre_action 03:52:06 INFO - Running main action method: download_and_extract 03:52:06 INFO - mkdir: /builds/slave/test/build/tests 03:52:06 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')]} 03:52:06 INFO - trying https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 03:52:06 INFO - Downloading https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/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 03:52:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/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 03:52:07 INFO - Downloaded 1199 bytes. 03:52:07 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 03:52:07 INFO - Using the following test package requirements: 03:52:07 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 03:52:07 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:07 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 03:52:07 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:07 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 03:52:07 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:07 INFO - u'jsshell-mac.zip'], 03:52:07 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:07 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 03:52:07 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 03:52:07 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:07 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 03:52:07 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:07 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 03:52:07 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:07 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 03:52:07 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 03:52:07 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 03:52:07 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:52:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 03:52:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:52:07 INFO - Fetch https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 03:52:09 INFO - Expected file size: 51792994 03:52:09 INFO - Obtained file size: 51792994 03:52:25 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 03:52:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 03:52:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:52:25 INFO - Fetch https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 03:52:27 INFO - Expected file size: 51792994 03:52:27 INFO - Obtained file size: 51792994 03:52:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 03:52:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:52:42 INFO - Fetch https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 03:52:43 INFO - Expected file size: 14404438 03:52:43 INFO - Obtained file size: 14404438 03:52: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')]} 03:52:44 INFO - trying https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 03:52:44 INFO - Downloading https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 03:52:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/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 03:52:46 INFO - Downloaded 74672956 bytes. 03:52:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 03:52:46 INFO - mkdir: /builds/slave/test/properties 03:52:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:52:46 INFO - Writing to file /builds/slave/test/properties/build_url 03:52:46 INFO - Contents: 03:52:46 INFO - build_url:https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 03:52:47 INFO - Running post-action listener: _resource_record_post_action 03:52:47 INFO - Running post-action listener: set_extra_try_arguments 03:52:47 INFO - [mozharness: 2017-04-06 10:52:47.655317Z] Finished download-and-extract step (success) 03:52:47 INFO - [mozharness: 2017-04-06 10:52:47.655451Z] Running populate-webroot step. 03:52:47 INFO - Running pre-action listener: _resource_record_pre_action 03:52:47 INFO - Running main action method: populate_webroot 03:52:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:52:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:52:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:52:47 INFO - '--tppagecycles', 03:52:47 INFO - '7'], 03:52:47 INFO - 'tests': ['tsvgm']}}, 03:52:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:52:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:52:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:52:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:52:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:52:47 INFO - 'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:52:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:52:47 INFO - 'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['damp', 'tps']}, 03:52:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:52:47 INFO - 'tests': ['damp', 'tps']}, 03:52:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['dromaeo_dom']}, 03:52:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:52:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['basic_compositor_video']}, 03:52:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:52:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['a11yr', 03:52:47 INFO - 'ts_paint', 03:52:47 INFO - 'tpaint', 03:52:47 INFO - 'sessionrestore', 03:52:47 INFO - 'sessionrestore_no_auto_restore', 03:52:47 INFO - 'tabpaint']}, 03:52:47 INFO - 'other-e10s': {'tests': ['a11yr', 03:52:47 INFO - 'ts_paint', 03:52:47 INFO - 'tpaint', 03:52:47 INFO - 'sessionrestore', 03:52:47 INFO - 'sessionrestore_no_auto_restore', 03:52:47 INFO - 'tabpaint']}, 03:52:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:52:47 INFO - 'ts_paint', 03:52:47 INFO - 'tpaint', 03:52:47 INFO - 'sessionrestore', 03:52:47 INFO - 'sessionrestore_no_auto_restore', 03:52:47 INFO - 'tabpaint']}, 03:52:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:52:47 INFO - 'ts_paint', 03:52:47 INFO - 'tpaint', 03:52:47 INFO - 'sessionrestore', 03:52:47 INFO - 'sessionrestore_no_auto_restore', 03:52:47 INFO - 'tabpaint']}, 03:52:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['a11yr', 03:52:47 INFO - 'ts_paint', 03:52:47 INFO - 'tpaint', 03:52:47 INFO - 'sessionrestore', 03:52:47 INFO - 'sessionrestore_no_auto_restore', 03:52:47 INFO - 'tabpaint']}, 03:52:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['a11yr', 03:52:47 INFO - 'ts_paint', 03:52:47 INFO - 'tpaint', 03:52:47 INFO - 'sessionrestore', 03:52:47 INFO - 'sessionrestore_no_auto_restore', 03:52:47 INFO - 'tabpaint']}, 03:52:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['tsvgx', 03:52:47 INFO - 'tsvgr_opacity', 03:52:47 INFO - 'tart', 03:52:47 INFO - 'tscrollx', 03:52:47 INFO - 'cart', 03:52:47 INFO - 'tsvg_static']}, 03:52:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:52:47 INFO - 'tsvgr_opacity', 03:52:47 INFO - 'tart', 03:52:47 INFO - 'tscrollx', 03:52:47 INFO - 'cart', 03:52:47 INFO - 'tsvg_static']}, 03:52:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:52:47 INFO - 'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['tp5o']}, 03:52:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:52:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:52:47 INFO - 'talos_options': ['--disable-e10s', 03:52:47 INFO - '--xperf_path', 03:52:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:47 INFO - 'tests': ['tp5n']}, 03:52:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:52:47 INFO - 'talos_options': ['--xperf_path', 03:52:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:47 INFO - 'tests': ['tp5n']}}, 03:52:47 INFO - 'talos.zip': {'path': '', 03:52:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:52:47 INFO - Running post-action listener: _resource_record_post_action 03:52:47 INFO - [mozharness: 2017-04-06 10:52:47.674311Z] Finished populate-webroot step (success) 03:52:47 INFO - [mozharness: 2017-04-06 10:52:47.674428Z] Running create-virtualenv step. 03:52:47 INFO - Running pre-action listener: _resource_record_pre_action 03:52:47 INFO - Running main action method: create_virtualenv 03:52:47 INFO - Creating virtualenv /builds/slave/test/build/venv 03:52:47 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 03:52:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:52:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:52:47 INFO - Using real prefix '/tools/python27' 03:52:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:52:48 INFO - Installing distribute.............................................................................................................................................................................................done. 03:52:51 INFO - Installing pip.................done. 03:52:51 INFO - Return code: 0 03:52:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:52:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:52:51 INFO - Reading from file tmpfile_stdout 03:52:51 INFO - Output received: 03:52:51 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) 03:52:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:52:51 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')]} 03:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:51 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')]} 03:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101f8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83684791f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d69d30>, '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 0x101c90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d5ebe8>, '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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 03:52:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:52:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:52:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wj2vpL0WBD/Render', 03:52:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:52:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:52:51 INFO - 'HOME': '/Users/cltbld', 03:52:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:52:51 INFO - 'LOGNAME': 'cltbld', 03:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:51 INFO - 'MOZ_NO_REMOTE': '1', 03:52:51 INFO - 'NO_EM_RESTART': '1', 03:52:51 INFO - 'PAGER': '/bin/cat', 03:52:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:51 INFO - 'PWD': '/builds/slave/test', 03:52:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:52:51 INFO - 'SHELL': '/bin/bash', 03:52:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f1T0HeW4ID/Listeners', 03:52:51 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 03:52:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:52:51 INFO - 'USER': 'cltbld', 03:52:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:52:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:51 INFO - 'XPC_FLAGS': '0x0', 03:52:51 INFO - 'XPC_SERVICE_NAME': '0', 03:52:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:51 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 03:52:51 INFO - Cleaning up... 03:52:51 INFO - Return code: 0 03:52:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:52:51 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')]} 03:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:51 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')]} 03:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101f8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83684791f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d69d30>, '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 0x101c90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d5ebe8>, '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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 03:52:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:52:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:52:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wj2vpL0WBD/Render', 03:52:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:52:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:52:51 INFO - 'HOME': '/Users/cltbld', 03:52:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:52:51 INFO - 'LOGNAME': 'cltbld', 03:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:51 INFO - 'MOZ_NO_REMOTE': '1', 03:52:51 INFO - 'NO_EM_RESTART': '1', 03:52:51 INFO - 'PAGER': '/bin/cat', 03:52:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:51 INFO - 'PWD': '/builds/slave/test', 03:52:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:52:51 INFO - 'SHELL': '/bin/bash', 03:52:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f1T0HeW4ID/Listeners', 03:52:51 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 03:52:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:52:51 INFO - 'USER': 'cltbld', 03:52:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:52:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:51 INFO - 'XPC_FLAGS': '0x0', 03:52:51 INFO - 'XPC_SERVICE_NAME': '0', 03:52:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:52 INFO - Downloading/unpacking psutil>=3.1.1 03:52:52 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 03:52:52 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 03:52:52 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 03:52:52 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 03:52:52 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 03:52:52 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 03:52:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:52:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:52:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:52:55 INFO - Installing collected packages: psutil 03:52:55 INFO - Running setup.py install for psutil 03:52:55 INFO - building 'psutil._psutil_osx' extension 03:52:55 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 03:52:56 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 03:52:56 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 03:52:56 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 03:52:56 INFO - building 'psutil._psutil_posix' extension 03:52:56 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 03:52:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:52:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:52:56 INFO - ^ 03:52:56 INFO - 1 warning generated. 03:52:56 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 03:52:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:52:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:52:56 INFO - Successfully installed psutil 03:52:56 INFO - Cleaning up... 03:52:56 INFO - Return code: 0 03:52:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:52:56 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')]} 03:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101f8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83684791f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d69d30>, '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 0x101c90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d5ebe8>, '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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 03:52: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:52: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 mozsystemmonitor==0.3 03:52:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wj2vpL0WBD/Render', 03:52:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:52:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:52:57 INFO - 'HOME': '/Users/cltbld', 03:52:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:52:57 INFO - 'LOGNAME': 'cltbld', 03:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:57 INFO - 'MOZ_NO_REMOTE': '1', 03:52:57 INFO - 'NO_EM_RESTART': '1', 03:52:57 INFO - 'PAGER': '/bin/cat', 03:52:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:57 INFO - 'PWD': '/builds/slave/test', 03:52:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:52:57 INFO - 'SHELL': '/bin/bash', 03:52:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f1T0HeW4ID/Listeners', 03:52:57 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 03:52:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:52:57 INFO - 'USER': 'cltbld', 03:52:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:52:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:57 INFO - 'XPC_FLAGS': '0x0', 03:52:57 INFO - 'XPC_SERVICE_NAME': '0', 03:52:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:53:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:53:01 INFO - Installing collected packages: mozsystemmonitor 03:53:01 INFO - Running setup.py install for mozsystemmonitor 03:53:01 INFO - Successfully installed mozsystemmonitor 03:53:01 INFO - Cleaning up... 03:53:01 INFO - Return code: 0 03:53:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:53:01 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')]} 03:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:01 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')]} 03:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:01 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 0x101f8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83684791f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d69d30>, '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 0x101c90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d5ebe8>, '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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 03:53:01 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 03:53:01 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 03:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wj2vpL0WBD/Render', 03:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:01 INFO - 'HOME': '/Users/cltbld', 03:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:01 INFO - 'LOGNAME': 'cltbld', 03:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:01 INFO - 'MOZ_NO_REMOTE': '1', 03:53:01 INFO - 'NO_EM_RESTART': '1', 03:53:01 INFO - 'PAGER': '/bin/cat', 03:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:01 INFO - 'PWD': '/builds/slave/test', 03:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:01 INFO - 'SHELL': '/bin/bash', 03:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f1T0HeW4ID/Listeners', 03:53:01 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 03:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:01 INFO - 'USER': 'cltbld', 03:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:01 INFO - 'XPC_FLAGS': '0x0', 03:53:01 INFO - 'XPC_SERVICE_NAME': '0', 03:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:01 INFO - Downloading/unpacking jsonschema==2.5.1 03:53: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 03:53: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 03:53: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 03:53: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 03:53: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 03:53: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 03:53:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:53:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:53:04 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 03:53:04 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 03:53:04 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 03:53:04 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 03:53:04 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 03:53:04 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 03:53:04 INFO - Downloading functools32-3.2.3-2.tar.gz 03:53:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:53:05 INFO - warning: no files found matching '*.txt' 03:53:05 INFO - no previously-included directories found matching 'build' 03:53:05 INFO - no previously-included directories found matching 'dist' 03:53:05 INFO - no previously-included directories found matching '.git*' 03:53:05 INFO - Installing collected packages: jsonschema, functools32 03:53:05 INFO - Running setup.py install for functools32 03:53:05 INFO - warning: no files found matching '*.txt' 03:53:05 INFO - no previously-included directories found matching 'build' 03:53:05 INFO - no previously-included directories found matching 'dist' 03:53:05 INFO - no previously-included directories found matching '.git*' 03:53:05 INFO - Successfully installed jsonschema functools32 03:53:05 INFO - Cleaning up... 03:53:05 INFO - Return code: 0 03:53:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:53:05 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')]} 03:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:05 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')]} 03:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:05 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 0x101f8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83684791f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d69d30>, '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 0x101c90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d5ebe8>, '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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 03:53:05 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 03:53:05 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 03:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wj2vpL0WBD/Render', 03:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:05 INFO - 'HOME': '/Users/cltbld', 03:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:05 INFO - 'LOGNAME': 'cltbld', 03:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:05 INFO - 'MOZ_NO_REMOTE': '1', 03:53:05 INFO - 'NO_EM_RESTART': '1', 03:53:05 INFO - 'PAGER': '/bin/cat', 03:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:05 INFO - 'PWD': '/builds/slave/test', 03:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:05 INFO - 'SHELL': '/bin/bash', 03:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f1T0HeW4ID/Listeners', 03:53:05 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 03:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:05 INFO - 'USER': 'cltbld', 03:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:05 INFO - 'XPC_FLAGS': '0x0', 03:53:05 INFO - 'XPC_SERVICE_NAME': '0', 03:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:53:05 INFO - Cleaning up... 03:53:05 INFO - Return code: 0 03:53:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:53:05 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')]} 03:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:05 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')]} 03:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:05 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 0x101f8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83684791f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d69d30>, '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 0x101c90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d5ebe8>, '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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 03:53:05 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 03:53:05 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 03:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wj2vpL0WBD/Render', 03:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:05 INFO - 'HOME': '/Users/cltbld', 03:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:05 INFO - 'LOGNAME': 'cltbld', 03:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:05 INFO - 'MOZ_NO_REMOTE': '1', 03:53:05 INFO - 'NO_EM_RESTART': '1', 03:53:05 INFO - 'PAGER': '/bin/cat', 03:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:05 INFO - 'PWD': '/builds/slave/test', 03:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:05 INFO - 'SHELL': '/bin/bash', 03:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f1T0HeW4ID/Listeners', 03:53:05 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 03:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:05 INFO - 'USER': 'cltbld', 03:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:05 INFO - 'XPC_FLAGS': '0x0', 03:53:05 INFO - 'XPC_SERVICE_NAME': '0', 03:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:05 INFO - Downloading/unpacking blobuploader==1.2.4 03:53:05 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 03:53:05 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 03:53:05 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 03:53:05 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 03:53:05 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 03:53:05 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 03:53:08 INFO - Downloading blobuploader-1.2.4.tar.gz 03:53:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:53:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:53:08 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 03:53:08 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 03:53:08 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 03:53:08 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 03:53:08 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 03:53:08 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 03:53:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:53:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:53: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 03:53: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 03:53: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 03:53: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 03:53: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 03:53: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 03:53:09 INFO - Downloading docopt-0.6.1.tar.gz 03:53:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:53:09 INFO - Installing collected packages: blobuploader, requests, docopt 03:53:09 INFO - Running setup.py install for blobuploader 03:53:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:53:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:53:10 INFO - Running setup.py install for requests 03:53:10 INFO - Running setup.py install for docopt 03:53:10 INFO - Successfully installed blobuploader requests docopt 03:53:10 INFO - Cleaning up... 03:53:10 INFO - Return code: 0 03:53:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:10 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 0x101f8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83684791f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d69d30>, '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 0x101c90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d5ebe8>, '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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 03:53:10 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 03:53:10 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 03:53:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wj2vpL0WBD/Render', 03:53:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:10 INFO - 'HOME': '/Users/cltbld', 03:53:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:10 INFO - 'LOGNAME': 'cltbld', 03:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:10 INFO - 'MOZ_NO_REMOTE': '1', 03:53:10 INFO - 'NO_EM_RESTART': '1', 03:53:10 INFO - 'PAGER': '/bin/cat', 03:53:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:10 INFO - 'PWD': '/builds/slave/test', 03:53:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:10 INFO - 'SHELL': '/bin/bash', 03:53:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f1T0HeW4ID/Listeners', 03:53:10 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 03:53:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:10 INFO - 'USER': 'cltbld', 03:53:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:10 INFO - 'XPC_FLAGS': '0x0', 03:53:10 INFO - 'XPC_SERVICE_NAME': '0', 03:53:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:53:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-0EokiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:53:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-gvFctb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:53:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-R4YQSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:53:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-XRTZK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:53:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-1i_wJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:53:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-c3iyRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:53:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Tx3Cnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:53:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-taV094-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:53:11 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-yqj5tG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:53:12 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-CqdKXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:53:12 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-xetbVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:53:12 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-iQ3tk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:53:12 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-ben6n0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:53:12 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-JUI9nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:12 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Qw95Vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:53:12 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-io7e_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:53:12 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Nx8cU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:53:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:53:12 INFO - Running setup.py install for manifestparser 03:53:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:53:12 INFO - Running setup.py install for mozcrash 03:53:13 INFO - Running setup.py install for mozdebug 03:53:13 INFO - Running setup.py install for mozdevice 03:53:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:53:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:53:13 INFO - Running setup.py install for mozfile 03:53:13 INFO - Running setup.py install for mozhttpd 03:53:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:53:13 INFO - Running setup.py install for mozinfo 03:53:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:53:13 INFO - Running setup.py install for mozInstall 03:53:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:53:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:53:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:53:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:53:14 INFO - Running setup.py install for mozleak 03:53:14 INFO - Running setup.py install for mozlog 03:53:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:53:14 INFO - Running setup.py install for moznetwork 03:53:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:53:14 INFO - Running setup.py install for mozprocess 03:53:14 INFO - Running setup.py install for mozprofile 03:53:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:53:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:53:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:53:14 INFO - Running setup.py install for mozrunner 03:53:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:53:14 INFO - Running setup.py install for mozscreenshot 03:53:15 INFO - Running setup.py install for moztest 03:53:15 INFO - Running setup.py install for mozversion 03:53:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:53:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:53:15 INFO - Cleaning up... 03:53:15 INFO - Return code: 0 03:53:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:53:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:15 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 0x101f8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83684791f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d69d30>, '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 0x101c90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d5ebe8>, '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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 03:53:15 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 03:53:15 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 03:53:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wj2vpL0WBD/Render', 03:53:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:15 INFO - 'HOME': '/Users/cltbld', 03:53:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:15 INFO - 'LOGNAME': 'cltbld', 03:53:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:15 INFO - 'MOZ_NO_REMOTE': '1', 03:53:15 INFO - 'NO_EM_RESTART': '1', 03:53:15 INFO - 'PAGER': '/bin/cat', 03:53:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:15 INFO - 'PWD': '/builds/slave/test', 03:53:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:15 INFO - 'SHELL': '/bin/bash', 03:53:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f1T0HeW4ID/Listeners', 03:53:15 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 03:53:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:15 INFO - 'USER': 'cltbld', 03:53:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:15 INFO - 'XPC_FLAGS': '0x0', 03:53:15 INFO - 'XPC_SERVICE_NAME': '0', 03:53:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:53:15 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-VoGA6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:53:15 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)) 03:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:53:15 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-7ELCCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:53:15 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)) 03:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:53:15 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-fx_STO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:53:16 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)) 03:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:53:16 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-vCEXCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:53:16 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)) 03:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:53:16 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-oGKlA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:53:16 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)) 03:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:53:16 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-j48NWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:53:16 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)) 03:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:53:16 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-7TUGEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:53:16 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)) 03:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:53:16 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-JgZawO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:53:16 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)) 03:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:53:16 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-zfsVjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:53:16 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)) 03:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:53:16 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-HkVrkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:53:16 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)) 03:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:53:16 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-0LU8tW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:53:16 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)) 03:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:53:16 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Iv2Apw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:53:16 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)) 03:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:53:17 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-5MkBA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:53:17 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)) 03:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:53:17 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-NPScC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:53:17 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)) 03:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:17 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-MT_gpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:17 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)) 03:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:53:17 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-15tI9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:53:17 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)) 03:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:53:17 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-91DrGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:53:17 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)) 03:53:17 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)) 03:53:17 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)) 03:53:17 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)) 03:53:17 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)) 03:53:17 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)) 03:53:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:53:17 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 03:53:17 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 03:53:17 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 03:53:17 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 03:53:17 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 03:53:17 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 03:53:20 INFO - Downloading blessings-1.6.tar.gz 03:53:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:53:20 INFO - Installing collected packages: blessings 03:53:20 INFO - Running setup.py install for blessings 03:53:20 INFO - Successfully installed blessings 03:53:20 INFO - Cleaning up... 03:53:20 INFO - Return code: 0 03:53:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:53:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:53:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:53:20 INFO - Reading from file tmpfile_stdout 03:53:20 INFO - Current package versions: 03:53:20 INFO - blessings == 1.6 03:53:20 INFO - blobuploader == 1.2.4 03:53:20 INFO - docopt == 0.6.1 03:53:20 INFO - functools32 == 3.2.3-2 03:53:20 INFO - jsonschema == 2.5.1 03:53:20 INFO - manifestparser == 1.1 03:53:20 INFO - mozInstall == 1.12 03:53:20 INFO - mozcrash == 1.0 03:53:20 INFO - mozdebug == 0.1 03:53:20 INFO - mozdevice == 0.49 03:53:20 INFO - mozfile == 1.2 03:53:20 INFO - mozhttpd == 0.7 03:53:20 INFO - mozinfo == 0.9 03:53:20 INFO - mozleak == 0.1 03:53:20 INFO - mozlog == 3.4 03:53:20 INFO - moznetwork == 0.27 03:53:20 INFO - mozprocess == 0.25 03:53:20 INFO - mozprofile == 0.28 03:53:20 INFO - mozrunner == 6.13 03:53:20 INFO - mozscreenshot == 0.1 03:53:20 INFO - mozsystemmonitor == 0.3 03:53:20 INFO - moztest == 0.8 03:53:20 INFO - mozversion == 1.4 03:53:20 INFO - psutil == 3.1.1 03:53:20 INFO - requests == 1.2.3 03:53:20 INFO - wsgiref == 0.1.2 03:53:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:53:20 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')]} 03:53:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:20 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')]} 03:53:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:20 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 0x101f8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83684791f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d69d30>, '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 0x101c90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d5ebe8>, '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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 03:53:20 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 03:53:20 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 03:53:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wj2vpL0WBD/Render', 03:53:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:20 INFO - 'HOME': '/Users/cltbld', 03:53:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:20 INFO - 'LOGNAME': 'cltbld', 03:53:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:20 INFO - 'MOZ_NO_REMOTE': '1', 03:53:20 INFO - 'NO_EM_RESTART': '1', 03:53:20 INFO - 'PAGER': '/bin/cat', 03:53:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:20 INFO - 'PWD': '/builds/slave/test', 03:53:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:20 INFO - 'SHELL': '/bin/bash', 03:53:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f1T0HeW4ID/Listeners', 03:53:20 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 03:53:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:20 INFO - 'USER': 'cltbld', 03:53:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:20 INFO - 'XPC_FLAGS': '0x0', 03:53:20 INFO - 'XPC_SERVICE_NAME': '0', 03:53:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:21 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)) 03:53:21 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)) 03:53:21 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)) 03:53:21 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)) 03:53:21 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)) 03:53:21 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)) 03:53:21 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)) 03:53:21 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)) 03:53:21 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)) 03:53:21 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)) 03:53:21 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)) 03:53:21 INFO - Cleaning up... 03:53:21 INFO - Return code: 0 03:53:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:53:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:21 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 0x101f8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83684791f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d69d30>, '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 0x101c90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d5ebe8>, '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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 03:53:21 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 03:53:21 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 03:53:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wj2vpL0WBD/Render', 03:53:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:21 INFO - 'HOME': '/Users/cltbld', 03:53:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:21 INFO - 'LOGNAME': 'cltbld', 03:53:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:21 INFO - 'MOZ_NO_REMOTE': '1', 03:53:21 INFO - 'NO_EM_RESTART': '1', 03:53:21 INFO - 'PAGER': '/bin/cat', 03:53:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:21 INFO - 'PWD': '/builds/slave/test', 03:53:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:21 INFO - 'SHELL': '/bin/bash', 03:53:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f1T0HeW4ID/Listeners', 03:53:21 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 03:53:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:21 INFO - 'USER': 'cltbld', 03:53:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:21 INFO - 'XPC_FLAGS': '0x0', 03:53:21 INFO - 'XPC_SERVICE_NAME': '0', 03:53:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:53:21 INFO - Cleaning up... 03:53:21 INFO - Return code: 0 03:53:21 INFO - Running post-action listener: _resource_record_post_action 03:53:21 INFO - Running post-action listener: _start_resource_monitoring 03:53:21 INFO - Starting resource monitoring. 03:53:21 INFO - [mozharness: 2017-04-06 10:53:21.340881Z] Finished create-virtualenv step (success) 03:53:21 INFO - [mozharness: 2017-04-06 10:53:21.341843Z] Running install step. 03:53:21 INFO - Running pre-action listener: _resource_record_pre_action 03:53:21 INFO - Running main action method: install 03:53:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:53:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:53:21 INFO - Reading from file tmpfile_stdout 03:53:21 INFO - Detecting whether we're running mozinstall >=1.0... 03:53:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:53:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:53:21 INFO - Reading from file tmpfile_stdout 03:53:21 INFO - Output received: 03:53:21 INFO - Usage: mozinstall [options] installer 03:53:21 INFO - Options: 03:53:21 INFO - -h, --help show this help message and exit 03:53:21 INFO - -d DEST, --destination=DEST 03:53:21 INFO - Directory to install application into. [default: 03:53:21 INFO - "/builds/slave/test"] 03:53:21 INFO - --app=APP Application being installed. [default: firefox] 03:53:21 INFO - mkdir: /builds/slave/test/build/application 03:53:21 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'] 03:53:21 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 03:53:41 INFO - Reading from file tmpfile_stdout 03:53:41 INFO - Output received: 03:53:41 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:53:41 INFO - Running post-action listener: _resource_record_post_action 03:53:41 INFO - [mozharness: 2017-04-06 10:53:41.668156Z] Finished install step (success) 03:53:41 INFO - [mozharness: 2017-04-06 10:53:41.668288Z] Running run-tests step. 03:53:41 INFO - Running pre-action listener: _resource_record_pre_action 03:53:41 INFO - Running main action method: run_tests 03:53:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:53:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:53:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:53:41 INFO - 2017-04-06 03:53:41.682 screenresolution[1627:7418] starting screenresolution argv=screenresolution get 03:53:41 INFO - 2017-04-06 03:53:41.685 screenresolution[1627:7418] Display 0: 1600x1200x32@60 03:53:41 INFO - 2017-04-06 03:53:41.691 screenresolution[1628:7421] starting screenresolution argv=screenresolution list 03:53:41 INFO - Available Modes on Display 0 03:53:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:53:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:53:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:53:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:53:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:53:41 INFO - Intel Iris: 03:53:41 INFO - Chipset Model: Intel Iris 03:53:41 INFO - Type: GPU 03:53:41 INFO - Bus: Built-In 03:53:41 INFO - VRAM (Dynamic, Max): 1536 MB 03:53:41 INFO - Vendor: Intel (0x8086) 03:53:41 INFO - Device ID: 0x0a2e 03:53:41 INFO - Revision ID: 0x0009 03:53:41 INFO - Displays: 03:53:41 INFO - Display: 03:53:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:53:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:53:41 INFO - Display Serial Number: 1600x1200 60 03:53:41 INFO - Main Display: Yes 03:53:41 INFO - Mirror: Off 03:53:41 INFO - Online: Yes 03:53:41 INFO - Rotation: Supported 03:53:41 INFO - Return code: 0 03:53:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:53:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:53:41 INFO - Python 2.7.3 03:53:41 INFO - Return code: 0 03:53:41 INFO - grabbing minidump binary from tooltool 03:53:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:41 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 0x101d69d30>, '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 0x101c90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d5ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:53:41 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 03:53:41 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 03:53:41 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 03:53:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:53:42 INFO - Return code: 0 03:53:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:53:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:53:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:53:42 INFO - ENV: RUST_BACKTRACE is now 1 03:53:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:53:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:53:42 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/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--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 03:53:42 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/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0129 --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 03:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wj2vpL0WBD/Render', 03:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:53:42 INFO - 'HOME': '/Users/cltbld', 03:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:53:42 INFO - 'LOGNAME': 'cltbld', 03:53:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:53:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:42 INFO - 'MOZ_NO_REMOTE': '1', 03:53:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:53:42 INFO - 'NO_EM_RESTART': '1', 03:53:42 INFO - 'PAGER': '/bin/cat', 03:53:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:42 INFO - 'PWD': '/builds/slave/test', 03:53:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:53:42 INFO - 'RUST_BACKTRACE': '1', 03:53:42 INFO - 'SHELL': '/bin/bash', 03:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f1T0HeW4ID/Listeners', 03:53:42 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 03:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:53:42 INFO - 'USER': 'cltbld', 03:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:42 INFO - 'XPC_FLAGS': '0x0', 03:53:42 INFO - 'XPC_SERVICE_NAME': '0', 03:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:53:42 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/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--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 03:53:42 INFO - mozversion application_buildid: 20170406021021 03:53:42 INFO - mozversion application_changeset: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 03:53:42 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:53:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:53:42 INFO - mozversion application_name: Firefox 03:53:42 INFO - mozversion application_remotingname: firefox-dev 03:53:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:53:42 INFO - mozversion application_vendor: Mozilla 03:53:42 INFO - mozversion application_version: 54.0a2 03:53:42 INFO - mozversion platform_buildid: 20170406021021 03:53:42 INFO - mozversion platform_changeset: 632a3c9e2b4b0dda89ca5924c616568af8c0dd85 03:53:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:53:42 INFO - mozversion platform_version: 54.0a2 03:53:42 INFO - using testdate: 1491476022 03:53:42 INFO - actual date: 1491476022 03:53:42 INFO - starting webserver on 'localhost:49240' 03:53:42 INFO - SUITE-START | Running 2 tests 03:53:42 INFO - TEST-START | dromaeo_css 03:53:42 INFO - operating with platform_type : mac_ 03:53:42 INFO - Initialising browser for dromaeo_css test... 03:53:42 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpqvm7wD/profile http://localhost:49240/getInfo.html) 03:53:45 INFO - PROCESS | 1638 | 2017-04-06 03:53:45.183 plugin-container[1640:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9037, name = 'com.apple.tsm.portname' 03:53:45 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:53:45 INFO - PROCESS | 1638 | 2017-04-06 03:53:45.186 plugin-container[1640:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 03:53:45 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:53:45 INFO - PROCESS | 1638 | 2017-04-06 03:53:45.186 plugin-container[1640:7598] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:53:45 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 03:53:45 INFO - PROCESS | 1638 | colorDepth:24 03:53:45 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 03:53:45 INFO - PROCESS | 1638 | __metrics 03:53:45 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 03:53:45 INFO - TEST-INFO | 1638: exit 0 03:53:45 INFO - Browser initialized. 03:53:45 INFO - Running cycle 1/1 for dromaeo_css test... 03:53:45 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpqvm7wD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:53:48 INFO - PROCESS | 1641 | 2017-04-06 03:53:48.731 plugin-container[1642:7733] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a47, name = 'com.apple.tsm.portname' 03:53:48 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:53:48 INFO - PROCESS | 1641 | 2017-04-06 03:53:48.731 plugin-container[1642:7733] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 03:53:48 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:53:48 INFO - PROCESS | 1641 | 2017-04-06 03:53:48.731 plugin-container[1642:7733] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:53:58 INFO - PROCESS | 1641 | RSS: Main: 221294592 03:53:58 INFO - PROCESS | 1641 | 03:57:02 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 03:57:03 INFO - PROCESS | 1641 | RSS: Main: 238915584 03:57:03 INFO - PROCESS | 1641 | 03:58:46 INFO - PROCESS | 1641 | ************************* 03:58:46 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 03:58:46 INFO - PROCESS | 1641 | 03:58:46 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:58:46 INFO - PROCESS | 1641 | Full stack: 03:58:46 INFO - PROCESS | 1641 | ************************* 03:58:46 INFO - PROCESS | 1641 | ************************* 03:58:46 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 03:58:46 INFO - PROCESS | 1641 | 03:58:46 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:58:46 INFO - PROCESS | 1641 | Full stack: 03:58:46 INFO - PROCESS | 1641 | ************************* 03:58:46 INFO - PROCESS | 1641 | ************************* 03:58:46 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 03:58:46 INFO - PROCESS | 1641 | 03:58:46 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:58:46 INFO - PROCESS | 1641 | Full stack: 03:58:46 INFO - PROCESS | 1641 | ************************* 03:58:46 INFO - PROCESS | 1641 | ************************* 03:58:46 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 03:58:46 INFO - PROCESS | 1641 | 03:58:46 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:58:46 INFO - PROCESS | 1641 | Full stack: 03:58:46 INFO - PROCESS | 1641 | ************************* 04:00:06 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 04:00:06 INFO - PROCESS | 1641 | RSS: Main: 247394304 04:00:06 INFO - PROCESS | 1641 | 04:03:08 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 04:03:09 INFO - PROCESS | 1641 | RSS: Main: 254861312 04:03:09 INFO - PROCESS | 1641 | 04:06:11 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 04:06:11 INFO - PROCESS | 1641 | RSS: Main: 251879424 04:06:11 INFO - PROCESS | 1641 | 04:09:15 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 04:09:15 INFO - PROCESS | 1641 | RSS: Main: 257019904 04:09:15 INFO - PROCESS | 1641 | 04:12:18 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 04:12:18 INFO - PROCESS | 1641 | RSS: Main: 253157376 04:12:18 INFO - PROCESS | 1641 | 04:12:18 INFO - PROCESS | 1641 | __start_tp_report 04:12:18 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 04:12:18 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 04:12:18 INFO - PROCESS | 1641 | |i|pagename|runs| 04:12:18 INFO - PROCESS | 1641 | |0;dojo.html;3405.5913290405138;3424.640412756662;3430.914227144321;3436.19249476373;3439.3121375724863;5437.619366644331;5865.824025279243;5925.437083477073;6062.545309101824;6071.60534565253;5983;5983.102534619809;6050.636961782297;6052.788152414666;6072.240969878765;4369.803358848843;4752.572268495835;5183.585313174937;5394.32570928634;5455.072637651589;5503.3120859892515;5506.174073888908;5508.614396992212;5508.7521061552325;5515.779368825242;4478.261086920655;5296.655717378378;5430.5927055471;5466.180072989044;5492.945070549304;5354.973225133869;5425.7287135643455;5429.4299098594865;5535.335759708861;5541.6674999499755;5320.787168513255;5442.945570544283;5448.91826622597;5477.096279113942;5477.260569823063;6033.9396606033815;6050;6135.055173516737;6158.56890017699;6177.011678131477;5126.359205099363;6170.660613666247;6303.991361382203;6318.87362252757;6319.24169099711;6398.424141827211;6485.513586481033;6492.383223593751;6513.413792758674;6546.803595892131;6150.938490615126;6162.96918515409;6169.074638804171;6176.567640265228;6200.038993955944;6316.399942005502;6389.744410223632;6511.446527045159;6587.703553340111;6644.335566443317;6214.689265536705;6267.122602835607;6295.811125666238;6299.401556852092;6347.206599175103;6450.838729031718;6462.32145624712;6467.2562655294305;6471.5146364022885;6486.643234622141;6126.724297406628;6294.402031806971;6324.620522768557;6381.8085457436355;6390.712417941204;6268.8119356418465;6291.5281353898645;6306.716197771111;6336.461400780893;6390.808275751735;5047.419546752097;5078.949210507922;5140.768665410065;5159.097157997336;5179.585633149339;4535.160995215896;4575.794089265924;4600.401947746772;4602.079584083197;4633.953660463352;5852.414758524199;5880;6050.2437195350585;6065.696715164224;6131.172291740558;6347.968260158762;6372.84067898297;6446.258680251737;6505.447037001908;6510.739570417225;6096.908546371808;6132.938670613326;6132.938670613326;6137.171481849982;6153.323134455206;5462.535684466785;5655.095184770417;5660.264165658438;5692.658440493584;5711.828645140652;5424.321959755031;6461.61230326182;6509.283978762238;6569.244536878129;6594.736210551524;6407.487401008001;6446.226452825691;6456.225252969673;6470.99699546571;6472.449841763503;6542.54202205841;6665;6682.46540276767;6696.096027036482;6697.799066028027;6186.288576813724;6261.874762504815;6281.842953926006;6283.842903927439;6333.20834895638;6387.105805187371;6424.10062591247;6448.516361272829;6455.67721613908;6459.224893012681;6124.958757011408;6256.749730010749;6292.590981586182;6296.937030629635;6309.305976342507;4559.77201139935;6117.553376797748;6166.691665416801;6219.378062193744;6336.398042185985;6113.658745237643;6173.969130154321;6276.9372306276355;6288.119663247057;6288.308286088435;5157.922631160461;5552.389237183826;6147.016477363601;6307.274663413767;6333.366663333814;6103.694815259308;6105.786297479567;6123.4961516426065;6199.535034872312;6205.441510264098 04:12:18 INFO - PROCESS | 1641 | |1;ext.html;671.6003977633308;740.914794798598;822.9012518497781;947.3179310896153;950.24930305059;38111;38770;40271.79864100675;40344.79827600858;40471.392929106085;34877.65122348776;35905.1023724407;37244.81377593115;37260.627393726056;37274.8136259319;8930.508822014786;9078.727638170865;9096.727098187064;9102.77243068922;9105.089491050909;8891.11088891109;8932.196102350787;8960.462372257653;8968.148025937526;8974.59614317354;8897.644094236255;8987.685431009915;9000.909990900072;9004.954975225115;9006.954965225164;8858.778530536723;8948.865767013467;8953.865692014593;8966.820663586692;8981.461112333282;8818.647254109828;8885.422447540954;8894.24398926092;8897.866532002025;8898.86651700225;34181.829090854386;34336.48495272573;34628.134296642536;34672.7864524743;34804.82597587021;3697.6672099510906;3739.8504059837574;3770.3967365221433;3815.00809789454;3817.9427308590116;24780.876095619406;24968.375790605198;25438.491230175478;26368.60447093295;26390.73609263902;3058;3090.474619314722;3170.4134735074085;3243.6107667079864;3279.622843373019;366121;369611;371458;371803.28196717956;372719;44037.33943990843;44456.11087778227;44560.3315950261;44618.10763784771;44763.880902977035;36155.63844361523;45893.082138357044;46138.538614614095;46285.76857115693;46325.76837115793;13541.39063742134;13643;13752.968527360488;13864.792028119586;13872.861271387359;12005.759884802255;12009.879901201051;12032.278063316055;12033.458494367775;12081.818772718416;9602.90397096034;10042.748931276776;10068.39589624625;10126.848097278547;10173.237007224488;4608.815647374067;4611.723296602215;4613.723176609415;4617.746023968714;4625.745583992845;1042.6819819954835;1060.4220699718671;1061.8141825180717;1075.4031512510633;1124.859392575928;11311.547538098548;12609.621711348675;16199.595010124842;16483.423080192137;16708.832911670972;1127.616610352484;1129.6159305836056;1130.4969288666464;1137.5382633316315;1183.248637115438;6465.321141280192;6631.86736265272;8261.876071858927;8306.418550701514;8537.701180458655;460874.69562651974;463133;465732;466143;466945;57181.14228286579;58129.128063079086;58503;63763.36236637574;65101.02348464777;1248.3570960955115;1480.0897448069575;1488.1517535004944;1489.8808095352563;1500.5498350495027;29809.764267680446;30776.076717698506;30828.69171308348;30858.845705771328;31439.528407073456;49366.01267974692;49475.257871131245;49530.504694954034;49710.75144624254;49773;459831;462424;462834;463295;465661;35777.28445431129;36778.26443471062;37099.62900370962;37145.88562343134;37220.81389593035;44093.559064408946;44636.77681611701;45224.773876130406;46445.76777116093;46554.301685475424;27890.721092789623;29231.97688080907;31283.84358078195;31942;31974.200644984063;30793.846030770597;31122.221944450663;31201.68798311988;31762.841185793925;31855.84072079625;28905.855470722512;36308.910732677;36874.078148046516;37060.44409333808;37197.44203836891;28183.154505364873;28267.57598635981;29940.850295748383;30306.84846575753;30668.07995760131;20108.195672173533;23758.643620345363;23775.405614859767;23908.163214287415;23968 04:12:18 INFO - PROCESS | 1641 | |2;jquery.html;380.6574083325007;407.7145997801538;410.9979450102749;421.83337581655246;448.23575803255454;16936.745948810778;19952.30166944157;20692.48268793283;21381;21491.355259342225;21321.893390533027;21623.783762162373;21723;21834.454138646543;22430;6292.339304373044;6607.5044371672075;6671.866562668743;6674.499412544054;6679.799606011803;6399.296077431479;6459.773907913224;6506.3818937200895;6617;6649.202095748493;5691.800786972457;5748.913766293509;6584.736610535573;6632.640308736697;6654.134962454874;5181.637285390024;6445.355464453564;6451.548391612589;6523.054157147187;6524.869502609921;6314.87370252597;6339.302676705604;6363.522735794788;6668.666566671647;6995.705794428047;25109.121180758768;25228.621570676456;25230.87384563084;25403.491930161294;25481.490370192678;2081.6044951459176;2568.6917569891734;2848.575712143928;2999.8500074996164;3001.9249518761985;12905.870941290561;15700.293486793122;15749.448769293134;15892;16542.751858722128;2028.340789243487;2053.260826102602;2062.3091264426434;2085.270155445597;2092.204962114402;150915;172375.13812430983;180633.0968345163;181797.9999999987;181818;19726;21097.367078987656;21118.894405527873;21378.89310553437;21446.57106857854;30872;31044;31377.8431107843;31435.371292574477;31471.21321966969;8444.451110677786;8979.14698103679;9438.95280523593;10203.438810865326;10580.52387642558;8733.38866279367;8770.3422243332;8772.298216142693;8934.329925255632;8937.73186804397;7640.923590764132;8661.740147795575;9363.297752668577;9373.578188981512;9465.337426380225;3451.6711066239677;4120.938185927213;4180.268453020709;4202.957970420318;4203.138356636882;818.6970820796344;912.8676341930516;983.4935008470643;1006.8439391894268;1048.9650078847012;20647.4838129045;20865.58268834645;21049.473763156042;21055.368338949855;21100.683489747666;881.6297155194834;995.7411073121041;1000.7698229407132;1031.1647565471842;1032.829583118777;10474.481200225924;10876.184286178568;10877.401742904058;10934.125269978382;10983.780324393467;500940;570434;597679;763415;769631;150205;150976;152873;156714.43285567;158803;1224.5469176404608;1243.881831226032;1294.8964082873347;1303.6545315491403;1304.5955753716378;19141.990091040796;19573.217071316987;19899.502512437306;19951.401457956286;20013.59972800507;62023;63361.68319158375;63656.36343636504;64652.67673661601;65140.72007479715;87521;87730;88325;88466;89758;10176.847347289944;11737.647870563897;11820.468078936297;11879.881201188224;12005.879941200825;13161.341932903126;13173.934130329288;13289.20264784116;13506.257155856129;13541.526046588322;9573.856392154263;10706;10721.158892893545;11013.788483266995;11375.089992800391;10607.522661480407;10748.838767418338;10861.294015888941;10895.182861285277;11087.944560277147;11482.138839586898;11546.076313895037;11717.76564468723;11937.462814173557;12003.339816310187;11404.714882128013;11723.41382930833;11905;12070.973967212885;12121.636350909486;11198;11235.325880446871;11270.549178032787;11414.600488982845;11513.42432878336 04:12:18 INFO - PROCESS | 1641 | |3;mootools.html;1465.4577806211703;1517.3020410611125;1542.8534289242525;1546.0414542983349;1558.2286768049814;10711.946440267797;11160.497777600007;11468;11478.483468243929;11515.790841961589;11235.651721793376;11362.431878406067;11424.485898134582;11448.198626096157;11462.14033947453;2372.501774627326;2409.6144616861307;2420.98318706143;2421.0799896039566;2434.6469761884514;2309.8036666883336;2375.334906226251;2390.6414037894283;2398.20859116492;2410.987945060281;2035.3792093411503;2288.0733303012307;2372.240882917467;2406.651035599837;2408.879556022192;2337.5091230841545;2357.8113750899975;2386.486905315357;2388.486475407787;2401.1715957994465;2366.8934897929635;2385.4632707640644;2394.341556072077;2396.9640455393182;2397.7961873240797;11118.444077796159;11323.830142547868;11324.6036388726;11326.433678316134;11457;5846.444587764156;5963.552733544958;6045.909311360333;6076.878462430726;6081.908771368433;3061.127578640077;3648.7445878788494;3666.8899933002053;3684.0973961379364;3692.5938146804097;1017.8066167428165;1154.9364784936824;1199.3044034459992;1199.9580014699532;1215.3680086355134;13808.1024733393;14003.229822359766;15086.773698394532;15503.767443488357;15517.612059698486;4670.182718024332;4763.351781648982;4763.356946812136;4853.12643724126;4877.048975449753;11852.111091667986;11894.464749086312;11929.985951194245;11932.463039163258;11936.164468487295;5866.354700982888;5885.911711324334;5902;5938.792142275;5955.315138759098;5253.947460525423;5291.523762861361;5347.171188465794;5366.195070739421;5429.4027656957705;3289.3914625794305;3877.728559000843;3962.0689138052535;4043.575424580377;4078.7756673382655;1041.6041904076403;1048.8374301983206;1048.9685309440576;1050.837120246363;1058.8888166742536;720.9618149864178;794.6026986506747;804.2761514636874;814.495013091875;822.5106061893164;6600.94384898414;6685.364890335411;6807.183138023469;6870.587764734132;6923.6884340204815;942.4298299528823;950.9524523773783;959.5510778724176;969.471637957315;1012.099231683802;3988.142549351903;4005.899852503711;4068.572799856032;4126.442930204384;4146.688998325138;13433.731325373437;13994.090384125;14071.788923166161;14085.577432677037;14095.36570854314;12987.545435909698;13210.405531751094;13882.236476993863;14020.579382618538;14039;10107.736532933382;10236.64171754;10312.432816195182;10321.793564128675;10374.636887708893;6113.572049956639;6759.831004224934;6934.202566704894;6954.061201737701;7192.100987376578;12362.505499780267;12378.690532736753;12579.056570757048;12583.811242831182;12616.87383126157;10052.69841904744;12869.871301287241;12911.612651620466;13061.412236449192;13141.54004609834;9131.36080474387;9360.297977651568;9410.294227932796;9509.762255943657;9531.61873525079;10339.121174700234;10393;10417.062464378243;10509.579616815241;10541.262111652111;8925.152110549487;8956.686515972171;8956.910430895608;9047.728568142966;9109.954450227706;8665.17680820321;8741.781455463664;8804.867926980973;8849.469031858109;8875.28997680171;6500;7149.785506434815;8056.073551541648;8147.981502312211;8166.224208700164;5556.666600004012;5975.850603734768;5991.161237426851;6031.306399764083;6105.145279660762;5318.308619879391;6001.609895356788;6069.332373439007;6106.847328816815;6293.779717709858 04:12:18 INFO - PROCESS | 1641 | |4;prototype.html;353.9805310707911;356.6843343640879;371.7063519819343;382.8066826252742;390.2039838728993;5170.767315470804;5239.266502689627;5283.498067683564;5287.418383977764;5367.651102678323;4289.206496798094;4541.682082254243;4912;5146.536811686948;5257.815976440816;3722;3990.4014397840265;4514.413126293577;4534.637229021687;4560.794764235601;4034.5360283567325;4067.3933796150504;4416.977915110436;4619.330197121415;4627.3624034300465;4159.7920103994675;4581.304917180648;4661.300804879278;4705.29420586913;4710.269908164223;4245.066085461193;4597.172738765668;4625.444946606395;4627;4639.327297541871;3535.9292814143832;4443.489428472903;4463.388917483277;4478.462584489849;4487.461504619434;4435.578620031092;5014.5708473085;5127;5219.869503262411;5251.658642188246;4586;5118.897622047575;5318.175682769177;5342.572594192456;5348.384935732402;4951.653384263103;5468.890622187534;5697.829065128011;5710.543156547466;5713.600167958835;4264.946038943862;4388;4771.379720636295;4969.155243608595;4981.953789704166;5393.325834270716;5526.889462210774;5546.500814926676;5679.944849084318;5919.6152250103605;4634.395985720335;4667.646382549091;4880.316755654211;5013.849584512471;5133.640645154878;4450.866474005785;4866.7566621668775;5218.504242096995;5220.608454365938;5255.684658920477;4384.364267181305;4656.674032817738;4891.9755401222765;5179.300794392709;5433.565314774809;5648.915266271009;5756.079027355687;5766.001679126831;5788.507976821933;5817.563682723812;1556.886825919473;1760.3046796515305;1776.1829558403022;1786.9807117956625;1788.839004489602;1249.2005116725304;1351.48623560178;1354.4379082680773;1409.2319685771279;1432.5702289313165;4804.759762011886;4979.30289759434;4986.725730084808;5121.513456221653;5417.187421886749;4723.433188017391;5419.566434685215;5746.195532625436;5855.882882342329;5861.824145275648;4448.777561121996;4942.728149951788;5371.979323928441;5458.754356053987;5472;2713.4708731797505;3093.273080826004;3127.62468503781;3144.371125774808;3149.4331020416544;5306.09796334624;5355.866103347447;6102.816915492543;6195.473384762256;6204.937950620436;5354.376355585813;5400.621956463088;5693.516051135617;6097.4817140543555;6179.84550386235;5218.895622087537;5309.601779866603;5322.247815290211;6137.938620613737;6168.629882207082;4690.366800482027;5153.45888681683;5208.583313335;5764.711764411846;5894.528437725057;4794.59245964083;5469.863253418696;5682.403347648603;5760.0783874579865;5780;4712.552307530779;4873.366462359871;5011;5664.082997256499;6127.662978536223;3835.0220693724036;4427.335899615084;4513.390692256503;4595.392616795799;4622.375979242759;5200.8818104107795;5829.59192856513;5882.941170588239;5919.467247947706;5966.701664916824;4773.618110551078;5107;5153.5361817436615;5242.895142097061;5578.748956297058;5579.7489112990825;5600.971995139998;5639.6334238274385;5658.717064146694;5675.659460432387;4844.733539655212;5336;5520.447955204608;5584.413636568264;5617.297837770278;5061.746912654279;5268.604854635841;5342.973285133705;5442.918356224739;5718.714064296685;4379.146066516999;5095.305903220135;5345.754782991075;5523.861903452445;5613.803516876889 04:12:18 INFO - PROCESS | 1641 | |5;yui.html;653.3727621483375;665.5507532415621;768.3968085053234;815.197030924539;823.127484866042;14910.925445372772;15572.221388930551;16088.51734447967;16370.345186192539;16410.261538230778;15114.47099351523;15299.464518741846;15311.6937661247;15362.07827530349;15367.462138825143;921.9170274675278;925.255169588481;947.1948843483053;950.6767698982345;952.7856232347735;930.930180236485;948.7580666929923;950.2113246005798;954.1508057828538;954.9618015279415;858.9656413743475;1005.3716427232979;1007.4811472091878;1008.6570566007556;1008.7629407089328;999.550202408919;1000.604761119361;1000.9649662261786;1001.4492029383846;1001.5843424978661;737.7196665267218;739.4528049243575;740.4409670698642;740.6370878269663;740.8740514112613;16228.594285142848;16518.587035324093;16653.583660408465;16852.74720879188;17072.317107315692;13560.389782459715;13889.236092014935;13921;13926.58220253394;14275.428982840675;11488.885111148866;11616.59341923037;11656.242344247597;11667.59163429276;11710.88289117115;3612.584552776412;3685.207680348711;3688.7417880748358;3714.8699795507296;3843;108338.91661083366;108890.45554772255;112183;112839.43580282129;113444.86555134425;10542.789144217226;10587.682369528926;10601.787964240826;10785.191110666574;10859.945700271606;22300.776992230196;22366.888165559394;22435.55128897413;22662.66006009911;22898;7814.6092695365005;7828.060632724109;7842.29419352261;7903.802404939923;8030.0363956324445;4285.164392943346;4342.848000319974;4402.93462700812;4419.602235798729;4534.251848445036;3463.8960831175104;3529.699975502111;3531.152523394418;3534.0811389039036;3540.1149712571855;648.3548868875405;665.4343807763363;669.534673401981;699.1959246866145;711.79713781572;663.9502037347122;689.1316940654812;689.4105539763531;693.4591019005184;700.6706847781535;8697.695580654647;9207.309451791143;10450.216233782345;10579.206559507913;10733;638.1990601794718;673.1148539670318;673.8685956238486;675.7837492002512;695.7599628128288;4786.7845946932475;4890.413150421901;5015.1975683890405;5059.2916991621205;5080.618953578496;103775;107446.46276768722;108366.91633083727;109581.90418095877;110275.44862275638;66772;69865;71488;96804.51597741967;99037;59865.70067149724;82919;83866.16133838583;83866.74199887007;85599;9610.231181505324;9695.22438204956;9775.266854985763;9794.85307720399;9844.753881153028;38257.6174238254;49341.75329123475;50581.74709126431;51046.723831903015;51874.740626296625;50944.745276274865;64904.06435942354;65663.34336656572;66135;66603.66698166478;10967.670969870916;11196.83204751913;11364.94317528407;11370.31778093317;11390.487428065591;12397.318147501974;12523.93738031304;12609.180403273758;12882.48470061187;13467.86532134666;11574.942125289319;11958.342291174069;12406.13157079032;12467.501299948264;12491.312977786309;11550.249233799776;11746.58886938953;11860.525578976743;12029.45867435985;12238.87761122413;11816.350100744541;11938.462769175581;12070.517179312728;12144.514219431123;12270.509179632714;8606.397552171287;9030;9101.817963640822;9338.906610934075;9664.130228279488;6778.898316525355;9058.230050445522;9214.617807328686;10286.639967601099;10376.16990640762 04:12:18 INFO - PROCESS | 1641 | __end_tp_report 04:12:18 INFO - PROCESS | 1641 | __start_cc_report 04:12:18 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,416 04:12:18 INFO - PROCESS | 1641 | __end_cc_report 04:12:18 INFO - PROCESS | 1641 | __startTimestamp1491477138888__endTimestamp 04:12:18 INFO - PROCESS | 1641 | 04:12:18 INFO - PROCESS | 1641 | ------- Summary: start ------- 04:12:18 INFO - PROCESS | 1641 | Number of tests: 6 04:12:18 INFO - PROCESS | 1641 | 04:12:18 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5912.73 Median:6158.57 stddev:689.14 (11.2%) stddev-sans-first:662.60 04:12:18 INFO - PROCESS | 1641 | Values: 3405.6 3424.6 3430.9 3436.2 3439.3 5437.6 5865.8 5925.4 6062.5 6071.6 5983.0 5983.1 6050.6 6052.8 6072.2 4369.8 4752.6 5183.6 5394.3 5455.1 5503.3 5506.2 5508.6 5508.8 5515.8 4478.3 5296.7 5430.6 5466.2 5492.9 5355.0 5425.7 5429.4 5535.3 5541.7 5320.8 5442.9 5448.9 5477.1 5477.3 6033.9 6050.0 6135.1 6158.6 6177.0 5126.4 6170.7 6304.0 6318.9 6319.2 6398.4 6485.5 6492.4 6513.4 6546.8 6150.9 6163.0 6169.1 6176.6 6200.0 6316.4 6389.7 6511.4 6587.7 6644.3 6214.7 6267.1 6295.8 6299.4 6347.2 6450.8 6462.3 6467.3 6471.5 6486.6 6126.7 6294.4 6324.6 6381.8 6390.7 6268.8 6291.5 6306.7 6336.5 6390.8 5047.4 5078.9 5140.8 5159.1 5179.6 4535.2 4575.8 4600.4 4602.1 4634.0 5852.4 5880.0 6050.2 6065.7 6131.2 6348.0 6372.8 6446.3 6505.4 6510.7 6096.9 6132.9 6132.9 6137.2 6153.3 5462.5 5655.1 5660.3 5692.7 5711.8 5424.3 6461.6 6509.3 6569.2 6594.7 6407.5 6446.2 6456.2 6471.0 6472.4 6542.5 6665.0 6682.5 6696.1 6697.8 6186.3 6261.9 6281.8 6283.8 6333.2 6387.1 6424.1 6448.5 6455.7 6459.2 6125.0 6256.7 6292.6 6296.9 6309.3 4559.8 6117.6 6166.7 6219.4 6336.4 6113.7 6174.0 6276.9 6288.1 6288.3 5157.9 5552.4 6147.0 6307.3 6333.4 6103.7 6105.8 6123.5 6199.5 6205.4 04:12:18 INFO - PROCESS | 1641 | 04:12:18 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:55950.13 Median:37209.13 stddev:115721.41 (311.0%) stddev-sans-first:115971.61 04:12:18 INFO - PROCESS | 1641 | Values: 671.6 740.9 822.9 947.3 950.2 38111.0 38770.0 40271.8 40344.8 40471.4 34877.7 35905.1 37244.8 37260.6 37274.8 8930.5 9078.7 9096.7 9102.8 9105.1 8891.1 8932.2 8960.5 8968.1 8974.6 8897.6 8987.7 9000.9 9005.0 9007.0 8858.8 8948.9 8953.9 8966.8 8981.5 8818.6 8885.4 8894.2 8897.9 8898.9 34181.8 34336.5 34628.1 34672.8 34804.8 3697.7 3739.9 3770.4 3815.0 3817.9 24780.9 24968.4 25438.5 26368.6 26390.7 3058.0 3090.5 3170.4 3243.6 3279.6 366121.0 369611.0 371458.0 371803.3 372719.0 44037.3 44456.1 44560.3 44618.1 44763.9 36155.6 45893.1 46138.5 46285.8 46325.8 13541.4 13643.0 13753.0 13864.8 13872.9 12005.8 12009.9 12032.3 12033.5 12081.8 9602.9 10042.7 10068.4 10126.8 10173.2 4608.8 4611.7 4613.7 4617.7 4625.7 1042.7 1060.4 1061.8 1075.4 1124.9 11311.5 12609.6 16199.6 16483.4 16708.8 1127.6 1129.6 1130.5 1137.5 1183.2 6465.3 6631.9 8261.9 8306.4 8537.7 460874.7 463133.0 465732.0 466143.0 466945.0 57181.1 58129.1 58503.0 63763.4 65101.0 1248.4 1480.1 1488.2 1489.9 1500.5 29809.8 30776.1 30828.7 30858.8 31439.5 49366.0 49475.3 49530.5 49710.8 49773.0 459831.0 462424.0 462834.0 463295.0 465661.0 35777.3 36778.3 37099.6 37145.9 37220.8 44093.6 44636.8 45224.8 46445.8 46554.3 27890.7 29232.0 31283.8 31942.0 31974.2 30793.8 31122.2 31201.7 31762.8 31855.8 28905.9 36308.9 36874.1 37060.4 37197.4 28183.2 28267.6 29940.9 30306.8 30668.1 20108.2 23758.6 23775.4 23908.2 23968.0 04:12:18 INFO - PROCESS | 1641 | 04:12:18 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:40535.71 Median:21413.79 stddev:110099.26 (514.2%) stddev-sans-first:110366.84 04:12:18 INFO - PROCESS | 1641 | Values: 380.7 407.7 411.0 421.8 448.2 16936.7 19952.3 20692.5 21381.0 21491.4 21321.9 21623.8 21723.0 21834.5 22430.0 6292.3 6607.5 6671.9 6674.5 6679.8 6399.3 6459.8 6506.4 6617.0 6649.2 5691.8 5748.9 6584.7 6632.6 6654.1 5181.6 6445.4 6451.5 6523.1 6524.9 6314.9 6339.3 6363.5 6668.7 6995.7 25109.1 25228.6 25230.9 25403.5 25481.5 2081.6 2568.7 2848.6 2999.9 3001.9 12905.9 15700.3 15749.4 15892.0 16542.8 2028.3 2053.3 2062.3 2085.3 2092.2 150915.0 172375.1 180633.1 181798.0 181818.0 19726.0 21097.4 21118.9 21378.9 21446.6 30872.0 31044.0 31377.8 31435.4 31471.2 8444.5 8979.1 9439.0 10203.4 10580.5 8733.4 8770.3 8772.3 8934.3 8937.7 7640.9 8661.7 9363.3 9373.6 9465.3 3451.7 4120.9 4180.3 4203.0 4203.1 818.7 912.9 983.5 1006.8 1049.0 20647.5 20865.6 21049.5 21055.4 21100.7 881.6 995.7 1000.8 1031.2 1032.8 10474.5 10876.2 10877.4 10934.1 10983.8 500940.0 570434.0 597679.0 763415.0 769631.0 150205.0 150976.0 152873.0 156714.4 158803.0 1224.5 1243.9 1294.9 1303.7 1304.6 19142.0 19573.2 19899.5 19951.4 20013.6 62023.0 63361.7 63656.4 64652.7 65140.7 87521.0 87730.0 88325.0 88466.0 89758.0 10176.8 11737.6 11820.5 11879.9 12005.9 13161.3 13173.9 13289.2 13506.3 13541.5 9573.9 10706.0 10721.2 11013.8 11375.1 10607.5 10748.8 10861.3 10895.2 11087.9 11482.1 11546.1 11717.8 11937.5 12003.3 11404.7 11723.4 11905.0 12071.0 12121.6 11198.0 11235.3 11270.5 11414.6 11513.4 04:12:18 INFO - PROCESS | 1641 | 04:12:18 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:6803.59 Median:2409.25 stddev:4274.78 (177.4%) stddev-sans-first:4267.95 04:12:18 INFO - PROCESS | 1641 | Values: 1465.5 1517.3 1542.9 1546.0 1558.2 10711.9 11160.5 11468.0 11478.5 11515.8 11235.7 11362.4 11424.5 11448.2 11462.1 2372.5 2409.6 2421.0 2421.1 2434.6 2309.8 2375.3 2390.6 2398.2 2411.0 2035.4 2288.1 2372.2 2406.7 2408.9 2337.5 2357.8 2386.5 2388.5 2401.2 2366.9 2385.5 2394.3 2397.0 2397.8 11118.4 11323.8 11324.6 11326.4 11457.0 5846.4 5963.6 6045.9 6076.9 6081.9 3061.1 3648.7 3666.9 3684.1 3692.6 1017.8 1154.9 1199.3 1200.0 1215.4 13808.1 14003.2 15086.8 15503.8 15517.6 4670.2 4763.4 4763.4 4853.1 4877.0 11852.1 11894.5 11930.0 11932.5 11936.2 5866.4 5885.9 5902.0 5938.8 5955.3 5253.9 5291.5 5347.2 5366.2 5429.4 3289.4 3877.7 3962.1 4043.6 4078.8 1041.6 1048.8 1049.0 1050.8 1058.9 721.0 794.6 804.3 814.5 822.5 6600.9 6685.4 6807.2 6870.6 6923.7 942.4 951.0 959.6 969.5 1012.1 3988.1 4005.9 4068.6 4126.4 4146.7 13433.7 13994.1 14071.8 14085.6 14095.4 12987.5 13210.4 13882.2 14020.6 14039.0 10107.7 10236.6 10312.4 10321.8 10374.6 6113.6 6759.8 6934.2 6954.1 7192.1 12362.5 12378.7 12579.1 12583.8 12616.9 10052.7 12869.9 12911.6 13061.4 13141.5 9131.4 9360.3 9410.3 9509.8 9531.6 10339.1 10393.0 10417.1 10509.6 10541.3 8925.2 8956.7 8956.9 9047.7 9110.0 8665.2 8741.8 8804.9 8849.5 8875.3 6500.0 7149.8 8056.1 8148.0 8166.2 5556.7 5975.9 5991.2 6031.3 6105.1 5318.3 6001.6 6069.3 6106.8 6293.8 04:12:18 INFO - PROCESS | 1641 | 04:12:18 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4751.40 Median:5130.32 stddev:1268.28 (24.7%) stddev-sans-first:1228.14 04:12:18 INFO - PROCESS | 1641 | Values: 354.0 356.7 371.7 382.8 390.2 5170.8 5239.3 5283.5 5287.4 5367.7 4289.2 4541.7 4912.0 5146.5 5257.8 3722.0 3990.4 4514.4 4534.6 4560.8 4034.5 4067.4 4417.0 4619.3 4627.4 4159.8 4581.3 4661.3 4705.3 4710.3 4245.1 4597.2 4625.4 4627.0 4639.3 3535.9 4443.5 4463.4 4478.5 4487.5 4435.6 5014.6 5127.0 5219.9 5251.7 4586.0 5118.9 5318.2 5342.6 5348.4 4951.7 5468.9 5697.8 5710.5 5713.6 4264.9 4388.0 4771.4 4969.2 4982.0 5393.3 5526.9 5546.5 5679.9 5919.6 4634.4 4667.6 4880.3 5013.8 5133.6 4450.9 4866.8 5218.5 5220.6 5255.7 4384.4 4656.7 4892.0 5179.3 5433.6 5648.9 5756.1 5766.0 5788.5 5817.6 1556.9 1760.3 1776.2 1787.0 1788.8 1249.2 1351.5 1354.4 1409.2 1432.6 4804.8 4979.3 4986.7 5121.5 5417.2 4723.4 5419.6 5746.2 5855.9 5861.8 4448.8 4942.7 5372.0 5458.8 5472.0 2713.5 3093.3 3127.6 3144.4 3149.4 5306.1 5355.9 6102.8 6195.5 6204.9 5354.4 5400.6 5693.5 6097.5 6179.8 5218.9 5309.6 5322.2 6137.9 6168.6 4690.4 5153.5 5208.6 5764.7 5894.5 4794.6 5469.9 5682.4 5760.1 5780.0 4712.6 4873.4 5011.0 5664.1 6127.7 3835.0 4427.3 4513.4 4595.4 4622.4 5200.9 5829.6 5882.9 5919.5 5966.7 4773.6 5107.0 5153.5 5242.9 5578.7 5579.7 5601.0 5639.6 5658.7 5675.7 4844.7 5336.0 5520.4 5584.4 5617.3 5061.7 5268.6 5343.0 5442.9 5718.7 4379.1 5095.3 5345.8 5523.9 5613.8 04:12:18 INFO - PROCESS | 1641 | 04:12:18 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20185.37 Median:3701.81 stddev:29760.82 (804.0%) stddev-sans-first:29808.17 04:12:18 INFO - PROCESS | 1641 | Values: 653.4 665.6 768.4 815.2 823.1 14910.9 15572.2 16088.5 16370.3 16410.3 15114.5 15299.5 15311.7 15362.1 15367.5 921.9 925.3 947.2 950.7 952.8 930.9 948.8 950.2 954.2 955.0 859.0 1005.4 1007.5 1008.7 1008.8 999.6 1000.6 1001.0 1001.4 1001.6 737.7 739.5 740.4 740.6 740.9 16228.6 16518.6 16653.6 16852.7 17072.3 13560.4 13889.2 13921.0 13926.6 14275.4 11488.9 11616.6 11656.2 11667.6 11710.9 3612.6 3685.2 3688.7 3714.9 3843.0 108338.9 108890.5 112183.0 112839.4 113444.9 10542.8 10587.7 10601.8 10785.2 10859.9 22300.8 22366.9 22435.6 22662.7 22898.0 7814.6 7828.1 7842.3 7903.8 8030.0 4285.2 4342.8 4402.9 4419.6 4534.3 3463.9 3529.7 3531.2 3534.1 3540.1 648.4 665.4 669.5 699.2 711.8 664.0 689.1 689.4 693.5 700.7 8697.7 9207.3 10450.2 10579.2 10733.0 638.2 673.1 673.9 675.8 695.8 4786.8 4890.4 5015.2 5059.3 5080.6 103775.0 107446.5 108366.9 109581.9 110275.4 66772.0 69865.0 71488.0 96804.5 99037.0 59865.7 82919.0 83866.2 83866.7 85599.0 9610.2 9695.2 9775.3 9794.9 9844.8 38257.6 49341.8 50581.7 51046.7 51874.7 50944.7 64904.1 65663.3 66135.0 66603.7 10967.7 11196.8 11364.9 11370.3 11390.5 12397.3 12523.9 12609.2 12882.5 13467.9 11574.9 11958.3 12406.1 12467.5 12491.3 11550.2 11746.6 11860.5 12029.5 12238.9 11816.4 11938.5 12070.5 12144.5 12270.5 8606.4 9030.0 9101.8 9338.9 9664.1 6778.9 9058.2 9214.6 10286.6 10376.2 04:12:18 INFO - PROCESS | 1641 | -------- Summary: end -------- 04:12:18 INFO - PROCESS | 1641 | 04:12:19 INFO - TEST-INFO | 1641: exit 0 04:12:19 INFO - TEST-OK | dromaeo_css | took 1117451ms 04:12:19 INFO - TEST-START | kraken 04:12:19 INFO - operating with platform_type : mac_ 04:12:19 INFO - Initialising browser for kraken test... 04:12:19 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpAmKEOH/profile http://localhost:49240/getInfo.html) 04:12:22 INFO - PROCESS | 1653 | 2017-04-06 04:12:22.684 plugin-container[1654:10196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4f, name = 'com.apple.tsm.portname' 04:12:22 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:12:22 INFO - PROCESS | 1653 | 2017-04-06 04:12:22.687 plugin-container[1654:10196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9303, name = 'com.apple.CFPasteboardClient' 04:12:22 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:12:22 INFO - PROCESS | 1653 | 2017-04-06 04:12:22.687 plugin-container[1654:10196] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:12:22 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 04:12:22 INFO - PROCESS | 1653 | colorDepth:24 04:12:22 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 04:12:22 INFO - PROCESS | 1653 | __metrics 04:12:22 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 04:12:23 INFO - TEST-INFO | 1653: exit 0 04:12:23 INFO - Browser initialized. 04:12:23 INFO - Running cycle 1/1 for kraken test... 04:12:23 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpAmKEOH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:12:25 INFO - PROCESS | 1655 | 2017-04-06 04:12:25.865 plugin-container[1656:10323] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8833, name = 'com.apple.tsm.portname' 04:12:25 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:12:25 INFO - PROCESS | 1655 | 2017-04-06 04:12:25.866 plugin-container[1656:10323] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 04:12:25 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:12:25 INFO - PROCESS | 1655 | 2017-04-06 04:12:25.866 plugin-container[1656:10323] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:12:35 INFO - PROCESS | 1655 | RSS: Main: 221065216 04:12:35 INFO - PROCESS | 1655 | 04:12:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 04:12:39 INFO - PROCESS | 1655 | RSS: Main: 235663360 04:12:39 INFO - PROCESS | 1655 | 04:12:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 04:12:42 INFO - PROCESS | 1655 | RSS: Main: 243617792 04:12:42 INFO - PROCESS | 1655 | 04:12:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 04:12:46 INFO - PROCESS | 1655 | RSS: Main: 247263232 04:12:46 INFO - PROCESS | 1655 | 04:12:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 04:12:49 INFO - PROCESS | 1655 | RSS: Main: 247767040 04:12:49 INFO - PROCESS | 1655 | 04:12:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:12:52 INFO - PROCESS | 1655 | RSS: Main: 247808000 04:12:52 INFO - PROCESS | 1655 | 04:12:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 04:12:55 INFO - PROCESS | 1655 | RSS: Main: 251756544 04:12:55 INFO - PROCESS | 1655 | 04:12:58 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 04:12:59 INFO - PROCESS | 1655 | RSS: Main: 252751872 04:12:59 INFO - PROCESS | 1655 | 04:13:02 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 04:13:03 INFO - PROCESS | 1655 | RSS: Main: 252502016 04:13:03 INFO - PROCESS | 1655 | 04:13:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:13:05 INFO - PROCESS | 1655 | RSS: Main: 252030976 04:13:05 INFO - PROCESS | 1655 | 04:13:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:13:07 INFO - PROCESS | 1655 | RSS: Main: 251801600 04:13:07 INFO - PROCESS | 1655 | 04:13:10 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:13:10 INFO - PROCESS | 1655 | RSS: Main: 251883520 04:13:10 INFO - PROCESS | 1655 | 04:13:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:13:14 INFO - PROCESS | 1655 | RSS: Main: 252215296 04:13:14 INFO - PROCESS | 1655 | 04:13:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:13:17 INFO - PROCESS | 1655 | RSS: Main: 252223488 04:13:17 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 04:13:19 INFO - PROCESS | 1655 | RSS: Main: 254029824 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | __start_tp_report 04:13:19 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 04:13:19 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 04:13:19 INFO - PROCESS | 1655 | |i|pagename|runs| 04:13:19 INFO - PROCESS | 1655 | |0;ai-astar;112;162;96;98;160;104;114;124;130;183 04:13:19 INFO - PROCESS | 1655 | |1;audio-beat-detection;137;142;142;147;111;113;112;112;135;114 04:13:19 INFO - PROCESS | 1655 | |2;audio-dft;202;197;196;204;198;199;196;196;201;194 04:13:19 INFO - PROCESS | 1655 | |3;audio-fft;91;51;51;101;73;59;78;85;94;83 04:13:19 INFO - PROCESS | 1655 | |4;audio-oscillator;86;71;86;73;72;70;90;70;71;84 04:13:19 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;92;88;88;87;90;98;89;88;89;108 04:13:19 INFO - PROCESS | 1655 | |6;imaging-darkroom;95;105;154;107;97;97;97;97;97;98 04:13:19 INFO - PROCESS | 1655 | |7;imaging-desaturate;90;93;112;146;97;111;115;91;90;110 04:13:19 INFO - PROCESS | 1655 | |8;json-parse-financial;67;68;66;67;70;69;68;69;70;70 04:13:19 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;61;59;74;67;62;58;59;59;62;86 04:13:19 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;91;103;94;83;83;86;88;85;86;94 04:13:19 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;146;154;150;152;140;142;142;145;184;141 04:13:19 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;170;168;168;168;168;168;166;170;165;197 04:13:19 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;63;66;69;66;66;67;66;67;67;64 04:13:19 INFO - PROCESS | 1655 | __end_tp_report 04:13:19 INFO - PROCESS | 1655 | __start_cc_report 04:13:19 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1023 04:13:19 INFO - PROCESS | 1655 | __end_cc_report 04:13:19 INFO - PROCESS | 1655 | __startTimestamp1491477199827__endTimestamp 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | ------- Summary: start ------- 04:13:19 INFO - PROCESS | 1655 | Number of tests: 14 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:128.30 Median:161.00 stddev:30.14 (18.7%) stddev-sans-first:31.39 04:13:19 INFO - PROCESS | 1655 | Values: 112.0 162.0 96.0 98.0 160.0 104.0 114.0 124.0 130.0 183.0 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:136.00 stddev:15.21 (11.2%) stddev-sans-first:15.65 04:13:19 INFO - PROCESS | 1655 | Values: 137.0 142.0 142.0 147.0 111.0 113.0 112.0 112.0 135.0 114.0 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:198.30 Median:198.50 stddev:3.16 (1.6%) stddev-sans-first:3.06 04:13:19 INFO - PROCESS | 1655 | Values: 202.0 197.0 196.0 204.0 198.0 199.0 196.0 196.0 201.0 194.0 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:76.60 Median:80.50 stddev:17.80 (22.1%) stddev-sans-first:18.10 04:13:19 INFO - PROCESS | 1655 | Values: 91.0 51.0 51.0 101.0 73.0 59.0 78.0 85.0 94.0 83.0 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:78.50 stddev:8.10 (10.3%) stddev-sans-first:7.95 04:13:19 INFO - PROCESS | 1655 | Values: 86.0 71.0 86.0 73.0 72.0 70.0 90.0 70.0 71.0 84.0 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:89.00 stddev:6.55 (7.4%) stddev-sans-first:6.95 04:13:19 INFO - PROCESS | 1655 | Values: 92.0 88.0 88.0 87.0 90.0 98.0 89.0 88.0 89.0 108.0 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:104.40 Median:97.00 stddev:17.86 (18.4%) stddev-sans-first:18.62 04:13:19 INFO - PROCESS | 1655 | Values: 95.0 105.0 154.0 107.0 97.0 97.0 97.0 97.0 97.0 98.0 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:105.50 Median:90.00 stddev:17.46 (19.4%) stddev-sans-first:17.59 04:13:19 INFO - PROCESS | 1655 | Values: 90.0 93.0 112.0 146.0 97.0 111.0 115.0 91.0 90.0 110.0 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:68.40 Median:69.00 stddev:1.43 (2.1%) stddev-sans-first:1.42 04:13:19 INFO - PROCESS | 1655 | Values: 67.0 68.0 66.0 67.0 70.0 69.0 68.0 69.0 70.0 70.0 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:62.00 stddev:8.92 (14.4%) stddev-sans-first:9.36 04:13:19 INFO - PROCESS | 1655 | Values: 61.0 59.0 74.0 67.0 62.0 58.0 59.0 59.0 62.0 86.0 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:87.00 stddev:6.29 (7.2%) stddev-sans-first:6.64 04:13:19 INFO - PROCESS | 1655 | Values: 91.0 103.0 94.0 83.0 83.0 86.0 88.0 85.0 86.0 94.0 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:148.00 stddev:13.01 (8.8%) stddev-sans-first:13.74 04:13:19 INFO - PROCESS | 1655 | Values: 146.0 154.0 150.0 152.0 140.0 142.0 142.0 145.0 184.0 141.0 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.80 Median:168.00 stddev:9.33 (5.6%) stddev-sans-first:9.89 04:13:19 INFO - PROCESS | 1655 | Values: 170.0 168.0 168.0 168.0 168.0 168.0 166.0 170.0 165.0 197.0 04:13:19 INFO - PROCESS | 1655 | 04:13:19 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:1.66 (2.5%) stddev-sans-first:1.33 04:13:19 INFO - PROCESS | 1655 | Values: 63.0 66.0 69.0 66.0 66.0 67.0 66.0 67.0 67.0 64.0 04:13:19 INFO - PROCESS | 1655 | -------- Summary: end -------- 04:13:19 INFO - PROCESS | 1655 | 04:13:20 INFO - TEST-INFO | 1655: exit 0 04:13:20 INFO - TEST-OK | kraken | took 61180ms 04:13:20 INFO - SUITE-END | took 1178s 04:13:20 INFO - Completed test suite (00:19:38) 04:13:20 INFO - javascript benchmark 04:13:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7634.34320680544, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3405.5913290405138, 3424.640412756662, 3430.914227144321, 3436.19249476373, 3439.3121375724863, 5437.619366644331, 5865.824025279243, 5925.437083477073, 6062.545309101824, 6071.60534565253, 5983.0, 5983.102534619809, 6050.636961782297, 6052.788152414666, 6072.240969878765, 4369.803358848843, 4752.572268495835, 5183.585313174937, 5394.32570928634, 5455.072637651589, 5503.3120859892515, 5506.174073888908, 5508.614396992212, 5508.7521061552325, 5515.779368825242, 4478.261086920655, 5296.655717378378, 5430.5927055471, 5466.180072989044, 5492.945070549304, 5354.973225133869, 5425.7287135643455, 5429.4299098594865, 5535.335759708861, 5541.6674999499755, 5320.787168513255, 5442.945570544283, 5448.91826622597, 5477.096279113942, 5477.260569823063, 6033.9396606033815, 6050.0, 6135.055173516737, 6158.56890017699, 6177.011678131477, 5126.359205099363, 6170.660613666247, 6303.991361382203, 6318.87362252757, 6319.24169099711, 6398.424141827211, 6485.513586481033, 6492.383223593751, 6513.413792758674, 6546.803595892131, 6150.938490615126, 6162.96918515409, 6169.074638804171, 6176.567640265228, 6200.038993955944, 6316.399942005502, 6389.744410223632, 6511.446527045159, 6587.703553340111, 6644.335566443317, 6214.689265536705, 6267.122602835607, 6295.811125666238, 6299.401556852092, 6347.206599175103, 6450.838729031718, 6462.32145624712, 6467.2562655294305, 6471.5146364022885, 6486.643234622141, 6126.724297406628, 6294.402031806971, 6324.620522768557, 6381.8085457436355, 6390.712417941204, 6268.8119356418465, 6291.5281353898645, 6306.716197771111, 6336.461400780893, 6390.808275751735, 5047.419546752097, 5078.949210507922, 5140.768665410065, 5159.097157997336, 5179.585633149339, 4535.160995215896, 4575.794089265924, 4600.401947746772, 4602.079584083197, 4633.953660463352, 5852.414758524199, 5880.0, 6050.2437195350585, 6065.696715164224, 6131.172291740558, 6347.968260158762, 6372.84067898297, 6446.258680251737, 6505.447037001908, 6510.739570417225, 6096.908546371808, 6132.938670613326, 6132.938670613326, 6137.171481849982, 6153.323134455206, 5462.535684466785, 5655.095184770417, 5660.264165658438, 5692.658440493584, 5711.828645140652, 5424.321959755031, 6461.61230326182, 6509.283978762238, 6569.244536878129, 6594.736210551524, 6407.487401008001, 6446.226452825691, 6456.225252969673, 6470.99699546571, 6472.449841763503, 6542.54202205841, 6665.0, 6682.46540276767, 6696.096027036482, 6697.799066028027, 6186.288576813724, 6261.874762504815, 6281.842953926006, 6283.842903927439, 6333.20834895638, 6387.105805187371, 6424.10062591247, 6448.516361272829, 6455.67721613908, 6459.224893012681, 6124.958757011408, 6256.749730010749, 6292.590981586182, 6296.937030629635, 6309.305976342507, 4559.77201139935, 6117.553376797748, 6166.691665416801, 6219.378062193744, 6336.398042185985, 6113.658745237643, 6173.969130154321, 6276.9372306276355, 6288.119663247057, 6288.308286088435, 5157.922631160461, 5552.389237183826, 6147.016477363601, 6307.274663413767, 6333.366663333814, 6103.694815259308, 6105.786297479567, 6123.4961516426065, 6199.535034872312, 6205.441510264098], "value": 5869.460535246757, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.6003977633308, 740.914794798598, 822.9012518497781, 947.3179310896153, 950.24930305059, 38111.0, 38770.0, 40271.79864100675, 40344.79827600858, 40471.392929106085, 34877.65122348776, 35905.1023724407, 37244.81377593115, 37260.627393726056, 37274.8136259319, 8930.508822014786, 9078.727638170865, 9096.727098187064, 9102.77243068922, 9105.089491050909, 8891.11088891109, 8932.196102350787, 8960.462372257653, 8968.148025937526, 8974.59614317354, 8897.644094236255, 8987.685431009915, 9000.909990900072, 9004.954975225115, 9006.954965225164, 8858.778530536723, 8948.865767013467, 8953.865692014593, 8966.820663586692, 8981.461112333282, 8818.647254109828, 8885.422447540954, 8894.24398926092, 8897.866532002025, 8898.86651700225, 34181.829090854386, 34336.48495272573, 34628.134296642536, 34672.7864524743, 34804.82597587021, 3697.6672099510906, 3739.8504059837574, 3770.3967365221433, 3815.00809789454, 3817.9427308590116, 24780.876095619406, 24968.375790605198, 25438.491230175478, 26368.60447093295, 26390.73609263902, 3058.0, 3090.474619314722, 3170.4134735074085, 3243.6107667079864, 3279.622843373019, 366121.0, 369611.0, 371458.0, 371803.28196717956, 372719.0, 44037.33943990843, 44456.11087778227, 44560.3315950261, 44618.10763784771, 44763.880902977035, 36155.63844361523, 45893.082138357044, 46138.538614614095, 46285.76857115693, 46325.76837115793, 13541.39063742134, 13643.0, 13752.968527360488, 13864.792028119586, 13872.861271387359, 12005.759884802255, 12009.879901201051, 12032.278063316055, 12033.458494367775, 12081.818772718416, 9602.90397096034, 10042.748931276776, 10068.39589624625, 10126.848097278547, 10173.237007224488, 4608.815647374067, 4611.723296602215, 4613.723176609415, 4617.746023968714, 4625.745583992845, 1042.6819819954835, 1060.4220699718671, 1061.8141825180717, 1075.4031512510633, 1124.859392575928, 11311.547538098548, 12609.621711348675, 16199.595010124842, 16483.423080192137, 16708.832911670972, 1127.616610352484, 1129.6159305836056, 1130.4969288666464, 1137.5382633316315, 1183.248637115438, 6465.321141280192, 6631.86736265272, 8261.876071858927, 8306.418550701514, 8537.701180458655, 460874.69562651974, 463133.0, 465732.0, 466143.0, 466945.0, 57181.14228286579, 58129.128063079086, 58503.0, 63763.36236637574, 65101.02348464777, 1248.3570960955115, 1480.0897448069575, 1488.1517535004944, 1489.8808095352563, 1500.5498350495027, 29809.764267680446, 30776.076717698506, 30828.69171308348, 30858.845705771328, 31439.528407073456, 49366.01267974692, 49475.257871131245, 49530.504694954034, 49710.75144624254, 49773.0, 459831.0, 462424.0, 462834.0, 463295.0, 465661.0, 35777.28445431129, 36778.26443471062, 37099.62900370962, 37145.88562343134, 37220.81389593035, 44093.559064408946, 44636.77681611701, 45224.773876130406, 46445.76777116093, 46554.301685475424, 27890.721092789623, 29231.97688080907, 31283.84358078195, 31942.0, 31974.200644984063, 30793.846030770597, 31122.221944450663, 31201.68798311988, 31762.841185793925, 31855.84072079625, 28905.855470722512, 36308.910732677, 36874.078148046516, 37060.44409333808, 37197.44203836891, 28183.154505364873, 28267.57598635981, 29940.850295748383, 30306.84846575753, 30668.07995760131, 20108.195672173533, 23758.643620345363, 23775.405614859767, 23908.163214287415, 23968.0], "value": 17540.64745669749, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.6574083325007, 407.7145997801538, 410.9979450102749, 421.83337581655246, 448.23575803255454, 16936.745948810778, 19952.30166944157, 20692.48268793283, 21381.0, 21491.355259342225, 21321.893390533027, 21623.783762162373, 21723.0, 21834.454138646543, 22430.0, 6292.339304373044, 6607.5044371672075, 6671.866562668743, 6674.499412544054, 6679.799606011803, 6399.296077431479, 6459.773907913224, 6506.3818937200895, 6617.0, 6649.202095748493, 5691.800786972457, 5748.913766293509, 6584.736610535573, 6632.640308736697, 6654.134962454874, 5181.637285390024, 6445.355464453564, 6451.548391612589, 6523.054157147187, 6524.869502609921, 6314.87370252597, 6339.302676705604, 6363.522735794788, 6668.666566671647, 6995.705794428047, 25109.121180758768, 25228.621570676456, 25230.87384563084, 25403.491930161294, 25481.490370192678, 2081.6044951459176, 2568.6917569891734, 2848.575712143928, 2999.8500074996164, 3001.9249518761985, 12905.870941290561, 15700.293486793122, 15749.448769293134, 15892.0, 16542.751858722128, 2028.340789243487, 2053.260826102602, 2062.3091264426434, 2085.270155445597, 2092.204962114402, 150915.0, 172375.13812430983, 180633.0968345163, 181797.9999999987, 181818.0, 19726.0, 21097.367078987656, 21118.894405527873, 21378.89310553437, 21446.57106857854, 30872.0, 31044.0, 31377.8431107843, 31435.371292574477, 31471.21321966969, 8444.451110677786, 8979.14698103679, 9438.95280523593, 10203.438810865326, 10580.52387642558, 8733.38866279367, 8770.3422243332, 8772.298216142693, 8934.329925255632, 8937.73186804397, 7640.923590764132, 8661.740147795575, 9363.297752668577, 9373.578188981512, 9465.337426380225, 3451.6711066239677, 4120.938185927213, 4180.268453020709, 4202.957970420318, 4203.138356636882, 818.6970820796344, 912.8676341930516, 983.4935008470643, 1006.8439391894268, 1048.9650078847012, 20647.4838129045, 20865.58268834645, 21049.473763156042, 21055.368338949855, 21100.683489747666, 881.6297155194834, 995.7411073121041, 1000.7698229407132, 1031.1647565471842, 1032.829583118777, 10474.481200225924, 10876.184286178568, 10877.401742904058, 10934.125269978382, 10983.780324393467, 500940.0, 570434.0, 597679.0, 763415.0, 769631.0, 150205.0, 150976.0, 152873.0, 156714.43285567, 158803.0, 1224.5469176404608, 1243.881831226032, 1294.8964082873347, 1303.6545315491403, 1304.5955753716378, 19141.990091040796, 19573.217071316987, 19899.502512437306, 19951.401457956286, 20013.59972800507, 62023.0, 63361.68319158375, 63656.36343636504, 64652.67673661601, 65140.72007479715, 87521.0, 87730.0, 88325.0, 88466.0, 89758.0, 10176.847347289944, 11737.647870563897, 11820.468078936297, 11879.881201188224, 12005.879941200825, 13161.341932903126, 13173.934130329288, 13289.20264784116, 13506.257155856129, 13541.526046588322, 9573.856392154263, 10706.0, 10721.158892893545, 11013.788483266995, 11375.089992800391, 10607.522661480407, 10748.838767418338, 10861.294015888941, 10895.182861285277, 11087.944560277147, 11482.138839586898, 11546.076313895037, 11717.76564468723, 11937.462814173557, 12003.339816310187, 11404.714882128013, 11723.41382930833, 11905.0, 12070.973967212885, 12121.636350909486, 11198.0, 11235.325880446871, 11270.549178032787, 11414.600488982845, 11513.42432878336], "value": 11432.30099250163, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1465.4577806211703, 1517.3020410611125, 1542.8534289242525, 1546.0414542983349, 1558.2286768049814, 10711.946440267797, 11160.497777600007, 11468.0, 11478.483468243929, 11515.790841961589, 11235.651721793376, 11362.431878406067, 11424.485898134582, 11448.198626096157, 11462.14033947453, 2372.501774627326, 2409.6144616861307, 2420.98318706143, 2421.0799896039566, 2434.6469761884514, 2309.8036666883336, 2375.334906226251, 2390.6414037894283, 2398.20859116492, 2410.987945060281, 2035.3792093411503, 2288.0733303012307, 2372.240882917467, 2406.651035599837, 2408.879556022192, 2337.5091230841545, 2357.8113750899975, 2386.486905315357, 2388.486475407787, 2401.1715957994465, 2366.8934897929635, 2385.4632707640644, 2394.341556072077, 2396.9640455393182, 2397.7961873240797, 11118.444077796159, 11323.830142547868, 11324.6036388726, 11326.433678316134, 11457.0, 5846.444587764156, 5963.552733544958, 6045.909311360333, 6076.878462430726, 6081.908771368433, 3061.127578640077, 3648.7445878788494, 3666.8899933002053, 3684.0973961379364, 3692.5938146804097, 1017.8066167428165, 1154.9364784936824, 1199.3044034459992, 1199.9580014699532, 1215.3680086355134, 13808.1024733393, 14003.229822359766, 15086.773698394532, 15503.767443488357, 15517.612059698486, 4670.182718024332, 4763.351781648982, 4763.356946812136, 4853.12643724126, 4877.048975449753, 11852.111091667986, 11894.464749086312, 11929.985951194245, 11932.463039163258, 11936.164468487295, 5866.354700982888, 5885.911711324334, 5902.0, 5938.792142275, 5955.315138759098, 5253.947460525423, 5291.523762861361, 5347.171188465794, 5366.195070739421, 5429.4027656957705, 3289.3914625794305, 3877.728559000843, 3962.0689138052535, 4043.575424580377, 4078.7756673382655, 1041.6041904076403, 1048.8374301983206, 1048.9685309440576, 1050.837120246363, 1058.8888166742536, 720.9618149864178, 794.6026986506747, 804.2761514636874, 814.495013091875, 822.5106061893164, 6600.94384898414, 6685.364890335411, 6807.183138023469, 6870.587764734132, 6923.6884340204815, 942.4298299528823, 950.9524523773783, 959.5510778724176, 969.471637957315, 1012.099231683802, 3988.142549351903, 4005.899852503711, 4068.572799856032, 4126.442930204384, 4146.688998325138, 13433.731325373437, 13994.090384125, 14071.788923166161, 14085.577432677037, 14095.36570854314, 12987.545435909698, 13210.405531751094, 13882.236476993863, 14020.579382618538, 14039.0, 10107.736532933382, 10236.64171754, 10312.432816195182, 10321.793564128675, 10374.636887708893, 6113.572049956639, 6759.831004224934, 6934.202566704894, 6954.061201737701, 7192.100987376578, 12362.505499780267, 12378.690532736753, 12579.056570757048, 12583.811242831182, 12616.87383126157, 10052.69841904744, 12869.871301287241, 12911.612651620466, 13061.412236449192, 13141.54004609834, 9131.36080474387, 9360.297977651568, 9410.294227932796, 9509.762255943657, 9531.61873525079, 10339.121174700234, 10393.0, 10417.062464378243, 10509.579616815241, 10541.262111652111, 8925.152110549487, 8956.686515972171, 8956.910430895608, 9047.728568142966, 9109.954450227706, 8665.17680820321, 8741.781455463664, 8804.867926980973, 8849.469031858109, 8875.28997680171, 6500.0, 7149.785506434815, 8056.073551541648, 8147.981502312211, 8166.224208700164, 5556.666600004012, 5975.850603734768, 5991.161237426851, 6031.306399764083, 6105.145279660762, 5318.308619879391, 6001.609895356788, 6069.332373439007, 6106.847328816815, 6293.779717709858], "value": 5141.682962055304, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.9805310707911, 356.6843343640879, 371.7063519819343, 382.8066826252742, 390.2039838728993, 5170.767315470804, 5239.266502689627, 5283.498067683564, 5287.418383977764, 5367.651102678323, 4289.206496798094, 4541.682082254243, 4912.0, 5146.536811686948, 5257.815976440816, 3722.0, 3990.4014397840265, 4514.413126293577, 4534.637229021687, 4560.794764235601, 4034.5360283567325, 4067.3933796150504, 4416.977915110436, 4619.330197121415, 4627.3624034300465, 4159.7920103994675, 4581.304917180648, 4661.300804879278, 4705.29420586913, 4710.269908164223, 4245.066085461193, 4597.172738765668, 4625.444946606395, 4627.0, 4639.327297541871, 3535.9292814143832, 4443.489428472903, 4463.388917483277, 4478.462584489849, 4487.461504619434, 4435.578620031092, 5014.5708473085, 5127.0, 5219.869503262411, 5251.658642188246, 4586.0, 5118.897622047575, 5318.175682769177, 5342.572594192456, 5348.384935732402, 4951.653384263103, 5468.890622187534, 5697.829065128011, 5710.543156547466, 5713.600167958835, 4264.946038943862, 4388.0, 4771.379720636295, 4969.155243608595, 4981.953789704166, 5393.325834270716, 5526.889462210774, 5546.500814926676, 5679.944849084318, 5919.6152250103605, 4634.395985720335, 4667.646382549091, 4880.316755654211, 5013.849584512471, 5133.640645154878, 4450.866474005785, 4866.7566621668775, 5218.504242096995, 5220.608454365938, 5255.684658920477, 4384.364267181305, 4656.674032817738, 4891.9755401222765, 5179.300794392709, 5433.565314774809, 5648.915266271009, 5756.079027355687, 5766.001679126831, 5788.507976821933, 5817.563682723812, 1556.886825919473, 1760.3046796515305, 1776.1829558403022, 1786.9807117956625, 1788.839004489602, 1249.2005116725304, 1351.48623560178, 1354.4379082680773, 1409.2319685771279, 1432.5702289313165, 4804.759762011886, 4979.30289759434, 4986.725730084808, 5121.513456221653, 5417.187421886749, 4723.433188017391, 5419.566434685215, 5746.195532625436, 5855.882882342329, 5861.824145275648, 4448.777561121996, 4942.728149951788, 5371.979323928441, 5458.754356053987, 5472.0, 2713.4708731797505, 3093.273080826004, 3127.62468503781, 3144.371125774808, 3149.4331020416544, 5306.09796334624, 5355.866103347447, 6102.816915492543, 6195.473384762256, 6204.937950620436, 5354.376355585813, 5400.621956463088, 5693.516051135617, 6097.4817140543555, 6179.84550386235, 5218.895622087537, 5309.601779866603, 5322.247815290211, 6137.938620613737, 6168.629882207082, 4690.366800482027, 5153.45888681683, 5208.583313335, 5764.711764411846, 5894.528437725057, 4794.59245964083, 5469.863253418696, 5682.403347648603, 5760.0783874579865, 5780.0, 4712.552307530779, 4873.366462359871, 5011.0, 5664.082997256499, 6127.662978536223, 3835.0220693724036, 4427.335899615084, 4513.390692256503, 4595.392616795799, 4622.375979242759, 5200.8818104107795, 5829.59192856513, 5882.941170588239, 5919.467247947706, 5966.701664916824, 4773.618110551078, 5107.0, 5153.5361817436615, 5242.895142097061, 5578.748956297058, 5579.7489112990825, 5600.971995139998, 5639.6334238274385, 5658.717064146694, 5675.659460432387, 4844.733539655212, 5336.0, 5520.447955204608, 5584.413636568264, 5617.297837770278, 5061.746912654279, 5268.604854635841, 5342.973285133705, 5442.918356224739, 5718.714064296685, 4379.146066516999, 5095.305903220135, 5345.754782991075, 5523.861903452445, 5613.803516876889], "value": 4389.000336002295, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.3727621483375, 665.5507532415621, 768.3968085053234, 815.197030924539, 823.127484866042, 14910.925445372772, 15572.221388930551, 16088.51734447967, 16370.345186192539, 16410.261538230778, 15114.47099351523, 15299.464518741846, 15311.6937661247, 15362.07827530349, 15367.462138825143, 921.9170274675278, 925.255169588481, 947.1948843483053, 950.6767698982345, 952.7856232347735, 930.930180236485, 948.7580666929923, 950.2113246005798, 954.1508057828538, 954.9618015279415, 858.9656413743475, 1005.3716427232979, 1007.4811472091878, 1008.6570566007556, 1008.7629407089328, 999.550202408919, 1000.604761119361, 1000.9649662261786, 1001.4492029383846, 1001.5843424978661, 737.7196665267218, 739.4528049243575, 740.4409670698642, 740.6370878269663, 740.8740514112613, 16228.594285142848, 16518.587035324093, 16653.583660408465, 16852.74720879188, 17072.317107315692, 13560.389782459715, 13889.236092014935, 13921.0, 13926.58220253394, 14275.428982840675, 11488.885111148866, 11616.59341923037, 11656.242344247597, 11667.59163429276, 11710.88289117115, 3612.584552776412, 3685.207680348711, 3688.7417880748358, 3714.8699795507296, 3843.0, 108338.91661083366, 108890.45554772255, 112183.0, 112839.43580282129, 113444.86555134425, 10542.789144217226, 10587.682369528926, 10601.787964240826, 10785.191110666574, 10859.945700271606, 22300.776992230196, 22366.888165559394, 22435.55128897413, 22662.66006009911, 22898.0, 7814.6092695365005, 7828.060632724109, 7842.29419352261, 7903.802404939923, 8030.0363956324445, 4285.164392943346, 4342.848000319974, 4402.93462700812, 4419.602235798729, 4534.251848445036, 3463.8960831175104, 3529.699975502111, 3531.152523394418, 3534.0811389039036, 3540.1149712571855, 648.3548868875405, 665.4343807763363, 669.534673401981, 699.1959246866145, 711.79713781572, 663.9502037347122, 689.1316940654812, 689.4105539763531, 693.4591019005184, 700.6706847781535, 8697.695580654647, 9207.309451791143, 10450.216233782345, 10579.206559507913, 10733.0, 638.1990601794718, 673.1148539670318, 673.8685956238486, 675.7837492002512, 695.7599628128288, 4786.7845946932475, 4890.413150421901, 5015.1975683890405, 5059.2916991621205, 5080.618953578496, 103775.0, 107446.46276768722, 108366.91633083727, 109581.90418095877, 110275.44862275638, 66772.0, 69865.0, 71488.0, 96804.51597741967, 99037.0, 59865.70067149724, 82919.0, 83866.16133838583, 83866.74199887007, 85599.0, 9610.231181505324, 9695.22438204956, 9775.266854985763, 9794.85307720399, 9844.753881153028, 38257.6174238254, 49341.75329123475, 50581.74709126431, 51046.723831903015, 51874.740626296625, 50944.745276274865, 64904.06435942354, 65663.34336656572, 66135.0, 66603.66698166478, 10967.670969870916, 11196.83204751913, 11364.94317528407, 11370.31778093317, 11390.487428065591, 12397.318147501974, 12523.93738031304, 12609.180403273758, 12882.48470061187, 13467.86532134666, 11574.942125289319, 11958.342291174069, 12406.13157079032, 12467.501299948264, 12491.312977786309, 11550.249233799776, 11746.58886938953, 11860.525578976743, 12029.45867435985, 12238.87761122413, 11816.350100744541, 11938.462769175581, 12070.517179312728, 12144.514219431123, 12270.509179632714, 8606.397552171287, 9030.0, 9101.817963640822, 9338.906610934075, 9664.130228279488, 6778.898316525355, 9058.230050445522, 9214.617807328686, 10286.639967601099, 10376.16990640762], "value": 7453.231673907306, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1517.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 162.0, 96.0, 98.0, 160.0, 104.0, 114.0, 124.0, 130.0, 183.0], "value": 128.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 142.0, 142.0, 147.0, 111.0, 113.0, 112.0, 112.0, 135.0, 114.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 197.0, 196.0, 204.0, 198.0, 199.0, 196.0, 196.0, 201.0, 194.0], "value": 198.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 51.0, 51.0, 101.0, 73.0, 59.0, 78.0, 85.0, 94.0, 83.0], "value": 76.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 86.0, 73.0, 72.0, 70.0, 90.0, 70.0, 71.0, 84.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 88.0, 87.0, 90.0, 98.0, 89.0, 88.0, 89.0, 108.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 105.0, 154.0, 107.0, 97.0, 97.0, 97.0, 97.0, 97.0, 98.0], "value": 104.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 112.0, 146.0, 97.0, 111.0, 115.0, 91.0, 90.0, 110.0], "value": 105.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 67.0, 70.0, 69.0, 68.0, 69.0, 70.0, 70.0], "value": 68.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 74.0, 67.0, 62.0, 58.0, 59.0, 59.0, 62.0, 86.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 103.0, 94.0, 83.0, 83.0, 86.0, 88.0, 85.0, 86.0, 94.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 154.0, 150.0, 152.0, 140.0, 142.0, 142.0, 145.0, 184.0, 141.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 168.0, 168.0, 168.0, 168.0, 166.0, 170.0, 165.0, 197.0], "value": 170.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 69.0, 66.0, 66.0, 67.0, 66.0, 67.0, 67.0, 64.0], "value": 66.1, "unit": "score"}]}]} 04:13:21 INFO - Return code: 0 04:13:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:13:21 ERROR - # TBPL SUCCESS # 04:13:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:13:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:13:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:13:21 INFO - 2017-04-06 04:13:21.145 screenresolution[1660:10703] starting screenresolution argv=screenresolution get 04:13:21 INFO - 2017-04-06 04:13:21.150 screenresolution[1660:10703] Display 0: 1600x1200x32@60 04:13:21 INFO - 2017-04-06 04:13:21.156 screenresolution[1661:10706] starting screenresolution argv=screenresolution list 04:13:21 INFO - Available Modes on Display 0 04:13:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:13:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:13:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:13:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:13:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:13:21 INFO - Intel Iris: 04:13:21 INFO - Chipset Model: Intel Iris 04:13:21 INFO - Type: GPU 04:13:21 INFO - Bus: Built-In 04:13:21 INFO - VRAM (Dynamic, Max): 1536 MB 04:13:21 INFO - Vendor: Intel (0x8086) 04:13:21 INFO - Device ID: 0x0a2e 04:13:21 INFO - Revision ID: 0x0009 04:13:21 INFO - Displays: 04:13:21 INFO - Display: 04:13:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:13:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:13:21 INFO - Display Serial Number: 1600x1200 60 04:13:21 INFO - Main Display: Yes 04:13:21 INFO - Mirror: Off 04:13:21 INFO - Online: Yes 04:13:21 INFO - Rotation: Supported 04:13:21 INFO - Return code: 0 04:13:21 INFO - Running post-action listener: _resource_record_post_action 04:13:21 INFO - [mozharness: 2017-04-06 11:13:21.399273Z] Finished run-tests step (success) 04:13:21 INFO - Running post-run listener: _resource_record_post_run 04:13:21 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 40258048; Write bytes: 513362944; Read time: 387; Write time: 1737 04:13:21 INFO - TinderboxPrint: CPU usage
54.5% 04:13:21 INFO - TinderboxPrint: I/O read bytes / time
40,258,048 / 387 04:13:21 INFO - TinderboxPrint: I/O write bytes / time
513,362,944 / 1,737 04:13:21 INFO - TinderboxPrint: CPU idle
2,183.8 (45.5%) 04:13:21 INFO - TinderboxPrint: CPU system
958.1 (20.0%) 04:13:21 INFO - TinderboxPrint: CPU user
1,655.8 (34.5%) 04:13:21 INFO - TinderboxPrint: Swap in / out
248,631,296 / 0 04:13:21 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205957632; Write bytes: 200816128; Read time: 15757; Write time: 425 04:13:21 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 38136320; Write bytes: 301483520; Read time: 361; Write time: 1276 04:13:21 INFO - Running post-run listener: _upload_blobber_files 04:13:21 INFO - Blob upload gear active. 04:13:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:13:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:13:21 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'] 04:13:21 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 04:13:22 INFO - (blobuploader) - INFO - Open directory for files ... 04:13:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:13:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:13:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:22 INFO - (blobuploader) - INFO - Done attempting. 04:13:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:13:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:13:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:23 INFO - (blobuploader) - INFO - Done attempting. 04:13:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:13:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:13:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:24 INFO - (blobuploader) - INFO - Done attempting. 04:13:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:13:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:13:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:26 INFO - (blobuploader) - INFO - Done attempting. 04:13:26 INFO - (blobuploader) - INFO - Iteration through files over. 04:13:26 INFO - Return code: 0 04:13:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:13:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:13:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e22721aac4ef25c97ec85ba2b92efad5985378da371f706b4d4c444ac7c7add9d6f495e289dc98dedd83730a2c20c4068d0c5c87cb698ab259e49deb021cebe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b7eb8630f3b02c42fe8dc055290d31c6a98655c04878ca3af9187abe7c043e6282f986441f6b6e989a92b4ffc7f7a8ca394c3a24ead6120c67ace2575cc32a91", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1479460ddb442956ac15d0c9a5ea2ede24328f9ce0641446a18899df39ce1995b6bdef8cf1123de634c61099c1e4bbfac8e4185a935059128b40681e56398c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/35b995139bdf7db3a767767a02c59cba3c0eb5a733583715f8413fb2fd36d8095722e2919734989229c2fdec7a18f59936d3d1a5b916682d846f5ab37f67f031"} 04:13:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:13:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:13:26 INFO - Contents: 04:13:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e22721aac4ef25c97ec85ba2b92efad5985378da371f706b4d4c444ac7c7add9d6f495e289dc98dedd83730a2c20c4068d0c5c87cb698ab259e49deb021cebe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b7eb8630f3b02c42fe8dc055290d31c6a98655c04878ca3af9187abe7c043e6282f986441f6b6e989a92b4ffc7f7a8ca394c3a24ead6120c67ace2575cc32a91", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1479460ddb442956ac15d0c9a5ea2ede24328f9ce0641446a18899df39ce1995b6bdef8cf1123de634c61099c1e4bbfac8e4185a935059128b40681e56398c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/35b995139bdf7db3a767767a02c59cba3c0eb5a733583715f8413fb2fd36d8095722e2919734989229c2fdec7a18f59936d3d1a5b916682d846f5ab37f67f031"} 04:13:26 INFO - Running post-run listener: copy_logs_to_upload_dir 04:13:26 INFO - Copying logs to upload dir... 04:13:26 INFO - mkdir: /builds/slave/test/build/upload/logs 04:13:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.884371 ========= master_lag: 1.27 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-06 04:13:28.273644) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 04:13:28.276825) ========= 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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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/6e22721aac4ef25c97ec85ba2b92efad5985378da371f706b4d4c444ac7c7add9d6f495e289dc98dedd83730a2c20c4068d0c5c87cb698ab259e49deb021cebe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b7eb8630f3b02c42fe8dc055290d31c6a98655c04878ca3af9187abe7c043e6282f986441f6b6e989a92b4ffc7f7a8ca394c3a24ead6120c67ace2575cc32a91", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1479460ddb442956ac15d0c9a5ea2ede24328f9ce0641446a18899df39ce1995b6bdef8cf1123de634c61099c1e4bbfac8e4185a935059128b40681e56398c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/35b995139bdf7db3a767767a02c59cba3c0eb5a733583715f8413fb2fd36d8095722e2919734989229c2fdec7a18f59936d3d1a5b916682d846f5ab37f67f031"} build_url:https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011108 build_url: 'https://queue.taskcluster.net/v1/task/MH3hCO0QRfGMxbDvq9OUBA/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/6e22721aac4ef25c97ec85ba2b92efad5985378da371f706b4d4c444ac7c7add9d6f495e289dc98dedd83730a2c20c4068d0c5c87cb698ab259e49deb021cebe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b7eb8630f3b02c42fe8dc055290d31c6a98655c04878ca3af9187abe7c043e6282f986441f6b6e989a92b4ffc7f7a8ca394c3a24ead6120c67ace2575cc32a91", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1479460ddb442956ac15d0c9a5ea2ede24328f9ce0641446a18899df39ce1995b6bdef8cf1123de634c61099c1e4bbfac8e4185a935059128b40681e56398c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/35b995139bdf7db3a767767a02c59cba3c0eb5a733583715f8413fb2fd36d8095722e2919734989229c2fdec7a18f59936d3d1a5b916682d846f5ab37f67f031"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 04:13:28.315577) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 04:13:28.315921) ========= 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.Wj2vpL0WBD/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.f1T0HeW4ID/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.005147 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 04:13:28.364040) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 04:13:28.364342) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 04:13:28.364665) ========= ========= Total master_lag: 1.42 =========