builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0086 starttime: 1494408155.24 results: success (0) buildid: 20170510004018 builduid: 3c51713f270946289aa864349cedb29e revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-10 02:22:35.244233) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-10 02:22:35.244741) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-10 02:22:35.245054) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-10 02:22:35.273532) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 02:22:35.273828) ========= 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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-05-10 02:22:35-- 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% 13.2M=0.001s 2017-05-10 02:22:35 (13.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.168967 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 02:22:35.468587) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 02:22:35.469174) ========= 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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.028120 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 02:22:35.519800) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-10 02:22:35.520301) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --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 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-05-10 02:22:35,617 truncating revision to first 12 chars 2017-05-10 02:22:35,617 Setting DEBUG logging. 2017-05-10 02:22:35,617 attempt 1/10 2017-05-10 02:22:35,617 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-10 02:22:36,655 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.270369 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-10 02:22:37.812846) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-10 02:22:37.813170) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-10 02:22:37.813668) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-10 02:22:37.813935) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-10 02:22:37.833309) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-10 02:22:37.833832) ========= /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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 02:22:38 INFO - MultiFileLogger online at 20170510 02:22:38 in /builds/slave/test 02:22:38 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 02:22:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:22:38 INFO - {'append_to_log': False, 02:22:38 INFO - 'base_work_dir': '/builds/slave/test', 02:22:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:22:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:22:38 INFO - 'branch': 'Mozilla-Aurora', 02:22:38 INFO - 'buildbot_json_path': 'buildprops.json', 02:22:38 INFO - 'config_files': ('talos/mac_config.py',), 02:22:38 INFO - 'default_actions': ('clobber', 02:22:38 INFO - 'read-buildbot-config', 02:22:38 INFO - 'download-and-extract', 02:22:38 INFO - 'populate-webroot', 02:22:38 INFO - 'create-virtualenv', 02:22:38 INFO - 'install', 02:22:38 INFO - 'run-tests'), 02:22:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:22:38 INFO - 'download_minidump_stackwalk': True, 02:22:38 INFO - 'download_symbols': 'ondemand', 02:22:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:22:38 INFO - 'tooltool.py': '/tools/tooltool.py', 02:22:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:22:38 INFO - '/tools/misc-python/virtualenv.py')}, 02:22:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:22:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:22:38 INFO - 'gecko_profile': False, 02:22:38 INFO - 'gecko_profile_interval': 0, 02:22:38 INFO - 'installer_path': 'installer.exe', 02:22:38 INFO - 'log_level': 'info', 02:22:38 INFO - 'log_name': 'talos', 02:22:38 INFO - 'log_to_console': True, 02:22:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:22:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:22:38 INFO - 'opt_config_files': (), 02:22:38 INFO - 'pip_index': False, 02:22:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:22:38 INFO - 'cmd': ('bash', 02:22:38 INFO - '-c', 02:22:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:22:38 INFO - 'enabled': True, 02:22:38 INFO - 'halt_on_failure': False, 02:22:38 INFO - 'name': 'check_screen_resolution'},), 02:22:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:22:38 INFO - 'cmd': ('bash', 02:22:38 INFO - '-c', 02:22:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:22:38 INFO - 'enabled': True, 02:22:38 INFO - 'halt_on_failure': False, 02:22:38 INFO - 'name': 'check_screen_resolution'},), 02:22:38 INFO - 'run_cmd_checks_enabled': True, 02:22:38 INFO - 'suite': 'dromaeojs-e10s', 02:22:38 INFO - 'system_bits': '32', 02:22:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:22:38 INFO - 'title': 't-yosemite-r7-0086', 02:22:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:22:38 INFO - 'use_talos_json': True, 02:22:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:22:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:22:38 INFO - 'work_dir': 'build'} 02:22:38 INFO - [mozharness: 2017-05-10 09:22:38.045111Z] Running clobber step. 02:22:38 INFO - Running pre-action listener: _resource_record_pre_action 02:22:38 INFO - Running main action method: clobber 02:22:38 INFO - rmtree: /builds/slave/test/build 02:22:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:22:40 INFO - Running post-action listener: _resource_record_post_action 02:22:40 INFO - [mozharness: 2017-05-10 09:22:40.555093Z] Finished clobber step (success) 02:22:40 INFO - [mozharness: 2017-05-10 09:22:40.555251Z] Running read-buildbot-config step. 02:22:40 INFO - Running pre-action listener: _resource_record_pre_action 02:22:40 INFO - Running main action method: read_buildbot_config 02:22:40 INFO - Using buildbot properties: 02:22:40 INFO - { 02:22:40 INFO - "project": "", 02:22:40 INFO - "product": "firefox", 02:22:40 INFO - "script_repo_revision": "production", 02:22:40 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:22:40 INFO - "repository": "", 02:22:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:22:40 INFO - "buildid": "20170510004018", 02:22:40 INFO - "stage_platform": "macosx64", 02:22:40 INFO - "basedir": "/builds/slave/test", 02:22:40 INFO - "nightly_build": "True", 02:22:40 INFO - "buildnumber": 0, 02:22:40 INFO - "slavename": "t-yosemite-r7-0086", 02:22:40 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:22:40 INFO - "platform": "yosemite_r7", 02:22:40 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:22:40 INFO - "branch": "mozilla-aurora", 02:22:40 INFO - "repo_path": "releases/mozilla-aurora", 02:22:40 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 02:22:40 INFO - "pgo_build": "False", 02:22:40 INFO - "builduid": "3c51713f270946289aa864349cedb29e", 02:22:40 INFO - "slavebuilddir": "test" 02:22:40 INFO - } 02:22:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:22:40 INFO - Running post-action listener: _resource_record_post_action 02:22:40 INFO - [mozharness: 2017-05-10 09:22:40.558825Z] Finished read-buildbot-config step (success) 02:22:40 INFO - [mozharness: 2017-05-10 09:22:40.558950Z] Running download-and-extract step. 02:22:40 INFO - Running pre-action listener: _resource_record_pre_action 02:22:40 INFO - Running main action method: download_and_extract 02:22:40 INFO - mkdir: /builds/slave/test/build/tests 02:22:40 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')]} 02:22:40 INFO - trying https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:22:40 INFO - Downloading https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:22:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 02:22:41 INFO - Downloaded 1199 bytes. 02:22:41 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:22:41 INFO - Using the following test package requirements: 02:22:41 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:22:41 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:22:41 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:22:41 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:22:41 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:22:41 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:22:41 INFO - u'jsshell-mac.zip'], 02:22:41 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:22:41 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:22:41 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:22:41 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:22:41 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:22:41 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:22:41 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:22:41 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:22:41 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:22:41 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:22:41 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:22:41 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:22:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:22:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:22:41 INFO - Fetch https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:22:43 INFO - Expected file size: 51791465 02:22:43 INFO - Obtained file size: 51791465 02:22:59 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:22:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:22:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:22:59 INFO - Fetch https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:23:01 INFO - Expected file size: 51791465 02:23:01 INFO - Obtained file size: 51791465 02:23:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:23:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:23:16 INFO - Fetch https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:23:18 INFO - Expected file size: 14404424 02:23:18 INFO - Obtained file size: 14404424 02:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:18 INFO - trying https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:23:18 INFO - Downloading https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:23:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 02:23:20 INFO - Downloaded 74782533 bytes. 02:23:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:23:20 INFO - mkdir: /builds/slave/test/properties 02:23:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:23:20 INFO - Writing to file /builds/slave/test/properties/build_url 02:23:20 INFO - Contents: 02:23:20 INFO - build_url:https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:23:21 INFO - Running post-action listener: _resource_record_post_action 02:23:21 INFO - Running post-action listener: set_extra_try_arguments 02:23:21 INFO - [mozharness: 2017-05-10 09:23:21.188064Z] Finished download-and-extract step (success) 02:23:21 INFO - [mozharness: 2017-05-10 09:23:21.188215Z] Running populate-webroot step. 02:23:21 INFO - Running pre-action listener: _resource_record_pre_action 02:23:21 INFO - Running main action method: populate_webroot 02:23:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:23:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:23:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:23:21 INFO - '--tppagecycles', 02:23:21 INFO - '7'], 02:23:21 INFO - 'tests': ['tsvgm']}}, 02:23:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:23:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:23:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:23:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:23:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:23:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:23:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:23:21 INFO - 'talos_options': ['--disable-e10s'], 02:23:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:23:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:23:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:23:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:23:21 INFO - 'talos_options': ['--disable-e10s'], 02:23:21 INFO - 'tests': ['damp', 'tps']}, 02:23:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:23:21 INFO - 'tests': ['damp', 'tps']}, 02:23:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:23:21 INFO - 'tests': ['dromaeo_dom']}, 02:23:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:23:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:23:21 INFO - 'tests': ['basic_compositor_video']}, 02:23:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:23:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:23:21 INFO - 'tests': ['a11yr', 02:23:21 INFO - 'ts_paint', 02:23:21 INFO - 'tpaint', 02:23:21 INFO - 'sessionrestore', 02:23:21 INFO - 'sessionrestore_no_auto_restore', 02:23:21 INFO - 'tabpaint']}, 02:23:21 INFO - 'other-e10s': {'tests': ['a11yr', 02:23:21 INFO - 'ts_paint', 02:23:21 INFO - 'tpaint', 02:23:21 INFO - 'sessionrestore', 02:23:21 INFO - 'sessionrestore_no_auto_restore', 02:23:21 INFO - 'tabpaint']}, 02:23:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:23:21 INFO - 'ts_paint', 02:23:21 INFO - 'tpaint', 02:23:21 INFO - 'sessionrestore', 02:23:21 INFO - 'sessionrestore_no_auto_restore', 02:23:21 INFO - 'tabpaint']}, 02:23:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:23:21 INFO - 'ts_paint', 02:23:21 INFO - 'tpaint', 02:23:21 INFO - 'sessionrestore', 02:23:21 INFO - 'sessionrestore_no_auto_restore', 02:23:21 INFO - 'tabpaint']}, 02:23:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:23:21 INFO - 'tests': ['a11yr', 02:23:21 INFO - 'ts_paint', 02:23:21 INFO - 'tpaint', 02:23:21 INFO - 'sessionrestore', 02:23:21 INFO - 'sessionrestore_no_auto_restore', 02:23:21 INFO - 'tabpaint']}, 02:23:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:23:21 INFO - 'tests': ['a11yr', 02:23:21 INFO - 'ts_paint', 02:23:21 INFO - 'tpaint', 02:23:21 INFO - 'sessionrestore', 02:23:21 INFO - 'sessionrestore_no_auto_restore', 02:23:21 INFO - 'tabpaint']}, 02:23:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:23:21 INFO - 'tests': ['tsvgx', 02:23:21 INFO - 'tsvgr_opacity', 02:23:21 INFO - 'tart', 02:23:21 INFO - 'tscrollx', 02:23:21 INFO - 'cart', 02:23:21 INFO - 'tsvg_static']}, 02:23:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:23:21 INFO - 'tsvgr_opacity', 02:23:21 INFO - 'tart', 02:23:21 INFO - 'tscrollx', 02:23:21 INFO - 'cart', 02:23:21 INFO - 'tsvg_static']}, 02:23:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:23:21 INFO - 'talos_options': ['--disable-e10s'], 02:23:21 INFO - 'tests': ['tp5o']}, 02:23:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:23:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:23:21 INFO - 'talos_options': ['--disable-e10s', 02:23:21 INFO - '--xperf_path', 02:23:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:23:21 INFO - 'tests': ['tp5n']}, 02:23:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:23:21 INFO - 'talos_options': ['--xperf_path', 02:23:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:23:21 INFO - 'tests': ['tp5n']}}, 02:23:21 INFO - 'talos.zip': {'path': '', 02:23:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:23:21 INFO - Running post-action listener: _resource_record_post_action 02:23:21 INFO - [mozharness: 2017-05-10 09:23:21.206859Z] Finished populate-webroot step (success) 02:23:21 INFO - [mozharness: 2017-05-10 09:23:21.206992Z] Running create-virtualenv step. 02:23:21 INFO - Running pre-action listener: _resource_record_pre_action 02:23:21 INFO - Running main action method: create_virtualenv 02:23:21 INFO - Creating virtualenv /builds/slave/test/build/venv 02:23:21 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 02:23:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:23:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:23:21 INFO - Using real prefix '/tools/python27' 02:23:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:23:22 INFO - Installing distribute.............................................................................................................................................................................................done. 02:23:24 INFO - Installing pip.................done. 02:23:24 INFO - Return code: 0 02:23:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:23:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:23:25 INFO - Reading from file tmpfile_stdout 02:23:25 INFO - Output received: 02:23:25 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) 02:23:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:23:25 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')]} 02:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:25 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')]} 02:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:25 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 0x107891b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107894768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ee844e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10766fd30>, '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 0x107596db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107664be8>, '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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 02:23:25 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 02:23:25 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 02:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rKd3h9cQHb/Render', 02:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:25 INFO - 'HOME': '/Users/cltbld', 02:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:25 INFO - 'LOGNAME': 'cltbld', 02:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:25 INFO - 'MOZ_NO_REMOTE': '1', 02:23:25 INFO - 'NO_EM_RESTART': '1', 02:23:25 INFO - 'PAGER': '/bin/cat', 02:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:25 INFO - 'PWD': '/builds/slave/test', 02:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:25 INFO - 'SHELL': '/bin/bash', 02:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJAIN7Cp1D/Listeners', 02:23:25 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 02:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:25 INFO - 'USER': 'cltbld', 02:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:25 INFO - 'XPC_FLAGS': '0x0', 02:23:25 INFO - 'XPC_SERVICE_NAME': '0', 02:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:25 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 02:23:25 INFO - Cleaning up... 02:23:25 INFO - Return code: 0 02:23:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:23:25 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')]} 02:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:25 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')]} 02:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:25 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 0x107891b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107894768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ee844e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10766fd30>, '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 0x107596db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107664be8>, '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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 02:23:25 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 02:23:25 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 02:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rKd3h9cQHb/Render', 02:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:25 INFO - 'HOME': '/Users/cltbld', 02:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:25 INFO - 'LOGNAME': 'cltbld', 02:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:25 INFO - 'MOZ_NO_REMOTE': '1', 02:23:25 INFO - 'NO_EM_RESTART': '1', 02:23:25 INFO - 'PAGER': '/bin/cat', 02:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:25 INFO - 'PWD': '/builds/slave/test', 02:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:25 INFO - 'SHELL': '/bin/bash', 02:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJAIN7Cp1D/Listeners', 02:23:25 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 02:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:25 INFO - 'USER': 'cltbld', 02:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:25 INFO - 'XPC_FLAGS': '0x0', 02:23:25 INFO - 'XPC_SERVICE_NAME': '0', 02:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:25 INFO - Downloading/unpacking psutil>=3.1.1 02:23:25 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 02:23:25 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 02:23:25 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 02:23:25 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 02:23:25 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 02:23:25 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 02:23:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:23:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:23:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:23:28 INFO - Installing collected packages: psutil 02:23:28 INFO - Running setup.py install for psutil 02:23:28 INFO - building 'psutil._psutil_osx' extension 02:23:28 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 02:23:29 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 02:23:29 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 02:23:29 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 02:23:29 INFO - building 'psutil._psutil_posix' extension 02:23:29 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 02:23:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:23:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:23:29 INFO - ^ 02:23:29 INFO - 1 warning generated. 02:23:29 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 02:23:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:23:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:23:29 INFO - Successfully installed psutil 02:23:29 INFO - Cleaning up... 02:23:29 INFO - Return code: 0 02:23:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:23:29 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')]} 02:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:29 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')]} 02:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:29 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 0x107891b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107894768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ee844e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10766fd30>, '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 0x107596db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107664be8>, '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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 02:23:29 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 02:23:29 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 02:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rKd3h9cQHb/Render', 02:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:29 INFO - 'HOME': '/Users/cltbld', 02:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:29 INFO - 'LOGNAME': 'cltbld', 02:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:29 INFO - 'MOZ_NO_REMOTE': '1', 02:23:29 INFO - 'NO_EM_RESTART': '1', 02:23:29 INFO - 'PAGER': '/bin/cat', 02:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:29 INFO - 'PWD': '/builds/slave/test', 02:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:29 INFO - 'SHELL': '/bin/bash', 02:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJAIN7Cp1D/Listeners', 02:23:29 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 02:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:29 INFO - 'USER': 'cltbld', 02:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:29 INFO - 'XPC_FLAGS': '0x0', 02:23:29 INFO - 'XPC_SERVICE_NAME': '0', 02:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:23:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:23:31 INFO - Installing collected packages: mozsystemmonitor 02:23:31 INFO - Running setup.py install for mozsystemmonitor 02:23:31 INFO - Successfully installed mozsystemmonitor 02:23:31 INFO - Cleaning up... 02:23:31 INFO - Return code: 0 02:23:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:31 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 0x107891b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107894768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ee844e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10766fd30>, '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 0x107596db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107664be8>, '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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 02:23:31 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 02:23:31 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 02:23:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rKd3h9cQHb/Render', 02:23:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:31 INFO - 'HOME': '/Users/cltbld', 02:23:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:31 INFO - 'LOGNAME': 'cltbld', 02:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:31 INFO - 'MOZ_NO_REMOTE': '1', 02:23:31 INFO - 'NO_EM_RESTART': '1', 02:23:31 INFO - 'PAGER': '/bin/cat', 02:23:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:31 INFO - 'PWD': '/builds/slave/test', 02:23:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:31 INFO - 'SHELL': '/bin/bash', 02:23:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJAIN7Cp1D/Listeners', 02:23:31 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 02:23:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:31 INFO - 'USER': 'cltbld', 02:23:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:31 INFO - 'XPC_FLAGS': '0x0', 02:23:31 INFO - 'XPC_SERVICE_NAME': '0', 02:23:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:31 INFO - Downloading/unpacking jsonschema==2.5.1 02:23:31 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 02:23:31 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 02:23:31 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 02:23:31 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 02:23:31 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 02:23:31 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 02:23:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:23:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:23:33 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 02:23:33 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 02:23:33 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 02:23:33 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 02:23:33 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 02:23:33 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 02:23:33 INFO - Downloading functools32-3.2.3-2.tar.gz 02:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:23:33 INFO - warning: no files found matching '*.txt' 02:23:33 INFO - no previously-included directories found matching 'build' 02:23:33 INFO - no previously-included directories found matching 'dist' 02:23:33 INFO - no previously-included directories found matching '.git*' 02:23:33 INFO - Installing collected packages: jsonschema, functools32 02:23:33 INFO - Running setup.py install for functools32 02:23:33 INFO - warning: no files found matching '*.txt' 02:23:33 INFO - no previously-included directories found matching 'build' 02:23:33 INFO - no previously-included directories found matching 'dist' 02:23:33 INFO - no previously-included directories found matching '.git*' 02:23:33 INFO - Successfully installed jsonschema functools32 02:23:33 INFO - Cleaning up... 02:23:33 INFO - Return code: 0 02:23:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:23:33 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')]} 02:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:33 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')]} 02:23:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:33 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 0x107891b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107894768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ee844e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10766fd30>, '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 0x107596db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107664be8>, '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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 02:23:33 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 02:23:33 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 02:23:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rKd3h9cQHb/Render', 02:23:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:33 INFO - 'HOME': '/Users/cltbld', 02:23:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:33 INFO - 'LOGNAME': 'cltbld', 02:23:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:33 INFO - 'MOZ_NO_REMOTE': '1', 02:23:33 INFO - 'NO_EM_RESTART': '1', 02:23:33 INFO - 'PAGER': '/bin/cat', 02:23:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:33 INFO - 'PWD': '/builds/slave/test', 02:23:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:33 INFO - 'SHELL': '/bin/bash', 02:23:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJAIN7Cp1D/Listeners', 02:23:33 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 02:23:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:33 INFO - 'USER': 'cltbld', 02:23:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:33 INFO - 'XPC_FLAGS': '0x0', 02:23:33 INFO - 'XPC_SERVICE_NAME': '0', 02:23:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:23:34 INFO - Cleaning up... 02:23:34 INFO - Return code: 0 02:23:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:34 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 0x107891b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107894768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ee844e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10766fd30>, '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 0x107596db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107664be8>, '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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 02:23:34 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 02:23:34 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 02:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rKd3h9cQHb/Render', 02:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:34 INFO - 'HOME': '/Users/cltbld', 02:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:34 INFO - 'LOGNAME': 'cltbld', 02:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:34 INFO - 'MOZ_NO_REMOTE': '1', 02:23:34 INFO - 'NO_EM_RESTART': '1', 02:23:34 INFO - 'PAGER': '/bin/cat', 02:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:34 INFO - 'PWD': '/builds/slave/test', 02:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:34 INFO - 'SHELL': '/bin/bash', 02:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJAIN7Cp1D/Listeners', 02:23:34 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 02:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:34 INFO - 'USER': 'cltbld', 02:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:34 INFO - 'XPC_FLAGS': '0x0', 02:23:34 INFO - 'XPC_SERVICE_NAME': '0', 02:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:34 INFO - Downloading/unpacking blobuploader==1.2.4 02:23:34 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 02:23:34 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 02:23:34 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 02:23:34 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 02:23:34 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 02:23:34 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 02:23:35 INFO - Downloading blobuploader-1.2.4.tar.gz 02:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:23:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:23:35 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 02:23:35 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 02:23:35 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 02:23:35 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 02:23:35 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 02:23:35 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 02:23:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:23:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:23:36 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 02:23:36 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 02:23:36 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 02:23:36 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 02:23:36 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 02:23:36 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 02:23:36 INFO - Downloading docopt-0.6.1.tar.gz 02:23:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:23:36 INFO - Installing collected packages: blobuploader, requests, docopt 02:23:36 INFO - Running setup.py install for blobuploader 02:23:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:23:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:23:36 INFO - Running setup.py install for requests 02:23:37 INFO - Running setup.py install for docopt 02:23:37 INFO - Successfully installed blobuploader requests docopt 02:23:37 INFO - Cleaning up... 02:23:37 INFO - Return code: 0 02:23:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:23:37 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')]} 02:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:37 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')]} 02:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:37 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 0x107891b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107894768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ee844e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10766fd30>, '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 0x107596db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107664be8>, '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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 02:23:37 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 02:23:37 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 02:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rKd3h9cQHb/Render', 02:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:37 INFO - 'HOME': '/Users/cltbld', 02:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:37 INFO - 'LOGNAME': 'cltbld', 02:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:37 INFO - 'MOZ_NO_REMOTE': '1', 02:23:37 INFO - 'NO_EM_RESTART': '1', 02:23:37 INFO - 'PAGER': '/bin/cat', 02:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:37 INFO - 'PWD': '/builds/slave/test', 02:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:37 INFO - 'SHELL': '/bin/bash', 02:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJAIN7Cp1D/Listeners', 02:23:37 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 02:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:37 INFO - 'USER': 'cltbld', 02:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:37 INFO - 'XPC_FLAGS': '0x0', 02:23:37 INFO - 'XPC_SERVICE_NAME': '0', 02:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:23:37 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-j8r23p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:23:37 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-4Zr_r6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:23:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-1FAYmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:23:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Hl04OX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:23:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-jzGpEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:23:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-gC99Ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:23:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-nZDPs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:23:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-e3HNUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:23:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-F7Tl1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:23:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-qQf38G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:23:38 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-w3QCu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:23:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-SF7jKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:23:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-FoU48w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:23:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-RgM97F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-O4dYQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:23:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-WfFkUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:23:39 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-40GM6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:23:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:23:39 INFO - Running setup.py install for manifestparser 02:23:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:23:39 INFO - Running setup.py install for mozcrash 02:23:39 INFO - Running setup.py install for mozdebug 02:23:40 INFO - Running setup.py install for mozdevice 02:23:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:23:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:23:40 INFO - Running setup.py install for mozfile 02:23:40 INFO - Running setup.py install for mozhttpd 02:23:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:23:40 INFO - Running setup.py install for mozinfo 02:23:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:23:40 INFO - Running setup.py install for mozInstall 02:23:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:23:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:23:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:23:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:23:40 INFO - Running setup.py install for mozleak 02:23:40 INFO - Running setup.py install for mozlog 02:23:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:23:41 INFO - Running setup.py install for moznetwork 02:23:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:23:41 INFO - Running setup.py install for mozprocess 02:23:41 INFO - Running setup.py install for mozprofile 02:23:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:23:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:23:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:23:41 INFO - Running setup.py install for mozrunner 02:23:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:23:41 INFO - Running setup.py install for mozscreenshot 02:23:41 INFO - Running setup.py install for moztest 02:23:42 INFO - Running setup.py install for mozversion 02:23:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:23:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:23:42 INFO - Cleaning up... 02:23:42 INFO - Return code: 0 02:23:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:23:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:23:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:42 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 0x107891b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107894768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ee844e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10766fd30>, '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 0x107596db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107664be8>, '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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 02:23:42 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 02:23:42 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 02:23:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rKd3h9cQHb/Render', 02:23:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:42 INFO - 'HOME': '/Users/cltbld', 02:23:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:42 INFO - 'LOGNAME': 'cltbld', 02:23:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:42 INFO - 'MOZ_NO_REMOTE': '1', 02:23:42 INFO - 'NO_EM_RESTART': '1', 02:23:42 INFO - 'PAGER': '/bin/cat', 02:23:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:42 INFO - 'PWD': '/builds/slave/test', 02:23:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:42 INFO - 'SHELL': '/bin/bash', 02:23:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJAIN7Cp1D/Listeners', 02:23:42 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 02:23:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:42 INFO - 'USER': 'cltbld', 02:23:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:42 INFO - 'XPC_FLAGS': '0x0', 02:23:42 INFO - 'XPC_SERVICE_NAME': '0', 02:23:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:23:42 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-IQeSJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:23:42 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)) 02:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:23:42 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-6hEUVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:23:42 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)) 02:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:23:42 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-1E5GV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:23:42 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)) 02:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:23:42 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-DZyJ2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:23:42 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)) 02:23:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:23:42 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-BcawMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:23:43 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)) 02:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:23:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-fxXVkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:23:43 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)) 02:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:23:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-EEeGGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:23:43 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)) 02:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:23:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-EN1thm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:23:43 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)) 02:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:23:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-xhXCWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:23:43 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)) 02:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:23:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Pul5ia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:23:43 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)) 02:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:23:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-54V0DF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:23:43 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)) 02:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:23:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-nAvu6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:23:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-o0qp9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:23:43 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)) 02:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:23:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-_fW_4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:23:43 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)) 02:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:43 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-mZGsyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:44 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)) 02:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:23:44 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-sNHzmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:23:44 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)) 02:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:23:44 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-krqDzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:23:44 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)) 02:23:44 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)) 02:23:44 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)) 02:23:44 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)) 02:23:44 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)) 02:23:44 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)) 02:23:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:23:44 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 02:23:44 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 02:23:44 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 02:23:44 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 02:23:44 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 02:23:44 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 02:23:46 INFO - Downloading blessings-1.6.tar.gz 02:23:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:23:46 INFO - Installing collected packages: blessings 02:23:46 INFO - Running setup.py install for blessings 02:23:46 INFO - Successfully installed blessings 02:23:46 INFO - Cleaning up... 02:23:47 INFO - Return code: 0 02:23:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:23:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:23:47 INFO - Reading from file tmpfile_stdout 02:23:47 INFO - Current package versions: 02:23:47 INFO - blessings == 1.6 02:23:47 INFO - blobuploader == 1.2.4 02:23:47 INFO - docopt == 0.6.1 02:23:47 INFO - functools32 == 3.2.3-2 02:23:47 INFO - jsonschema == 2.5.1 02:23:47 INFO - manifestparser == 1.1 02:23:47 INFO - mozInstall == 1.12 02:23:47 INFO - mozcrash == 1.0 02:23:47 INFO - mozdebug == 0.1 02:23:47 INFO - mozdevice == 0.49 02:23:47 INFO - mozfile == 1.2 02:23:47 INFO - mozhttpd == 0.7 02:23:47 INFO - mozinfo == 0.9 02:23:47 INFO - mozleak == 0.1 02:23:47 INFO - mozlog == 3.4 02:23:47 INFO - moznetwork == 0.27 02:23:47 INFO - mozprocess == 0.25 02:23:47 INFO - mozprofile == 0.28 02:23:47 INFO - mozrunner == 6.13 02:23:47 INFO - mozscreenshot == 0.1 02:23:47 INFO - mozsystemmonitor == 0.3 02:23:47 INFO - moztest == 0.8 02:23:47 INFO - mozversion == 1.4 02:23:47 INFO - psutil == 3.1.1 02:23:47 INFO - requests == 1.2.3 02:23:47 INFO - wsgiref == 0.1.2 02:23:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:23:47 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')]} 02:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:47 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')]} 02:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:47 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 0x107891b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107894768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ee844e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10766fd30>, '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 0x107596db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107664be8>, '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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 02:23:47 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 02:23:47 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 02:23:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rKd3h9cQHb/Render', 02:23:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:47 INFO - 'HOME': '/Users/cltbld', 02:23:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:47 INFO - 'LOGNAME': 'cltbld', 02:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:47 INFO - 'MOZ_NO_REMOTE': '1', 02:23:47 INFO - 'NO_EM_RESTART': '1', 02:23:47 INFO - 'PAGER': '/bin/cat', 02:23:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:47 INFO - 'PWD': '/builds/slave/test', 02:23:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:47 INFO - 'SHELL': '/bin/bash', 02:23:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJAIN7Cp1D/Listeners', 02:23:47 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 02:23:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:47 INFO - 'USER': 'cltbld', 02:23:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:47 INFO - 'XPC_FLAGS': '0x0', 02:23:47 INFO - 'XPC_SERVICE_NAME': '0', 02:23:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:47 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)) 02:23:47 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)) 02:23:47 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)) 02:23:47 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)) 02:23:47 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)) 02:23:47 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)) 02:23:47 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)) 02:23:47 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)) 02:23:47 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)) 02:23:47 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)) 02:23:47 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)) 02:23:47 INFO - Cleaning up... 02:23:47 INFO - Return code: 0 02:23:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:23:47 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')]} 02:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:47 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')]} 02:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:47 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 0x107891b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107894768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ee844e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10766fd30>, '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 0x107596db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107664be8>, '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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 02:23:47 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 02:23:47 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 02:23:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rKd3h9cQHb/Render', 02:23:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:47 INFO - 'HOME': '/Users/cltbld', 02:23:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:47 INFO - 'LOGNAME': 'cltbld', 02:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:47 INFO - 'MOZ_NO_REMOTE': '1', 02:23:47 INFO - 'NO_EM_RESTART': '1', 02:23:47 INFO - 'PAGER': '/bin/cat', 02:23:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:47 INFO - 'PWD': '/builds/slave/test', 02:23:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:47 INFO - 'SHELL': '/bin/bash', 02:23:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJAIN7Cp1D/Listeners', 02:23:47 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 02:23:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:47 INFO - 'USER': 'cltbld', 02:23:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:47 INFO - 'XPC_FLAGS': '0x0', 02:23:47 INFO - 'XPC_SERVICE_NAME': '0', 02:23:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:23:47 INFO - Cleaning up... 02:23:47 INFO - Return code: 0 02:23:47 INFO - Running post-action listener: _resource_record_post_action 02:23:47 INFO - Running post-action listener: _start_resource_monitoring 02:23:47 INFO - Starting resource monitoring. 02:23:47 INFO - [mozharness: 2017-05-10 09:23:47.853363Z] Finished create-virtualenv step (success) 02:23:47 INFO - [mozharness: 2017-05-10 09:23:47.854346Z] Running install step. 02:23:47 INFO - Running pre-action listener: _resource_record_pre_action 02:23:47 INFO - Running main action method: install 02:23:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:23:48 INFO - Reading from file tmpfile_stdout 02:23:48 INFO - Detecting whether we're running mozinstall >=1.0... 02:23:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:23:48 INFO - Reading from file tmpfile_stdout 02:23:48 INFO - Output received: 02:23:48 INFO - Usage: mozinstall [options] installer 02:23:48 INFO - Options: 02:23:48 INFO - -h, --help show this help message and exit 02:23:48 INFO - -d DEST, --destination=DEST 02:23:48 INFO - Directory to install application into. [default: 02:23:48 INFO - "/builds/slave/test"] 02:23:48 INFO - --app=APP Application being installed. [default: firefox] 02:23:48 INFO - mkdir: /builds/slave/test/build/application 02:23:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:24:08 INFO - Reading from file tmpfile_stdout 02:24:08 INFO - Output received: 02:24:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:24:08 INFO - Running post-action listener: _resource_record_post_action 02:24:08 INFO - [mozharness: 2017-05-10 09:24:08.373438Z] Finished install step (success) 02:24:08 INFO - [mozharness: 2017-05-10 09:24:08.373589Z] Running run-tests step. 02:24:08 INFO - Running pre-action listener: _resource_record_pre_action 02:24:08 INFO - Running main action method: run_tests 02:24:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:24:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:24:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:24:08 INFO - 2017-05-10 02:24:08.388 screenresolution[1631:8587] starting screenresolution argv=screenresolution get 02:24:08 INFO - 2017-05-10 02:24:08.390 screenresolution[1631:8587] Display 0: 1600x1200x32@60 02:24:08 INFO - 2017-05-10 02:24:08.396 screenresolution[1632:8589] starting screenresolution argv=screenresolution list 02:24:08 INFO - Available Modes on Display 0 02:24:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:24:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:24:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:24:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:24:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:24:08 INFO - Intel Iris: 02:24:08 INFO - Chipset Model: Intel Iris 02:24:08 INFO - Type: GPU 02:24:08 INFO - Bus: Built-In 02:24:08 INFO - VRAM (Dynamic, Max): 1536 MB 02:24:08 INFO - Vendor: Intel (0x8086) 02:24:08 INFO - Device ID: 0x0a2e 02:24:08 INFO - Revision ID: 0x0009 02:24:08 INFO - Displays: 02:24:08 INFO - Display: 02:24:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:24:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:24:08 INFO - Display Serial Number: 1600x1200 60 02:24:08 INFO - Main Display: Yes 02:24:08 INFO - Mirror: Off 02:24:08 INFO - Online: Yes 02:24:08 INFO - Rotation: Supported 02:24:08 INFO - Return code: 0 02:24:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:24:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:24:08 INFO - Python 2.7.3 02:24:08 INFO - Return code: 0 02:24:08 INFO - grabbing minidump binary from tooltool 02:24:08 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')]} 02:24:08 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 0x10766fd30>, '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 0x107596db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107664be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:24:08 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 02:24:08 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 02:24:08 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 02:24:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:24:08 INFO - Return code: 0 02:24:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:24:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:24:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:24:08 INFO - ENV: RUST_BACKTRACE is now 1 02:24:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:24:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:24:08 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/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 02:24:08 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/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0086 --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 02:24:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rKd3h9cQHb/Render', 02:24:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:08 INFO - 'HOME': '/Users/cltbld', 02:24:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:08 INFO - 'LOGNAME': 'cltbld', 02:24:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:24:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:24:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:08 INFO - 'MOZ_NO_REMOTE': '1', 02:24:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:24:08 INFO - 'NO_EM_RESTART': '1', 02:24:08 INFO - 'PAGER': '/bin/cat', 02:24:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:08 INFO - 'PWD': '/builds/slave/test', 02:24:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:24:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:08 INFO - 'RUST_BACKTRACE': '1', 02:24:08 INFO - 'SHELL': '/bin/bash', 02:24:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJAIN7Cp1D/Listeners', 02:24:08 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 02:24:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:08 INFO - 'USER': 'cltbld', 02:24:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:08 INFO - 'XPC_FLAGS': '0x0', 02:24:08 INFO - 'XPC_SERVICE_NAME': '0', 02:24:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:08 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/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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 02:24:08 INFO - mozversion application_buildid: 20170510004018 02:24:08 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:24:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:24:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:24:08 INFO - mozversion application_name: Firefox 02:24:08 INFO - mozversion application_remotingname: firefox-dev 02:24:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:24:08 INFO - mozversion application_vendor: Mozilla 02:24:08 INFO - mozversion application_version: 54.0a2 02:24:08 INFO - mozversion platform_buildid: 20170510004018 02:24:08 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:24:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:24:08 INFO - mozversion platform_version: 54.0a2 02:24:08 INFO - using testdate: 1494408248 02:24:08 INFO - actual date: 1494408248 02:24:08 INFO - starting webserver on 'localhost:49244' 02:24:08 INFO - SUITE-START | Running 2 tests 02:24:08 INFO - TEST-START | dromaeo_css 02:24:08 INFO - operating with platform_type : mac_ 02:24:08 INFO - Initialising browser for dromaeo_css test... 02:24:09 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpGs1PRT/profile http://localhost:49244/getInfo.html) 02:24:12 INFO - PROCESS | 1642 | 2017-05-10 02:24:12.073 plugin-container[1645:8773] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c4f, name = 'com.apple.tsm.portname' 02:24:12 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:24:12 INFO - PROCESS | 1642 | 2017-05-10 02:24:12.074 plugin-container[1645:8773] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 02:24:12 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:24:12 INFO - PROCESS | 1642 | 2017-05-10 02:24:12.074 plugin-container[1645:8773] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:24:12 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 02:24:12 INFO - PROCESS | 1642 | colorDepth:24 02:24:12 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 02:24:12 INFO - PROCESS | 1642 | __metrics 02:24:12 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 02:24:12 INFO - TEST-INFO | 1642: exit 0 02:24:12 INFO - Browser initialized. 02:24:12 INFO - Running cycle 1/1 for dromaeo_css test... 02:24:12 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpGs1PRT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:24:15 INFO - PROCESS | 1647 | 2017-05-10 02:24:15.514 plugin-container[1648:8907] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 02:24:15 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:24:15 INFO - PROCESS | 1647 | 2017-05-10 02:24:15.515 plugin-container[1648:8907] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient' 02:24:15 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:24:15 INFO - PROCESS | 1647 | 2017-05-10 02:24:15.515 plugin-container[1648:8907] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:24:25 INFO - PROCESS | 1647 | RSS: Main: 221282304 02:24:25 INFO - PROCESS | 1647 | 02:27:28 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 02:27:30 INFO - PROCESS | 1647 | RSS: Main: 239611904 02:27:30 INFO - PROCESS | 1647 | 02:29:13 INFO - PROCESS | 1647 | ************************* 02:29:13 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 02:29:13 INFO - PROCESS | 1647 | 02:29:13 ERROR - PROCESS | 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:29:13 INFO - PROCESS | 1647 | Full stack: 02:29:13 INFO - PROCESS | 1647 | ************************* 02:29:13 INFO - PROCESS | 1647 | ************************* 02:29:13 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 02:29:13 INFO - PROCESS | 1647 | 02:29:13 ERROR - PROCESS | 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:29:13 INFO - PROCESS | 1647 | Full stack: 02:29:13 INFO - PROCESS | 1647 | ************************* 02:29:13 INFO - PROCESS | 1647 | ************************* 02:29:13 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 02:29:13 INFO - PROCESS | 1647 | 02:29:13 ERROR - PROCESS | 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:29:13 INFO - PROCESS | 1647 | Full stack: 02:29:13 INFO - PROCESS | 1647 | ************************* 02:29:13 INFO - PROCESS | 1647 | ************************* 02:29:13 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 02:29:13 INFO - PROCESS | 1647 | 02:29:13 ERROR - PROCESS | 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:29:13 INFO - PROCESS | 1647 | Full stack: 02:29:13 INFO - PROCESS | 1647 | ************************* 02:30:33 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 02:30:33 INFO - PROCESS | 1647 | RSS: Main: 250302464 02:30:33 INFO - PROCESS | 1647 | 02:33:35 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 02:33:36 INFO - PROCESS | 1647 | RSS: Main: 257323008 02:33:36 INFO - PROCESS | 1647 | 02:36:38 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 02:36:39 INFO - PROCESS | 1647 | RSS: Main: 253538304 02:36:39 INFO - PROCESS | 1647 | 02:39:42 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 02:39:42 INFO - PROCESS | 1647 | RSS: Main: 258555904 02:39:42 INFO - PROCESS | 1647 | 02:42:45 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 02:42:45 INFO - PROCESS | 1647 | RSS: Main: 254820352 02:42:45 INFO - PROCESS | 1647 | 02:42:45 INFO - PROCESS | 1647 | __start_tp_report 02:42:45 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 02:42:45 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 02:42:45 INFO - PROCESS | 1647 | |i|pagename|runs| 02:42:45 INFO - PROCESS | 1647 | |0;dojo.html;3443.4146195146836;3459.031471188067;3483.8954831355054;3486.3550243205027;3494.72042236621;5884.058550631899;5896.469317761401;5912;5971.581989260753;5986.161937328778;4436.467623885138;5890.17537544743;5938.228030356058;5955.910661340084;5992.670403127826;5362.2224777407255;5395.406505284415;5440.374356948962;5488.643238189525;5491.890162196753;5492.780288788444;5494.2582751328455;5501.862453438656;5504.2294078829;5512.614117011811;5334.093204155283;5350.598705097113;5399.947010332987;5497.615166938316;5525.613207075487;5400.000999815047;5419.674819510842;5433.972830135864;5476.7535460904355;5481.424450432727;4539.500654927955;5238.502342277516;5321.068812957717;5426.213199086111;5493.23094766733;4788.9521104788855;5474.507294343488;6007.939920600782;6125.877482450326;6140.785072522485;6260.060990851363;6286.434220920093;6301.7164227609865;6319;6361.173047503841;6396.520260980398;6402.13571167891;6431.8392040198905;6438.678066096676;6458.870822583522;6073.99779036454;6117.755289788448;6125.601835880653;6126.264848218242;6149.446549810494;5397.27136836522;5687.8862422752145;5796.1595568642315;6266.592671476339;6300.117983482316;6292.244930608356;6358.586691865014;6366.522510811707;6374.362563743682;6382.744690212339;5356.3572371315695;6397;6397.712102955285;6399.744010239631;6427.614343139427;6244.718987645567;6298.937185515317;6300.086487459384;6354.269259035178;6369;6300.1494798202575;6310.400511951358;6330.651814150266;6344.270408902943;6353.174087368705;5083.186690129562;5102.795888164506;5116.74416279192;5121.820736274287;5124.5131712487255;4245.745255284693;4409.581089796528;4549.704269222556;4567.771611419482;4621.907561848744;4822.9758851205515;5660.773569057274;5756.395578464285;5895.67573783446;5919.230500035054;6385.936140638534;6404.295527491972;6424.261209960914;6426;6431.260405053479;4929.580985616263;5756.136579513107;5925.970370148121;5993.101034844808;5998.1602575639445;5641.069223578145;5669.376368599534;5696.658200507983;5701.71491425427;5712.942870571324;5565.6104072714525;6565.088963370356;6621.304762999912;6627.469802415699;6629.7348106075215;5385.057614917452;6251.562390632794;6276.968615156895;6407.871842563216;6417.2299324081405;6404;6434.453071488976;6437.517186210959;6461.61230326182;6487.740490380332;6354.872902541831;6384.3934826191435;6387.361263873576;6389.616623002635;6390.4248617624635;6273.874522509615;6318.052292156213;6347.365263473615;6356.936430635635;6369.426751592379;5770.1344798278915;6290.119383286435;6291.653959032297;6353.491720662429;6376.649284289409;6274.843128921813;6305.747770089145;6306.054091886071;6344.904826427515;6348.968255158695;6130.785422510191;6134;6219.564630476004;6230.501559875109;6274.090256912631;6100;6113.537117903944;6203.565750397429;6257.405546473078;6277.623342599276;5171;6234.407731265523;6235.844103897439;6241.407066328691;6254.906176407449 02:42:45 INFO - PROCESS | 1647 | |1;ext.html;729.9489035767496;799.1289494451049;825.8809313380367;953.3517208298358;955.2739917662583;38863.41704874425;39663;39832.00419989502;39863.601363986425;39864.80405587837;38426.03934901629;38586.8070659647;38717.22565548687;38739.418908716325;38773.80613096931;7900.565468899223;8041.236082572146;10742.785144297148;10999.285046471994;11023.724406889853;10688.786224275509;10806.459677016157;10806.675799725981;10810.837837432446;10858.077063449577;10820.621278255263;10830.458477076154;10838.295510791773;10838.566457341696;10855.131589472863;9317.72046838596;10703.983121603434;10757.408342541157;10789.514471848786;10812.837807432894;9924.354916930377;10243.180545556337;10506.632267870587;10571.947140264327;10587.417692026935;33654.1586460338;33658.49512257318;33766.49350259748;33973.660263397294;34031.829840850885;3687.6865466435383;3754.9812250938844;3755.342815007363;3789.147441825595;3814.7901865397394;24851.751482485124;24888.751112489004;25271.36821579475;26161.73838261631;26303;2895.319599894023;3022.6826183250882;3063.494523403636;3174.190581401751;3287.3096649703352;294954;301071;302009;343560;345569;51928.48071519312;54050.459495404546;54483.18275225875;54625;54671.63320917009;56806.715966419906;56907;57247;57273.14090288649;57302.7134864323;15224.162671052976;15235.933484655967;15307.46423875159;15318;15318.540443786704;12889.548865789653;12959.416826242841;12965.675858103623;13013.349332533335;13023.739525209443;13752.31238438094;13781.379837907318;13798.93100534511;13827.377768000464;13883.791743123862;5256.26412302278;6118.357572454917;6123.601965872204;6128.693565321716;6173.536984726164;1125.4429057616533;1137.306243191669;1140.5266814271984;1154.9942250288696;1159.646307876089;24872.751272487407;26386.20841374762;27122.457550848874;27328.043518477156;27535.31161720973;1037.4968140451874;1083.3824719909576;1131.8794393550327;1248.6066888929959;1263.9620811375673;11587.88412115885;13406.329683515785;13445.663858403617;14127.505537306144;14266.928665356607;491230.5438472856;493687;494006;494074;496444;47246.52753472421;47446;47495.76252118717;47555.5244447558;47667.808304792656;1397.7623803953352;1422.8648278413534;1433.0756661952983;1439.863212994743;1442.5167568864338;25305.493890122463;25737.970481180542;26433.33916652099;26780.330491737863;26906.058287959826;42072.3689144657;42322.36516452317;42393.15213695647;42444.57555424406;42513.5748642522;450314;453274;453577;454623;462556;29294.24234545934;29834.701652983193;29902;29981.250468738457;29998.400031999674;37267.81366093152;37487;38413;38514.65198718032;38572;27191.456170876867;27228.31929201786;27281.72718272871;27496.725032750215;28199.43601128007;27284.181474555797;27379.863100684368;27400.72599273982;27813.582796258477;28109.437811243253;31462.213444664067;31811.68188318087;31937;32093.518597220595;32188.839055804572;22938.27148189304;27042.864785675945;27063.729362706123;27805.72194278112;28340.01619886588;19371.51571210731;21701.34895953124;22347.217847375916;22634.886825566424;22649.433764156027 02:42:45 INFO - PROCESS | 1647 | |2;jquery.html;402.40645048553404;403.1714826032502;409.8422107488617;425.1497005988023;441.85559401151033;16539.751903721437;18107.185176667048;20542;21172.682409763845;21197.470063248427;18533.702640815147;20474.590508189827;21551.461213469673;21758.803265820374;21852.45368865775;6209.099680546306;6472.708728107223;6624.470042396621;6642.169728783902;6649.767258145942;6386.64873431961;6625.17185351831;6630.336966303355;6633.071370008202;6666.3000384959605;6563.573367731082;6599.241087274977;6619.735210591594;6621.337866213365;6625.768098116567;6461.967690161543;6515.0553169790355;6525.249596296416;6632.568883022588;6667.333266673342;6247.750089996394;6425.518086143511;6472.449841763456;6491.318411566812;6499.707513161919;25086.623700644504;25156.622650660254;25273.115440959664;25553.74446255532;25613.871930640413;2510.3598582361565;2808.719128087175;2872.8276303421862;2874.870630821597;2884.812487188347;15750.527484175493;15809.683806323925;16057;16115;16578.58553536171;2090.905909234088;2091.5084955035554;2126.5959467701086;2148.5165837686395;2156.439325775294;134937.3253133728;138672.99999999898;140825.29587351996;141314;141546.58453415436;16338.509844704678;19353.709694354595;20161.29435469781;20182.99085045742;20430.284940027326;30301;30566.84716576403;30678.539821902243;30709.84645076805;30727.23181920425;10248.948755256326;10467;10599.576016959236;10658.520366583522;10680.732981675521;8777.341699372573;8781.648734050694;8781.69264075767;8965.551722413853;8985.46087234768;9795.706128816148;9915.405075695482;9937.304388692866;9948.701538953843;9969.651062212934;3312.4368857293834;3602.441621548664;3886.0479182600443;4256.702030857872;4264.317709166518;982.2436723722693;989.2531138990161;989.42118860466;993.9006099390148;1030.5517100061497;16111.436099736688;16259.51221463358;20073.996300184932;20748.481287967923;21039.894800526206;991.3308516751164;995.1491474789096;997.1075887081022;1031.4481752262504;1034.2863424237253;9524.428534287965;9698.836139663286;10307.690769276784;10431.37411755297;10815.459227038617;737145.6285437077;739670;743105;750207.3735021632;758022;155003;158292;158417;160220;162312;1152.4410660829492;1172.056494521901;1191.880811918801;1197.7784109939514;1206.5776978057786;15296.617584560476;17568.2094305754;18018.36935707244;18398.47426314958;18523.629527409106;63009.36990630035;63565.68217158884;63641.681791590745;64480.67759661172;64937.35062649313;90718;91010.54494727484;91613;91826.16347673142;91997;8545.700900468453;11007.889921101007;11467.139964502863;11535.480903359537;11801.29192248468;10953.740319863318;11905.345206013752;12353.94991425739;12557.623271301876;12900.741985160432;9983.400995939975;10705.411202383631;10708.892911071101;10917.508712108132;10937.398443085389;10208.744781380228;10317.896821031694;10318.845217321596;10362.274640775073;10642.62750803749;10655.999361557775;10674.46627668629;10764.838527422251;10956.890431095906;11049.668509944715;8911.420757650732;9749.9025009749;11669.824952625548;11686.298822070703;11702.35637039937;9649.9035009649;9735.026497350209;10880.45597720095;10981.066609337977;11005.889941100486 02:42:45 INFO - PROCESS | 1647 | |3;mootools.html;1403.7613605687031;1522.16281045425;1522.92385380731;1525.832158462569;1562.320390630076;10250.231232657543;10546.630867919615;10846.132309415247;11142.331460112398;11180.273282236649;11028.44857757111;11195.720106997309;11211.943940280287;11258.943705281463;11261.943690281538;2065.029436165;2068.7103805467173;2427.7086749590076;2446.8654224017673;2460.9261722148362;2362.4093976505874;2433.1483980606736;2435.4398488347692;2440.548498527769;2447.7185123710733;2029.3708950225403;2344.800691941187;2382.5711371953043;2427.745086765891;2436.7441418650965;2015.9596808063757;2367.9526409471714;2374.1215750172373;2383.2135395319588;2397.4485868250313;2376.738558758535;2381.2380038387723;2384.332386931662;2396.8015992004;2404.507076049406;10837.349759014405;10890.183236257233;10922.18083643722;10934.398608076554;10934.398608076554;5607.327120745495;5798.9130163047585;5877.089051197071;5892.96873047215;5898.056310990264;3482.4253998090285;3653.3789255826573;3705.962940370616;3742.850285988557;3775.4903088083033;1173.342927960345;1204.3376143121247;1237.412229191136;1238.6222202228316;1255.54172726954;13657.931710341385;13833.239171845646;14884.70230595382;15680.529584112495;15802.683946321009;4163.8959026024595;4658.767061646972;4722.52774722525;4896.828610998598;4907.754612269444;11683;11732;11763.117766167572;11776.470058847372;11781.76436471283;5888.852778680567;5901.17383566301;5935.465808077293;5939.554533410012;5948.018576934844;4592.563706447882;5235.8429247121885;5356.517913387814;5424.132138857843;5429.049916264717;3844.1927195288745;3917.2557214129224;3919.235749028912;3960.683145348365;4017.4777278954134;1052.0899421999916;1054.4938429553856;1061.9628313009007;1065.2543219746208;1065.387402243713;734.8273155808381;787.9409044321699;804.2922228438937;813.808754942588;818.4720855048579;6506.60960342381;6541;6605.768798092044;6740.460763138937;6755.729770809211;949.4255975134944;978.5938835383379;988.1304452082122;990.4898977026837;995.9800803983879;3555.928881422357;4244.808983595745;4274.572542745763;4392.9896123891685;4414.27164517851;12521.937390313173;13977.951653626018;13981.930090349482;13984.091034082752;14015.159090454605;11826.172167948162;13495.325233738275;13588.320583970763;13897.0966887152;13911.095778774346;8689.304855611663;9224.12370824784;9655.613775448903;10150.03574660406;10311.948440257751;6361.650109244036;6678.065070889982;6734.528582999143;6898.310168983262;7023.894641580278;10420.374777513374;12227.938860305641;12405.503779849067;12421.31682757457;12484.87515124837;10243.7439064024;12626.55807046749;12858.614241572768;12921.418536166084;13014.479420823061;9138.497382644018;9347.205487533636;9441.150296473075;9512.95243523778;9562.90437095648;10421.270511064153;10490.580376784843;10519.631812886511;10521.158307335549;10598.576056957636;8877.422967507091;8893.822123557622;9091.545422728705;9230.723078307661;9313.115254050854;8395.454295470774;9118.863217051883;9142.222911052635;9183.632654693738;9349.53252337394;7839;7865.134835168013;7883.211678832071;7962.084360298408;7964.6415911282975;5149.48505149482;5810.564207684356;5972.372900845348;6036.094585812163;6092.421219984094;5912.46787789101;6052.152698622109;6115.816525504242;6173.660448675366;6207.410296021871 02:42:45 INFO - PROCESS | 1647 | |4;prototype.html;336.46334097115096;362.6899001353843;370.80347415869596;376.3564305038382;380.9409541521064;4197.895052623682;4554.362389265497;4974.990130970765;5065.696058236508;5151.562117220036;4208.800491859821;4437.445319335083;4544.568266014723;5064.974675126619;5169.689818610886;4163.917381480806;4321.82712691492;4361.454818147731;4481.753503557311;4550.249208880531;3832.9808350958347;4156.625903668669;4189.6438802701805;4517.028838799657;4517.644706588027;4209.347551129565;4226.957730422687;4542;4574.611158051553;4592.770361481913;4177.7857735400285;4453.755043472608;4549.067441174568;4554.3851580036935;4601.28680054592;3677.5770786359376;3707.4994875691705;4336.891577710551;4360.956390436118;4380.956190438119;4395.978020109911;5083.9918232298405;5094.923576146361;5108.693478391308;5147.382314122292;4391.3193455014525;5077.033922561403;5204.34945631796;5307.867303317409;5350.886399872029;5092.905025419553;5243.528082472558;5515.531179849718;5523.806666766684;5649.915251271234;4075.572064933199;4439.934415740199;4549.155963211081;4563.726176429424;4860.756962151913;4907.803687852482;5255.76349064293;5344.919826202571;5439.422567455433;5814.563907706896;4546.545345465427;5052.494750524992;5056.244346830986;5095.859738909667;5100.591952643779;4376.540463251376;4738.526147385234;4951.533926251638;5115.309433226466;5188.507091826271;4338.457692788402;5099.878026834091;5189.42916279209;5224.40655600038;5361.973190134025;4708.929366059512;5480.80817171405;5607.635503692247;5619.892540297388;5671.404502527175;1545.0343535290444;1661.9252133653772;1757.7539144519778;1762.8765986381086;1783.9286428542714;1201.8978386837216;1276.16411250631;1292.2182079841562;1318.3935389720837;1435.956921292363;4626.537346265414;4636.652251081183;5072.467390923974;5098.821541246113;5126.307948426989;4835.371401717754;5008.549230569193;5087.669301495465;5540;5717.799877004285;4561.407017087758;4732.313814496879;5098.5411312982005;5098.643094983389;5115.902479983263;2834.4189441164513;2896.232498387929;2898.811577247472;2996.7902246842946;3008.608880845476;4950.168437678082;5266.8419947401635;5719.170720245541;5726.541876649858;5987.916223145084;5558.44415558449;5658.632188907708;5780.889975159037;5855.414458554195;5878.764849406061;5093.617978651616;5161.638685292069;5541.501264886179;5650;5758.544873076205;5085.46602606721;5295.867603309948;5340.75966581497;5359.651622644516;5772.9711351442975;4913.778879950482;4917.188663870421;5169.4830516949505;5378.677279363251;5554.555635549227;5289.233061206027;5295.655782374133;5458.6724796512335;5497.862553436196;5947.940520594739;4441.335198880646;4677.947461821199;4916.729579872998;4978.4274808397495;5018.946021335414;4814.638902082288;4886.828960986349;5330.560748597934;5595.524380427709;5754.856128596651;5120.539151476236;5275.472452754847;5326.840194794162;5517.503424691797;5587.636803607753;4750.216214324736;4750.358701575381;5349.9465005351;5505.642133261325;5582.441755824385;4368.716033457942;4890.4249155961525;5300.3639563252655;5328.307320048524;5330.307060082165;4556.362109304634;5441.29263195782;5468.26178465902;5540.529055030368;5605.7106865420365;4552.385427967182;4876.658633895593;4996.475370086088;5176.275321454924;5422.539084177889 02:42:45 INFO - PROCESS | 1647 | |5;yui.html;689.4725534965751;707.3492387003956;717.6842189436651;812.3663542436901;862.9989212513485;15075.62310942227;15549.144797036173;16078.51764447067;16416.589585260375;16611.50165495034;14850.48023319184;15182.54452366428;15278.312475938581;15392.692146157098;15482.225888705576;923.7967647117623;932.8554074118523;955.0735786287325;958.9520523973773;959.4531117263163;957.4733896357011;968.4479846487505;969.413504829575;971.0580736685404;979.9906096720389;1004.3772860826298;1021.6015753856002;1022.4989755020061;1023.4064242739194;1023.9590416383373;1016.8627235323211;1018.0582960761266;1018.3177271228295;1018.6485662446481;1019.4647809899787;750.3471979377977;751.9812004699871;752.3529764402609;754.2570567990525;756.1984296645574;16550.172491375382;16862.24119914607;17029.318827246894;17030.914845425817;17256.913715431467;13059.412326445237;14000.439982400692;14010;14134.92932535341;14303.210132228776;10181.643642472478;11626.360550169737;11784.587539436165;11787;11856.525738970431;3608.6030536640947;3681.668649821529;3752.5496940367057;3782.3191825471404;3819.598942111066;114798.42600786942;117232;120694;120700;123189;10574.682759517227;10615.150787936947;10842.349459032483;10934.617288395028;11024.338539687644;22551.661725074136;22557.4360640982;22604.321870343916;22627.43431414195;22714.20500282515;7444.516106453063;7530.962345188239;7736.6518506667335;7832.216778322122;7897;4280.764557949343;4316.309390497506;4368.388425620416;4389.846355377546;4430.977845110819;3461.6711412416284;3508.5088087667746;3543.7696549724187;3554.87557935471;3706.1661126246377;693.826543364159;694.3299715774302;698.4866123399297;703.2826516952782;708.3093983366176;655.7868692674899;670.5641333133502;689.7068745783022;698.6506746626686;721.1598487761804;10514.737131571772;10712.46437678113;11047.392393418279;11059.391733454577;11563.6530904073;534.2707204665682;566.4002229765397;585.7100735136049;649.5290914087343;705.3581241070601;4480.507144214133;5038.622103342264;5047.570956468741;5048.697078175322;5082;86026.56986715026;87407;87955;89236;92118.53940730388;78189.4362112766;78586.6070669643;79091.8136227957;81539;88192;66278.0058299126;72425.63787181031;73677.26322736811;76041;78286.60856695793;9700.320977531504;10705.785884282426;11088.889111109109;11188.328700278009;11190.94404527972;39222.41166382564;49085.527434177035;50487.73780655513;50676.74661626668;51194.74402627963;63349.68325158529;64867.35132648613;66497;67194;67944;12362;12638.431270592662;12742.060994166206;12796.232226066466;13391.263480508665;12113.939430302793;14541.85458145405;15106.39574417011;15509.379624815332;15634.140122293384;10998.34009959405;12111.636650900487;13149.802752958905;13403.396847141707;13955.790663140264;12858.357082146042;13405.329733513481;13556.59330220095;13610.523631672844;13849.376778045214;11603.593874214363;12880.592403599929;13102.410391532214;13219.074664773374;13588.388522516709;9160.725178244664;9681.757956051155;9720.659776907774;9977.800443990935;10082.848757268794;8810;9391.229587271635;10095.949520252645;10725.517351719036;11485 02:42:45 INFO - PROCESS | 1647 | __end_tp_report 02:42:45 INFO - PROCESS | 1647 | __start_cc_report 02:42:45 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,408 02:42:45 INFO - PROCESS | 1647 | __end_cc_report 02:42:45 INFO - PROCESS | 1647 | __startTimestamp1494409365778__endTimestamp 02:42:45 INFO - PROCESS | 1647 | 02:42:45 INFO - PROCESS | 1647 | ------- Summary: start ------- 02:42:45 INFO - PROCESS | 1647 | Number of tests: 6 02:42:45 INFO - PROCESS | 1647 | 02:42:45 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5859.57 Median:6126.26 stddev:674.48 (11.0%) stddev-sans-first:649.37 02:42:45 INFO - PROCESS | 1647 | Values: 3443.4 3459.0 3483.9 3486.4 3494.7 5884.1 5896.5 5912.0 5971.6 5986.2 4436.5 5890.2 5938.2 5955.9 5992.7 5362.2 5395.4 5440.4 5488.6 5491.9 5492.8 5494.3 5501.9 5504.2 5512.6 5334.1 5350.6 5399.9 5497.6 5525.6 5400.0 5419.7 5434.0 5476.8 5481.4 4539.5 5238.5 5321.1 5426.2 5493.2 4789.0 5474.5 6007.9 6125.9 6140.8 6260.1 6286.4 6301.7 6319.0 6361.2 6396.5 6402.1 6431.8 6438.7 6458.9 6074.0 6117.8 6125.6 6126.3 6149.4 5397.3 5687.9 5796.2 6266.6 6300.1 6292.2 6358.6 6366.5 6374.4 6382.7 5356.4 6397.0 6397.7 6399.7 6427.6 6244.7 6298.9 6300.1 6354.3 6369.0 6300.1 6310.4 6330.7 6344.3 6353.2 5083.2 5102.8 5116.7 5121.8 5124.5 4245.7 4409.6 4549.7 4567.8 4621.9 4823.0 5660.8 5756.4 5895.7 5919.2 6385.9 6404.3 6424.3 6426.0 6431.3 4929.6 5756.1 5926.0 5993.1 5998.2 5641.1 5669.4 5696.7 5701.7 5712.9 5565.6 6565.1 6621.3 6627.5 6629.7 5385.1 6251.6 6277.0 6407.9 6417.2 6404.0 6434.5 6437.5 6461.6 6487.7 6354.9 6384.4 6387.4 6389.6 6390.4 6273.9 6318.1 6347.4 6356.9 6369.4 5770.1 6290.1 6291.7 6353.5 6376.6 6274.8 6305.7 6306.1 6344.9 6349.0 6130.8 6134.0 6219.6 6230.5 6274.1 6100.0 6113.5 6203.6 6257.4 6277.6 5171.0 6234.4 6235.8 6241.4 6254.9 02:42:45 INFO - PROCESS | 1647 | 02:42:45 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:55160.44 Median:15617.67 stddev:114151.86 (730.9%) stddev-sans-first:114398.93 02:42:45 INFO - PROCESS | 1647 | Values: 729.9 799.1 825.9 953.4 955.3 38863.4 39663.0 39832.0 39863.6 39864.8 38426.0 38586.8 38717.2 38739.4 38773.8 7900.6 8041.2 10742.8 10999.3 11023.7 10688.8 10806.5 10806.7 10810.8 10858.1 10820.6 10830.5 10838.3 10838.6 10855.1 9317.7 10704.0 10757.4 10789.5 10812.8 9924.4 10243.2 10506.6 10571.9 10587.4 33654.2 33658.5 33766.5 33973.7 34031.8 3687.7 3755.0 3755.3 3789.1 3814.8 24851.8 24888.8 25271.4 26161.7 26303.0 2895.3 3022.7 3063.5 3174.2 3287.3 294954.0 301071.0 302009.0 343560.0 345569.0 51928.5 54050.5 54483.2 54625.0 54671.6 56806.7 56907.0 57247.0 57273.1 57302.7 15224.2 15235.9 15307.5 15318.0 15318.5 12889.5 12959.4 12965.7 13013.3 13023.7 13752.3 13781.4 13798.9 13827.4 13883.8 5256.3 6118.4 6123.6 6128.7 6173.5 1125.4 1137.3 1140.5 1155.0 1159.6 24872.8 26386.2 27122.5 27328.0 27535.3 1037.5 1083.4 1131.9 1248.6 1264.0 11587.9 13406.3 13445.7 14127.5 14266.9 491230.5 493687.0 494006.0 494074.0 496444.0 47246.5 47446.0 47495.8 47555.5 47667.8 1397.8 1422.9 1433.1 1439.9 1442.5 25305.5 25738.0 26433.3 26780.3 26906.1 42072.4 42322.4 42393.2 42444.6 42513.6 450314.0 453274.0 453577.0 454623.0 462556.0 29294.2 29834.7 29902.0 29981.3 29998.4 37267.8 37487.0 38413.0 38514.7 38572.0 27191.5 27228.3 27281.7 27496.7 28199.4 27284.2 27379.9 27400.7 27813.6 28109.4 31462.2 31811.7 31937.0 32093.5 32188.8 22938.3 27042.9 27063.7 27805.7 28340.0 19371.5 21701.3 22347.2 22634.9 22649.4 02:42:45 INFO - PROCESS | 1647 | 02:42:45 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:42442.41 Median:13798.49 stddev:124339.87 (901.1%) stddev-sans-first:124648.60 02:42:45 INFO - PROCESS | 1647 | Values: 402.4 403.2 409.8 425.1 441.9 16539.8 18107.2 20542.0 21172.7 21197.5 18533.7 20474.6 21551.5 21758.8 21852.5 6209.1 6472.7 6624.5 6642.2 6649.8 6386.6 6625.2 6630.3 6633.1 6666.3 6563.6 6599.2 6619.7 6621.3 6625.8 6462.0 6515.1 6525.2 6632.6 6667.3 6247.8 6425.5 6472.4 6491.3 6499.7 25086.6 25156.6 25273.1 25553.7 25613.9 2510.4 2808.7 2872.8 2874.9 2884.8 15750.5 15809.7 16057.0 16115.0 16578.6 2090.9 2091.5 2126.6 2148.5 2156.4 134937.3 138673.0 140825.3 141314.0 141546.6 16338.5 19353.7 20161.3 20183.0 20430.3 30301.0 30566.8 30678.5 30709.8 30727.2 10248.9 10467.0 10599.6 10658.5 10680.7 8777.3 8781.6 8781.7 8965.6 8985.5 9795.7 9915.4 9937.3 9948.7 9969.7 3312.4 3602.4 3886.0 4256.7 4264.3 982.2 989.3 989.4 993.9 1030.6 16111.4 16259.5 20074.0 20748.5 21039.9 991.3 995.1 997.1 1031.4 1034.3 9524.4 9698.8 10307.7 10431.4 10815.5 737145.6 739670.0 743105.0 750207.4 758022.0 155003.0 158292.0 158417.0 160220.0 162312.0 1152.4 1172.1 1191.9 1197.8 1206.6 15296.6 17568.2 18018.4 18398.5 18523.6 63009.4 63565.7 63641.7 64480.7 64937.4 90718.0 91010.5 91613.0 91826.2 91997.0 8545.7 11007.9 11467.1 11535.5 11801.3 10953.7 11905.3 12353.9 12557.6 12900.7 9983.4 10705.4 10708.9 10917.5 10937.4 10208.7 10317.9 10318.8 10362.3 10642.6 10656.0 10674.5 10764.8 10956.9 11049.7 8911.4 9749.9 11669.8 11686.3 11702.4 9649.9 9735.0 10880.5 10981.1 11005.9 02:42:45 INFO - PROCESS | 1647 | 02:42:45 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:6730.00 Median:2454.32 stddev:4190.99 (170.8%) stddev-sans-first:4183.63 02:42:45 INFO - PROCESS | 1647 | Values: 1403.8 1522.2 1522.9 1525.8 1562.3 10250.2 10546.6 10846.1 11142.3 11180.3 11028.4 11195.7 11211.9 11258.9 11261.9 2065.0 2068.7 2427.7 2446.9 2460.9 2362.4 2433.1 2435.4 2440.5 2447.7 2029.4 2344.8 2382.6 2427.7 2436.7 2016.0 2368.0 2374.1 2383.2 2397.4 2376.7 2381.2 2384.3 2396.8 2404.5 10837.3 10890.2 10922.2 10934.4 10934.4 5607.3 5798.9 5877.1 5893.0 5898.1 3482.4 3653.4 3706.0 3742.9 3775.5 1173.3 1204.3 1237.4 1238.6 1255.5 13657.9 13833.2 14884.7 15680.5 15802.7 4163.9 4658.8 4722.5 4896.8 4907.8 11683.0 11732.0 11763.1 11776.5 11781.8 5888.9 5901.2 5935.5 5939.6 5948.0 4592.6 5235.8 5356.5 5424.1 5429.0 3844.2 3917.3 3919.2 3960.7 4017.5 1052.1 1054.5 1062.0 1065.3 1065.4 734.8 787.9 804.3 813.8 818.5 6506.6 6541.0 6605.8 6740.5 6755.7 949.4 978.6 988.1 990.5 996.0 3555.9 4244.8 4274.6 4393.0 4414.3 12521.9 13978.0 13981.9 13984.1 14015.2 11826.2 13495.3 13588.3 13897.1 13911.1 8689.3 9224.1 9655.6 10150.0 10311.9 6361.7 6678.1 6734.5 6898.3 7023.9 10420.4 12227.9 12405.5 12421.3 12484.9 10243.7 12626.6 12858.6 12921.4 13014.5 9138.5 9347.2 9441.2 9513.0 9562.9 10421.3 10490.6 10519.6 10521.2 10598.6 8877.4 8893.8 9091.5 9230.7 9313.1 8395.5 9118.9 9142.2 9183.6 9349.5 7839.0 7865.1 7883.2 7962.1 7964.6 5149.5 5810.6 5972.4 6036.1 6092.4 5912.5 6052.2 6115.8 6173.7 6207.4 02:42:45 INFO - PROCESS | 1647 | 02:42:45 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4634.07 Median:5060.61 stddev:1220.52 (24.1%) stddev-sans-first:1180.55 02:42:45 INFO - PROCESS | 1647 | Values: 336.5 362.7 370.8 376.4 380.9 4197.9 4554.4 4975.0 5065.7 5151.6 4208.8 4437.4 4544.6 5065.0 5169.7 4163.9 4321.8 4361.5 4481.8 4550.2 3833.0 4156.6 4189.6 4517.0 4517.6 4209.3 4227.0 4542.0 4574.6 4592.8 4177.8 4453.8 4549.1 4554.4 4601.3 3677.6 3707.5 4336.9 4361.0 4381.0 4396.0 5084.0 5094.9 5108.7 5147.4 4391.3 5077.0 5204.3 5307.9 5350.9 5092.9 5243.5 5515.5 5523.8 5649.9 4075.6 4439.9 4549.2 4563.7 4860.8 4907.8 5255.8 5344.9 5439.4 5814.6 4546.5 5052.5 5056.2 5095.9 5100.6 4376.5 4738.5 4951.5 5115.3 5188.5 4338.5 5099.9 5189.4 5224.4 5362.0 4708.9 5480.8 5607.6 5619.9 5671.4 1545.0 1661.9 1757.8 1762.9 1783.9 1201.9 1276.2 1292.2 1318.4 1436.0 4626.5 4636.7 5072.5 5098.8 5126.3 4835.4 5008.5 5087.7 5540.0 5717.8 4561.4 4732.3 5098.5 5098.6 5115.9 2834.4 2896.2 2898.8 2996.8 3008.6 4950.2 5266.8 5719.2 5726.5 5987.9 5558.4 5658.6 5780.9 5855.4 5878.8 5093.6 5161.6 5541.5 5650.0 5758.5 5085.5 5295.9 5340.8 5359.7 5773.0 4913.8 4917.2 5169.5 5378.7 5554.6 5289.2 5295.7 5458.7 5497.9 5947.9 4441.3 4677.9 4916.7 4978.4 5018.9 4814.6 4886.8 5330.6 5595.5 5754.9 5120.5 5275.5 5326.8 5517.5 5587.6 4750.2 4750.4 5349.9 5505.6 5582.4 4368.7 4890.4 5300.4 5328.3 5330.3 4556.4 5441.3 5468.3 5540.5 5605.7 4552.4 4876.7 4996.5 5176.3 5422.5 02:42:45 INFO - PROCESS | 1647 | 02:42:45 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20084.95 Median:3549.32 stddev:28674.81 (807.9%) stddev-sans-first:28718.27 02:42:45 INFO - PROCESS | 1647 | Values: 689.5 707.3 717.7 812.4 863.0 15075.6 15549.1 16078.5 16416.6 16611.5 14850.5 15182.5 15278.3 15392.7 15482.2 923.8 932.9 955.1 959.0 959.5 957.5 968.4 969.4 971.1 980.0 1004.4 1021.6 1022.5 1023.4 1024.0 1016.9 1018.1 1018.3 1018.6 1019.5 750.3 752.0 752.4 754.3 756.2 16550.2 16862.2 17029.3 17030.9 17256.9 13059.4 14000.4 14010.0 14134.9 14303.2 10181.6 11626.4 11784.6 11787.0 11856.5 3608.6 3681.7 3752.5 3782.3 3819.6 114798.4 117232.0 120694.0 120700.0 123189.0 10574.7 10615.2 10842.3 10934.6 11024.3 22551.7 22557.4 22604.3 22627.4 22714.2 7444.5 7531.0 7736.7 7832.2 7897.0 4280.8 4316.3 4368.4 4389.8 4431.0 3461.7 3508.5 3543.8 3554.9 3706.2 693.8 694.3 698.5 703.3 708.3 655.8 670.6 689.7 698.7 721.2 10514.7 10712.5 11047.4 11059.4 11563.7 534.3 566.4 585.7 649.5 705.4 4480.5 5038.6 5047.6 5048.7 5082.0 86026.6 87407.0 87955.0 89236.0 92118.5 78189.4 78586.6 79091.8 81539.0 88192.0 66278.0 72425.6 73677.3 76041.0 78286.6 9700.3 10705.8 11088.9 11188.3 11190.9 39222.4 49085.5 50487.7 50676.7 51194.7 63349.7 64867.4 66497.0 67194.0 67944.0 12362.0 12638.4 12742.1 12796.2 13391.3 12113.9 14541.9 15106.4 15509.4 15634.1 10998.3 12111.6 13149.8 13403.4 13955.8 12858.4 13405.3 13556.6 13610.5 13849.4 11603.6 12880.6 13102.4 13219.1 13588.4 9160.7 9681.8 9720.7 9977.8 10082.8 8810.0 9391.2 10095.9 10725.5 11485.0 02:42:45 INFO - PROCESS | 1647 | -------- Summary: end -------- 02:42:45 INFO - PROCESS | 1647 | 02:42:46 INFO - TEST-INFO | 1647: exit 0 02:42:46 INFO - TEST-OK | dromaeo_css | took 1117447ms 02:42:46 INFO - TEST-START | kraken 02:42:46 INFO - operating with platform_type : mac_ 02:42:46 INFO - Initialising browser for kraken test... 02:42:46 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpROwEJ3/profile http://localhost:49244/getInfo.html) 02:42:49 INFO - PROCESS | 1656 | 2017-05-10 02:42:49.153 plugin-container[1658:11304] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 02:42:49 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:42:49 INFO - PROCESS | 1656 | 2017-05-10 02:42:49.154 plugin-container[1658:11304] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 02:42:49 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:42:49 INFO - PROCESS | 1656 | 2017-05-10 02:42:49.154 plugin-container[1658:11304] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:42:49 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 02:42:49 INFO - PROCESS | 1656 | colorDepth:24 02:42:49 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 02:42:49 INFO - PROCESS | 1656 | __metrics 02:42:49 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 02:42:50 INFO - TEST-INFO | 1656: exit 0 02:42:50 INFO - Browser initialized. 02:42:50 INFO - Running cycle 1/1 for kraken test... 02:42:50 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpROwEJ3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:42:52 INFO - PROCESS | 1659 | 2017-05-10 02:42:52.693 plugin-container[1660:11433] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 02:42:52 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:42:52 INFO - PROCESS | 1659 | 2017-05-10 02:42:52.695 plugin-container[1660:11433] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 02:42:52 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:42:52 INFO - PROCESS | 1659 | 2017-05-10 02:42:52.695 plugin-container[1660:11433] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:43:02 INFO - PROCESS | 1659 | RSS: Main: 220917760 02:43:02 INFO - PROCESS | 1659 | 02:43:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 02:43:06 INFO - PROCESS | 1659 | RSS: Main: 235515904 02:43:06 INFO - PROCESS | 1659 | 02:43:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 02:43:09 INFO - PROCESS | 1659 | RSS: Main: 243453952 02:43:09 INFO - PROCESS | 1659 | 02:43:13 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 02:43:13 INFO - PROCESS | 1659 | RSS: Main: 245125120 02:43:13 INFO - PROCESS | 1659 | 02:43:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 02:43:16 INFO - PROCESS | 1659 | RSS: Main: 246849536 02:43:16 INFO - PROCESS | 1659 | 02:43:18 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:43:19 INFO - PROCESS | 1659 | RSS: Main: 248545280 02:43:19 INFO - PROCESS | 1659 | 02:43:22 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 02:43:22 INFO - PROCESS | 1659 | RSS: Main: 252698624 02:43:22 INFO - PROCESS | 1659 | 02:43:25 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 02:43:26 INFO - PROCESS | 1659 | RSS: Main: 251899904 02:43:26 INFO - PROCESS | 1659 | 02:43:29 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 02:43:30 INFO - PROCESS | 1659 | RSS: Main: 252940288 02:43:30 INFO - PROCESS | 1659 | 02:43:31 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:43:32 INFO - PROCESS | 1659 | RSS: Main: 251641856 02:43:32 INFO - PROCESS | 1659 | 02:43:34 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:43:35 INFO - PROCESS | 1659 | RSS: Main: 251666432 02:43:35 INFO - PROCESS | 1659 | 02:43:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:43:37 INFO - PROCESS | 1659 | RSS: Main: 252182528 02:43:37 INFO - PROCESS | 1659 | 02:43:40 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:43:41 INFO - PROCESS | 1659 | RSS: Main: 252178432 02:43:41 INFO - PROCESS | 1659 | 02:43:44 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:43:45 INFO - PROCESS | 1659 | RSS: Main: 251752448 02:43:45 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 02:43:47 INFO - PROCESS | 1659 | RSS: Main: 253841408 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | __start_tp_report 02:43:47 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 02:43:47 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 02:43:47 INFO - PROCESS | 1659 | |i|pagename|runs| 02:43:47 INFO - PROCESS | 1659 | |0;ai-astar;108;121;121;121;122;121;121;122;120;119 02:43:47 INFO - PROCESS | 1659 | |1;audio-beat-detection;142;134;135;178;139;108;114;114;128;155 02:43:47 INFO - PROCESS | 1659 | |2;audio-dft;201;196;197;228;205;244;249;279;185;274 02:43:47 INFO - PROCESS | 1659 | |3;audio-fft;84;61;84;85;63;65;60;68;74;64 02:43:47 INFO - PROCESS | 1659 | |4;audio-oscillator;90;57;86;67;88;89;67;89;60;81 02:43:47 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;96;89;90;89;90;95;90;92;93;90 02:43:47 INFO - PROCESS | 1659 | |6;imaging-darkroom;122;98;97;98;120;97;105;134;151;125 02:43:47 INFO - PROCESS | 1659 | |7;imaging-desaturate;93;93;92;87;139;91;133;91;93;92 02:43:47 INFO - PROCESS | 1659 | |8;json-parse-financial;67;65;65;65;71;66;68;70;68;69 02:43:47 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;62;60;71;69;56;58;58;58;59;61 02:43:47 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;83;88;88;86;100;105;94;83;87;88 02:43:47 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;137;140;142;150;155;147;151;145;191;154 02:43:47 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;171;171;165;169;170;170;206;225;220;172 02:43:47 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;67;69;77;88;86;81;84;85;57;80 02:43:47 INFO - PROCESS | 1659 | __end_tp_report 02:43:47 INFO - PROCESS | 1659 | __start_cc_report 02:43:47 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1018 02:43:47 INFO - PROCESS | 1659 | __end_cc_report 02:43:47 INFO - PROCESS | 1659 | __startTimestamp1494409427373__endTimestamp 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | ------- Summary: start ------- 02:43:47 INFO - PROCESS | 1659 | Number of tests: 14 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:119.60 Median:121.00 stddev:4.17 (3.4%) stddev-sans-first:0.93 02:43:47 INFO - PROCESS | 1659 | Values: 108.0 121.0 121.0 121.0 122.0 121.0 121.0 122.0 120.0 119.0 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:134.70 Median:137.00 stddev:21.01 (15.3%) stddev-sans-first:22.12 02:43:47 INFO - PROCESS | 1659 | Values: 142.0 134.0 135.0 178.0 139.0 108.0 114.0 114.0 128.0 155.0 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:225.80 Median:236.00 stddev:34.09 (14.4%) stddev-sans-first:34.95 02:43:47 INFO - PROCESS | 1659 | Values: 201.0 196.0 197.0 228.0 205.0 244.0 249.0 279.0 185.0 274.0 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:70.80 Median:71.00 stddev:10.12 (14.3%) stddev-sans-first:9.54 02:43:47 INFO - PROCESS | 1659 | Values: 84.0 61.0 84.0 85.0 63.0 65.0 60.0 68.0 74.0 64.0 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:87.00 stddev:13.18 (15.1%) stddev-sans-first:13.16 02:43:47 INFO - PROCESS | 1659 | Values: 90.0 57.0 86.0 67.0 88.0 89.0 67.0 89.0 60.0 81.0 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:91.40 Median:91.00 stddev:2.50 (2.8%) stddev-sans-first:2.03 02:43:47 INFO - PROCESS | 1659 | Values: 96.0 89.0 90.0 89.0 90.0 95.0 90.0 92.0 93.0 90.0 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:114.70 Median:124.00 stddev:18.73 (15.1%) stddev-sans-first:19.68 02:43:47 INFO - PROCESS | 1659 | Values: 122.0 98.0 97.0 98.0 120.0 97.0 105.0 134.0 151.0 125.0 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:100.40 Median:92.00 stddev:18.90 (20.5%) stddev-sans-first:19.85 02:43:47 INFO - PROCESS | 1659 | Values: 93.0 93.0 92.0 87.0 139.0 91.0 133.0 91.0 93.0 92.0 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:67.40 Median:68.00 stddev:2.17 (3.2%) stddev-sans-first:2.30 02:43:47 INFO - PROCESS | 1659 | Values: 67.0 65.0 65.0 65.0 71.0 66.0 68.0 70.0 68.0 69.0 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:60.50 stddev:4.96 (8.2%) stddev-sans-first:5.25 02:43:47 INFO - PROCESS | 1659 | Values: 62.0 60.0 71.0 69.0 56.0 58.0 58.0 58.0 59.0 61.0 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:87.50 stddev:7.27 (8.3%) stddev-sans-first:7.23 02:43:47 INFO - PROCESS | 1659 | Values: 83.0 88.0 88.0 86.0 100.0 105.0 94.0 83.0 87.0 88.0 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:151.20 Median:150.50 stddev:15.19 (10.1%) stddev-sans-first:15.21 02:43:47 INFO - PROCESS | 1659 | Values: 137.0 140.0 142.0 150.0 155.0 147.0 151.0 145.0 191.0 154.0 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.90 Median:171.50 stddev:23.38 (13.6%) stddev-sans-first:24.33 02:43:47 INFO - PROCESS | 1659 | Values: 171.0 171.0 165.0 169.0 170.0 170.0 206.0 225.0 220.0 172.0 02:43:47 INFO - PROCESS | 1659 | 02:43:47 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.40 Median:82.50 stddev:10.01 (12.1%) stddev-sans-first:9.89 02:43:47 INFO - PROCESS | 1659 | Values: 67.0 69.0 77.0 88.0 86.0 81.0 84.0 85.0 57.0 80.0 02:43:47 INFO - PROCESS | 1659 | -------- Summary: end -------- 02:43:47 INFO - PROCESS | 1659 | 02:43:48 INFO - TEST-INFO | 1659: exit 0 02:43:48 INFO - TEST-OK | kraken | took 61880ms 02:43:48 INFO - SUITE-END | took 1179s 02:43:48 INFO - Completed test suite (00:19:39) 02:43:48 INFO - javascript benchmark 02:43:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7651.82686015904, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3443.4146195146836, 3459.031471188067, 3483.8954831355054, 3486.3550243205027, 3494.72042236621, 5884.058550631899, 5896.469317761401, 5912.0, 5971.581989260753, 5986.161937328778, 4436.467623885138, 5890.17537544743, 5938.228030356058, 5955.910661340084, 5992.670403127826, 5362.2224777407255, 5395.406505284415, 5440.374356948962, 5488.643238189525, 5491.890162196753, 5492.780288788444, 5494.2582751328455, 5501.862453438656, 5504.2294078829, 5512.614117011811, 5334.093204155283, 5350.598705097113, 5399.947010332987, 5497.615166938316, 5525.613207075487, 5400.000999815047, 5419.674819510842, 5433.972830135864, 5476.7535460904355, 5481.424450432727, 4539.500654927955, 5238.502342277516, 5321.068812957717, 5426.213199086111, 5493.23094766733, 4788.9521104788855, 5474.507294343488, 6007.939920600782, 6125.877482450326, 6140.785072522485, 6260.060990851363, 6286.434220920093, 6301.7164227609865, 6319.0, 6361.173047503841, 6396.520260980398, 6402.13571167891, 6431.8392040198905, 6438.678066096676, 6458.870822583522, 6073.99779036454, 6117.755289788448, 6125.601835880653, 6126.264848218242, 6149.446549810494, 5397.27136836522, 5687.8862422752145, 5796.1595568642315, 6266.592671476339, 6300.117983482316, 6292.244930608356, 6358.586691865014, 6366.522510811707, 6374.362563743682, 6382.744690212339, 5356.3572371315695, 6397.0, 6397.712102955285, 6399.744010239631, 6427.614343139427, 6244.718987645567, 6298.937185515317, 6300.086487459384, 6354.269259035178, 6369.0, 6300.1494798202575, 6310.400511951358, 6330.651814150266, 6344.270408902943, 6353.174087368705, 5083.186690129562, 5102.795888164506, 5116.74416279192, 5121.820736274287, 5124.5131712487255, 4245.745255284693, 4409.581089796528, 4549.704269222556, 4567.771611419482, 4621.907561848744, 4822.9758851205515, 5660.773569057274, 5756.395578464285, 5895.67573783446, 5919.230500035054, 6385.936140638534, 6404.295527491972, 6424.261209960914, 6426.0, 6431.260405053479, 4929.580985616263, 5756.136579513107, 5925.970370148121, 5993.101034844808, 5998.1602575639445, 5641.069223578145, 5669.376368599534, 5696.658200507983, 5701.71491425427, 5712.942870571324, 5565.6104072714525, 6565.088963370356, 6621.304762999912, 6627.469802415699, 6629.7348106075215, 5385.057614917452, 6251.562390632794, 6276.968615156895, 6407.871842563216, 6417.2299324081405, 6404.0, 6434.453071488976, 6437.517186210959, 6461.61230326182, 6487.740490380332, 6354.872902541831, 6384.3934826191435, 6387.361263873576, 6389.616623002635, 6390.4248617624635, 6273.874522509615, 6318.052292156213, 6347.365263473615, 6356.936430635635, 6369.426751592379, 5770.1344798278915, 6290.119383286435, 6291.653959032297, 6353.491720662429, 6376.649284289409, 6274.843128921813, 6305.747770089145, 6306.054091886071, 6344.904826427515, 6348.968255158695, 6130.785422510191, 6134.0, 6219.564630476004, 6230.501559875109, 6274.090256912631, 6100.0, 6113.537117903944, 6203.565750397429, 6257.405546473078, 6277.623342599276, 5171.0, 6234.407731265523, 6235.844103897439, 6241.407066328691, 6254.906176407449], "value": 5819.640103155918, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.9489035767496, 799.1289494451049, 825.8809313380367, 953.3517208298358, 955.2739917662583, 38863.41704874425, 39663.0, 39832.00419989502, 39863.601363986425, 39864.80405587837, 38426.03934901629, 38586.8070659647, 38717.22565548687, 38739.418908716325, 38773.80613096931, 7900.565468899223, 8041.236082572146, 10742.785144297148, 10999.285046471994, 11023.724406889853, 10688.786224275509, 10806.459677016157, 10806.675799725981, 10810.837837432446, 10858.077063449577, 10820.621278255263, 10830.458477076154, 10838.295510791773, 10838.566457341696, 10855.131589472863, 9317.72046838596, 10703.983121603434, 10757.408342541157, 10789.514471848786, 10812.837807432894, 9924.354916930377, 10243.180545556337, 10506.632267870587, 10571.947140264327, 10587.417692026935, 33654.1586460338, 33658.49512257318, 33766.49350259748, 33973.660263397294, 34031.829840850885, 3687.6865466435383, 3754.9812250938844, 3755.342815007363, 3789.147441825595, 3814.7901865397394, 24851.751482485124, 24888.751112489004, 25271.36821579475, 26161.73838261631, 26303.0, 2895.319599894023, 3022.6826183250882, 3063.494523403636, 3174.190581401751, 3287.3096649703352, 294954.0, 301071.0, 302009.0, 343560.0, 345569.0, 51928.48071519312, 54050.459495404546, 54483.18275225875, 54625.0, 54671.63320917009, 56806.715966419906, 56907.0, 57247.0, 57273.14090288649, 57302.7134864323, 15224.162671052976, 15235.933484655967, 15307.46423875159, 15318.0, 15318.540443786704, 12889.548865789653, 12959.416826242841, 12965.675858103623, 13013.349332533335, 13023.739525209443, 13752.31238438094, 13781.379837907318, 13798.93100534511, 13827.377768000464, 13883.791743123862, 5256.26412302278, 6118.357572454917, 6123.601965872204, 6128.693565321716, 6173.536984726164, 1125.4429057616533, 1137.306243191669, 1140.5266814271984, 1154.9942250288696, 1159.646307876089, 24872.751272487407, 26386.20841374762, 27122.457550848874, 27328.043518477156, 27535.31161720973, 1037.4968140451874, 1083.3824719909576, 1131.8794393550327, 1248.6066888929959, 1263.9620811375673, 11587.88412115885, 13406.329683515785, 13445.663858403617, 14127.505537306144, 14266.928665356607, 491230.5438472856, 493687.0, 494006.0, 494074.0, 496444.0, 47246.52753472421, 47446.0, 47495.76252118717, 47555.5244447558, 47667.808304792656, 1397.7623803953352, 1422.8648278413534, 1433.0756661952983, 1439.863212994743, 1442.5167568864338, 25305.493890122463, 25737.970481180542, 26433.33916652099, 26780.330491737863, 26906.058287959826, 42072.3689144657, 42322.36516452317, 42393.15213695647, 42444.57555424406, 42513.5748642522, 450314.0, 453274.0, 453577.0, 454623.0, 462556.0, 29294.24234545934, 29834.701652983193, 29902.0, 29981.250468738457, 29998.400031999674, 37267.81366093152, 37487.0, 38413.0, 38514.65198718032, 38572.0, 27191.456170876867, 27228.31929201786, 27281.72718272871, 27496.725032750215, 28199.43601128007, 27284.181474555797, 27379.863100684368, 27400.72599273982, 27813.582796258477, 28109.437811243253, 31462.213444664067, 31811.68188318087, 31937.0, 32093.518597220595, 32188.839055804572, 22938.27148189304, 27042.864785675945, 27063.729362706123, 27805.72194278112, 28340.01619886588, 19371.51571210731, 21701.34895953124, 22347.217847375916, 22634.886825566424, 22649.433764156027], "value": 18377.767432563385, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.40645048553404, 403.1714826032502, 409.8422107488617, 425.1497005988023, 441.85559401151033, 16539.751903721437, 18107.185176667048, 20542.0, 21172.682409763845, 21197.470063248427, 18533.702640815147, 20474.590508189827, 21551.461213469673, 21758.803265820374, 21852.45368865775, 6209.099680546306, 6472.708728107223, 6624.470042396621, 6642.169728783902, 6649.767258145942, 6386.64873431961, 6625.17185351831, 6630.336966303355, 6633.071370008202, 6666.3000384959605, 6563.573367731082, 6599.241087274977, 6619.735210591594, 6621.337866213365, 6625.768098116567, 6461.967690161543, 6515.0553169790355, 6525.249596296416, 6632.568883022588, 6667.333266673342, 6247.750089996394, 6425.518086143511, 6472.449841763456, 6491.318411566812, 6499.707513161919, 25086.623700644504, 25156.622650660254, 25273.115440959664, 25553.74446255532, 25613.871930640413, 2510.3598582361565, 2808.719128087175, 2872.8276303421862, 2874.870630821597, 2884.812487188347, 15750.527484175493, 15809.683806323925, 16057.0, 16115.0, 16578.58553536171, 2090.905909234088, 2091.5084955035554, 2126.5959467701086, 2148.5165837686395, 2156.439325775294, 134937.3253133728, 138672.99999999898, 140825.29587351996, 141314.0, 141546.58453415436, 16338.509844704678, 19353.709694354595, 20161.29435469781, 20182.99085045742, 20430.284940027326, 30301.0, 30566.84716576403, 30678.539821902243, 30709.84645076805, 30727.23181920425, 10248.948755256326, 10467.0, 10599.576016959236, 10658.520366583522, 10680.732981675521, 8777.341699372573, 8781.648734050694, 8781.69264075767, 8965.551722413853, 8985.46087234768, 9795.706128816148, 9915.405075695482, 9937.304388692866, 9948.701538953843, 9969.651062212934, 3312.4368857293834, 3602.441621548664, 3886.0479182600443, 4256.702030857872, 4264.317709166518, 982.2436723722693, 989.2531138990161, 989.42118860466, 993.9006099390148, 1030.5517100061497, 16111.436099736688, 16259.51221463358, 20073.996300184932, 20748.481287967923, 21039.894800526206, 991.3308516751164, 995.1491474789096, 997.1075887081022, 1031.4481752262504, 1034.2863424237253, 9524.428534287965, 9698.836139663286, 10307.690769276784, 10431.37411755297, 10815.459227038617, 737145.6285437077, 739670.0, 743105.0, 750207.3735021632, 758022.0, 155003.0, 158292.0, 158417.0, 160220.0, 162312.0, 1152.4410660829492, 1172.056494521901, 1191.880811918801, 1197.7784109939514, 1206.5776978057786, 15296.617584560476, 17568.2094305754, 18018.36935707244, 18398.47426314958, 18523.629527409106, 63009.36990630035, 63565.68217158884, 63641.681791590745, 64480.67759661172, 64937.35062649313, 90718.0, 91010.54494727484, 91613.0, 91826.16347673142, 91997.0, 8545.700900468453, 11007.889921101007, 11467.139964502863, 11535.480903359537, 11801.29192248468, 10953.740319863318, 11905.345206013752, 12353.94991425739, 12557.623271301876, 12900.741985160432, 9983.400995939975, 10705.411202383631, 10708.892911071101, 10917.508712108132, 10937.398443085389, 10208.744781380228, 10317.896821031694, 10318.845217321596, 10362.274640775073, 10642.62750803749, 10655.999361557775, 10674.46627668629, 10764.838527422251, 10956.890431095906, 11049.668509944715, 8911.420757650732, 9749.9025009749, 11669.824952625548, 11686.298822070703, 11702.35637039937, 9649.9035009649, 9735.026497350209, 10880.45597720095, 10981.066609337977, 11005.889941100486], "value": 11256.738157635791, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.7613605687031, 1522.16281045425, 1522.92385380731, 1525.832158462569, 1562.320390630076, 10250.231232657543, 10546.630867919615, 10846.132309415247, 11142.331460112398, 11180.273282236649, 11028.44857757111, 11195.720106997309, 11211.943940280287, 11258.943705281463, 11261.943690281538, 2065.029436165, 2068.7103805467173, 2427.7086749590076, 2446.8654224017673, 2460.9261722148362, 2362.4093976505874, 2433.1483980606736, 2435.4398488347692, 2440.548498527769, 2447.7185123710733, 2029.3708950225403, 2344.800691941187, 2382.5711371953043, 2427.745086765891, 2436.7441418650965, 2015.9596808063757, 2367.9526409471714, 2374.1215750172373, 2383.2135395319588, 2397.4485868250313, 2376.738558758535, 2381.2380038387723, 2384.332386931662, 2396.8015992004, 2404.507076049406, 10837.349759014405, 10890.183236257233, 10922.18083643722, 10934.398608076554, 10934.398608076554, 5607.327120745495, 5798.9130163047585, 5877.089051197071, 5892.96873047215, 5898.056310990264, 3482.4253998090285, 3653.3789255826573, 3705.962940370616, 3742.850285988557, 3775.4903088083033, 1173.342927960345, 1204.3376143121247, 1237.412229191136, 1238.6222202228316, 1255.54172726954, 13657.931710341385, 13833.239171845646, 14884.70230595382, 15680.529584112495, 15802.683946321009, 4163.8959026024595, 4658.767061646972, 4722.52774722525, 4896.828610998598, 4907.754612269444, 11683.0, 11732.0, 11763.117766167572, 11776.470058847372, 11781.76436471283, 5888.852778680567, 5901.17383566301, 5935.465808077293, 5939.554533410012, 5948.018576934844, 4592.563706447882, 5235.8429247121885, 5356.517913387814, 5424.132138857843, 5429.049916264717, 3844.1927195288745, 3917.2557214129224, 3919.235749028912, 3960.683145348365, 4017.4777278954134, 1052.0899421999916, 1054.4938429553856, 1061.9628313009007, 1065.2543219746208, 1065.387402243713, 734.8273155808381, 787.9409044321699, 804.2922228438937, 813.808754942588, 818.4720855048579, 6506.60960342381, 6541.0, 6605.768798092044, 6740.460763138937, 6755.729770809211, 949.4255975134944, 978.5938835383379, 988.1304452082122, 990.4898977026837, 995.9800803983879, 3555.928881422357, 4244.808983595745, 4274.572542745763, 4392.9896123891685, 4414.27164517851, 12521.937390313173, 13977.951653626018, 13981.930090349482, 13984.091034082752, 14015.159090454605, 11826.172167948162, 13495.325233738275, 13588.320583970763, 13897.0966887152, 13911.095778774346, 8689.304855611663, 9224.12370824784, 9655.613775448903, 10150.03574660406, 10311.948440257751, 6361.650109244036, 6678.065070889982, 6734.528582999143, 6898.310168983262, 7023.894641580278, 10420.374777513374, 12227.938860305641, 12405.503779849067, 12421.31682757457, 12484.87515124837, 10243.7439064024, 12626.55807046749, 12858.614241572768, 12921.418536166084, 13014.479420823061, 9138.497382644018, 9347.205487533636, 9441.150296473075, 9512.95243523778, 9562.90437095648, 10421.270511064153, 10490.580376784843, 10519.631812886511, 10521.158307335549, 10598.576056957636, 8877.422967507091, 8893.822123557622, 9091.545422728705, 9230.723078307661, 9313.115254050854, 8395.454295470774, 9118.863217051883, 9142.222911052635, 9183.632654693738, 9349.53252337394, 7839.0, 7865.134835168013, 7883.211678832071, 7962.084360298408, 7964.6415911282975, 5149.48505149482, 5810.564207684356, 5972.372900845348, 6036.094585812163, 6092.421219984094, 5912.46787789101, 6052.152698622109, 6115.816525504242, 6173.660448675366, 6207.410296021871], "value": 5114.670473573101, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.46334097115096, 362.6899001353843, 370.80347415869596, 376.3564305038382, 380.9409541521064, 4197.895052623682, 4554.362389265497, 4974.990130970765, 5065.696058236508, 5151.562117220036, 4208.800491859821, 4437.445319335083, 4544.568266014723, 5064.974675126619, 5169.689818610886, 4163.917381480806, 4321.82712691492, 4361.454818147731, 4481.753503557311, 4550.249208880531, 3832.9808350958347, 4156.625903668669, 4189.6438802701805, 4517.028838799657, 4517.644706588027, 4209.347551129565, 4226.957730422687, 4542.0, 4574.611158051553, 4592.770361481913, 4177.7857735400285, 4453.755043472608, 4549.067441174568, 4554.3851580036935, 4601.28680054592, 3677.5770786359376, 3707.4994875691705, 4336.891577710551, 4360.956390436118, 4380.956190438119, 4395.978020109911, 5083.9918232298405, 5094.923576146361, 5108.693478391308, 5147.382314122292, 4391.3193455014525, 5077.033922561403, 5204.34945631796, 5307.867303317409, 5350.886399872029, 5092.905025419553, 5243.528082472558, 5515.531179849718, 5523.806666766684, 5649.915251271234, 4075.572064933199, 4439.934415740199, 4549.155963211081, 4563.726176429424, 4860.756962151913, 4907.803687852482, 5255.76349064293, 5344.919826202571, 5439.422567455433, 5814.563907706896, 4546.545345465427, 5052.494750524992, 5056.244346830986, 5095.859738909667, 5100.591952643779, 4376.540463251376, 4738.526147385234, 4951.533926251638, 5115.309433226466, 5188.507091826271, 4338.457692788402, 5099.878026834091, 5189.42916279209, 5224.40655600038, 5361.973190134025, 4708.929366059512, 5480.80817171405, 5607.635503692247, 5619.892540297388, 5671.404502527175, 1545.0343535290444, 1661.9252133653772, 1757.7539144519778, 1762.8765986381086, 1783.9286428542714, 1201.8978386837216, 1276.16411250631, 1292.2182079841562, 1318.3935389720837, 1435.956921292363, 4626.537346265414, 4636.652251081183, 5072.467390923974, 5098.821541246113, 5126.307948426989, 4835.371401717754, 5008.549230569193, 5087.669301495465, 5540.0, 5717.799877004285, 4561.407017087758, 4732.313814496879, 5098.5411312982005, 5098.643094983389, 5115.902479983263, 2834.4189441164513, 2896.232498387929, 2898.811577247472, 2996.7902246842946, 3008.608880845476, 4950.168437678082, 5266.8419947401635, 5719.170720245541, 5726.541876649858, 5987.916223145084, 5558.44415558449, 5658.632188907708, 5780.889975159037, 5855.414458554195, 5878.764849406061, 5093.617978651616, 5161.638685292069, 5541.501264886179, 5650.0, 5758.544873076205, 5085.46602606721, 5295.867603309948, 5340.75966581497, 5359.651622644516, 5772.9711351442975, 4913.778879950482, 4917.188663870421, 5169.4830516949505, 5378.677279363251, 5554.555635549227, 5289.233061206027, 5295.655782374133, 5458.6724796512335, 5497.862553436196, 5947.940520594739, 4441.335198880646, 4677.947461821199, 4916.729579872998, 4978.4274808397495, 5018.946021335414, 4814.638902082288, 4886.828960986349, 5330.560748597934, 5595.524380427709, 5754.856128596651, 5120.539151476236, 5275.472452754847, 5326.840194794162, 5517.503424691797, 5587.636803607753, 4750.216214324736, 4750.358701575381, 5349.9465005351, 5505.642133261325, 5582.441755824385, 4368.716033457942, 4890.4249155961525, 5300.3639563252655, 5328.307320048524, 5330.307060082165, 4556.362109304634, 5441.29263195782, 5468.26178465902, 5540.529055030368, 5605.7106865420365, 4552.385427967182, 4876.658633895593, 4996.475370086088, 5176.275321454924, 5422.539084177889], "value": 4283.108636214243, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.4725534965751, 707.3492387003956, 717.6842189436651, 812.3663542436901, 862.9989212513485, 15075.62310942227, 15549.144797036173, 16078.51764447067, 16416.589585260375, 16611.50165495034, 14850.48023319184, 15182.54452366428, 15278.312475938581, 15392.692146157098, 15482.225888705576, 923.7967647117623, 932.8554074118523, 955.0735786287325, 958.9520523973773, 959.4531117263163, 957.4733896357011, 968.4479846487505, 969.413504829575, 971.0580736685404, 979.9906096720389, 1004.3772860826298, 1021.6015753856002, 1022.4989755020061, 1023.4064242739194, 1023.9590416383373, 1016.8627235323211, 1018.0582960761266, 1018.3177271228295, 1018.6485662446481, 1019.4647809899787, 750.3471979377977, 751.9812004699871, 752.3529764402609, 754.2570567990525, 756.1984296645574, 16550.172491375382, 16862.24119914607, 17029.318827246894, 17030.914845425817, 17256.913715431467, 13059.412326445237, 14000.439982400692, 14010.0, 14134.92932535341, 14303.210132228776, 10181.643642472478, 11626.360550169737, 11784.587539436165, 11787.0, 11856.525738970431, 3608.6030536640947, 3681.668649821529, 3752.5496940367057, 3782.3191825471404, 3819.598942111066, 114798.42600786942, 117232.0, 120694.0, 120700.0, 123189.0, 10574.682759517227, 10615.150787936947, 10842.349459032483, 10934.617288395028, 11024.338539687644, 22551.661725074136, 22557.4360640982, 22604.321870343916, 22627.43431414195, 22714.20500282515, 7444.516106453063, 7530.962345188239, 7736.6518506667335, 7832.216778322122, 7897.0, 4280.764557949343, 4316.309390497506, 4368.388425620416, 4389.846355377546, 4430.977845110819, 3461.6711412416284, 3508.5088087667746, 3543.7696549724187, 3554.87557935471, 3706.1661126246377, 693.826543364159, 694.3299715774302, 698.4866123399297, 703.2826516952782, 708.3093983366176, 655.7868692674899, 670.5641333133502, 689.7068745783022, 698.6506746626686, 721.1598487761804, 10514.737131571772, 10712.46437678113, 11047.392393418279, 11059.391733454577, 11563.6530904073, 534.2707204665682, 566.4002229765397, 585.7100735136049, 649.5290914087343, 705.3581241070601, 4480.507144214133, 5038.622103342264, 5047.570956468741, 5048.697078175322, 5082.0, 86026.56986715026, 87407.0, 87955.0, 89236.0, 92118.53940730388, 78189.4362112766, 78586.6070669643, 79091.8136227957, 81539.0, 88192.0, 66278.0058299126, 72425.63787181031, 73677.26322736811, 76041.0, 78286.60856695793, 9700.320977531504, 10705.785884282426, 11088.889111109109, 11188.328700278009, 11190.94404527972, 39222.41166382564, 49085.527434177035, 50487.73780655513, 50676.74661626668, 51194.74402627963, 63349.68325158529, 64867.35132648613, 66497.0, 67194.0, 67944.0, 12362.0, 12638.431270592662, 12742.060994166206, 12796.232226066466, 13391.263480508665, 12113.939430302793, 14541.85458145405, 15106.39574417011, 15509.379624815332, 15634.140122293384, 10998.34009959405, 12111.636650900487, 13149.802752958905, 13403.396847141707, 13955.790663140264, 12858.357082146042, 13405.329733513481, 13556.59330220095, 13610.523631672844, 13849.376778045214, 11603.593874214363, 12880.592403599929, 13102.410391532214, 13219.074664773374, 13588.388522516709, 9160.725178244664, 9681.757956051155, 9720.659776907774, 9977.800443990935, 10082.848757268794, 8810.0, 9391.229587271635, 10095.949520252645, 10725.517351719036, 11485.0], "value": 7609.794886011553, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 121.0, 121.0, 122.0, 121.0, 121.0, 122.0, 120.0, 119.0], "value": 119.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 134.0, 135.0, 178.0, 139.0, 108.0, 114.0, 114.0, 128.0, 155.0], "value": 134.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 196.0, 197.0, 228.0, 205.0, 244.0, 249.0, 279.0, 185.0, 274.0], "value": 225.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 61.0, 84.0, 85.0, 63.0, 65.0, 60.0, 68.0, 74.0, 64.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 57.0, 86.0, 67.0, 88.0, 89.0, 67.0, 89.0, 60.0, 81.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 89.0, 90.0, 89.0, 90.0, 95.0, 90.0, 92.0, 93.0, 90.0], "value": 91.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 98.0, 97.0, 98.0, 120.0, 97.0, 105.0, 134.0, 151.0, 125.0], "value": 114.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 87.0, 139.0, 91.0, 133.0, 91.0, 93.0, 92.0], "value": 100.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 65.0, 65.0, 71.0, 66.0, 68.0, 70.0, 68.0, 69.0], "value": 67.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 71.0, 69.0, 56.0, 58.0, 58.0, 58.0, 59.0, 61.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 88.0, 86.0, 100.0, 105.0, 94.0, 83.0, 87.0, 88.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 142.0, 150.0, 155.0, 147.0, 151.0, 145.0, 191.0, 154.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 165.0, 169.0, 170.0, 170.0, 206.0, 225.0, 220.0, 172.0], "value": 183.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 77.0, 88.0, 86.0, 81.0, 84.0, 85.0, 57.0, 80.0], "value": 77.4, "unit": "score"}]}]} 02:43:48 INFO - Return code: 0 02:43:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:43:48 ERROR - # TBPL SUCCESS # 02:43:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:43:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:43:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:43:48 INFO - 2017-05-10 02:43:48.730 screenresolution[1664:11825] starting screenresolution argv=screenresolution get 02:43:48 INFO - 2017-05-10 02:43:48.732 screenresolution[1664:11825] Display 0: 1600x1200x32@60 02:43:48 INFO - 2017-05-10 02:43:48.738 screenresolution[1665:11828] starting screenresolution argv=screenresolution list 02:43:48 INFO - Available Modes on Display 0 02:43:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:43:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:43:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:43:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:43:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:43:48 INFO - Intel Iris: 02:43:48 INFO - Chipset Model: Intel Iris 02:43:48 INFO - Type: GPU 02:43:48 INFO - Bus: Built-In 02:43:48 INFO - VRAM (Dynamic, Max): 1536 MB 02:43:48 INFO - Vendor: Intel (0x8086) 02:43:48 INFO - Device ID: 0x0a2e 02:43:48 INFO - Revision ID: 0x0009 02:43:48 INFO - Displays: 02:43:48 INFO - Display: 02:43:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:43:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:43:48 INFO - Display Serial Number: 1600x1200 60 02:43:48 INFO - Main Display: Yes 02:43:48 INFO - Mirror: Off 02:43:48 INFO - Online: Yes 02:43:48 INFO - Rotation: Supported 02:43:48 INFO - Return code: 0 02:43:48 INFO - Running post-action listener: _resource_record_post_action 02:43:48 INFO - [mozharness: 2017-05-10 09:43:48.977251Z] Finished run-tests step (success) 02:43:48 INFO - Running post-run listener: _resource_record_post_run 02:43:49 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 35368960; Write bytes: 515254272; Read time: 327; Write time: 1846 02:43:49 INFO - TinderboxPrint: CPU usage
54.7% 02:43:49 INFO - TinderboxPrint: I/O read bytes / time
35,368,960 / 327 02:43:49 INFO - TinderboxPrint: I/O write bytes / time
515,254,272 / 1,846 02:43:49 INFO - TinderboxPrint: CPU idle
2,175.3 (45.3%) 02:43:49 INFO - TinderboxPrint: CPU system
964.9 (20.1%) 02:43:49 INFO - TinderboxPrint: CPU user
1,661.6 (34.6%) 02:43:49 INFO - TinderboxPrint: Swap in / out
240,271,360 / 0 02:43:49 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203870720; Write bytes: 203409408; Read time: 15675; Write time: 451 02:43:49 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 33378304; Write bytes: 302120960; Read time: 305; Write time: 1360 02:43:49 INFO - Running post-run listener: _upload_blobber_files 02:43:49 INFO - Blob upload gear active. 02:43:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:43:49 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 02:43:49 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'] 02:43:49 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 02:43:49 INFO - (blobuploader) - INFO - Open directory for files ... 02:43:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:43:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:43:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:43:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:43:50 INFO - (blobuploader) - INFO - Done attempting. 02:43:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:43:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:43:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:43:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:43:52 INFO - (blobuploader) - INFO - Done attempting. 02:43:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:43:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:43:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:43:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:43:53 INFO - (blobuploader) - INFO - Done attempting. 02:43:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:43:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:43:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:43:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:43:55 INFO - (blobuploader) - INFO - Done attempting. 02:43:55 INFO - (blobuploader) - INFO - Iteration through files over. 02:43:55 INFO - Return code: 0 02:43:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:43:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:43:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9595a3c223afd83d2f09072c8d63b059d850ef461e22c59c40ac4bf3f7fc2a2e8691fc282dedbc56b18227357f548e25e7b45ff516a85bd2c4f4f6e0b2c61275", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10773ff4e420ece16aca2144e23dbea67cf03962828627575fcb8b54bc87837a318da4656ce834cc478abbc0e7715a58f1e9e34193009638a6aab8e72555adf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89e4d5bdf6e08df44cd37f58194abe52ace9b95330e16419f329ec9320588c1ab570699097f99c4ddc29eeb91c9c4a1751d99611ec18116bec1231d9f92e763e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/123a1fb8e38e7f0a6261705d4b0e94371fb7d3c1657c6d74ae30829f2e92965c14f6b7a01a24e7017516d0ec363b3c34f03af4b6248b92c6807834f7506de1c5"} 02:43:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:43:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:43:55 INFO - Contents: 02:43:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9595a3c223afd83d2f09072c8d63b059d850ef461e22c59c40ac4bf3f7fc2a2e8691fc282dedbc56b18227357f548e25e7b45ff516a85bd2c4f4f6e0b2c61275", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10773ff4e420ece16aca2144e23dbea67cf03962828627575fcb8b54bc87837a318da4656ce834cc478abbc0e7715a58f1e9e34193009638a6aab8e72555adf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89e4d5bdf6e08df44cd37f58194abe52ace9b95330e16419f329ec9320588c1ab570699097f99c4ddc29eeb91c9c4a1751d99611ec18116bec1231d9f92e763e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/123a1fb8e38e7f0a6261705d4b0e94371fb7d3c1657c6d74ae30829f2e92965c14f6b7a01a24e7017516d0ec363b3c34f03af4b6248b92c6807834f7506de1c5"} 02:43:55 INFO - Running post-run listener: copy_logs_to_upload_dir 02:43:55 INFO - Copying logs to upload dir... 02:43:55 INFO - mkdir: /builds/slave/test/build/upload/logs 02:43:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.456867 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-10 02:43:55.337070) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-10 02:43:55.340650) ========= 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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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/9595a3c223afd83d2f09072c8d63b059d850ef461e22c59c40ac4bf3f7fc2a2e8691fc282dedbc56b18227357f548e25e7b45ff516a85bd2c4f4f6e0b2c61275", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10773ff4e420ece16aca2144e23dbea67cf03962828627575fcb8b54bc87837a318da4656ce834cc478abbc0e7715a58f1e9e34193009638a6aab8e72555adf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89e4d5bdf6e08df44cd37f58194abe52ace9b95330e16419f329ec9320588c1ab570699097f99c4ddc29eeb91c9c4a1751d99611ec18116bec1231d9f92e763e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/123a1fb8e38e7f0a6261705d4b0e94371fb7d3c1657c6d74ae30829f2e92965c14f6b7a01a24e7017516d0ec363b3c34f03af4b6248b92c6807834f7506de1c5"} build_url:https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011436 build_url: 'https://queue.taskcluster.net/v1/task/NTfKedzYS6aaTbYQc5z29w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9595a3c223afd83d2f09072c8d63b059d850ef461e22c59c40ac4bf3f7fc2a2e8691fc282dedbc56b18227357f548e25e7b45ff516a85bd2c4f4f6e0b2c61275", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10773ff4e420ece16aca2144e23dbea67cf03962828627575fcb8b54bc87837a318da4656ce834cc478abbc0e7715a58f1e9e34193009638a6aab8e72555adf6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89e4d5bdf6e08df44cd37f58194abe52ace9b95330e16419f329ec9320588c1ab570699097f99c4ddc29eeb91c9c4a1751d99611ec18116bec1231d9f92e763e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/123a1fb8e38e7f0a6261705d4b0e94371fb7d3c1657c6d74ae30829f2e92965c14f6b7a01a24e7017516d0ec363b3c34f03af4b6248b92c6807834f7506de1c5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-10 02:43:55.374422) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 02:43:55.374729) ========= 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.rKd3h9cQHb/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.gJAIN7Cp1D/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.005369 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 02:43:55.422805) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-10 02:43:55.423135) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-10 02:43:55.423509) ========= ========= Total master_lag: 0.18 =========