builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0172 starttime: 1485392246.88 results: success (0) buildid: 20170125145631 builduid: 6e7fbdc50fe24eb0a711df44a7530ed9 revision: b123b9a23ce95286b60fd9b0bbd27a1cc12f4d1d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-25 16:57:26.879578) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-25 16:57:26.879989) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-25 16:57:26.880307) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-25 16:57:26.906051) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 16:57:26.906317) ========= 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.JAUDryTnKl/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.UCuDyMLpDd/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-25 16:57:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.6M=0.001s 2017-01-25 16:57:27 (14.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127873 ========= master_lag: 0.18 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 16:57:27.216670) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2017-01-25 16:57:27.217002) ========= 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.JAUDryTnKl/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.UCuDyMLpDd/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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.026839 ========= master_lag: 3.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2017-01-25 16:57:30.248917) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-25 16:57:30.249212) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b123b9a23ce95286b60fd9b0bbd27a1cc12f4d1d --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 b123b9a23ce95286b60fd9b0bbd27a1cc12f4d1d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JAUDryTnKl/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.UCuDyMLpDd/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-25 16:57:30,349 truncating revision to first 12 chars 2017-01-25 16:57:30,349 Setting DEBUG logging. 2017-01-25 16:57:30,349 attempt 1/10 2017-01-25 16:57:30,349 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b123b9a23ce9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-25 16:57:31,784 unpacking tar archive at: mozilla-aurora-b123b9a23ce9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.697526 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-25 16:57:31.976485) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-25 16:57:31.976807) ========= script_repo_revision: b123b9a23ce95286b60fd9b0bbd27a1cc12f4d1d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-25 16:57:31.977211) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-25 16:57:31.977488) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-25 16:57:31.996930) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-01-25 16:57:31.997206) ========= /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.JAUDryTnKl/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.UCuDyMLpDd/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 16:57:32 INFO - MultiFileLogger online at 20170125 16:57:32 in /builds/slave/test 16:57:32 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 16:57:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:57:32 INFO - {'append_to_log': False, 16:57:32 INFO - 'base_work_dir': '/builds/slave/test', 16:57:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:57:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:57:32 INFO - 'branch': 'Mozilla-Aurora', 16:57:32 INFO - 'buildbot_json_path': 'buildprops.json', 16:57:32 INFO - 'config_files': ('talos/mac_config.py',), 16:57:32 INFO - 'default_actions': ('clobber', 16:57:32 INFO - 'read-buildbot-config', 16:57:32 INFO - 'download-and-extract', 16:57:32 INFO - 'populate-webroot', 16:57:32 INFO - 'create-virtualenv', 16:57:32 INFO - 'install', 16:57:32 INFO - 'run-tests'), 16:57:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:57:32 INFO - 'download_minidump_stackwalk': True, 16:57:32 INFO - 'download_symbols': 'ondemand', 16:57:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:57:32 INFO - 'tooltool.py': '/tools/tooltool.py', 16:57:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:57:32 INFO - '/tools/misc-python/virtualenv.py')}, 16:57:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:57:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:57:32 INFO - 'installer_path': 'installer.exe', 16:57:32 INFO - 'log_level': 'info', 16:57:32 INFO - 'log_name': 'talos', 16:57:32 INFO - 'log_to_console': True, 16:57:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:57:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:57:32 INFO - 'opt_config_files': (), 16:57:32 INFO - 'pip_index': False, 16:57:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:57:32 INFO - 'cmd': ('bash', 16:57:32 INFO - '-c', 16:57:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:57:32 INFO - 'enabled': True, 16:57:32 INFO - 'halt_on_failure': False, 16:57:32 INFO - 'name': 'check_screen_resolution'},), 16:57:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:57:32 INFO - 'cmd': ('bash', 16:57:32 INFO - '-c', 16:57:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:57:32 INFO - 'enabled': True, 16:57:32 INFO - 'halt_on_failure': False, 16:57:32 INFO - 'name': 'check_screen_resolution'},), 16:57:32 INFO - 'run_cmd_checks_enabled': True, 16:57:32 INFO - 'sps_profile': False, 16:57:32 INFO - 'sps_profile_interval': 0, 16:57:32 INFO - 'suite': 'dromaeojs-e10s', 16:57:32 INFO - 'system_bits': '32', 16:57:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:57:32 INFO - 'title': 't-yosemite-r7-0172', 16:57:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:57:32 INFO - 'use_talos_json': True, 16:57:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:57:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:57:32 INFO - 'work_dir': 'build'} 16:57:32 INFO - [mozharness: 2017-01-26 00:57:32.206209Z] Running clobber step. 16:57:32 INFO - Running pre-action listener: _resource_record_pre_action 16:57:32 INFO - Running main action method: clobber 16:57:32 INFO - rmtree: /builds/slave/test/build 16:57:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:57:35 INFO - Running post-action listener: _resource_record_post_action 16:57:35 INFO - [mozharness: 2017-01-26 00:57:35.142814Z] Finished clobber step (success) 16:57:35 INFO - [mozharness: 2017-01-26 00:57:35.142965Z] Running read-buildbot-config step. 16:57:35 INFO - Running pre-action listener: _resource_record_pre_action 16:57:35 INFO - Running main action method: read_buildbot_config 16:57:35 INFO - Using buildbot properties: 16:57:35 INFO - { 16:57:35 INFO - "project": "", 16:57:35 INFO - "product": "firefox", 16:57:35 INFO - "script_repo_revision": "production", 16:57:35 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 16:57:35 INFO - "repository": "", 16:57:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 16:57:35 INFO - "buildid": "20170125145631", 16:57:35 INFO - "pgo_build": "False", 16:57:35 INFO - "basedir": "/builds/slave/test", 16:57:35 INFO - "buildnumber": 36, 16:57:35 INFO - "slavename": "t-yosemite-r7-0172", 16:57:35 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:57:35 INFO - "platform": "yosemite_r7", 16:57:35 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 16:57:35 INFO - "branch": "mozilla-aurora", 16:57:35 INFO - "repo_path": "releases/mozilla-aurora", 16:57:35 INFO - "revision": "b123b9a23ce95286b60fd9b0bbd27a1cc12f4d1d", 16:57:35 INFO - "stage_platform": "macosx64", 16:57:35 INFO - "builduid": "6e7fbdc50fe24eb0a711df44a7530ed9", 16:57:35 INFO - "slavebuilddir": "test" 16:57:35 INFO - } 16:57:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 16:57:35 INFO - Running post-action listener: _resource_record_post_action 16:57:35 INFO - [mozharness: 2017-01-26 00:57:35.146184Z] Finished read-buildbot-config step (success) 16:57:35 INFO - [mozharness: 2017-01-26 00:57:35.146302Z] Running download-and-extract step. 16:57:35 INFO - Running pre-action listener: _resource_record_pre_action 16:57:35 INFO - Running main action method: download_and_extract 16:57:35 INFO - mkdir: /builds/slave/test/build/tests 16:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:57:35 INFO - trying https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 16:57:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 16:57:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 16:57:36 INFO - Downloaded 1199 bytes. 16:57:36 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 16:57:36 INFO - Using the following test package requirements: 16:57:36 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 16:57:36 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 16:57:36 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 16:57:36 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 16:57:36 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 16:57:36 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 16:57:36 INFO - u'jsshell-mac.zip'], 16:57:36 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 16:57:36 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 16:57:36 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 16:57:36 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 16:57:36 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 16:57:36 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 16:57:36 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 16:57:36 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 16:57:36 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 16:57:36 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 16:57:36 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 16:57:36 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 16:57:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 16:57:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:57:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 16:57:37 INFO - Expected file size: 16349693 16:57:37 INFO - Obtained file size: 16349693 16:57:44 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 16:57:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 16:57:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:57:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 16:57:44 INFO - Expected file size: 16349693 16:57:44 INFO - Obtained file size: 16349693 16:57:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 16:57:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 16:57:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 16:57:52 INFO - Expected file size: 14403485 16:57:52 INFO - Obtained file size: 14403485 16:57:52 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')]} 16:57:52 INFO - trying https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 16:57:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 16:57:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 16:57:54 INFO - Downloaded 73891627 bytes. 16:57:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 16:57:54 INFO - mkdir: /builds/slave/test/properties 16:57:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:57:54 INFO - Writing to file /builds/slave/test/properties/build_url 16:57:54 INFO - Contents: 16:57:54 INFO - build_url:https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 16:57:55 INFO - Running post-action listener: _resource_record_post_action 16:57:55 INFO - Running post-action listener: set_extra_try_arguments 16:57:55 INFO - [mozharness: 2017-01-26 00:57:55.158751Z] Finished download-and-extract step (success) 16:57:55 INFO - [mozharness: 2017-01-26 00:57:55.158858Z] Running populate-webroot step. 16:57:55 INFO - Running pre-action listener: _resource_record_pre_action 16:57:55 INFO - Running main action method: populate_webroot 16:57:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:57:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:57:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:57:55 INFO - '--tppagecycles', 16:57:55 INFO - '7'], 16:57:55 INFO - 'tests': ['tsvgm']}}, 16:57:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:57:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:57:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:57:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:57:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:57:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:57:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:57:55 INFO - 'talos_options': ['--disable-e10s'], 16:57:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:57:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:57:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:57:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:57:55 INFO - 'talos_options': ['--disable-e10s'], 16:57:55 INFO - 'tests': ['damp', 'tps']}, 16:57:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:57:55 INFO - 'tests': ['damp', 'tps']}, 16:57:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:57:55 INFO - 'tests': ['dromaeo_dom']}, 16:57:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:57:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:57:55 INFO - 'tests': ['basic_compositor_video']}, 16:57:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:57:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:57:55 INFO - 'tests': ['a11yr', 16:57:55 INFO - 'ts_paint', 16:57:55 INFO - 'tpaint', 16:57:55 INFO - 'sessionrestore', 16:57:55 INFO - 'sessionrestore_no_auto_restore', 16:57:55 INFO - 'tabpaint']}, 16:57:55 INFO - 'other-e10s': {'tests': ['a11yr', 16:57:55 INFO - 'ts_paint', 16:57:55 INFO - 'tpaint', 16:57:55 INFO - 'sessionrestore', 16:57:55 INFO - 'sessionrestore_no_auto_restore', 16:57:55 INFO - 'tabpaint']}, 16:57:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:57:55 INFO - 'ts_paint', 16:57:55 INFO - 'tpaint', 16:57:55 INFO - 'sessionrestore', 16:57:55 INFO - 'sessionrestore_no_auto_restore', 16:57:55 INFO - 'tabpaint']}, 16:57:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:57:55 INFO - 'ts_paint', 16:57:55 INFO - 'tpaint', 16:57:55 INFO - 'sessionrestore', 16:57:55 INFO - 'sessionrestore_no_auto_restore', 16:57:55 INFO - 'tabpaint']}, 16:57:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:57:55 INFO - 'tests': ['a11yr', 16:57:55 INFO - 'ts_paint', 16:57:55 INFO - 'tpaint', 16:57:55 INFO - 'sessionrestore', 16:57:55 INFO - 'sessionrestore_no_auto_restore', 16:57:55 INFO - 'tabpaint']}, 16:57:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:57:55 INFO - 'tests': ['a11yr', 16:57:55 INFO - 'ts_paint', 16:57:55 INFO - 'tpaint', 16:57:55 INFO - 'sessionrestore', 16:57:55 INFO - 'sessionrestore_no_auto_restore', 16:57:55 INFO - 'tabpaint']}, 16:57:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:57:55 INFO - 'tests': ['tsvgx', 16:57:55 INFO - 'tsvgr_opacity', 16:57:55 INFO - 'tart', 16:57:55 INFO - 'tscrollx', 16:57:55 INFO - 'cart', 16:57:55 INFO - 'tsvg_static']}, 16:57:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:57:55 INFO - 'tsvgr_opacity', 16:57:55 INFO - 'tart', 16:57:55 INFO - 'tscrollx', 16:57:55 INFO - 'cart', 16:57:55 INFO - 'tsvg_static']}, 16:57:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:57:55 INFO - 'talos_options': ['--disable-e10s'], 16:57:55 INFO - 'tests': ['tp5o']}, 16:57:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:57:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:57:55 INFO - 'talos_options': ['--disable-e10s', 16:57:55 INFO - '--xperf_path', 16:57:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:57:55 INFO - 'tests': ['tp5n']}, 16:57:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:57:55 INFO - 'talos_options': ['--xperf_path', 16:57:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:57:55 INFO - 'tests': ['tp5n']}}, 16:57:55 INFO - 'talos.zip': {'path': '', 16:57:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:57:55 INFO - Running post-action listener: _resource_record_post_action 16:57:55 INFO - [mozharness: 2017-01-26 00:57:55.177413Z] Finished populate-webroot step (success) 16:57:55 INFO - [mozharness: 2017-01-26 00:57:55.177557Z] Running create-virtualenv step. 16:57:55 INFO - Running pre-action listener: _resource_record_pre_action 16:57:55 INFO - Running main action method: create_virtualenv 16:57:55 INFO - Creating virtualenv /builds/slave/test/build/venv 16:57:55 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 16:57:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:57:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:57:55 INFO - Using real prefix '/tools/python27' 16:57:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:57:56 INFO - Installing distribute.............................................................................................................................................................................................done. 16:57:58 INFO - Installing pip.................done. 16:57:58 INFO - Return code: 0 16:57:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:57:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:57:59 INFO - Reading from file tmpfile_stdout 16:57:59 INFO - Output received: 16:57:59 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) 16:57:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:57:59 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')]} 16:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:57:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:59 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')]} 16:57:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:57:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:59 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 0x101a29b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9c6bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101807d30>, '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 0x10172edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.JAUDryTnKl/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.UCuDyMLpDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 16:57:59 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 16:57:59 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 16:57:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JAUDryTnKl/Render', 16:57:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:57:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:57:59 INFO - 'HOME': '/Users/cltbld', 16:57:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:57:59 INFO - 'LOGNAME': 'cltbld', 16:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:59 INFO - 'MOZ_NO_REMOTE': '1', 16:57:59 INFO - 'NO_EM_RESTART': '1', 16:57:59 INFO - 'PAGER': '/bin/cat', 16:57:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:59 INFO - 'PWD': '/builds/slave/test', 16:57:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:57:59 INFO - 'SHELL': '/bin/bash', 16:57:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UCuDyMLpDd/Listeners', 16:57:59 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 16:57:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:57:59 INFO - 'USER': 'cltbld', 16:57:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:57:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:59 INFO - 'XPC_FLAGS': '0x0', 16:57:59 INFO - 'XPC_SERVICE_NAME': '0', 16:57:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:57:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:57:59 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 16:57:59 INFO - Cleaning up... 16:57:59 INFO - Return code: 0 16:57:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:57:59 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')]} 16:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:57:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:59 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')]} 16:57:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:57:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:57:59 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 0x101a29b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9c6bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101807d30>, '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 0x10172edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.JAUDryTnKl/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.UCuDyMLpDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 16:57:59 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 16:57:59 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 16:57:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JAUDryTnKl/Render', 16:57:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:57:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:57:59 INFO - 'HOME': '/Users/cltbld', 16:57:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:57:59 INFO - 'LOGNAME': 'cltbld', 16:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:57:59 INFO - 'MOZ_NO_REMOTE': '1', 16:57:59 INFO - 'NO_EM_RESTART': '1', 16:57:59 INFO - 'PAGER': '/bin/cat', 16:57:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:57:59 INFO - 'PWD': '/builds/slave/test', 16:57:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:57:59 INFO - 'SHELL': '/bin/bash', 16:57:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UCuDyMLpDd/Listeners', 16:57:59 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 16:57:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:57:59 INFO - 'USER': 'cltbld', 16:57:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:57:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:57:59 INFO - 'XPC_FLAGS': '0x0', 16:57:59 INFO - 'XPC_SERVICE_NAME': '0', 16:57:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:57:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:57:59 INFO - Downloading/unpacking psutil>=3.1.1 16:57:59 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 16:57:59 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 16:57:59 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 16:57:59 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 16:57:59 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 16:57:59 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 16:58:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:58:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:58:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:58:03 INFO - Installing collected packages: psutil 16:58:03 INFO - Running setup.py install for psutil 16:58:03 INFO - building 'psutil._psutil_osx' extension 16:58:03 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 16:58:03 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 16:58:04 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 16:58:04 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 16:58:04 INFO - building 'psutil._psutil_posix' extension 16:58:04 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 16:58:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:58:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:58:04 INFO - ^ 16:58:04 INFO - 1 warning generated. 16:58:04 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 16:58:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:58:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:58:04 INFO - Successfully installed psutil 16:58:04 INFO - Cleaning up... 16:58:04 INFO - Return code: 0 16:58:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:58:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:04 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 0x101a29b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9c6bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101807d30>, '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 0x10172edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.JAUDryTnKl/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.UCuDyMLpDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 16:58:04 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 16:58:04 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 16:58:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JAUDryTnKl/Render', 16:58:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:58:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:58:04 INFO - 'HOME': '/Users/cltbld', 16:58:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:58:04 INFO - 'LOGNAME': 'cltbld', 16:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:04 INFO - 'MOZ_NO_REMOTE': '1', 16:58:04 INFO - 'NO_EM_RESTART': '1', 16:58:04 INFO - 'PAGER': '/bin/cat', 16:58:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:04 INFO - 'PWD': '/builds/slave/test', 16:58:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:58:04 INFO - 'SHELL': '/bin/bash', 16:58:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UCuDyMLpDd/Listeners', 16:58:04 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 16:58:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:58:04 INFO - 'USER': 'cltbld', 16:58:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:58:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:04 INFO - 'XPC_FLAGS': '0x0', 16:58:04 INFO - 'XPC_SERVICE_NAME': '0', 16:58:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:58:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:58: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 16:58: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 16:58: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 16:58: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 16:58: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 16:58: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 16:58:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:58:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:58:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:58:07 INFO - Installing collected packages: mozsystemmonitor 16:58:07 INFO - Running setup.py install for mozsystemmonitor 16:58:07 INFO - Successfully installed mozsystemmonitor 16:58:07 INFO - Cleaning up... 16:58:07 INFO - Return code: 0 16:58:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:58:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:07 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 0x101a29b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9c6bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101807d30>, '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 0x10172edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.JAUDryTnKl/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.UCuDyMLpDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 16:58:07 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 16:58:07 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 16:58:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JAUDryTnKl/Render', 16:58:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:58:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:58:07 INFO - 'HOME': '/Users/cltbld', 16:58:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:58:07 INFO - 'LOGNAME': 'cltbld', 16:58:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:07 INFO - 'MOZ_NO_REMOTE': '1', 16:58:07 INFO - 'NO_EM_RESTART': '1', 16:58:07 INFO - 'PAGER': '/bin/cat', 16:58:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:58:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:07 INFO - 'PWD': '/builds/slave/test', 16:58:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:58:07 INFO - 'SHELL': '/bin/bash', 16:58:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UCuDyMLpDd/Listeners', 16:58:07 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 16:58:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:58:07 INFO - 'USER': 'cltbld', 16:58:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:58:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:58:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:07 INFO - 'XPC_FLAGS': '0x0', 16:58:07 INFO - 'XPC_SERVICE_NAME': '0', 16:58:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:58:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:07 INFO - Downloading/unpacking jsonschema==2.5.1 16:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:58:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:58:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:58:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:58:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:58:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:58:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:58:11 INFO - Downloading functools32-3.2.3-2.tar.gz 16:58:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:58:11 INFO - warning: no files found matching '*.txt' 16:58:11 INFO - no previously-included directories found matching 'build' 16:58:11 INFO - no previously-included directories found matching 'dist' 16:58:11 INFO - no previously-included directories found matching '.git*' 16:58:11 INFO - Installing collected packages: jsonschema, functools32 16:58:11 INFO - Running setup.py install for functools32 16:58:11 INFO - warning: no files found matching '*.txt' 16:58:11 INFO - no previously-included directories found matching 'build' 16:58:11 INFO - no previously-included directories found matching 'dist' 16:58:11 INFO - no previously-included directories found matching '.git*' 16:58:11 INFO - Successfully installed jsonschema functools32 16:58:11 INFO - Cleaning up... 16:58:11 INFO - Return code: 0 16:58:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:58:11 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')]} 16:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:11 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')]} 16:58:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:11 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 0x101a29b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9c6bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101807d30>, '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 0x10172edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.JAUDryTnKl/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.UCuDyMLpDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 16:58:11 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 16:58:11 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 16:58:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JAUDryTnKl/Render', 16:58:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:58:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:58:11 INFO - 'HOME': '/Users/cltbld', 16:58:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:58:11 INFO - 'LOGNAME': 'cltbld', 16:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:11 INFO - 'MOZ_NO_REMOTE': '1', 16:58:11 INFO - 'NO_EM_RESTART': '1', 16:58:11 INFO - 'PAGER': '/bin/cat', 16:58:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:58:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:11 INFO - 'PWD': '/builds/slave/test', 16:58:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:58:11 INFO - 'SHELL': '/bin/bash', 16:58:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UCuDyMLpDd/Listeners', 16:58:11 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 16:58:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:58:11 INFO - 'USER': 'cltbld', 16:58:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:58:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:11 INFO - 'XPC_FLAGS': '0x0', 16:58:11 INFO - 'XPC_SERVICE_NAME': '0', 16:58:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:58:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:58:11 INFO - Cleaning up... 16:58:11 INFO - Return code: 0 16:58:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:58:11 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')]} 16:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:11 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')]} 16:58:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:11 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 0x101a29b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9c6bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101807d30>, '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 0x10172edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.JAUDryTnKl/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.UCuDyMLpDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 16:58:11 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 16:58:11 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 16:58:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JAUDryTnKl/Render', 16:58:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:58:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:58:11 INFO - 'HOME': '/Users/cltbld', 16:58:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:58:11 INFO - 'LOGNAME': 'cltbld', 16:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:11 INFO - 'MOZ_NO_REMOTE': '1', 16:58:11 INFO - 'NO_EM_RESTART': '1', 16:58:11 INFO - 'PAGER': '/bin/cat', 16:58:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:58:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:11 INFO - 'PWD': '/builds/slave/test', 16:58:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:58:11 INFO - 'SHELL': '/bin/bash', 16:58:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UCuDyMLpDd/Listeners', 16:58:11 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 16:58:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:58:11 INFO - 'USER': 'cltbld', 16:58:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:58:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:11 INFO - 'XPC_FLAGS': '0x0', 16:58:11 INFO - 'XPC_SERVICE_NAME': '0', 16:58:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:58:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:12 INFO - Downloading/unpacking blobuploader==1.2.4 16:58:12 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 16:58:12 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 16:58:12 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 16:58:12 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 16:58:12 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 16:58:12 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 16:58:14 INFO - Downloading blobuploader-1.2.4.tar.gz 16:58:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:58:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:58:14 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 16:58:14 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 16:58:14 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 16:58:14 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 16:58:14 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 16:58:14 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 16:58:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:58:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:58:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:58:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:58:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:58:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:58:15 INFO - Downloading docopt-0.6.1.tar.gz 16:58:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:58:16 INFO - Installing collected packages: blobuploader, requests, docopt 16:58:16 INFO - Running setup.py install for blobuploader 16:58:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:58:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:58:16 INFO - Running setup.py install for requests 16:58:16 INFO - Running setup.py install for docopt 16:58:16 INFO - Successfully installed blobuploader requests docopt 16:58:16 INFO - Cleaning up... 16:58:16 INFO - Return code: 0 16:58:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:58:16 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')]} 16:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:16 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')]} 16:58:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:16 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 0x101a29b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9c6bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101807d30>, '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 0x10172edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.JAUDryTnKl/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.UCuDyMLpDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 16:58:16 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 16:58:16 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 16:58:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JAUDryTnKl/Render', 16:58:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:58:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:58:16 INFO - 'HOME': '/Users/cltbld', 16:58:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:58:16 INFO - 'LOGNAME': 'cltbld', 16:58:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:16 INFO - 'MOZ_NO_REMOTE': '1', 16:58:16 INFO - 'NO_EM_RESTART': '1', 16:58:16 INFO - 'PAGER': '/bin/cat', 16:58:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:58:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:16 INFO - 'PWD': '/builds/slave/test', 16:58:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:58:16 INFO - 'SHELL': '/bin/bash', 16:58:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UCuDyMLpDd/Listeners', 16:58:16 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 16:58:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:58:16 INFO - 'USER': 'cltbld', 16:58:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:58:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:58:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:16 INFO - 'XPC_FLAGS': '0x0', 16:58:16 INFO - 'XPC_SERVICE_NAME': '0', 16:58:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:58:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:58:17 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-vNIPQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:58:17 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Q7Io54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:58:17 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-VJyymy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:58:17 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-LlBck5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:58:17 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Ok6XlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:58:17 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-jCVWAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:58:17 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-jF9Fdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:58:17 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-pacYj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:58:18 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-ab_Eat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:58:18 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-aqBA5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:58:18 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-_FcpkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:58:18 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-4rGK3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:58:18 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-68nvrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:58:18 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Jz7d7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:58:18 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Fb6FEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:58:18 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-8SjuUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:58:18 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-J1ztWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:58:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:58:19 INFO - Running setup.py install for manifestparser 16:58:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:58:19 INFO - Running setup.py install for mozcrash 16:58:19 INFO - Running setup.py install for mozdebug 16:58:19 INFO - Running setup.py install for mozdevice 16:58:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:58:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:58:19 INFO - Running setup.py install for mozfile 16:58:19 INFO - Running setup.py install for mozhttpd 16:58:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:58:19 INFO - Running setup.py install for mozinfo 16:58:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:58:20 INFO - Running setup.py install for mozInstall 16:58:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:58:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:58:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:58:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:58:20 INFO - Running setup.py install for mozleak 16:58:20 INFO - Running setup.py install for mozlog 16:58:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:58:20 INFO - Running setup.py install for moznetwork 16:58:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:58:20 INFO - Running setup.py install for mozprocess 16:58:20 INFO - Running setup.py install for mozprofile 16:58:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:58:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:58:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:58:20 INFO - Running setup.py install for mozrunner 16:58:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:58:21 INFO - Running setup.py install for mozscreenshot 16:58:21 INFO - Running setup.py install for moztest 16:58:21 INFO - Running setup.py install for mozversion 16:58:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:58:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:58:21 INFO - Cleaning up... 16:58:21 INFO - Return code: 0 16:58:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:58: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')]} 16:58:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:22 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')]} 16:58:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:22 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 0x101a29b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9c6bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101807d30>, '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 0x10172edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.JAUDryTnKl/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.UCuDyMLpDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 16:58:22 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 16:58:22 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 16:58:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JAUDryTnKl/Render', 16:58:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:58:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:58:22 INFO - 'HOME': '/Users/cltbld', 16:58:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:58:22 INFO - 'LOGNAME': 'cltbld', 16:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:22 INFO - 'MOZ_NO_REMOTE': '1', 16:58:22 INFO - 'NO_EM_RESTART': '1', 16:58:22 INFO - 'PAGER': '/bin/cat', 16:58:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:22 INFO - 'PWD': '/builds/slave/test', 16:58:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:58:22 INFO - 'SHELL': '/bin/bash', 16:58:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UCuDyMLpDd/Listeners', 16:58:22 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 16:58:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:58:22 INFO - 'USER': 'cltbld', 16:58:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:58:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:22 INFO - 'XPC_FLAGS': '0x0', 16:58:22 INFO - 'XPC_SERVICE_NAME': '0', 16:58:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:58:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:58:22 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-no9qWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:58:22 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)) 16:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:58:22 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-ATdus8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:58:22 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)) 16:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:58:22 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-dfMgld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:58:22 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)) 16:58:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:58:22 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-1qWSe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:58:23 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)) 16:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:58:23 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-S7_yzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:58:23 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)) 16:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:58:23 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-6L0Ydv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:58:23 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)) 16:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:58:23 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-MWfVny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:58:23 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)) 16:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:58:23 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-dSqsDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:58:23 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)) 16:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:58:23 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-qAB757-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:58:23 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)) 16:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:58:23 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-VJFo5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:58:23 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)) 16:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:58:23 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-MKCrlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:58:23 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)) 16:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:58:23 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-xj9hsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:58:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:58:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:58:23 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-rXDGpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:58:24 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)) 16:58:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:58:24 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-_jBNvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:58:24 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)) 16:58:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:58:24 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-YPqAl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:58:24 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)) 16:58:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:58:24 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Gwxo6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:58:24 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)) 16:58:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:58:24 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-6M2oZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:58:24 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)) 16:58:24 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)) 16:58:24 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)) 16:58:24 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)) 16:58:24 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)) 16:58:24 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)) 16:58:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:58:24 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 16:58:24 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 16:58:24 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 16:58:24 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 16:58:24 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 16:58:24 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 16:58:27 INFO - Downloading blessings-1.6.tar.gz 16:58:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:58:27 INFO - Installing collected packages: blessings 16:58:27 INFO - Running setup.py install for blessings 16:58:27 INFO - Successfully installed blessings 16:58:27 INFO - Cleaning up... 16:58:27 INFO - Return code: 0 16:58:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:58:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:58:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:58:28 INFO - Reading from file tmpfile_stdout 16:58:28 INFO - Current package versions: 16:58:28 INFO - blessings == 1.6 16:58:28 INFO - blobuploader == 1.2.4 16:58:28 INFO - docopt == 0.6.1 16:58:28 INFO - functools32 == 3.2.3-2 16:58:28 INFO - jsonschema == 2.5.1 16:58:28 INFO - manifestparser == 1.1 16:58:28 INFO - mozInstall == 1.12 16:58:28 INFO - mozcrash == 1.0 16:58:28 INFO - mozdebug == 0.1 16:58:28 INFO - mozdevice == 0.49 16:58:28 INFO - mozfile == 1.2 16:58:28 INFO - mozhttpd == 0.7 16:58:28 INFO - mozinfo == 0.9 16:58:28 INFO - mozleak == 0.1 16:58:28 INFO - mozlog == 3.4 16:58:28 INFO - moznetwork == 0.27 16:58:28 INFO - mozprocess == 0.24 16:58:28 INFO - mozprofile == 0.28 16:58:28 INFO - mozrunner == 6.13 16:58:28 INFO - mozscreenshot == 0.1 16:58:28 INFO - mozsystemmonitor == 0.3 16:58:28 INFO - moztest == 0.8 16:58:28 INFO - mozversion == 1.4 16:58:28 INFO - psutil == 3.1.1 16:58:28 INFO - requests == 1.2.3 16:58:28 INFO - wsgiref == 0.1.2 16:58:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:58:28 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')]} 16:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:28 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')]} 16:58:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:28 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 0x101a29b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9c6bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101807d30>, '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 0x10172edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.JAUDryTnKl/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.UCuDyMLpDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 16:58:28 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 16:58:28 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 16:58:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JAUDryTnKl/Render', 16:58:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:58:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:58:28 INFO - 'HOME': '/Users/cltbld', 16:58:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:58:28 INFO - 'LOGNAME': 'cltbld', 16:58:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:28 INFO - 'MOZ_NO_REMOTE': '1', 16:58:28 INFO - 'NO_EM_RESTART': '1', 16:58:28 INFO - 'PAGER': '/bin/cat', 16:58:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:58:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:28 INFO - 'PWD': '/builds/slave/test', 16:58:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:58:28 INFO - 'SHELL': '/bin/bash', 16:58:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UCuDyMLpDd/Listeners', 16:58:28 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 16:58:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:58:28 INFO - 'USER': 'cltbld', 16:58:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:58:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:58:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:28 INFO - 'XPC_FLAGS': '0x0', 16:58:28 INFO - 'XPC_SERVICE_NAME': '0', 16:58:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:58:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:28 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)) 16:58:28 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)) 16:58:28 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)) 16:58:28 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)) 16:58:28 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)) 16:58:28 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)) 16:58:28 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)) 16:58:28 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)) 16:58:28 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)) 16:58:28 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)) 16:58:28 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)) 16:58:28 INFO - Cleaning up... 16:58:28 INFO - Return code: 0 16:58:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:58:28 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')]} 16:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:58:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:28 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')]} 16:58:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:58:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:58:28 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 0x101a29b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a2c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfd9c6bd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101807d30>, '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 0x10172edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.JAUDryTnKl/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.UCuDyMLpDd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 16:58:28 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 16:58:28 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 16:58:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JAUDryTnKl/Render', 16:58:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:58:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:58:28 INFO - 'HOME': '/Users/cltbld', 16:58:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:58:28 INFO - 'LOGNAME': 'cltbld', 16:58:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:28 INFO - 'MOZ_NO_REMOTE': '1', 16:58:28 INFO - 'NO_EM_RESTART': '1', 16:58:28 INFO - 'PAGER': '/bin/cat', 16:58:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:58:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:28 INFO - 'PWD': '/builds/slave/test', 16:58:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:58:28 INFO - 'SHELL': '/bin/bash', 16:58:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UCuDyMLpDd/Listeners', 16:58:28 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 16:58:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:58:28 INFO - 'USER': 'cltbld', 16:58:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:58:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:58:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:28 INFO - 'XPC_FLAGS': '0x0', 16:58:28 INFO - 'XPC_SERVICE_NAME': '0', 16:58:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:58:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:58:28 INFO - Cleaning up... 16:58:28 INFO - Return code: 0 16:58:28 INFO - Running post-action listener: _resource_record_post_action 16:58:28 INFO - Running post-action listener: _start_resource_monitoring 16:58:28 INFO - Starting resource monitoring. 16:58:28 INFO - [mozharness: 2017-01-26 00:58:28.732033Z] Finished create-virtualenv step (success) 16:58:28 INFO - [mozharness: 2017-01-26 00:58:28.732982Z] Running install step. 16:58:28 INFO - Running pre-action listener: _resource_record_pre_action 16:58:28 INFO - Running main action method: install 16:58:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:58:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:58:28 INFO - Reading from file tmpfile_stdout 16:58:28 INFO - Detecting whether we're running mozinstall >=1.0... 16:58:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:58:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:58:29 INFO - Reading from file tmpfile_stdout 16:58:29 INFO - Output received: 16:58:29 INFO - Usage: mozinstall [options] installer 16:58:29 INFO - Options: 16:58:29 INFO - -h, --help show this help message and exit 16:58:29 INFO - -d DEST, --destination=DEST 16:58:29 INFO - Directory to install application into. [default: 16:58:29 INFO - "/builds/slave/test"] 16:58:29 INFO - --app=APP Application being installed. [default: firefox] 16:58:29 INFO - mkdir: /builds/slave/test/build/application 16:58:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:58:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 16:58:49 INFO - Reading from file tmpfile_stdout 16:58:49 INFO - Output received: 16:58:49 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:58:49 INFO - Running post-action listener: _resource_record_post_action 16:58:49 INFO - [mozharness: 2017-01-26 00:58:49.064369Z] Finished install step (success) 16:58:49 INFO - [mozharness: 2017-01-26 00:58:49.064539Z] Running run-tests step. 16:58:49 INFO - Running pre-action listener: _resource_record_pre_action 16:58:49 INFO - Running main action method: run_tests 16:58:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:58:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:58:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:58:49 INFO - 2017-01-25 16:58:49.079 screenresolution[1622:7338] starting screenresolution argv=screenresolution get 16:58:49 INFO - 2017-01-25 16:58:49.081 screenresolution[1622:7338] Display 0: 1600x1200x32@60 16:58:49 INFO - 2017-01-25 16:58:49.087 screenresolution[1623:7341] starting screenresolution argv=screenresolution list 16:58:49 INFO - Available Modes on Display 0 16:58:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:58:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:58:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:58:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:58:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:58:49 INFO - Intel Iris: 16:58:49 INFO - Chipset Model: Intel Iris 16:58:49 INFO - Type: GPU 16:58:49 INFO - Bus: Built-In 16:58:49 INFO - VRAM (Dynamic, Max): 1536 MB 16:58:49 INFO - Vendor: Intel (0x8086) 16:58:49 INFO - Device ID: 0x0a2e 16:58:49 INFO - Revision ID: 0x0009 16:58:49 INFO - Displays: 16:58:49 INFO - Display: 16:58:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:58:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:58:49 INFO - Display Serial Number: 1600x1200 60 16:58:49 INFO - Main Display: Yes 16:58:49 INFO - Mirror: Off 16:58:49 INFO - Online: Yes 16:58:49 INFO - Rotation: Supported 16:58:49 INFO - Return code: 0 16:58:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:58:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:58:49 INFO - Python 2.7.3 16:58:49 INFO - Return code: 0 16:58:49 INFO - grabbing minidump binary from tooltool 16:58:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:58:49 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 0x101807d30>, '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 0x10172edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:58:49 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 16:58:49 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 16:58:49 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 16:58:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:58:49 INFO - Return code: 0 16:58:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:58:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:58:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:58:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:58:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:58:49 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/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0172', '--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 16:58:49 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/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0172 --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 16:58:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JAUDryTnKl/Render', 16:58:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:58:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:58:49 INFO - 'HOME': '/Users/cltbld', 16:58:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:58:49 INFO - 'LOGNAME': 'cltbld', 16:58:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:58:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:58:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:58:49 INFO - 'MOZ_NO_REMOTE': '1', 16:58:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:58:49 INFO - 'NO_EM_RESTART': '1', 16:58:49 INFO - 'PAGER': '/bin/cat', 16:58:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:58:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:58:49 INFO - 'PWD': '/builds/slave/test', 16:58:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:58:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:58:49 INFO - 'SHELL': '/bin/bash', 16:58:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UCuDyMLpDd/Listeners', 16:58:49 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 16:58:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:58:49 INFO - 'USER': 'cltbld', 16:58:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:58:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:58:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:58:49 INFO - 'XPC_FLAGS': '0x0', 16:58:49 INFO - 'XPC_SERVICE_NAME': '0', 16:58:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:58:49 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/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0172', '--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 16:58:49 INFO - mozversion application_buildid: 20170125145631 16:58:49 INFO - mozversion application_changeset: b123b9a23ce95286b60fd9b0bbd27a1cc12f4d1d 16:58:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:58:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:58:49 INFO - mozversion application_name: Firefox 16:58:49 INFO - mozversion application_remotingname: firefox-dev 16:58:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:58:49 INFO - mozversion application_vendor: Mozilla 16:58:49 INFO - mozversion application_version: 53.0a2 16:58:49 INFO - mozversion platform_buildid: 20170125145631 16:58:49 INFO - mozversion platform_changeset: b123b9a23ce95286b60fd9b0bbd27a1cc12f4d1d 16:58:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:58:49 INFO - mozversion platform_version: 53.0a2 16:58:49 INFO - using testdate: 1485392329 16:58:49 INFO - actual date: 1485392329 16:58:49 INFO - starting webserver on 'localhost:49248' 16:58:49 INFO - SUITE-START | Running 2 tests 16:58:49 INFO - TEST-START | dromaeo_css 16:58:49 INFO - operating with platform_type : mac_ 16:58:49 INFO - Initialising browser for dromaeo_css test... 16:58:49 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpuVObIo/profile http://localhost:49248/getInfo.html) 16:58:52 INFO - PROCESS | 1633 | 2017-01-25 16:58:52.881 plugin-container[1636:7523] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3b, name = 'com.apple.tsm.portname' 16:58:52 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:58:52 INFO - PROCESS | 1633 | 2017-01-25 16:58:52.885 plugin-container[1636:7523] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 16:58:52 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:58:52 INFO - PROCESS | 1633 | 2017-01-25 16:58:52.885 plugin-container[1636:7523] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:58:52 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 16:58:52 INFO - PROCESS | 1633 | colorDepth:24 16:58:52 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 16:58:52 INFO - PROCESS | 1633 | __metrics 16:58:53 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 16:58:53 INFO - PROCESS | 1633 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 16:58:53 INFO - TEST-INFO | 1633: exit 0 16:58:53 INFO - Browser initialized. 16:58:53 INFO - Running cycle 1/1 for dromaeo_css test... 16:58:53 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpuVObIo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:58:56 INFO - PROCESS | 1637 | 2017-01-25 16:58:56.064 plugin-container[1638:7661] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 16:58:56 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:58:56 INFO - PROCESS | 1637 | 2017-01-25 16:58:56.066 plugin-container[1638:7661] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.CFPasteboardClient' 16:58:56 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:58:56 INFO - PROCESS | 1637 | 2017-01-25 16:58:56.066 plugin-container[1638:7661] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:59:05 INFO - PROCESS | 1637 | RSS: Main: 218644480 16:59:05 INFO - PROCESS | 1637 | 17:02:08 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 17:02:10 INFO - PROCESS | 1637 | RSS: Main: 238907392 17:02:10 INFO - PROCESS | 1637 | 17:03:54 INFO - PROCESS | 1637 | ************************* 17:03:54 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 17:03:54 INFO - PROCESS | 1637 | 17:03:54 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:03:54 INFO - PROCESS | 1637 | Full stack: 17:03:54 INFO - PROCESS | 1637 | ************************* 17:05:12 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 17:05:13 INFO - PROCESS | 1637 | RSS: Main: 248807424 17:05:13 INFO - PROCESS | 1637 | 17:08:15 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 17:08:15 INFO - PROCESS | 1637 | RSS: Main: 255635456 17:08:15 INFO - PROCESS | 1637 | 17:11:17 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 17:11:18 INFO - PROCESS | 1637 | RSS: Main: 252030976 17:11:18 INFO - PROCESS | 1637 | 17:14:20 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 17:14:21 INFO - PROCESS | 1637 | RSS: Main: 256770048 17:14:21 INFO - PROCESS | 1637 | 17:17:24 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 17:17:24 INFO - PROCESS | 1637 | RSS: Main: 257867776 17:17:24 INFO - PROCESS | 1637 | 17:17:24 INFO - PROCESS | 1637 | __start_tp_report 17:17:24 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 17:17:24 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 17:17:24 INFO - PROCESS | 1637 | |i|pagename|runs| 17:17:24 INFO - PROCESS | 1637 | |0;dojo.html;3372.1738174147335;3385.5598772159615;3391.3386889556537;3391.5421418108563;3407.097119263397;5507.752151153198;5750.1949727038245;5840.912386314203;5905.055191169414;5918.674472903988;5432.728363581825;5870.735816888239;5959.851003724909;5971.402859714027;5986.580939334249;4474.574915383049;5248.456189706614;5319.5744340452675;5337.11937530307;5380.192971054334;5393.946060539403;5397.892042159155;5399.676019438846;5403.51368376846;5429.212764149196;5216.843494695166;5239.096872829748;5345.4120046795015;5389.51494365507;5404.594655400841;5334;5336.546393556572;5341.145416733343;5350.678959262456;5359.9732001340135;5175.637705360627;5302.761375738101;5309.442508536626;5340.332458442695;5344.812931547377;5919.644821310735;5950.404959504058;5974.97012514939;5975.492083172936;5978.252718410199;5975.551833612496;6132.324915438552;6143.051335359008;6191.752329906798;6228.626282423069;5216.243644671501;6221.160143380676;6235.438810507031;6238.095476155977;6243.87512249757;6018.6689732064715;6023.548233882477;6023.728932198062;6052.727627256784;6057.182280392179;5221.451747566527;6141.240538582545;6188.907166392508;6210.658413787285;6226.937730622726;6196.690165491797;6209.534284928558;6214.564980451325;6215.471684906834;6230.906536401958;6226.346233645492;6240.375962403723;6246.75012999484;6246.968765156145;6250.874982500415;6053.364396738367;6094.085887116879;6114.540688159484;6157.784477543264;6206.844828879314;6161.691915404212;6190.102435146879;6194.225721784777;6197.81406557804;6208.72060757267;5060.5951523878;5063.8987220255385;5075.390953085654;5076.44159142494;5087.64386492942;4358.847440339573;4394.560543945644;4410.80151393188;4423.623991960655;4493;5920.615160014585;6015.187949626832;6070;6086.330503644595;6095.6952152392205;6201.937980620226;6220.688965551704;6224.408681175281;6226.283977342573;6240.032794916795;5753.683547404849;5761.625494342854;6016.939830601726;6022.608530445507;6059.697015149313;5443.401225865152;5499.065158922993;5515.944840551623;5585.1901474286005;5650.378458369658;6282.465990390685;6356.872862542723;6365.427111559982;6385.393387628168;6441.71012304438;6122.916604401471;6130.233720784902;6131.049687298476;6137.84655383619;6160.568760186744;6277.905831412441;6325.1144839721555;6340.7463701451425;6342.968285158545;6348.492120630431;6159.384061593984;6164.198654174928;6181.227346581677;6188.133661287513;6213.471854892385;6107.61364822091;6130.245269657117;6141.393954534099;6155.199824022849;6197.318294987638;6181.907271390843;6187.81436556904;6201.131841542278;6201.844953876189;6212.844678882883;4839.443464001685;4927.9507204927495;5337.519623233928;5743.253377060955;6168.290646575701;5912.408759124053;5964.940350596438;6022.036474164112;6045.183900173421;6158.507319414526;6140.508759299335;6192.504599632109;6215.875682486416;6230.127782110599;6234.220722409699;5934.584579079423;6077.848053798691;6155.784547540994;6157.84605384869;6173.197484327009 17:17:24 INFO - PROCESS | 1637 | |1;ext.html;451.3838610296946;497.8456678429687;499.0288360974151;502.58536707216547;548.7558036673681;37080.81459592702;39012.21975560487;39020.21959560807;39044.609553904455;39120.41319380208;40061.998450038765;40843.59156408435;40862.38706419399;40929.56746513872;40939.385909211385;8448.450850694717;8647.135286471334;9153.95423022884;9509.001554836746;11302.547898084067;11242.437878106102;11530.884691153107;11537.192396532246;11543.595974140906;11551.942240288787;11532.884671153264;11541.307521548733;11568.59509917153;11576.59481918133;11579.073674106094;11485.138614603897;11541.365224912626;11543.826842597367;11550.191486595942;11567.768644627102;11379.544818207345;11426.37154956477;11459.31244125355;11470.885291147066;11472.655820325404;39157.608423915684;39414.802925985474;39596.40605390922;39636.80181599074;39723;3803.2393521295853;3862.2854771867014;4003.499562554681;4057.7768222747736;4288.614024737789;28573.57139642907;29164.854175729197;29243.68403421851;30217;30324.393512129853;3206.951895721566;3260.315333779909;3273.492608645664;3279.737620990315;3346.6987971082476;360461;360485;361489;361810;363138;61746.69126654428;62052.68973655193;62060.37939620546;62135.75728485405;62145;61081.77836443246;61417;61637.69181154066;61700;61782.76434471375;16188;16728.33086676544;16848.663026739643;16867.240974156193;16880.240389182516;11786.823197652042;12064.457099430547;12533.498660053496;12841.871581284067;12916.935415322863;14355.210463424402;16255;16306.429274975319;16347.019178849307;16401.753973690404;6578.63817490033;7747.535147891144;7783.33841623457;7785.844283114306;7848.489848159851;893.8972018217858;897.3225214962662;912.9908700913039;918.5315489100474;935.7192842147329;25551.61672574913;25968.610470842952;27768.472733999824;30502.23744406363;30880;903.5753195998002;927.9350445468888;986.7039888033561;1013.3565186106728;1045.6444808765057;14011.159330440207;15672.981256218309;15858.96916700434;15973.121478318804;16746.246418911178;442092;513009;513516;516774;517590;59339.516512087546;60603.69698151481;60711;60730.69634651799;60942;1332.4270563657526;1355.1462578575433;1366.1120271823195;1366.719822436418;1406.9869693820433;28403.86384544595;29967.400651987275;29995.400091997602;30156.396872061996;30632.081037568907;50819.74590127026;51221;51298.48701512937;51311.74344128255;51379.2293115611;397799;399971;401958;402258.9887050546;402417.9879100703;24374.634380483953;25149.87425062863;25196.24411267665;25828.966841326135;40767.1846563073;42082.947926302084;42296.45093945623;42934.35598466088;43078.138437231704;43331.78334108309;33846;35543.289134216655;36108.45837312495;36182.81908590545;36368.27263454769;32938.67061329356;33750;35789.46315805317;36169.63830361663;36774.8161259192;34390.28520765512;34877;35270.47094293536;35609;35750.8212458936;26984.865075674497;31030;32418.865339712997;32605.836970815944;33097.669023309456;24072.75927240775;24193.516129676955;24262.636060458753;24411.633825492278;24744.876275619226 17:17:24 INFO - PROCESS | 1637 | |2;jquery.html;361.36710675550256;387.8332317103646;396.6806720589924;400.4173926936308;412.12012353625;16598.419055333037;20879.47801304968;20915.895420522913;21536.676949845765;21554.245601403953;21073.894630526825;21250.36248912535;21253.68119478209;21308.36074917751;21462.570748585018;5658.377578466366;5815.47660710536;5852.824415267549;5892.14563888236;5926.1110833374905;5681.801136960207;5882.14708867214;5896.262967129109;6003.369646187153;6006.729697163616;5850.414958504158;5860.824175274749;5883.705814709268;5885.470307672309;5911.3201981772;5593.04918163911;5791.652500849962;5835.795747148872;5857.297124345085;5858.12128180772;4749.382580264543;5012.348394708664;5172.250023746573;5172.482751724835;5175.560077393426;24797.876010620013;25244.49511009788;25917.61123583148;25917.704114794185;26251;2767.501849667059;2796.860156992142;2810.367667274847;2829.3492496725867;2834.63149790528;15583.987040842308;16028.919855400765;16071;16357.263923123488;16766.413175538797;1888.055972013993;1888.962220755591;1897.089397089391;1901.277514544478;1901.9429417117508;117314;121728;121785.39107304496;121925.99999999911;123714;11571.884281157249;11585.478653460614;11665.125115616363;11812.35032073227;11856.110791690486;12840.165389249669;13493.460261589622;14519.709605807824;14522.056066355653;14537.636559086108;8737.475751454933;8990.235829954396;9121.589528471235;9170.495622740813;9191.540422978824;9056.275497960147;9079.773005674779;9092.454452732858;9238.44569325829;9279.443233406017;6432;7264.019357386791;7302.96348518254;7451.031365922396;7492.175860655324;1798.8021317655048;1900.2019151956213;1921.567647279351;1925.2202857842597;1931.169597073272;895.2614093372994;919.2002957426947;931.5807886451124;942.7696855603504;948.9905100948902;22104;22303;22375.440613984454;22582;22703.318900433012;751.8233215194425;946.8461523256054;957.7222605444343;969.6703120938773;983.5180761426992;6610.933890661032;6993.965030174817;6996.755113571103;7013.649317534103;7030.191527974246;751836;768229;784093;784709;812169;143756;166671;166886.33113668952;167529.16235418746;173843;987.9506024698736;1064.7657515346611;1066.6693325069252;1105.9778804423865;1131.185546406586;19539.51151221231;20100.59798804045;20351.59296814026;20514.743378228086;20529.562930594715;60165.69917150386;61716.69141654263;62163;63235.68382158244;64052.35947640464;86546;89816;90630;91805.62291565498;92158;8010.599470026359;8212.507249565046;8281.50310981319;8441.95779021101;8606.268467180109;9171.495567743837;12855;12874.485020599446;12890.35548222604;12906.419211135333;11073.335599864035;11076.335419874833;11320.37737924422;11389.031932285518;11400.94299528497;8017.078036025699;10680.412577308323;11027.503762330874;11031.558737650405;11413.771724565297;11007.559697612005;11212.887871121511;11430.371329576954;11486.540538378371;11773.705657358634;6395.8720825582295;8003.359731221604;8246.876296855671;8254.257116859511;8470.915290847013;8129.593520324079;9981.950090249793;11151.609693660683;11220.831687524857;11370.94314528422 17:17:24 INFO - PROCESS | 1637 | |3;mootools.html;1200.603800745754;1442.9422823087077;1445.140141615739;1447.3052934591392;1461.4154338264693;7557.848843023136;7654.846903061935;7805.92194078059;8552.401331906769;8576.528290943996;8003.8799418008775;8248.257656810885;8299.83400331993;8417.831643367144;8419.915800841989;2289.3246492284816;2327.418145463634;2333.0900948630047;2335.719713634366;2351.5532048910654;2077.8649387789746;2160.6758986152045;2329.056732023533;2365.44412063165;2367.5028244068767;2250.077468238023;2327.7090363704538;2334.3463830127507;2338.684277622525;2345.64815277708;2295.299933520284;2298.367948814081;2306.3426923326856;2322.419395151212;2323.14043803792;1972.9802701973083;2008.899555022243;2349.1777877742825;2364.3616223619697;2365.8817059147145;8311.33509319253;8463.873041904377;8478.406511544194;8492.490450572985;8494.575271236476;5025.773840177164;5182.041322355339;5189.04002759487;5204.1673332266655;5219.686818790885;3241.7406607471344;3265.7877237979455;3283.0807373935336;3288.6053673559327;3339.532465454838;1038.6832016235046;1177.3877583656536;1199.286424577375;1216.6471723200261;1742.8605711543046;10490.790184196274;10615.31000484966;10725.195610329256;10747.925207479268;10765.407902565355;3975.5626881043063;4073.5315438724924;4368.4102646143;4436.290193569078;4454.4654641442585;8614.44006139599;8641.827163456695;8692.478451292942;8754.912450875538;8808.77978050541;5061.44324124346;5092.847214583494;5097.235414687752;5109.642325037212;5117.385913690381;4620.283856002325;4697.389339385926;4739.928901066487;4748.050389922029;4761.976190119027;3869.690424766012;3932.6264004919485;3980.5621381647998;3991.16185601027;4026.6309454785514;1186.792311345511;1223.25992774372;1263.740933108729;1271.974560508803;1285.2352850054203;688.8071340024801;784.6429874407131;808.292743849132;818.5661599352353;822.2229992657001;5643.633163844337;5672.914906276409;5701.059325211377;5707.800226992035;5837.970810145922;913.7167478081683;993.9801203976025;994.7612572982432;1025.3796453145887;1080.3949788118678;3466.791992480459;3542.8405721742583;3784.0539865033743;3894.1238221400527;3938.4092386141733;9539.950605433396;9861.457619830979;9875.901240987498;9888.307818452784;9902.405855648683;9797.265205109645;9808.803823923625;9812.90187098134;9881.308308418342;9909.355891867006;5931.644101354019;7392.371648409838;7682.193369696213;7773.5724535149975;7810.687572497037;5137.1010073237785;5666.943330566642;5722.2561067061015;5871.060630299132;5934.851628709317;8879.511626860585;9032.548372581476;9080.545972701471;9297.674581389889;9359.719208423758;7893.684252629831;9101;9234.815303694022;9333.859992099988;9370.437773733598;6308.6530240835355;7180.892286615809;7189.99340092398;7275.017872587133;7291.744788932362;7835.686572537034;7865.882011769713;7876.960615196887;7923.20767923203;7953.32396746283;7148.535345202546;7175.569465832067;7204.4956853019785;7216.134063912364;7248.166460857069;7055.717771289091;7056.470764692566;7091.2554181810165;7096.680649370687;7171.713131474891;6244.094606281973;6475.287718351072;6531.020346947996;6540.607563546202;6548.410643042149;4907.55831975124;5018.372703412074;5040.647154699136;5125.8718532037;5214.009338225725;4991.750412479434;5075.492450755042;5141.460146684544;5176.741162941913;5256.816011439581 17:17:24 INFO - PROCESS | 1637 | |4;prototype.html;358.98025608591536;362.36947710982884;368.2781747774362;373.4939157441668;376.3714596623638;5173.275741396207;5320.654157479761;5332.613520484673;5478.123500239963;5694.658320500767;4560;4574.473935497419;5170.508801663846;5459.890802183964;5625.578081643873;4347.369631403445;4359.411479450281;4381.802818873142;4387.824487020515;4395.450568678915;3549.503069570262;3712.5916149223563;4028.4964379452567;4176.914002359394;4358.869233922987;4231.957680423203;4261.659067274626;4300.655947524207;4306.189022714889;4306.397104405386;3852.8266228019806;4129.8967525812095;4257.638100761439;4318.870433886973;4340.826366945318;3423.2639982403653;3815.5039844819994;3835.9232815343535;3852.210296889131;3853.5761066282685;5325.6419612998825;5417.8103766367985;5418.322709661293;5435.83692489222;5522.972385138049;4256.7445953242905;4931.284963680247;4935.827246046371;4937.753112344369;4966.677165984235;5204.34945631796;5327.014502317045;5566.55467562594;5859.589828711992;5874.97062514689;3891.3579259422163;4182.456280683522;4189.114898295748;4199.706020578561;4231.661467082626;5829.533637309005;5968.657052163272;6102.938970610281;6156.168917196166;6210.192674952273;4116.629503344713;4271.487421509439;4278.743275403485;4279.97860010698;4293.098449325614;4292.957070429318;4327.32926396409;4340.348947657813;4353.9129217415475;4384.780760961939;3907.238088572701;4702.976485117552;4838.685485443435;4866.489018653061;4908.239222920453;5607.41122182165;5791.3339965903615;5883.998880761038;5929.407059294036;5962.850928726816;1297.8767017133357;1372.0327169345587;1393.7978993045951;1400.3278426355203;1401.0192864994544;989.9851502227473;1007.8488226765897;1019.913307368882;1022;1022.3661329975314;4643.535646435329;4919.729414882216;5239.47605239473;5247.790088396421;5256.6320357574605;4934.777934992862;5051.696898186121;5686.644879521103;5691.544676425876;5742.7415766290615;4520.254158063883;4884.3650325458175;5167.302414174113;5281.894362112813;5285.709285989307;2608.4652646207483;2725.672919249663;2758.1863350311305;2781.0822428598917;2972.375801081797;5157.194981756355;5527.613067085265;5791.88416231673;5841.561882858718;5963.880722385615;5468.453154684579;5519.917201241985;5789.710514474259;5907.852303692442;5925.4520556160405;5455.345358556999;5554.833354999357;5689.231953686282;5818.8254352369495;5971.937883856758;4688.578027977498;5237.590708274237;5277.709725964955;5409.648372855751;5590.965671350813;4973.9253911190635;4978.406909788978;5107.9489205107475;5162;5678.744456499385;5026.74866256693;5135.281060651437;5226.8954620908125;5873.823785286449;6006.609570377911;3215.5015972524297;3783.6405541473514;4123.60825721556;4127.174565087054;4142.591518883594;4911.852644420673;5098.796048158032;5124.410692770379;5228.790848366023;5945.256842894638;4974.925376119289;5647.717614119196;5751.74117164737;5837.824865254049;5854.882902342015;4813.844677277498;5141.28022076917;5158.1172871900735;5246.6065045121;5453.345598528202;5280.287161233338;5440.265564148947;5460.471068100938;5624.29696287964;5736.42635736423;5741.966446039753;5745.224394706828;5752.252207213036;5762.4525670061275;5768.278965129359;5588.513111665433;5620.831375058755;5640.88718225625;5649.37856835756;5709.628874123168 17:17:24 INFO - PROCESS | 1637 | |5;yui.html;686.7081490366593;724.073186321508;776.0764690018881;813.9959300203498;829.1004260377488;14738.852611473882;15623.531294061197;15744.897857150005;15763.68472630548;15905.125218112998;11995.16033877629;12101.576444824434;12200.145989780718;14238.145711257332;14385.712285754278;681.4752640466837;709.0427922304873;840.0633293877344;856.8757530158108;879.6789171952255;873.9606717697719;874.0953113527501;875.2604049578097;876.0276093536202;876.4784952953015;913.3013244867682;919.8482250428705;922.9769255768592;923.5566927874625;925.2782829393084;905.855063189893;918.1094338491653;920.0707285641483;920.3511524375299;920.6455514626917;684.288340126268;684.3396122741528;684.4079870911676;684.7261095561765;685.413971054751;14932.776008359884;15240.46658366963;15380.230988450645;15752.527424177179;16027.118508482028;14321.71356572873;14439.711205775931;14701.1914344711;14729.189894555795;14784.778228326584;11341.070693137095;11422.771544569063;11554.53781848725;11758.118141139363;11901.702457438547;3755.1738617504107;3797.3924172132324;3817.7327587068917;3868.709846761476;3944.704147188944;82262;105120;105275;106136.93863061426;106292.46853765682;10795.83806242907;11036.558537658564;11070.501827417786;11086.057685096695;11222.943885280521;20199.8990005049;23134.305970820904;23164.420889477562;23352.18267360634;23583.528329433317;7997.800054998672;8046.637901294456;8066.516009039477;8185;8186.83626327482;4356.346548017823;4401;4481.2381895077915;4489.438820147481;4489.663275254304;3567.090391950036;3641.1443310821937;3654.7076233901225;3668;3681.8711345102793;672.7914346552584;674.932506749331;677.5392732941549;677.7593954146207;678.657278074579;590.4567797626108;660.6498555765452;677.2786981864299;691.1671435919704;707.9221285658573;9983.400995940267;10626.096781773482;10741.19441041925;10777.892221077846;10887.891121088687;548.7366064289084;550.8099705601561;583.6935608805411;587.2102022779355;603.6709993053796;4903.828366007173;4924.556789888856;4980.427250866124;5178.585713142939;5201.427842937273;96175;96345.5548166778;96360;96909;99229.00770992342;58183.70908145432;67453;69856.30143698498;74207;74742.62628686822;51760.74119629378;59134.22597322087;65421.691566169364;71165.22086947868;74727;10590.735231619272;11178.161637877029;11218.439078046229;11507.309561426342;11512.53949841997;39688.60311396964;51806.70483237798;52085.958280833416;52372;53079;51488.22767658413;66038;68560.6571967137;69500;69710;12927;13037.370328708912;13119.950403967468;13120.60638180856;13473.191608503521;14706.264686765833;14708.779368309699;14912.478063267734;15013.849861501245;15121.546353609408;13136.474541018251;13322.40049197769;13701.040927135005;13810.723785524435;14016.369263382969;13275.933620331836;13715.657108571966;13764.898808095128;14080.507182248572;14614.196219208046;13372.598822035354;14056.297185140498;14095.647608809451;14155.292235388395;14212.50256241068;10463.895361046596;10916.563337466412;11399.943000284946;11470.254433461798;11563.30620162793;10807.297525660806;11100;11171.497282622464;11179.161562882979;11185.552577897119 17:17:24 INFO - PROCESS | 1637 | __end_tp_report 17:17:24 INFO - PROCESS | 1637 | __start_cc_report 17:17:24 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,370 17:17:24 INFO - PROCESS | 1637 | __end_cc_report 17:17:24 INFO - PROCESS | 1637 | __startTimestamp1485393444345__endTimestamp 17:17:24 INFO - PROCESS | 1637 | 17:17:24 INFO - PROCESS | 1637 | ------- Summary: start ------- 17:17:24 INFO - PROCESS | 1637 | Number of tests: 6 17:17:24 INFO - PROCESS | 1637 | 17:17:24 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5780.45 Median:6053.36 stddev:633.38 (10.5%) stddev-sans-first:606.49 17:17:24 INFO - PROCESS | 1637 | Values: 3372.2 3385.6 3391.3 3391.5 3407.1 5507.8 5750.2 5840.9 5905.1 5918.7 5432.7 5870.7 5959.9 5971.4 5986.6 4474.6 5248.5 5319.6 5337.1 5380.2 5393.9 5397.9 5399.7 5403.5 5429.2 5216.8 5239.1 5345.4 5389.5 5404.6 5334.0 5336.5 5341.1 5350.7 5360.0 5175.6 5302.8 5309.4 5340.3 5344.8 5919.6 5950.4 5975.0 5975.5 5978.3 5975.6 6132.3 6143.1 6191.8 6228.6 5216.2 6221.2 6235.4 6238.1 6243.9 6018.7 6023.5 6023.7 6052.7 6057.2 5221.5 6141.2 6188.9 6210.7 6226.9 6196.7 6209.5 6214.6 6215.5 6230.9 6226.3 6240.4 6246.8 6247.0 6250.9 6053.4 6094.1 6114.5 6157.8 6206.8 6161.7 6190.1 6194.2 6197.8 6208.7 5060.6 5063.9 5075.4 5076.4 5087.6 4358.8 4394.6 4410.8 4423.6 4493.0 5920.6 6015.2 6070.0 6086.3 6095.7 6201.9 6220.7 6224.4 6226.3 6240.0 5753.7 5761.6 6016.9 6022.6 6059.7 5443.4 5499.1 5515.9 5585.2 5650.4 6282.5 6356.9 6365.4 6385.4 6441.7 6122.9 6130.2 6131.0 6137.8 6160.6 6277.9 6325.1 6340.7 6343.0 6348.5 6159.4 6164.2 6181.2 6188.1 6213.5 6107.6 6130.2 6141.4 6155.2 6197.3 6181.9 6187.8 6201.1 6201.8 6212.8 4839.4 4928.0 5337.5 5743.3 6168.3 5912.4 5964.9 6022.0 6045.2 6158.5 6140.5 6192.5 6215.9 6230.1 6234.2 5934.6 6077.8 6155.8 6157.8 6173.2 17:17:24 INFO - PROCESS | 1637 | 17:17:24 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:57547.48 Median:35770.14 stddev:112663.43 (315.0%) stddev-sans-first:112897.93 17:17:24 INFO - PROCESS | 1637 | Values: 451.4 497.8 499.0 502.6 548.8 37080.8 39012.2 39020.2 39044.6 39120.4 40062.0 40843.6 40862.4 40929.6 40939.4 8448.5 8647.1 9154.0 9509.0 11302.5 11242.4 11530.9 11537.2 11543.6 11551.9 11532.9 11541.3 11568.6 11576.6 11579.1 11485.1 11541.4 11543.8 11550.2 11567.8 11379.5 11426.4 11459.3 11470.9 11472.7 39157.6 39414.8 39596.4 39636.8 39723.0 3803.2 3862.3 4003.5 4057.8 4288.6 28573.6 29164.9 29243.7 30217.0 30324.4 3207.0 3260.3 3273.5 3279.7 3346.7 360461.0 360485.0 361489.0 361810.0 363138.0 61746.7 62052.7 62060.4 62135.8 62145.0 61081.8 61417.0 61637.7 61700.0 61782.8 16188.0 16728.3 16848.7 16867.2 16880.2 11786.8 12064.5 12533.5 12841.9 12916.9 14355.2 16255.0 16306.4 16347.0 16401.8 6578.6 7747.5 7783.3 7785.8 7848.5 893.9 897.3 913.0 918.5 935.7 25551.6 25968.6 27768.5 30502.2 30880.0 903.6 927.9 986.7 1013.4 1045.6 14011.2 15673.0 15859.0 15973.1 16746.2 442092.0 513009.0 513516.0 516774.0 517590.0 59339.5 60603.7 60711.0 60730.7 60942.0 1332.4 1355.1 1366.1 1366.7 1407.0 28403.9 29967.4 29995.4 30156.4 30632.1 50819.7 51221.0 51298.5 51311.7 51379.2 397799.0 399971.0 401958.0 402259.0 402418.0 24374.6 25149.9 25196.2 25829.0 40767.2 42082.9 42296.5 42934.4 43078.1 43331.8 33846.0 35543.3 36108.5 36182.8 36368.3 32938.7 33750.0 35789.5 36169.6 36774.8 34390.3 34877.0 35270.5 35609.0 35750.8 26984.9 31030.0 32418.9 32605.8 33097.7 24072.8 24193.5 24262.6 24411.6 24744.9 17:17:24 INFO - PROCESS | 1637 | 17:17:24 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:41993.89 Median:374.60 stddev:129838.99 (34660.7%) stddev-sans-first:130165.59 17:17:24 INFO - PROCESS | 1637 | Values: 361.4 387.8 396.7 400.4 412.1 16598.4 20879.5 20915.9 21536.7 21554.2 21073.9 21250.4 21253.7 21308.4 21462.6 5658.4 5815.5 5852.8 5892.1 5926.1 5681.8 5882.1 5896.3 6003.4 6006.7 5850.4 5860.8 5883.7 5885.5 5911.3 5593.0 5791.7 5835.8 5857.3 5858.1 4749.4 5012.3 5172.3 5172.5 5175.6 24797.9 25244.5 25917.6 25917.7 26251.0 2767.5 2796.9 2810.4 2829.3 2834.6 15584.0 16028.9 16071.0 16357.3 16766.4 1888.1 1889.0 1897.1 1901.3 1901.9 117314.0 121728.0 121785.4 121926.0 123714.0 11571.9 11585.5 11665.1 11812.4 11856.1 12840.2 13493.5 14519.7 14522.1 14537.6 8737.5 8990.2 9121.6 9170.5 9191.5 9056.3 9079.8 9092.5 9238.4 9279.4 6432.0 7264.0 7303.0 7451.0 7492.2 1798.8 1900.2 1921.6 1925.2 1931.2 895.3 919.2 931.6 942.8 949.0 22104.0 22303.0 22375.4 22582.0 22703.3 751.8 946.8 957.7 969.7 983.5 6610.9 6994.0 6996.8 7013.6 7030.2 751836.0 768229.0 784093.0 784709.0 812169.0 143756.0 166671.0 166886.3 167529.2 173843.0 988.0 1064.8 1066.7 1106.0 1131.2 19539.5 20100.6 20351.6 20514.7 20529.6 60165.7 61716.7 62163.0 63235.7 64052.4 86546.0 89816.0 90630.0 91805.6 92158.0 8010.6 8212.5 8281.5 8442.0 8606.3 9171.5 12855.0 12874.5 12890.4 12906.4 11073.3 11076.3 11320.4 11389.0 11400.9 8017.1 10680.4 11027.5 11031.6 11413.8 11007.6 11212.9 11430.4 11486.5 11773.7 6395.9 8003.4 8246.9 8254.3 8470.9 8129.6 9982.0 11151.6 11220.8 11370.9 17:17:24 INFO - PROCESS | 1637 | 17:17:24 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5384.07 Median:5216.85 stddev:2902.59 (55.6%) stddev-sans-first:2893.70 17:17:24 INFO - PROCESS | 1637 | Values: 1200.6 1442.9 1445.1 1447.3 1461.4 7557.8 7654.8 7805.9 8552.4 8576.5 8003.9 8248.3 8299.8 8417.8 8419.9 2289.3 2327.4 2333.1 2335.7 2351.6 2077.9 2160.7 2329.1 2365.4 2367.5 2250.1 2327.7 2334.3 2338.7 2345.6 2295.3 2298.4 2306.3 2322.4 2323.1 1973.0 2008.9 2349.2 2364.4 2365.9 8311.3 8463.9 8478.4 8492.5 8494.6 5025.8 5182.0 5189.0 5204.2 5219.7 3241.7 3265.8 3283.1 3288.6 3339.5 1038.7 1177.4 1199.3 1216.6 1742.9 10490.8 10615.3 10725.2 10747.9 10765.4 3975.6 4073.5 4368.4 4436.3 4454.5 8614.4 8641.8 8692.5 8754.9 8808.8 5061.4 5092.8 5097.2 5109.6 5117.4 4620.3 4697.4 4739.9 4748.1 4762.0 3869.7 3932.6 3980.6 3991.2 4026.6 1186.8 1223.3 1263.7 1272.0 1285.2 688.8 784.6 808.3 818.6 822.2 5643.6 5672.9 5701.1 5707.8 5838.0 913.7 994.0 994.8 1025.4 1080.4 3466.8 3542.8 3784.1 3894.1 3938.4 9540.0 9861.5 9875.9 9888.3 9902.4 9797.3 9808.8 9812.9 9881.3 9909.4 5931.6 7392.4 7682.2 7773.6 7810.7 5137.1 5666.9 5722.3 5871.1 5934.9 8879.5 9032.5 9080.5 9297.7 9359.7 7893.7 9101.0 9234.8 9333.9 9370.4 6308.7 7180.9 7190.0 7275.0 7291.7 7835.7 7865.9 7877.0 7923.2 7953.3 7148.5 7175.6 7204.5 7216.1 7248.2 7055.7 7056.5 7091.3 7096.7 7171.7 6244.1 6475.3 6531.0 6540.6 6548.4 4907.6 5018.4 5040.6 5125.9 5214.0 4991.8 5075.5 5141.5 5176.7 5256.8 17:17:24 INFO - PROCESS | 1637 | 17:17:24 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4636.03 Median:5116.18 stddev:1358.85 (26.6%) stddev-sans-first:1324.20 17:17:24 INFO - PROCESS | 1637 | Values: 359.0 362.4 368.3 373.5 376.4 5173.3 5320.7 5332.6 5478.1 5694.7 4560.0 4574.5 5170.5 5459.9 5625.6 4347.4 4359.4 4381.8 4387.8 4395.5 3549.5 3712.6 4028.5 4176.9 4358.9 4232.0 4261.7 4300.7 4306.2 4306.4 3852.8 4129.9 4257.6 4318.9 4340.8 3423.3 3815.5 3835.9 3852.2 3853.6 5325.6 5417.8 5418.3 5435.8 5523.0 4256.7 4931.3 4935.8 4937.8 4966.7 5204.3 5327.0 5566.6 5859.6 5875.0 3891.4 4182.5 4189.1 4199.7 4231.7 5829.5 5968.7 6102.9 6156.2 6210.2 4116.6 4271.5 4278.7 4280.0 4293.1 4293.0 4327.3 4340.3 4353.9 4384.8 3907.2 4703.0 4838.7 4866.5 4908.2 5607.4 5791.3 5884.0 5929.4 5962.9 1297.9 1372.0 1393.8 1400.3 1401.0 990.0 1007.8 1019.9 1022.0 1022.4 4643.5 4919.7 5239.5 5247.8 5256.6 4934.8 5051.7 5686.6 5691.5 5742.7 4520.3 4884.4 5167.3 5281.9 5285.7 2608.5 2725.7 2758.2 2781.1 2972.4 5157.2 5527.6 5791.9 5841.6 5963.9 5468.5 5519.9 5789.7 5907.9 5925.5 5455.3 5554.8 5689.2 5818.8 5971.9 4688.6 5237.6 5277.7 5409.6 5591.0 4973.9 4978.4 5107.9 5162.0 5678.7 5026.7 5135.3 5226.9 5873.8 6006.6 3215.5 3783.6 4123.6 4127.2 4142.6 4911.9 5098.8 5124.4 5228.8 5945.3 4974.9 5647.7 5751.7 5837.8 5854.9 4813.8 5141.3 5158.1 5246.6 5453.3 5280.3 5440.3 5460.5 5624.3 5736.4 5742.0 5745.2 5752.3 5762.5 5768.3 5588.5 5620.8 5640.9 5649.4 5709.6 17:17:24 INFO - PROCESS | 1637 | 17:17:24 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19322.91 Median:3843.22 stddev:26576.91 (691.5%) stddev-sans-first:26614.63 17:17:24 INFO - PROCESS | 1637 | Values: 686.7 724.1 776.1 814.0 829.1 14738.9 15623.5 15744.9 15763.7 15905.1 11995.2 12101.6 12200.1 14238.1 14385.7 681.5 709.0 840.1 856.9 879.7 874.0 874.1 875.3 876.0 876.5 913.3 919.8 923.0 923.6 925.3 905.9 918.1 920.1 920.4 920.6 684.3 684.3 684.4 684.7 685.4 14932.8 15240.5 15380.2 15752.5 16027.1 14321.7 14439.7 14701.2 14729.2 14784.8 11341.1 11422.8 11554.5 11758.1 11901.7 3755.2 3797.4 3817.7 3868.7 3944.7 82262.0 105120.0 105275.0 106136.9 106292.5 10795.8 11036.6 11070.5 11086.1 11222.9 20199.9 23134.3 23164.4 23352.2 23583.5 7997.8 8046.6 8066.5 8185.0 8186.8 4356.3 4401.0 4481.2 4489.4 4489.7 3567.1 3641.1 3654.7 3668.0 3681.9 672.8 674.9 677.5 677.8 678.7 590.5 660.6 677.3 691.2 707.9 9983.4 10626.1 10741.2 10777.9 10887.9 548.7 550.8 583.7 587.2 603.7 4903.8 4924.6 4980.4 5178.6 5201.4 96175.0 96345.6 96360.0 96909.0 99229.0 58183.7 67453.0 69856.3 74207.0 74742.6 51760.7 59134.2 65421.7 71165.2 74727.0 10590.7 11178.2 11218.4 11507.3 11512.5 39688.6 51806.7 52086.0 52372.0 53079.0 51488.2 66038.0 68560.7 69500.0 69710.0 12927.0 13037.4 13120.0 13120.6 13473.2 14706.3 14708.8 14912.5 15013.8 15121.5 13136.5 13322.4 13701.0 13810.7 14016.4 13275.9 13715.7 13764.9 14080.5 14614.2 13372.6 14056.3 14095.6 14155.3 14212.5 10463.9 10916.6 11399.9 11470.3 11563.3 10807.3 11100.0 11171.5 11179.2 11185.6 17:17:24 INFO - PROCESS | 1637 | -------- Summary: end -------- 17:17:24 INFO - PROCESS | 1637 | 17:17:25 INFO - TEST-INFO | 1637: exit 0 17:17:25 INFO - TEST-OK | dromaeo_css | took 1115394ms 17:17:25 INFO - TEST-START | kraken 17:17:25 INFO - operating with platform_type : mac_ 17:17:25 INFO - Initialising browser for kraken test... 17:17:25 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpAV6DB5/profile http://localhost:49248/getInfo.html) 17:17:27 INFO - PROCESS | 1649 | 2017-01-25 17:17:27.883 plugin-container[1650:9902] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 17:17:27 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:17:27 INFO - PROCESS | 1649 | 2017-01-25 17:17:27.886 plugin-container[1650:9902] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 17:17:27 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:17:27 INFO - PROCESS | 1649 | 2017-01-25 17:17:27.886 plugin-container[1650:9902] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:17:27 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 17:17:27 INFO - PROCESS | 1649 | colorDepth:24 17:17:27 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 17:17:27 INFO - PROCESS | 1649 | __metrics 17:17:28 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 17:17:28 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 17:17:28 INFO - TEST-INFO | 1649: exit 0 17:17:28 INFO - Browser initialized. 17:17:28 INFO - Running cycle 1/1 for kraken test... 17:17:28 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpAV6DB5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:17:31 INFO - PROCESS | 1651 | 2017-01-25 17:17:31.451 plugin-container[1652:10024] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 17:17:31 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:17:31 INFO - PROCESS | 1651 | 2017-01-25 17:17:31.451 plugin-container[1652:10024] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 17:17:31 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:17:31 INFO - PROCESS | 1651 | 2017-01-25 17:17:31.451 plugin-container[1652:10024] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:17:41 INFO - PROCESS | 1651 | RSS: Main: 218976256 17:17:41 INFO - PROCESS | 1651 | 17:17:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 17:17:45 INFO - PROCESS | 1651 | RSS: Main: 233062400 17:17:45 INFO - PROCESS | 1651 | 17:17:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 17:17:48 INFO - PROCESS | 1651 | RSS: Main: 240939008 17:17:48 INFO - PROCESS | 1651 | 17:17:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 17:17:52 INFO - PROCESS | 1651 | RSS: Main: 244617216 17:17:52 INFO - PROCESS | 1651 | 17:17:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 17:17:55 INFO - PROCESS | 1651 | RSS: Main: 245919744 17:17:55 INFO - PROCESS | 1651 | 17:17:57 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:17:57 INFO - PROCESS | 1651 | RSS: Main: 245956608 17:17:57 INFO - PROCESS | 1651 | 17:18:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 17:18:01 INFO - PROCESS | 1651 | RSS: Main: 249462784 17:18:01 INFO - PROCESS | 1651 | 17:18:04 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 17:18:05 INFO - PROCESS | 1651 | RSS: Main: 249036800 17:18:05 INFO - PROCESS | 1651 | 17:18:08 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 17:18:08 INFO - PROCESS | 1651 | RSS: Main: 249208832 17:18:08 INFO - PROCESS | 1651 | 17:18:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:18:10 INFO - PROCESS | 1651 | RSS: Main: 248684544 17:18:10 INFO - PROCESS | 1651 | 17:18:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:18:13 INFO - PROCESS | 1651 | RSS: Main: 248717312 17:18:13 INFO - PROCESS | 1651 | 17:18:15 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:18:16 INFO - PROCESS | 1651 | RSS: Main: 248774656 17:18:16 INFO - PROCESS | 1651 | 17:18:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:18:20 INFO - PROCESS | 1651 | RSS: Main: 249118720 17:18:20 INFO - PROCESS | 1651 | 17:18:23 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:18:23 INFO - PROCESS | 1651 | RSS: Main: 248860672 17:18:23 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 17:18:25 INFO - PROCESS | 1651 | RSS: Main: 250425344 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | __start_tp_report 17:18:25 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 17:18:25 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 17:18:25 INFO - PROCESS | 1651 | |i|pagename|runs| 17:18:25 INFO - PROCESS | 1651 | |0;ai-astar;105;117;118;138;118;116;118;118;118;119 17:18:25 INFO - PROCESS | 1651 | |1;audio-beat-detection;160;143;107;180;143;139;118;120;155;141 17:18:25 INFO - PROCESS | 1651 | |2;audio-dft;195;197;197;196;229;263;198;200;198;199 17:18:25 INFO - PROCESS | 1651 | |3;audio-fft;82;59;61;91;60;62;59;52;70;73 17:18:25 INFO - PROCESS | 1651 | |4;audio-oscillator;83;76;108;73;72;72;83;70;73;84 17:18:25 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;92;90;92;88;81;130;134;128;91;99 17:18:25 INFO - PROCESS | 1651 | |6;imaging-darkroom;94;151;118;97;97;97;97;106;97;99 17:18:25 INFO - PROCESS | 1651 | |7;imaging-desaturate;92;96;90;93;112;133;97;147;99;145 17:18:25 INFO - PROCESS | 1651 | |8;json-parse-financial;61;60;61;62;63;64;63;63;65;63 17:18:25 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;69;78;75;76;75;72;71;74;74;74 17:18:25 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;95;108;107;106;84;88;88;85;85;86 17:18:25 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;149;151;147;155;155;148;144;156;187;154 17:18:25 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;174;174;171;170;174;172;188;171;170;181 17:18:25 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;79;87;71;62;88;61;71;72;59;88 17:18:25 INFO - PROCESS | 1651 | __end_tp_report 17:18:25 INFO - PROCESS | 1651 | __start_cc_report 17:18:25 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,944 17:18:25 INFO - PROCESS | 1651 | __end_cc_report 17:18:25 INFO - PROCESS | 1651 | __startTimestamp1485393505786__endTimestamp 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | ------- Summary: start ------- 17:18:25 INFO - PROCESS | 1651 | Number of tests: 14 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:118.50 Median:118.00 stddev:7.98 (6.8%) stddev-sans-first:6.80 17:18:25 INFO - PROCESS | 1651 | Values: 105.0 117.0 118.0 138.0 118.0 116.0 118.0 118.0 118.0 119.0 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:143.00 stddev:21.64 (15.1%) stddev-sans-first:21.78 17:18:25 INFO - PROCESS | 1651 | Values: 160.0 143.0 107.0 180.0 143.0 139.0 118.0 120.0 155.0 141.0 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:207.20 Median:198.50 stddev:22.01 (11.1%) stddev-sans-first:22.90 17:18:25 INFO - PROCESS | 1651 | Values: 195.0 197.0 197.0 196.0 229.0 263.0 198.0 200.0 198.0 199.0 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:66.90 Median:66.00 stddev:12.06 (18.3%) stddev-sans-first:11.49 17:18:25 INFO - PROCESS | 1651 | Values: 82.0 59.0 61.0 91.0 60.0 62.0 59.0 52.0 70.0 73.0 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:79.40 Median:74.50 stddev:11.34 (15.2%) stddev-sans-first:11.95 17:18:25 INFO - PROCESS | 1651 | Values: 83.0 76.0 108.0 73.0 72.0 72.0 83.0 70.0 73.0 84.0 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:102.50 Median:90.50 stddev:19.98 (22.1%) stddev-sans-first:20.83 17:18:25 INFO - PROCESS | 1651 | Values: 92.0 90.0 92.0 88.0 81.0 130.0 134.0 128.0 91.0 99.0 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:105.30 Median:97.00 stddev:17.52 (18.1%) stddev-sans-first:18.10 17:18:25 INFO - PROCESS | 1651 | Values: 94.0 151.0 118.0 97.0 97.0 97.0 97.0 106.0 97.0 99.0 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:110.40 Median:92.50 stddev:22.67 (24.5%) stddev-sans-first:23.04 17:18:25 INFO - PROCESS | 1651 | Values: 92.0 96.0 90.0 93.0 112.0 133.0 97.0 147.0 99.0 145.0 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:1.51 (2.4%) stddev-sans-first:1.50 17:18:25 INFO - PROCESS | 1651 | Values: 61.0 60.0 61.0 62.0 63.0 64.0 63.0 63.0 65.0 63.0 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:74.50 stddev:2.57 (3.5%) stddev-sans-first:2.06 17:18:25 INFO - PROCESS | 1651 | Values: 69.0 78.0 75.0 76.0 75.0 72.0 71.0 74.0 74.0 74.0 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:93.20 Median:85.50 stddev:10.01 (11.7%) stddev-sans-first:10.59 17:18:25 INFO - PROCESS | 1651 | Values: 95.0 108.0 107.0 106.0 84.0 88.0 88.0 85.0 85.0 86.0 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:154.60 Median:154.50 stddev:12.07 (7.8%) stddev-sans-first:12.63 17:18:25 INFO - PROCESS | 1651 | Values: 149.0 151.0 147.0 155.0 155.0 148.0 144.0 156.0 187.0 154.0 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.50 Median:174.00 stddev:5.74 (3.3%) stddev-sans-first:6.09 17:18:25 INFO - PROCESS | 1651 | Values: 174.0 174.0 171.0 170.0 174.0 172.0 188.0 171.0 170.0 181.0 17:18:25 INFO - PROCESS | 1651 | 17:18:25 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.80 Median:75.50 stddev:11.28 (14.9%) stddev-sans-first:11.81 17:18:25 INFO - PROCESS | 1651 | Values: 79.0 87.0 71.0 62.0 88.0 61.0 71.0 72.0 59.0 88.0 17:18:25 INFO - PROCESS | 1651 | -------- Summary: end -------- 17:18:25 INFO - PROCESS | 1651 | 17:18:26 INFO - TEST-INFO | 1651: exit 0 17:18:26 INFO - TEST-OK | kraken | took 61698ms 17:18:26 INFO - SUITE-END | took 1177s 17:18:26 INFO - Completed test suite (00:19:37) 17:18:26 INFO - javascript benchmark 17:18:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7333.57012414795, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3372.1738174147335, 3385.5598772159615, 3391.3386889556537, 3391.5421418108563, 3407.097119263397, 5507.752151153198, 5750.1949727038245, 5840.912386314203, 5905.055191169414, 5918.674472903988, 5432.728363581825, 5870.735816888239, 5959.851003724909, 5971.402859714027, 5986.580939334249, 4474.574915383049, 5248.456189706614, 5319.5744340452675, 5337.11937530307, 5380.192971054334, 5393.946060539403, 5397.892042159155, 5399.676019438846, 5403.51368376846, 5429.212764149196, 5216.843494695166, 5239.096872829748, 5345.4120046795015, 5389.51494365507, 5404.594655400841, 5334.0, 5336.546393556572, 5341.145416733343, 5350.678959262456, 5359.9732001340135, 5175.637705360627, 5302.761375738101, 5309.442508536626, 5340.332458442695, 5344.812931547377, 5919.644821310735, 5950.404959504058, 5974.97012514939, 5975.492083172936, 5978.252718410199, 5975.551833612496, 6132.324915438552, 6143.051335359008, 6191.752329906798, 6228.626282423069, 5216.243644671501, 6221.160143380676, 6235.438810507031, 6238.095476155977, 6243.87512249757, 6018.6689732064715, 6023.548233882477, 6023.728932198062, 6052.727627256784, 6057.182280392179, 5221.451747566527, 6141.240538582545, 6188.907166392508, 6210.658413787285, 6226.937730622726, 6196.690165491797, 6209.534284928558, 6214.564980451325, 6215.471684906834, 6230.906536401958, 6226.346233645492, 6240.375962403723, 6246.75012999484, 6246.968765156145, 6250.874982500415, 6053.364396738367, 6094.085887116879, 6114.540688159484, 6157.784477543264, 6206.844828879314, 6161.691915404212, 6190.102435146879, 6194.225721784777, 6197.81406557804, 6208.72060757267, 5060.5951523878, 5063.8987220255385, 5075.390953085654, 5076.44159142494, 5087.64386492942, 4358.847440339573, 4394.560543945644, 4410.80151393188, 4423.623991960655, 4493.0, 5920.615160014585, 6015.187949626832, 6070.0, 6086.330503644595, 6095.6952152392205, 6201.937980620226, 6220.688965551704, 6224.408681175281, 6226.283977342573, 6240.032794916795, 5753.683547404849, 5761.625494342854, 6016.939830601726, 6022.608530445507, 6059.697015149313, 5443.401225865152, 5499.065158922993, 5515.944840551623, 5585.1901474286005, 5650.378458369658, 6282.465990390685, 6356.872862542723, 6365.427111559982, 6385.393387628168, 6441.71012304438, 6122.916604401471, 6130.233720784902, 6131.049687298476, 6137.84655383619, 6160.568760186744, 6277.905831412441, 6325.1144839721555, 6340.7463701451425, 6342.968285158545, 6348.492120630431, 6159.384061593984, 6164.198654174928, 6181.227346581677, 6188.133661287513, 6213.471854892385, 6107.61364822091, 6130.245269657117, 6141.393954534099, 6155.199824022849, 6197.318294987638, 6181.907271390843, 6187.81436556904, 6201.131841542278, 6201.844953876189, 6212.844678882883, 4839.443464001685, 4927.9507204927495, 5337.519623233928, 5743.253377060955, 6168.290646575701, 5912.408759124053, 5964.940350596438, 6022.036474164112, 6045.183900173421, 6158.507319414526, 6140.508759299335, 6192.504599632109, 6215.875682486416, 6230.127782110599, 6234.220722409699, 5934.584579079423, 6077.848053798691, 6155.784547540994, 6157.84605384869, 6173.197484327009], "value": 5741.269255263836, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.3838610296946, 497.8456678429687, 499.0288360974151, 502.58536707216547, 548.7558036673681, 37080.81459592702, 39012.21975560487, 39020.21959560807, 39044.609553904455, 39120.41319380208, 40061.998450038765, 40843.59156408435, 40862.38706419399, 40929.56746513872, 40939.385909211385, 8448.450850694717, 8647.135286471334, 9153.95423022884, 9509.001554836746, 11302.547898084067, 11242.437878106102, 11530.884691153107, 11537.192396532246, 11543.595974140906, 11551.942240288787, 11532.884671153264, 11541.307521548733, 11568.59509917153, 11576.59481918133, 11579.073674106094, 11485.138614603897, 11541.365224912626, 11543.826842597367, 11550.191486595942, 11567.768644627102, 11379.544818207345, 11426.37154956477, 11459.31244125355, 11470.885291147066, 11472.655820325404, 39157.608423915684, 39414.802925985474, 39596.40605390922, 39636.80181599074, 39723.0, 3803.2393521295853, 3862.2854771867014, 4003.499562554681, 4057.7768222747736, 4288.614024737789, 28573.57139642907, 29164.854175729197, 29243.68403421851, 30217.0, 30324.393512129853, 3206.951895721566, 3260.315333779909, 3273.492608645664, 3279.737620990315, 3346.6987971082476, 360461.0, 360485.0, 361489.0, 361810.0, 363138.0, 61746.69126654428, 62052.68973655193, 62060.37939620546, 62135.75728485405, 62145.0, 61081.77836443246, 61417.0, 61637.69181154066, 61700.0, 61782.76434471375, 16188.0, 16728.33086676544, 16848.663026739643, 16867.240974156193, 16880.240389182516, 11786.823197652042, 12064.457099430547, 12533.498660053496, 12841.871581284067, 12916.935415322863, 14355.210463424402, 16255.0, 16306.429274975319, 16347.019178849307, 16401.753973690404, 6578.63817490033, 7747.535147891144, 7783.33841623457, 7785.844283114306, 7848.489848159851, 893.8972018217858, 897.3225214962662, 912.9908700913039, 918.5315489100474, 935.7192842147329, 25551.61672574913, 25968.610470842952, 27768.472733999824, 30502.23744406363, 30880.0, 903.5753195998002, 927.9350445468888, 986.7039888033561, 1013.3565186106728, 1045.6444808765057, 14011.159330440207, 15672.981256218309, 15858.96916700434, 15973.121478318804, 16746.246418911178, 442092.0, 513009.0, 513516.0, 516774.0, 517590.0, 59339.516512087546, 60603.69698151481, 60711.0, 60730.69634651799, 60942.0, 1332.4270563657526, 1355.1462578575433, 1366.1120271823195, 1366.719822436418, 1406.9869693820433, 28403.86384544595, 29967.400651987275, 29995.400091997602, 30156.396872061996, 30632.081037568907, 50819.74590127026, 51221.0, 51298.48701512937, 51311.74344128255, 51379.2293115611, 397799.0, 399971.0, 401958.0, 402258.9887050546, 402417.9879100703, 24374.634380483953, 25149.87425062863, 25196.24411267665, 25828.966841326135, 40767.1846563073, 42082.947926302084, 42296.45093945623, 42934.35598466088, 43078.138437231704, 43331.78334108309, 33846.0, 35543.289134216655, 36108.45837312495, 36182.81908590545, 36368.27263454769, 32938.67061329356, 33750.0, 35789.46315805317, 36169.63830361663, 36774.8161259192, 34390.28520765512, 34877.0, 35270.47094293536, 35609.0, 35750.8212458936, 26984.865075674497, 31030.0, 32418.865339712997, 32605.836970815944, 33097.669023309456, 24072.75927240775, 24193.516129676955, 24262.636060458753, 24411.633825492278, 24744.876275619226], "value": 19496.503091855, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.36710675550256, 387.8332317103646, 396.6806720589924, 400.4173926936308, 412.12012353625, 16598.419055333037, 20879.47801304968, 20915.895420522913, 21536.676949845765, 21554.245601403953, 21073.894630526825, 21250.36248912535, 21253.68119478209, 21308.36074917751, 21462.570748585018, 5658.377578466366, 5815.47660710536, 5852.824415267549, 5892.14563888236, 5926.1110833374905, 5681.801136960207, 5882.14708867214, 5896.262967129109, 6003.369646187153, 6006.729697163616, 5850.414958504158, 5860.824175274749, 5883.705814709268, 5885.470307672309, 5911.3201981772, 5593.04918163911, 5791.652500849962, 5835.795747148872, 5857.297124345085, 5858.12128180772, 4749.382580264543, 5012.348394708664, 5172.250023746573, 5172.482751724835, 5175.560077393426, 24797.876010620013, 25244.49511009788, 25917.61123583148, 25917.704114794185, 26251.0, 2767.501849667059, 2796.860156992142, 2810.367667274847, 2829.3492496725867, 2834.63149790528, 15583.987040842308, 16028.919855400765, 16071.0, 16357.263923123488, 16766.413175538797, 1888.055972013993, 1888.962220755591, 1897.089397089391, 1901.277514544478, 1901.9429417117508, 117314.0, 121728.0, 121785.39107304496, 121925.99999999911, 123714.0, 11571.884281157249, 11585.478653460614, 11665.125115616363, 11812.35032073227, 11856.110791690486, 12840.165389249669, 13493.460261589622, 14519.709605807824, 14522.056066355653, 14537.636559086108, 8737.475751454933, 8990.235829954396, 9121.589528471235, 9170.495622740813, 9191.540422978824, 9056.275497960147, 9079.773005674779, 9092.454452732858, 9238.44569325829, 9279.443233406017, 6432.0, 7264.019357386791, 7302.96348518254, 7451.031365922396, 7492.175860655324, 1798.8021317655048, 1900.2019151956213, 1921.567647279351, 1925.2202857842597, 1931.169597073272, 895.2614093372994, 919.2002957426947, 931.5807886451124, 942.7696855603504, 948.9905100948902, 22104.0, 22303.0, 22375.440613984454, 22582.0, 22703.318900433012, 751.8233215194425, 946.8461523256054, 957.7222605444343, 969.6703120938773, 983.5180761426992, 6610.933890661032, 6993.965030174817, 6996.755113571103, 7013.649317534103, 7030.191527974246, 751836.0, 768229.0, 784093.0, 784709.0, 812169.0, 143756.0, 166671.0, 166886.33113668952, 167529.16235418746, 173843.0, 987.9506024698736, 1064.7657515346611, 1066.6693325069252, 1105.9778804423865, 1131.185546406586, 19539.51151221231, 20100.59798804045, 20351.59296814026, 20514.743378228086, 20529.562930594715, 60165.69917150386, 61716.69141654263, 62163.0, 63235.68382158244, 64052.35947640464, 86546.0, 89816.0, 90630.0, 91805.62291565498, 92158.0, 8010.599470026359, 8212.507249565046, 8281.50310981319, 8441.95779021101, 8606.268467180109, 9171.495567743837, 12855.0, 12874.485020599446, 12890.35548222604, 12906.419211135333, 11073.335599864035, 11076.335419874833, 11320.37737924422, 11389.031932285518, 11400.94299528497, 8017.078036025699, 10680.412577308323, 11027.503762330874, 11031.558737650405, 11413.771724565297, 11007.559697612005, 11212.887871121511, 11430.371329576954, 11486.540538378371, 11773.705657358634, 6395.8720825582295, 8003.359731221604, 8246.876296855671, 8254.257116859511, 8470.915290847013, 8129.593520324079, 9981.950090249793, 11151.609693660683, 11220.831687524857, 11370.94314528422], "value": 10032.859840987425, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1200.603800745754, 1442.9422823087077, 1445.140141615739, 1447.3052934591392, 1461.4154338264693, 7557.848843023136, 7654.846903061935, 7805.92194078059, 8552.401331906769, 8576.528290943996, 8003.8799418008775, 8248.257656810885, 8299.83400331993, 8417.831643367144, 8419.915800841989, 2289.3246492284816, 2327.418145463634, 2333.0900948630047, 2335.719713634366, 2351.5532048910654, 2077.8649387789746, 2160.6758986152045, 2329.056732023533, 2365.44412063165, 2367.5028244068767, 2250.077468238023, 2327.7090363704538, 2334.3463830127507, 2338.684277622525, 2345.64815277708, 2295.299933520284, 2298.367948814081, 2306.3426923326856, 2322.419395151212, 2323.14043803792, 1972.9802701973083, 2008.899555022243, 2349.1777877742825, 2364.3616223619697, 2365.8817059147145, 8311.33509319253, 8463.873041904377, 8478.406511544194, 8492.490450572985, 8494.575271236476, 5025.773840177164, 5182.041322355339, 5189.04002759487, 5204.1673332266655, 5219.686818790885, 3241.7406607471344, 3265.7877237979455, 3283.0807373935336, 3288.6053673559327, 3339.532465454838, 1038.6832016235046, 1177.3877583656536, 1199.286424577375, 1216.6471723200261, 1742.8605711543046, 10490.790184196274, 10615.31000484966, 10725.195610329256, 10747.925207479268, 10765.407902565355, 3975.5626881043063, 4073.5315438724924, 4368.4102646143, 4436.290193569078, 4454.4654641442585, 8614.44006139599, 8641.827163456695, 8692.478451292942, 8754.912450875538, 8808.77978050541, 5061.44324124346, 5092.847214583494, 5097.235414687752, 5109.642325037212, 5117.385913690381, 4620.283856002325, 4697.389339385926, 4739.928901066487, 4748.050389922029, 4761.976190119027, 3869.690424766012, 3932.6264004919485, 3980.5621381647998, 3991.16185601027, 4026.6309454785514, 1186.792311345511, 1223.25992774372, 1263.740933108729, 1271.974560508803, 1285.2352850054203, 688.8071340024801, 784.6429874407131, 808.292743849132, 818.5661599352353, 822.2229992657001, 5643.633163844337, 5672.914906276409, 5701.059325211377, 5707.800226992035, 5837.970810145922, 913.7167478081683, 993.9801203976025, 994.7612572982432, 1025.3796453145887, 1080.3949788118678, 3466.791992480459, 3542.8405721742583, 3784.0539865033743, 3894.1238221400527, 3938.4092386141733, 9539.950605433396, 9861.457619830979, 9875.901240987498, 9888.307818452784, 9902.405855648683, 9797.265205109645, 9808.803823923625, 9812.90187098134, 9881.308308418342, 9909.355891867006, 5931.644101354019, 7392.371648409838, 7682.193369696213, 7773.5724535149975, 7810.687572497037, 5137.1010073237785, 5666.943330566642, 5722.2561067061015, 5871.060630299132, 5934.851628709317, 8879.511626860585, 9032.548372581476, 9080.545972701471, 9297.674581389889, 9359.719208423758, 7893.684252629831, 9101.0, 9234.815303694022, 9333.859992099988, 9370.437773733598, 6308.6530240835355, 7180.892286615809, 7189.99340092398, 7275.017872587133, 7291.744788932362, 7835.686572537034, 7865.882011769713, 7876.960615196887, 7923.20767923203, 7953.32396746283, 7148.535345202546, 7175.569465832067, 7204.4956853019785, 7216.134063912364, 7248.166460857069, 7055.717771289091, 7056.470764692566, 7091.2554181810165, 7096.680649370687, 7171.713131474891, 6244.094606281973, 6475.287718351072, 6531.020346947996, 6540.607563546202, 6548.410643042149, 4907.55831975124, 5018.372703412074, 5040.647154699136, 5125.8718532037, 5214.009338225725, 4991.750412479434, 5075.492450755042, 5141.460146684544, 5176.741162941913, 5256.816011439581], "value": 4386.34439614502, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.98025608591536, 362.36947710982884, 368.2781747774362, 373.4939157441668, 376.3714596623638, 5173.275741396207, 5320.654157479761, 5332.613520484673, 5478.123500239963, 5694.658320500767, 4560.0, 4574.473935497419, 5170.508801663846, 5459.890802183964, 5625.578081643873, 4347.369631403445, 4359.411479450281, 4381.802818873142, 4387.824487020515, 4395.450568678915, 3549.503069570262, 3712.5916149223563, 4028.4964379452567, 4176.914002359394, 4358.869233922987, 4231.957680423203, 4261.659067274626, 4300.655947524207, 4306.189022714889, 4306.397104405386, 3852.8266228019806, 4129.8967525812095, 4257.638100761439, 4318.870433886973, 4340.826366945318, 3423.2639982403653, 3815.5039844819994, 3835.9232815343535, 3852.210296889131, 3853.5761066282685, 5325.6419612998825, 5417.8103766367985, 5418.322709661293, 5435.83692489222, 5522.972385138049, 4256.7445953242905, 4931.284963680247, 4935.827246046371, 4937.753112344369, 4966.677165984235, 5204.34945631796, 5327.014502317045, 5566.55467562594, 5859.589828711992, 5874.97062514689, 3891.3579259422163, 4182.456280683522, 4189.114898295748, 4199.706020578561, 4231.661467082626, 5829.533637309005, 5968.657052163272, 6102.938970610281, 6156.168917196166, 6210.192674952273, 4116.629503344713, 4271.487421509439, 4278.743275403485, 4279.97860010698, 4293.098449325614, 4292.957070429318, 4327.32926396409, 4340.348947657813, 4353.9129217415475, 4384.780760961939, 3907.238088572701, 4702.976485117552, 4838.685485443435, 4866.489018653061, 4908.239222920453, 5607.41122182165, 5791.3339965903615, 5883.998880761038, 5929.407059294036, 5962.850928726816, 1297.8767017133357, 1372.0327169345587, 1393.7978993045951, 1400.3278426355203, 1401.0192864994544, 989.9851502227473, 1007.8488226765897, 1019.913307368882, 1022.0, 1022.3661329975314, 4643.535646435329, 4919.729414882216, 5239.47605239473, 5247.790088396421, 5256.6320357574605, 4934.777934992862, 5051.696898186121, 5686.644879521103, 5691.544676425876, 5742.7415766290615, 4520.254158063883, 4884.3650325458175, 5167.302414174113, 5281.894362112813, 5285.709285989307, 2608.4652646207483, 2725.672919249663, 2758.1863350311305, 2781.0822428598917, 2972.375801081797, 5157.194981756355, 5527.613067085265, 5791.88416231673, 5841.561882858718, 5963.880722385615, 5468.453154684579, 5519.917201241985, 5789.710514474259, 5907.852303692442, 5925.4520556160405, 5455.345358556999, 5554.833354999357, 5689.231953686282, 5818.8254352369495, 5971.937883856758, 4688.578027977498, 5237.590708274237, 5277.709725964955, 5409.648372855751, 5590.965671350813, 4973.9253911190635, 4978.406909788978, 5107.9489205107475, 5162.0, 5678.744456499385, 5026.74866256693, 5135.281060651437, 5226.8954620908125, 5873.823785286449, 6006.609570377911, 3215.5015972524297, 3783.6405541473514, 4123.60825721556, 4127.174565087054, 4142.591518883594, 4911.852644420673, 5098.796048158032, 5124.410692770379, 5228.790848366023, 5945.256842894638, 4974.925376119289, 5647.717614119196, 5751.74117164737, 5837.824865254049, 5854.882902342015, 4813.844677277498, 5141.28022076917, 5158.1172871900735, 5246.6065045121, 5453.345598528202, 5280.287161233338, 5440.265564148947, 5460.471068100938, 5624.29696287964, 5736.42635736423, 5741.966446039753, 5745.224394706828, 5752.252207213036, 5762.4525670061275, 5768.278965129359, 5588.513111665433, 5620.831375058755, 5640.88718225625, 5649.37856835756, 5709.628874123168], "value": 4221.461600010387, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.7081490366593, 724.073186321508, 776.0764690018881, 813.9959300203498, 829.1004260377488, 14738.852611473882, 15623.531294061197, 15744.897857150005, 15763.68472630548, 15905.125218112998, 11995.16033877629, 12101.576444824434, 12200.145989780718, 14238.145711257332, 14385.712285754278, 681.4752640466837, 709.0427922304873, 840.0633293877344, 856.8757530158108, 879.6789171952255, 873.9606717697719, 874.0953113527501, 875.2604049578097, 876.0276093536202, 876.4784952953015, 913.3013244867682, 919.8482250428705, 922.9769255768592, 923.5566927874625, 925.2782829393084, 905.855063189893, 918.1094338491653, 920.0707285641483, 920.3511524375299, 920.6455514626917, 684.288340126268, 684.3396122741528, 684.4079870911676, 684.7261095561765, 685.413971054751, 14932.776008359884, 15240.46658366963, 15380.230988450645, 15752.527424177179, 16027.118508482028, 14321.71356572873, 14439.711205775931, 14701.1914344711, 14729.189894555795, 14784.778228326584, 11341.070693137095, 11422.771544569063, 11554.53781848725, 11758.118141139363, 11901.702457438547, 3755.1738617504107, 3797.3924172132324, 3817.7327587068917, 3868.709846761476, 3944.704147188944, 82262.0, 105120.0, 105275.0, 106136.93863061426, 106292.46853765682, 10795.83806242907, 11036.558537658564, 11070.501827417786, 11086.057685096695, 11222.943885280521, 20199.8990005049, 23134.305970820904, 23164.420889477562, 23352.18267360634, 23583.528329433317, 7997.800054998672, 8046.637901294456, 8066.516009039477, 8185.0, 8186.83626327482, 4356.346548017823, 4401.0, 4481.2381895077915, 4489.438820147481, 4489.663275254304, 3567.090391950036, 3641.1443310821937, 3654.7076233901225, 3668.0, 3681.8711345102793, 672.7914346552584, 674.932506749331, 677.5392732941549, 677.7593954146207, 678.657278074579, 590.4567797626108, 660.6498555765452, 677.2786981864299, 691.1671435919704, 707.9221285658573, 9983.400995940267, 10626.096781773482, 10741.19441041925, 10777.892221077846, 10887.891121088687, 548.7366064289084, 550.8099705601561, 583.6935608805411, 587.2102022779355, 603.6709993053796, 4903.828366007173, 4924.556789888856, 4980.427250866124, 5178.585713142939, 5201.427842937273, 96175.0, 96345.5548166778, 96360.0, 96909.0, 99229.00770992342, 58183.70908145432, 67453.0, 69856.30143698498, 74207.0, 74742.62628686822, 51760.74119629378, 59134.22597322087, 65421.691566169364, 71165.22086947868, 74727.0, 10590.735231619272, 11178.161637877029, 11218.439078046229, 11507.309561426342, 11512.53949841997, 39688.60311396964, 51806.70483237798, 52085.958280833416, 52372.0, 53079.0, 51488.22767658413, 66038.0, 68560.6571967137, 69500.0, 69710.0, 12927.0, 13037.370328708912, 13119.950403967468, 13120.60638180856, 13473.191608503521, 14706.264686765833, 14708.779368309699, 14912.478063267734, 15013.849861501245, 15121.546353609408, 13136.474541018251, 13322.40049197769, 13701.040927135005, 13810.723785524435, 14016.369263382969, 13275.933620331836, 13715.657108571966, 13764.898808095128, 14080.507182248572, 14614.196219208046, 13372.598822035354, 14056.297185140498, 14095.647608809451, 14155.292235388395, 14212.50256241068, 10463.895361046596, 10916.563337466412, 11399.943000284946, 11470.254433461798, 11563.30620162793, 10807.297525660806, 11100.0, 11171.497282622464, 11179.161562882979, 11185.552577897119], "value": 7479.8332508787735, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1563.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 118.0, 138.0, 118.0, 116.0, 118.0, 118.0, 118.0, 119.0], "value": 118.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 143.0, 107.0, 180.0, 143.0, 139.0, 118.0, 120.0, 155.0, 141.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 197.0, 197.0, 196.0, 229.0, 263.0, 198.0, 200.0, 198.0, 199.0], "value": 207.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 61.0, 91.0, 60.0, 62.0, 59.0, 52.0, 70.0, 73.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 76.0, 108.0, 73.0, 72.0, 72.0, 83.0, 70.0, 73.0, 84.0], "value": 79.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 92.0, 88.0, 81.0, 130.0, 134.0, 128.0, 91.0, 99.0], "value": 102.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 151.0, 118.0, 97.0, 97.0, 97.0, 97.0, 106.0, 97.0, 99.0], "value": 105.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 96.0, 90.0, 93.0, 112.0, 133.0, 97.0, 147.0, 99.0, 145.0], "value": 110.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 61.0, 62.0, 63.0, 64.0, 63.0, 63.0, 65.0, 63.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 75.0, 76.0, 75.0, 72.0, 71.0, 74.0, 74.0, 74.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 108.0, 107.0, 106.0, 84.0, 88.0, 88.0, 85.0, 85.0, 86.0], "value": 93.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 151.0, 147.0, 155.0, 155.0, 148.0, 144.0, 156.0, 187.0, 154.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 171.0, 170.0, 174.0, 172.0, 188.0, 171.0, 170.0, 181.0], "value": 174.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 87.0, 71.0, 62.0, 88.0, 61.0, 71.0, 72.0, 59.0, 88.0], "value": 73.8, "unit": "score"}]}]} 17:18:27 INFO - Return code: 0 17:18:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:18:27 ERROR - # TBPL SUCCESS # 17:18:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:18:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:18:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:18:27 INFO - 2017-01-25 17:18:27.075 screenresolution[1656:10404] starting screenresolution argv=screenresolution get 17:18:27 INFO - 2017-01-25 17:18:27.078 screenresolution[1656:10404] Display 0: 1600x1200x32@60 17:18:27 INFO - 2017-01-25 17:18:27.084 screenresolution[1657:10407] starting screenresolution argv=screenresolution list 17:18:27 INFO - Available Modes on Display 0 17:18:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:18:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:18:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:18:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:18:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:18:27 INFO - Intel Iris: 17:18:27 INFO - Chipset Model: Intel Iris 17:18:27 INFO - Type: GPU 17:18:27 INFO - Bus: Built-In 17:18:27 INFO - VRAM (Dynamic, Max): 1536 MB 17:18:27 INFO - Vendor: Intel (0x8086) 17:18:27 INFO - Device ID: 0x0a2e 17:18:27 INFO - Revision ID: 0x0009 17:18:27 INFO - Displays: 17:18:27 INFO - Display: 17:18:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:18:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:18:27 INFO - Display Serial Number: 1600x1200 60 17:18:27 INFO - Main Display: Yes 17:18:27 INFO - Mirror: Off 17:18:27 INFO - Online: Yes 17:18:27 INFO - Rotation: Supported 17:18:27 INFO - Return code: 0 17:18:27 INFO - Running post-action listener: _resource_record_post_action 17:18:27 INFO - [mozharness: 2017-01-26 01:18:27.326506Z] Finished run-tests step (success) 17:18:27 INFO - Running post-run listener: _resource_record_post_run 17:18:27 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 39303680; Write bytes: 511332352; Read time: 381; Write time: 1803 17:18:27 INFO - TinderboxPrint: CPU usage
54.4% 17:18:27 INFO - TinderboxPrint: I/O read bytes / time
39,303,680 / 381 17:18:27 INFO - TinderboxPrint: I/O write bytes / time
511,332,352 / 1,803 17:18:27 INFO - TinderboxPrint: CPU idle
2,185.3 (45.6%) 17:18:27 INFO - TinderboxPrint: CPU system
966.4 (20.2%) 17:18:27 INFO - TinderboxPrint: CPU user
1,642.0 (34.3%) 17:18:27 INFO - TinderboxPrint: Swap in / out
261,865,472 / 0 17:18:27 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205277696; Write bytes: 200976384; Read time: 15693; Write time: 460 17:18:27 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 37239296; Write bytes: 299399168; Read time: 357; Write time: 1307 17:18:27 INFO - Running post-run listener: _upload_blobber_files 17:18:27 INFO - Blob upload gear active. 17:18:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:18:27 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 17:18:27 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'] 17:18:27 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 17:18:28 INFO - (blobuploader) - INFO - Open directory for files ... 17:18:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:18:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:18:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:18:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:18:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:18:29 INFO - (blobuploader) - INFO - Done attempting. 17:18:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:18:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:18:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:18:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:18:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:18:30 INFO - (blobuploader) - INFO - Done attempting. 17:18:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:18:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:18:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:18:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:18:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:18:31 INFO - (blobuploader) - INFO - Done attempting. 17:18:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:18:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:18:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:18:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:18:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:18:34 INFO - (blobuploader) - INFO - Done attempting. 17:18:34 INFO - (blobuploader) - INFO - Iteration through files over. 17:18:34 INFO - Return code: 0 17:18:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:18:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:18:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8603d5e924b33ce167dd4b6e6f46853233875da652430a584ae93d10ce95e756cac8402885f56845f89458f8ff8c5b9c996b46712651ba0cb0c502df85709928", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19c055739f4ab3bb4d4d258bcb0330193761d95da376ff0859ef520140a28cc75805fcf6de48ed974375a28ba99a9fd7c8e4b70c42249e4ac661641b3bed438f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c272b69ca5038a79b84d41a1e58e6ef8e3ee9c1422dbcacac644a912538ac1d1fa663fa4b3b09137a5f89ba59bb1ed860c3b60ae5d87b72ddf7e0c4d35e6a637", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be9df0a0d956ddcd8c298848b253ced925f640500757463569061c5b52647dbf72041560e4b32fab2fb97c57f62075ee26cb8ca4c7112aa9e99459e0808c79bd"} 17:18:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:18:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:18:34 INFO - Contents: 17:18:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8603d5e924b33ce167dd4b6e6f46853233875da652430a584ae93d10ce95e756cac8402885f56845f89458f8ff8c5b9c996b46712651ba0cb0c502df85709928", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19c055739f4ab3bb4d4d258bcb0330193761d95da376ff0859ef520140a28cc75805fcf6de48ed974375a28ba99a9fd7c8e4b70c42249e4ac661641b3bed438f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c272b69ca5038a79b84d41a1e58e6ef8e3ee9c1422dbcacac644a912538ac1d1fa663fa4b3b09137a5f89ba59bb1ed860c3b60ae5d87b72ddf7e0c4d35e6a637", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be9df0a0d956ddcd8c298848b253ced925f640500757463569061c5b52647dbf72041560e4b32fab2fb97c57f62075ee26cb8ca4c7112aa9e99459e0808c79bd"} 17:18:34 INFO - Running post-run listener: copy_logs_to_upload_dir 17:18:34 INFO - Copying logs to upload dir... 17:18:34 INFO - mkdir: /builds/slave/test/build/upload/logs 17:18:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.334007 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-01-25 17:18:34.376821) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-25 17:18:34.379814) ========= 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.JAUDryTnKl/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.UCuDyMLpDd/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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/8603d5e924b33ce167dd4b6e6f46853233875da652430a584ae93d10ce95e756cac8402885f56845f89458f8ff8c5b9c996b46712651ba0cb0c502df85709928", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19c055739f4ab3bb4d4d258bcb0330193761d95da376ff0859ef520140a28cc75805fcf6de48ed974375a28ba99a9fd7c8e4b70c42249e4ac661641b3bed438f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c272b69ca5038a79b84d41a1e58e6ef8e3ee9c1422dbcacac644a912538ac1d1fa663fa4b3b09137a5f89ba59bb1ed860c3b60ae5d87b72ddf7e0c4d35e6a637", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be9df0a0d956ddcd8c298848b253ced925f640500757463569061c5b52647dbf72041560e4b32fab2fb97c57f62075ee26cb8ca4c7112aa9e99459e0808c79bd"} build_url:https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010969 build_url: 'https://queue.taskcluster.net/v1/task/Jkp9nLmvRriYxAv7bn6CmA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8603d5e924b33ce167dd4b6e6f46853233875da652430a584ae93d10ce95e756cac8402885f56845f89458f8ff8c5b9c996b46712651ba0cb0c502df85709928", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19c055739f4ab3bb4d4d258bcb0330193761d95da376ff0859ef520140a28cc75805fcf6de48ed974375a28ba99a9fd7c8e4b70c42249e4ac661641b3bed438f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c272b69ca5038a79b84d41a1e58e6ef8e3ee9c1422dbcacac644a912538ac1d1fa663fa4b3b09137a5f89ba59bb1ed860c3b60ae5d87b72ddf7e0c4d35e6a637", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be9df0a0d956ddcd8c298848b253ced925f640500757463569061c5b52647dbf72041560e4b32fab2fb97c57f62075ee26cb8ca4c7112aa9e99459e0808c79bd"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-25 17:18:34.419436) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 17:18:34.419734) ========= 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.JAUDryTnKl/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.UCuDyMLpDd/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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.004995 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-25 17:18:34.468256) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-25 17:18:34.471590) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-25 17:18:34.471904) ========= ========= Total master_lag: 3.34 =========