builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0028 starttime: 1490877763.78 results: success (0) buildid: 20170330044822 builduid: 04cc058cea644d1dbc50df001f67719f revision: 03d602fd723ad6ff4588c04855884ffa1dee9410 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:43.779220) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:43.779650) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:43.779939) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:43.846586) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:43.846905) ========= 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.HUpyFxVnH3/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.A0iCWLojyl/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-30 05:42:43-- 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% 157M=0s 2017-03-30 05:42:43 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147266 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:44.018473) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:44.018762) ========= 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.HUpyFxVnH3/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.A0iCWLojyl/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.028743 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:44.079349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-30 05:42:44.079666) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 03d602fd723ad6ff4588c04855884ffa1dee9410 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 03d602fd723ad6ff4588c04855884ffa1dee9410 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HUpyFxVnH3/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.A0iCWLojyl/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-30 05:42:44,089 truncating revision to first 12 chars 2017-03-30 05:42:44,089 Setting DEBUG logging. 2017-03-30 05:42:44,089 attempt 1/10 2017-03-30 05:42:44,089 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/03d602fd723a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-30 05:42:45,784 unpacking tar archive at: mozilla-central-03d602fd723a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.974176 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-30 05:42:46.078065) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:46.078427) ========= script_repo_revision: 03d602fd723ad6ff4588c04855884ffa1dee9410 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:46.078829) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:46.079110) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 05:42:46.099140) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-30 05:42:46.099539) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HUpyFxVnH3/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.A0iCWLojyl/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 05:42:46 INFO - MultiFileLogger online at 20170330 05:42:46 in /builds/slave/test 05:42:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:42:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:42:46 INFO - {'append_to_log': False, 05:42:46 INFO - 'base_work_dir': '/builds/slave/test', 05:42:46 INFO - 'blob_upload_branch': 'Firefox', 05:42:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:42:46 INFO - 'branch': 'Firefox', 05:42:46 INFO - 'buildbot_json_path': 'buildprops.json', 05:42:46 INFO - 'config_files': ('talos/mac_config.py',), 05:42:46 INFO - 'default_actions': ('clobber', 05:42:46 INFO - 'read-buildbot-config', 05:42:46 INFO - 'download-and-extract', 05:42:46 INFO - 'populate-webroot', 05:42:46 INFO - 'create-virtualenv', 05:42:46 INFO - 'install', 05:42:46 INFO - 'run-tests'), 05:42:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:42:46 INFO - 'download_minidump_stackwalk': True, 05:42:46 INFO - 'download_symbols': 'ondemand', 05:42:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:42:46 INFO - 'tooltool.py': '/tools/tooltool.py', 05:42:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:42:46 INFO - '/tools/misc-python/virtualenv.py')}, 05:42:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:42:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:42:46 INFO - 'gecko_profile': False, 05:42:46 INFO - 'gecko_profile_interval': 0, 05:42:46 INFO - 'installer_path': 'installer.exe', 05:42:46 INFO - 'log_level': 'info', 05:42:46 INFO - 'log_name': 'talos', 05:42:46 INFO - 'log_to_console': True, 05:42:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:42:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:42:46 INFO - 'opt_config_files': (), 05:42:46 INFO - 'pip_index': False, 05:42:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:42:46 INFO - 'cmd': ('bash', 05:42:46 INFO - '-c', 05:42:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:42:46 INFO - 'enabled': True, 05:42:46 INFO - 'halt_on_failure': False, 05:42:46 INFO - 'name': 'check_screen_resolution'},), 05:42:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:42:46 INFO - 'cmd': ('bash', 05:42:46 INFO - '-c', 05:42:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:42:46 INFO - 'enabled': True, 05:42:46 INFO - 'halt_on_failure': False, 05:42:46 INFO - 'name': 'check_screen_resolution'},), 05:42:46 INFO - 'run_cmd_checks_enabled': True, 05:42:46 INFO - 'suite': 'dromaeojs-e10s', 05:42:46 INFO - 'system_bits': '32', 05:42:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:42:46 INFO - 'title': 't-yosemite-r7-0028', 05:42:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:42:46 INFO - 'use_talos_json': True, 05:42:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:42:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:42:46 INFO - 'work_dir': 'build'} 05:42:46 INFO - [mozharness: 2017-03-30 12:42:46.220865Z] Running clobber step. 05:42:46 INFO - Running pre-action listener: _resource_record_pre_action 05:42:46 INFO - Running main action method: clobber 05:42:46 INFO - rmtree: /builds/slave/test/build 05:42:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:42:48 INFO - Running post-action listener: _resource_record_post_action 05:42:48 INFO - [mozharness: 2017-03-30 12:42:48.751903Z] Finished clobber step (success) 05:42:48 INFO - [mozharness: 2017-03-30 12:42:48.752027Z] Running read-buildbot-config step. 05:42:48 INFO - Running pre-action listener: _resource_record_pre_action 05:42:48 INFO - Running main action method: read_buildbot_config 05:42:48 INFO - Using buildbot properties: 05:42:48 INFO - { 05:42:48 INFO - "project": "", 05:42:48 INFO - "product": "firefox", 05:42:48 INFO - "script_repo_revision": "production", 05:42:48 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:42:48 INFO - "repository": "", 05:42:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:42:48 INFO - "buildid": "20170330044822", 05:42:48 INFO - "pgo_build": "False", 05:42:48 INFO - "basedir": "/builds/slave/test", 05:42:48 INFO - "buildnumber": 181, 05:42:48 INFO - "slavename": "t-yosemite-r7-0028", 05:42:48 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:42:48 INFO - "platform": "yosemite_r7", 05:42:48 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:42:48 INFO - "branch": "mozilla-central", 05:42:48 INFO - "repo_path": "mozilla-central", 05:42:48 INFO - "revision": "03d602fd723ad6ff4588c04855884ffa1dee9410", 05:42:48 INFO - "stage_platform": "macosx64", 05:42:48 INFO - "builduid": "04cc058cea644d1dbc50df001f67719f", 05:42:48 INFO - "slavebuilddir": "test" 05:42:48 INFO - } 05:42:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:42:48 INFO - Running post-action listener: _resource_record_post_action 05:42:48 INFO - [mozharness: 2017-03-30 12:42:48.755279Z] Finished read-buildbot-config step (success) 05:42:48 INFO - [mozharness: 2017-03-30 12:42:48.755381Z] Running download-and-extract step. 05:42:48 INFO - Running pre-action listener: _resource_record_pre_action 05:42:48 INFO - Running main action method: download_and_extract 05:42:48 INFO - mkdir: /builds/slave/test/build/tests 05:42:48 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')]} 05:42:48 INFO - trying https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:42:48 INFO - Downloading https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:42:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 05:42:50 INFO - Downloaded 1325 bytes. 05:42:50 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:42:50 INFO - Using the following test package requirements: 05:42:50 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:50 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:42:50 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:42:50 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:50 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:42:50 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:50 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:42:50 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:50 INFO - u'jsshell-mac.zip'], 05:42:50 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:50 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:42:50 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:42:50 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:50 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:42:50 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:50 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:42:50 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:50 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:42:50 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:42:50 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:42:50 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:42:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:42:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:42:50 INFO - Fetch https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:42:51 INFO - Expected file size: 52511532 05:42:51 INFO - Obtained file size: 52511532 05:43:07 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:43:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:43:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:43:07 INFO - Fetch https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:43:09 INFO - Expected file size: 52511532 05:43:09 INFO - Obtained file size: 52511532 05:43:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:43:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:43:25 INFO - Fetch https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:43:26 INFO - Expected file size: 14404152 05:43:26 INFO - Obtained file size: 14404152 05:43:26 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')]} 05:43:26 INFO - trying https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:43:26 INFO - Downloading https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:43:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 05:43:28 INFO - Downloaded 75535078 bytes. 05:43:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:43:28 INFO - mkdir: /builds/slave/test/properties 05:43:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:43:28 INFO - Writing to file /builds/slave/test/properties/build_url 05:43:28 INFO - Contents: 05:43:28 INFO - build_url:https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:43:29 INFO - Running post-action listener: _resource_record_post_action 05:43:29 INFO - Running post-action listener: set_extra_try_arguments 05:43:29 INFO - [mozharness: 2017-03-30 12:43:29.574709Z] Finished download-and-extract step (success) 05:43:29 INFO - [mozharness: 2017-03-30 12:43:29.574828Z] Running populate-webroot step. 05:43:29 INFO - Running pre-action listener: _resource_record_pre_action 05:43:29 INFO - Running main action method: populate_webroot 05:43:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:43:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:43:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:43:29 INFO - '--tppagecycles', 05:43:29 INFO - '7'], 05:43:29 INFO - 'tests': ['tsvgm']}}, 05:43:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:43:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:43:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:43:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:43:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:43:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:43:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['damp', 'tps']}, 05:43:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'tests': ['damp', 'tps']}, 05:43:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['dromaeo_dom']}, 05:43:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:43:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['basic_compositor_video']}, 05:43:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:43:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['a11yr', 05:43:29 INFO - 'ts_paint', 05:43:29 INFO - 'tpaint', 05:43:29 INFO - 'sessionrestore', 05:43:29 INFO - 'sessionrestore_no_auto_restore', 05:43:29 INFO - 'tabpaint']}, 05:43:29 INFO - 'other-e10s': {'tests': ['a11yr', 05:43:29 INFO - 'ts_paint', 05:43:29 INFO - 'tpaint', 05:43:29 INFO - 'sessionrestore', 05:43:29 INFO - 'sessionrestore_no_auto_restore', 05:43:29 INFO - 'tabpaint']}, 05:43:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:43:29 INFO - 'ts_paint', 05:43:29 INFO - 'tpaint', 05:43:29 INFO - 'sessionrestore', 05:43:29 INFO - 'sessionrestore_no_auto_restore', 05:43:29 INFO - 'tabpaint']}, 05:43:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:43:29 INFO - 'ts_paint', 05:43:29 INFO - 'tpaint', 05:43:29 INFO - 'sessionrestore', 05:43:29 INFO - 'sessionrestore_no_auto_restore', 05:43:29 INFO - 'tabpaint']}, 05:43:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['a11yr', 05:43:29 INFO - 'ts_paint', 05:43:29 INFO - 'tpaint', 05:43:29 INFO - 'sessionrestore', 05:43:29 INFO - 'sessionrestore_no_auto_restore', 05:43:29 INFO - 'tabpaint']}, 05:43:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['a11yr', 05:43:29 INFO - 'ts_paint', 05:43:29 INFO - 'tpaint', 05:43:29 INFO - 'sessionrestore', 05:43:29 INFO - 'sessionrestore_no_auto_restore', 05:43:29 INFO - 'tabpaint']}, 05:43:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['tsvgx', 05:43:29 INFO - 'tsvgr_opacity', 05:43:29 INFO - 'tart', 05:43:29 INFO - 'tscrollx', 05:43:29 INFO - 'cart', 05:43:29 INFO - 'tsvg_static']}, 05:43:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:43:29 INFO - 'tsvgr_opacity', 05:43:29 INFO - 'tart', 05:43:29 INFO - 'tscrollx', 05:43:29 INFO - 'cart', 05:43:29 INFO - 'tsvg_static']}, 05:43:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'talos_options': ['--disable-e10s'], 05:43:29 INFO - 'tests': ['tp5o']}, 05:43:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:43:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'talos_options': ['--disable-e10s', 05:43:29 INFO - '--xperf_path', 05:43:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:43:29 INFO - 'tests': ['tp5n']}, 05:43:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:43:29 INFO - 'talos_options': ['--xperf_path', 05:43:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:43:29 INFO - 'tests': ['tp5n']}}, 05:43:29 INFO - 'talos.zip': {'path': '', 05:43:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:43:29 INFO - Running post-action listener: _resource_record_post_action 05:43:29 INFO - [mozharness: 2017-03-30 12:43:29.592871Z] Finished populate-webroot step (success) 05:43:29 INFO - [mozharness: 2017-03-30 12:43:29.593018Z] Running create-virtualenv step. 05:43:29 INFO - Running pre-action listener: _resource_record_pre_action 05:43:29 INFO - Running main action method: create_virtualenv 05:43:29 INFO - Creating virtualenv /builds/slave/test/build/venv 05:43:29 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 05:43:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:43:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:43:29 INFO - Using real prefix '/tools/python27' 05:43:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:43:30 INFO - Installing distribute.............................................................................................................................................................................................done. 05:43:33 INFO - Installing pip.................done. 05:43:33 INFO - Return code: 0 05:43:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:43:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:43:33 INFO - Reading from file tmpfile_stdout 05:43:33 INFO - Output received: 05:43:33 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) 05:43:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2234a2760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102660d30>, '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 0x102587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102655be8>, '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.HUpyFxVnH3/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.A0iCWLojyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 05:43: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', 'pip>=1.5'] in /builds/slave/test/build 05:43: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 pip>=1.5 05:43:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUpyFxVnH3/Render', 05:43:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:33 INFO - 'HOME': '/Users/cltbld', 05:43:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:33 INFO - 'LOGNAME': 'cltbld', 05:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:33 INFO - 'MOZ_NO_REMOTE': '1', 05:43:33 INFO - 'NO_EM_RESTART': '1', 05:43:33 INFO - 'PAGER': '/bin/cat', 05:43:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:33 INFO - 'PWD': '/builds/slave/test', 05:43:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:33 INFO - 'SHELL': '/bin/bash', 05:43:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A0iCWLojyl/Listeners', 05:43:33 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 05:43:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:33 INFO - 'USER': 'cltbld', 05:43:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:33 INFO - 'XPC_FLAGS': '0x0', 05:43:33 INFO - 'XPC_SERVICE_NAME': '0', 05:43:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:33 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 05:43:33 INFO - Cleaning up... 05:43:33 INFO - Return code: 0 05:43:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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', '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 0x102882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2234a2760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102660d30>, '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 0x102587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102655be8>, '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.HUpyFxVnH3/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.A0iCWLojyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 05:43: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:43: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 psutil>=3.1.1 05:43:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUpyFxVnH3/Render', 05:43:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:33 INFO - 'HOME': '/Users/cltbld', 05:43:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:33 INFO - 'LOGNAME': 'cltbld', 05:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:33 INFO - 'MOZ_NO_REMOTE': '1', 05:43:33 INFO - 'NO_EM_RESTART': '1', 05:43:33 INFO - 'PAGER': '/bin/cat', 05:43:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:33 INFO - 'PWD': '/builds/slave/test', 05:43:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:33 INFO - 'SHELL': '/bin/bash', 05:43:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A0iCWLojyl/Listeners', 05:43:33 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 05:43:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:33 INFO - 'USER': 'cltbld', 05:43:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:33 INFO - 'XPC_FLAGS': '0x0', 05:43:33 INFO - 'XPC_SERVICE_NAME': '0', 05:43:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:34 INFO - Downloading/unpacking psutil>=3.1.1 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43: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 05:43:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:43:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:43:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:43:37 INFO - Installing collected packages: psutil 05:43:37 INFO - Running setup.py install for psutil 05:43:38 INFO - building 'psutil._psutil_osx' extension 05:43:38 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 05:43:38 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 05:43:38 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 05:43:38 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 05:43:38 INFO - building 'psutil._psutil_posix' extension 05:43:38 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 05:43:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:43:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:43:38 INFO - ^ 05:43:38 INFO - 1 warning generated. 05:43:38 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 05:43:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:43:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:43:39 INFO - Successfully installed psutil 05:43:39 INFO - Cleaning up... 05:43:39 INFO - Return code: 0 05:43:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:43:39 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')]} 05:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:39 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')]} 05:43:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:39 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 0x102882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2234a2760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102660d30>, '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 0x102587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102655be8>, '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.HUpyFxVnH3/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.A0iCWLojyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 05:43:39 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 05:43:39 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 05:43:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUpyFxVnH3/Render', 05:43:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:39 INFO - 'HOME': '/Users/cltbld', 05:43:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:39 INFO - 'LOGNAME': 'cltbld', 05:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:39 INFO - 'MOZ_NO_REMOTE': '1', 05:43:39 INFO - 'NO_EM_RESTART': '1', 05:43:39 INFO - 'PAGER': '/bin/cat', 05:43:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:39 INFO - 'PWD': '/builds/slave/test', 05:43:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:39 INFO - 'SHELL': '/bin/bash', 05:43:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A0iCWLojyl/Listeners', 05:43:39 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 05:43:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:39 INFO - 'USER': 'cltbld', 05:43:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:39 INFO - 'XPC_FLAGS': '0x0', 05:43:39 INFO - 'XPC_SERVICE_NAME': '0', 05:43:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:43:39 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 05:43:39 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 05:43:39 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 05:43:39 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 05:43:39 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 05:43:39 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 05:43:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:43:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:43:42 INFO - Installing collected packages: mozsystemmonitor 05:43:42 INFO - Running setup.py install for mozsystemmonitor 05:43:42 INFO - Successfully installed mozsystemmonitor 05:43:42 INFO - Cleaning up... 05:43:42 INFO - Return code: 0 05:43:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2234a2760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102660d30>, '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 0x102587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102655be8>, '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.HUpyFxVnH3/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.A0iCWLojyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 05:43:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:43:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:43:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUpyFxVnH3/Render', 05:43:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:42 INFO - 'HOME': '/Users/cltbld', 05:43:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:42 INFO - 'LOGNAME': 'cltbld', 05:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:42 INFO - 'MOZ_NO_REMOTE': '1', 05:43:42 INFO - 'NO_EM_RESTART': '1', 05:43:42 INFO - 'PAGER': '/bin/cat', 05:43:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:42 INFO - 'PWD': '/builds/slave/test', 05:43:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:42 INFO - 'SHELL': '/bin/bash', 05:43:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A0iCWLojyl/Listeners', 05:43:42 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 05:43:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:42 INFO - 'USER': 'cltbld', 05:43:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:42 INFO - 'XPC_FLAGS': '0x0', 05:43:42 INFO - 'XPC_SERVICE_NAME': '0', 05:43:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:42 INFO - Downloading/unpacking jsonschema==2.5.1 05:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:43:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:43:45 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 05:43:45 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 05:43:45 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 05:43:45 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 05:43:45 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 05:43:45 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 05:43:45 INFO - Downloading functools32-3.2.3-2.tar.gz 05:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:43:45 INFO - warning: no files found matching '*.txt' 05:43:45 INFO - no previously-included directories found matching 'build' 05:43:45 INFO - no previously-included directories found matching 'dist' 05:43:45 INFO - no previously-included directories found matching '.git*' 05:43:45 INFO - Installing collected packages: jsonschema, functools32 05:43:45 INFO - Running setup.py install for functools32 05:43:45 INFO - warning: no files found matching '*.txt' 05:43:45 INFO - no previously-included directories found matching 'build' 05:43:45 INFO - no previously-included directories found matching 'dist' 05:43:45 INFO - no previously-included directories found matching '.git*' 05:43:46 INFO - Successfully installed jsonschema functools32 05:43:46 INFO - Cleaning up... 05:43:46 INFO - Return code: 0 05:43:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:43:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2234a2760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102660d30>, '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 0x102587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102655be8>, '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.HUpyFxVnH3/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.A0iCWLojyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 05:43:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:43:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:43:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUpyFxVnH3/Render', 05:43:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:46 INFO - 'HOME': '/Users/cltbld', 05:43:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:46 INFO - 'LOGNAME': 'cltbld', 05:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:46 INFO - 'MOZ_NO_REMOTE': '1', 05:43:46 INFO - 'NO_EM_RESTART': '1', 05:43:46 INFO - 'PAGER': '/bin/cat', 05:43:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:46 INFO - 'PWD': '/builds/slave/test', 05:43:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:46 INFO - 'SHELL': '/bin/bash', 05:43:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A0iCWLojyl/Listeners', 05:43:46 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 05:43:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:46 INFO - 'USER': 'cltbld', 05:43:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:46 INFO - 'XPC_FLAGS': '0x0', 05:43:46 INFO - 'XPC_SERVICE_NAME': '0', 05:43:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:43:46 INFO - Cleaning up... 05:43:46 INFO - Return code: 0 05:43:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:43:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2234a2760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102660d30>, '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 0x102587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102655be8>, '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.HUpyFxVnH3/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.A0iCWLojyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 05:43:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:43:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:43:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUpyFxVnH3/Render', 05:43:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:46 INFO - 'HOME': '/Users/cltbld', 05:43:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:46 INFO - 'LOGNAME': 'cltbld', 05:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:46 INFO - 'MOZ_NO_REMOTE': '1', 05:43:46 INFO - 'NO_EM_RESTART': '1', 05:43:46 INFO - 'PAGER': '/bin/cat', 05:43:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:46 INFO - 'PWD': '/builds/slave/test', 05:43:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:46 INFO - 'SHELL': '/bin/bash', 05:43:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A0iCWLojyl/Listeners', 05:43:46 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 05:43:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:46 INFO - 'USER': 'cltbld', 05:43:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:46 INFO - 'XPC_FLAGS': '0x0', 05:43:46 INFO - 'XPC_SERVICE_NAME': '0', 05:43:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:46 INFO - Downloading/unpacking blobuploader==1.2.4 05:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:49 INFO - Downloading blobuploader-1.2.4.tar.gz 05:43:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:43:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:43:49 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 05:43:49 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 05:43:49 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 05:43:49 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 05:43:49 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 05:43:49 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 05:43:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:43:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:50 INFO - Downloading docopt-0.6.1.tar.gz 05:43:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:43:50 INFO - Installing collected packages: blobuploader, requests, docopt 05:43:50 INFO - Running setup.py install for blobuploader 05:43:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:43:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:43:50 INFO - Running setup.py install for requests 05:43:51 INFO - Running setup.py install for docopt 05:43:51 INFO - Successfully installed blobuploader requests docopt 05:43:51 INFO - Cleaning up... 05:43:51 INFO - Return code: 0 05:43:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:51 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 0x102882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2234a2760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102660d30>, '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 0x102587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102655be8>, '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.HUpyFxVnH3/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.A0iCWLojyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 05:43:51 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 05:43:51 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 05:43:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUpyFxVnH3/Render', 05:43:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:51 INFO - 'HOME': '/Users/cltbld', 05:43:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:51 INFO - 'LOGNAME': 'cltbld', 05:43:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:51 INFO - 'MOZ_NO_REMOTE': '1', 05:43:51 INFO - 'NO_EM_RESTART': '1', 05:43:51 INFO - 'PAGER': '/bin/cat', 05:43:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:51 INFO - 'PWD': '/builds/slave/test', 05:43:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:51 INFO - 'SHELL': '/bin/bash', 05:43:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A0iCWLojyl/Listeners', 05:43:51 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 05:43:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:51 INFO - 'USER': 'cltbld', 05:43:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:51 INFO - 'XPC_FLAGS': '0x0', 05:43:51 INFO - 'XPC_SERVICE_NAME': '0', 05:43:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:43:51 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-K5NB6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:43:51 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-GMABPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:43:51 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-jpbx_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:43:52 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-IzFuKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:43:52 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-e0wFdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:43:52 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-nwj9FP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:43:52 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-jB3kfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:43:52 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-NdmaxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:43:52 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-qD8yt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:43:52 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-PV2dB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:43:52 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-ZhGFM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:43:52 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-BOdRSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:43:52 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-DKX59D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:43:53 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-um_SjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:53 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-Cvhyn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:43:53 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-g2cpV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:43:53 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-PvD5Sz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:43:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:43:53 INFO - Running setup.py install for manifestparser 05:43:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:43:53 INFO - Running setup.py install for mozcrash 05:43:53 INFO - Running setup.py install for mozdebug 05:43:53 INFO - Running setup.py install for mozdevice 05:43:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:43:54 INFO - Running setup.py install for mozfile 05:43:54 INFO - Running setup.py install for mozhttpd 05:43:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:43:54 INFO - Running setup.py install for mozinfo 05:43:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:43:54 INFO - Running setup.py install for mozInstall 05:43:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:43:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:43:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:43:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:43:54 INFO - Running setup.py install for mozleak 05:43:54 INFO - Running setup.py install for mozlog 05:43:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:43:54 INFO - Running setup.py install for moznetwork 05:43:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Running setup.py install for mozprocess 05:43:55 INFO - Running setup.py install for mozprofile 05:43:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Running setup.py install for mozrunner 05:43:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:43:55 INFO - Running setup.py install for mozscreenshot 05:43:55 INFO - Running setup.py install for moztest 05:43:55 INFO - Running setup.py install for mozversion 05:43:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:43:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:43:56 INFO - Cleaning up... 05:43:56 INFO - Return code: 0 05:43:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:56 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 0x102882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2234a2760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102660d30>, '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 0x102587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102655be8>, '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.HUpyFxVnH3/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.A0iCWLojyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 05:43:56 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 05:43:56 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 05:43:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUpyFxVnH3/Render', 05:43:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:56 INFO - 'HOME': '/Users/cltbld', 05:43:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:56 INFO - 'LOGNAME': 'cltbld', 05:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:56 INFO - 'MOZ_NO_REMOTE': '1', 05:43:56 INFO - 'NO_EM_RESTART': '1', 05:43:56 INFO - 'PAGER': '/bin/cat', 05:43:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:56 INFO - 'PWD': '/builds/slave/test', 05:43:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:56 INFO - 'SHELL': '/bin/bash', 05:43:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A0iCWLojyl/Listeners', 05:43:56 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 05:43:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:56 INFO - 'USER': 'cltbld', 05:43:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:56 INFO - 'XPC_FLAGS': '0x0', 05:43:56 INFO - 'XPC_SERVICE_NAME': '0', 05:43:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:43:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-2lVxef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:43:56 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)) 05:43:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:43:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-d_wl4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:43:56 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)) 05:43:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:43:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-3A1gr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:43:56 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)) 05:43:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:43:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-CLUkKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:43:56 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)) 05:43:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:43:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-luvGUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:43:56 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)) 05:43:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:43:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-TkTL0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:43:56 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)) 05:43:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:43:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-SAgHj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:43:57 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-xUpTKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:43:57 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-RoxSEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:43:57 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-1KgPxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:43:57 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-5aVgkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:43:57 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-WiUDRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:43:57 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-61Ymnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:43:57 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-kc4SmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:57 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-ycEe6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:57 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)) 05:43:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:43:57 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-K_aaiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:43:58 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)) 05:43:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:43:58 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-zcUzFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:43:58 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)) 05:43:58 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)) 05:43:58 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)) 05:43:58 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)) 05:43:58 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)) 05:43:58 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)) 05:43:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:00 INFO - Downloading blessings-1.6.tar.gz 05:44:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:44:00 INFO - Installing collected packages: blessings 05:44:00 INFO - Running setup.py install for blessings 05:44:01 INFO - Successfully installed blessings 05:44:01 INFO - Cleaning up... 05:44:01 INFO - Return code: 0 05:44:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:44:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:44:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:44:01 INFO - Reading from file tmpfile_stdout 05:44:01 INFO - Current package versions: 05:44:01 INFO - blessings == 1.6 05:44:01 INFO - blobuploader == 1.2.4 05:44:01 INFO - docopt == 0.6.1 05:44:01 INFO - functools32 == 3.2.3-2 05:44:01 INFO - jsonschema == 2.5.1 05:44:01 INFO - manifestparser == 1.1 05:44:01 INFO - mozInstall == 1.12 05:44:01 INFO - mozcrash == 1.0 05:44:01 INFO - mozdebug == 0.1 05:44:01 INFO - mozdevice == 0.49 05:44:01 INFO - mozfile == 1.2 05:44:01 INFO - mozhttpd == 0.7 05:44:01 INFO - mozinfo == 0.9 05:44:01 INFO - mozleak == 0.1 05:44:01 INFO - mozlog == 3.4 05:44:01 INFO - moznetwork == 0.27 05:44:01 INFO - mozprocess == 0.25 05:44:01 INFO - mozprofile == 0.28 05:44:01 INFO - mozrunner == 6.13 05:44:01 INFO - mozscreenshot == 0.1 05:44:01 INFO - mozsystemmonitor == 0.3 05:44:01 INFO - moztest == 0.8 05:44:01 INFO - mozversion == 1.4 05:44:01 INFO - psutil == 3.1.1 05:44:01 INFO - requests == 1.2.3 05:44:01 INFO - wsgiref == 0.1.2 05:44:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:44:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:44:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:01 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 0x102882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2234a2760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102660d30>, '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 0x102587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102655be8>, '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.HUpyFxVnH3/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.A0iCWLojyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 05:44:01 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 05:44:01 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 05:44:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUpyFxVnH3/Render', 05:44:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:01 INFO - 'HOME': '/Users/cltbld', 05:44:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:01 INFO - 'LOGNAME': 'cltbld', 05:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:01 INFO - 'MOZ_NO_REMOTE': '1', 05:44:01 INFO - 'NO_EM_RESTART': '1', 05:44:01 INFO - 'PAGER': '/bin/cat', 05:44:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:01 INFO - 'PWD': '/builds/slave/test', 05:44:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:01 INFO - 'SHELL': '/bin/bash', 05:44:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A0iCWLojyl/Listeners', 05:44:01 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 05:44:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:01 INFO - 'USER': 'cltbld', 05:44:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:01 INFO - 'XPC_FLAGS': '0x0', 05:44:01 INFO - 'XPC_SERVICE_NAME': '0', 05:44:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:01 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)) 05:44:01 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)) 05:44:01 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)) 05:44:01 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)) 05:44:01 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)) 05:44:01 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)) 05:44:01 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)) 05:44:01 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)) 05:44:01 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)) 05:44:01 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)) 05:44:01 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)) 05:44:01 INFO - Cleaning up... 05:44:01 INFO - Return code: 0 05:44:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:44:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:44:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102882b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102885768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2234a2760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102660d30>, '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 0x102587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102655be8>, '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.HUpyFxVnH3/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.A0iCWLojyl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 05:44:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:44:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:44:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUpyFxVnH3/Render', 05:44:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:01 INFO - 'HOME': '/Users/cltbld', 05:44:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:01 INFO - 'LOGNAME': 'cltbld', 05:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:01 INFO - 'MOZ_NO_REMOTE': '1', 05:44:01 INFO - 'NO_EM_RESTART': '1', 05:44:01 INFO - 'PAGER': '/bin/cat', 05:44:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:01 INFO - 'PWD': '/builds/slave/test', 05:44:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:01 INFO - 'SHELL': '/bin/bash', 05:44:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A0iCWLojyl/Listeners', 05:44:01 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 05:44:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:01 INFO - 'USER': 'cltbld', 05:44:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:01 INFO - 'XPC_FLAGS': '0x0', 05:44:01 INFO - 'XPC_SERVICE_NAME': '0', 05:44:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:44:01 INFO - Cleaning up... 05:44:01 INFO - Return code: 0 05:44:01 INFO - Running post-action listener: _resource_record_post_action 05:44:01 INFO - Running post-action listener: _start_resource_monitoring 05:44:01 INFO - Starting resource monitoring. 05:44:02 INFO - [mozharness: 2017-03-30 12:44:02.000942Z] Finished create-virtualenv step (success) 05:44:02 INFO - [mozharness: 2017-03-30 12:44:02.001879Z] Running install step. 05:44:02 INFO - Running pre-action listener: _resource_record_pre_action 05:44:02 INFO - Running main action method: install 05:44:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:44:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:44:02 INFO - Reading from file tmpfile_stdout 05:44:02 INFO - Detecting whether we're running mozinstall >=1.0... 05:44:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:44:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:44:02 INFO - Reading from file tmpfile_stdout 05:44:02 INFO - Output received: 05:44:02 INFO - Usage: mozinstall [options] installer 05:44:02 INFO - Options: 05:44:02 INFO - -h, --help show this help message and exit 05:44:02 INFO - -d DEST, --destination=DEST 05:44:02 INFO - Directory to install application into. [default: 05:44:02 INFO - "/builds/slave/test"] 05:44:02 INFO - --app=APP Application being installed. [default: firefox] 05:44:02 INFO - mkdir: /builds/slave/test/build/application 05:44:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:44:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:44:23 INFO - Reading from file tmpfile_stdout 05:44:23 INFO - Output received: 05:44:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:44:23 INFO - Running post-action listener: _resource_record_post_action 05:44:23 INFO - [mozharness: 2017-03-30 12:44:23.006611Z] Finished install step (success) 05:44:23 INFO - [mozharness: 2017-03-30 12:44:23.006743Z] Running run-tests step. 05:44:23 INFO - Running pre-action listener: _resource_record_pre_action 05:44:23 INFO - Running main action method: run_tests 05:44:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:44:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:44:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:44:23 INFO - 2017-03-30 05:44:23.021 screenresolution[1633:8814] starting screenresolution argv=screenresolution get 05:44:23 INFO - 2017-03-30 05:44:23.024 screenresolution[1633:8814] Display 0: 1600x1200x32@60 05:44:23 INFO - 2017-03-30 05:44:23.029 screenresolution[1634:8816] starting screenresolution argv=screenresolution list 05:44:23 INFO - Available Modes on Display 0 05:44:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:44:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:44:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:44:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:44:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:44:23 INFO - Intel Iris: 05:44:23 INFO - Chipset Model: Intel Iris 05:44:23 INFO - Type: GPU 05:44:23 INFO - Bus: Built-In 05:44:23 INFO - VRAM (Dynamic, Max): 1536 MB 05:44:23 INFO - Vendor: Intel (0x8086) 05:44:23 INFO - Device ID: 0x0a2e 05:44:23 INFO - Revision ID: 0x0009 05:44:23 INFO - Displays: 05:44:23 INFO - Display: 05:44:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:44:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:44:23 INFO - Display Serial Number: 1600x1200 60 05:44:23 INFO - Main Display: Yes 05:44:23 INFO - Mirror: Off 05:44:23 INFO - Online: Yes 05:44:23 INFO - Rotation: Supported 05:44:23 INFO - Return code: 0 05:44:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:44:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:44:23 INFO - Python 2.7.3 05:44:23 INFO - Return code: 0 05:44:23 INFO - grabbing minidump binary from tooltool 05:44:23 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')]} 05:44:23 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 0x102660d30>, '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 0x102587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102655be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:44:23 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 05:44:23 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 05:44:23 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 05:44:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:44:23 INFO - Return code: 0 05:44:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:44:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:44:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:44:23 INFO - ENV: RUST_BACKTRACE is now 1 05:44:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:44:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:44:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0028', '--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 05:44:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0028 --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 05:44:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HUpyFxVnH3/Render', 05:44:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:23 INFO - 'HOME': '/Users/cltbld', 05:44:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:23 INFO - 'LOGNAME': 'cltbld', 05:44:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:44:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:44:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:23 INFO - 'MOZ_NO_REMOTE': '1', 05:44:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:44:23 INFO - 'NO_EM_RESTART': '1', 05:44:23 INFO - 'PAGER': '/bin/cat', 05:44:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:23 INFO - 'PWD': '/builds/slave/test', 05:44:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:44:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:23 INFO - 'RUST_BACKTRACE': '1', 05:44:23 INFO - 'SHELL': '/bin/bash', 05:44:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A0iCWLojyl/Listeners', 05:44:23 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 05:44:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:23 INFO - 'USER': 'cltbld', 05:44:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:23 INFO - 'XPC_FLAGS': '0x0', 05:44:23 INFO - 'XPC_SERVICE_NAME': '0', 05:44:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0028', '--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 05:44:23 INFO - mozversion application_buildid: 20170330044822 05:44:23 INFO - mozversion application_changeset: 03d602fd723ad6ff4588c04855884ffa1dee9410 05:44:23 INFO - mozversion application_display_name: Nightly 05:44:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:44:23 INFO - mozversion application_name: Firefox 05:44:23 INFO - mozversion application_remotingname: firefox 05:44:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:44:23 INFO - mozversion application_vendor: Mozilla 05:44:23 INFO - mozversion application_version: 55.0a1 05:44:23 INFO - mozversion platform_buildid: 20170330044822 05:44:23 INFO - mozversion platform_changeset: 03d602fd723ad6ff4588c04855884ffa1dee9410 05:44:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:44:23 INFO - mozversion platform_version: 55.0a1 05:44:23 INFO - using testdate: 1490877863 05:44:23 INFO - actual date: 1490877863 05:44:23 INFO - starting webserver on 'localhost:49248' 05:44:23 INFO - SUITE-START | Running 2 tests 05:44:23 INFO - TEST-START | dromaeo_css 05:44:23 INFO - operating with platform_type : mac_ 05:44:23 INFO - Initialising browser for dromaeo_css test... 05:44:23 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmps1ZRuw/profile http://localhost:49248/getInfo.html) 05:44:26 INFO - PID 1644 | 2017-03-30 05:44:26.816 plugin-container[1646:8993] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 05:44:26 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:44:26 INFO - PID 1644 | 2017-03-30 05:44:26.820 plugin-container[1646:8993] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 05:44:26 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:44:26 INFO - PID 1644 | 2017-03-30 05:44:26.820 plugin-container[1646:8993] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:44:26 INFO - PID 1644 | __metrics Screen width/height:1600/1200 05:44:26 INFO - PID 1644 | colorDepth:24 05:44:26 INFO - PID 1644 | Browser inner width/height: 1024/689 05:44:26 INFO - PID 1644 | __metrics 05:44:27 ERROR - PID 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 05:44:27 ERROR - PID 1644 | 1490877867051 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmps1ZRuw/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmps1ZRuw/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmps1ZRuw/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmps1ZRuw/profile/extensions/talos-powers@TalosPowersService.js:55:9 05:44:27 INFO - TEST-INFO | 1644: exit 0 05:44:27 INFO - Browser initialized. 05:44:27 INFO - Running cycle 1/1 for dromaeo_css test... 05:44:27 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmps1ZRuw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:44:30 INFO - PID 1648 | 2017-03-30 05:44:30.413 plugin-container[1649:9126] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474b, name = 'com.apple.tsm.portname' 05:44:30 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:44:30 INFO - PID 1648 | 2017-03-30 05:44:30.414 plugin-container[1649:9126] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 05:44:30 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:44:30 INFO - PID 1648 | 2017-03-30 05:44:30.414 plugin-container[1649:9126] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:44:40 INFO - PID 1648 | RSS: Main: 230117376 05:44:40 INFO - PID 1648 | 05:47:44 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 05:47:45 INFO - PID 1648 | RSS: Main: 255389696 05:47:45 INFO - PID 1648 | 05:49:28 INFO - PID 1648 | ************************* 05:49:28 INFO - PID 1648 | A coding exception was thrown and uncaught in a Task. 05:49:28 INFO - PID 1648 | 05:49:28 ERROR - PID 1648 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:49:28 INFO - PID 1648 | Full stack: 05:49:28 INFO - PID 1648 | ************************* 05:49:28 INFO - PID 1648 | ************************* 05:49:28 INFO - PID 1648 | A coding exception was thrown and uncaught in a Task. 05:49:28 INFO - PID 1648 | 05:49:28 ERROR - PID 1648 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:49:28 INFO - PID 1648 | Full stack: 05:49:28 INFO - PID 1648 | ************************* 05:49:28 INFO - PID 1648 | ************************* 05:49:28 INFO - PID 1648 | A coding exception was thrown and uncaught in a Task. 05:49:28 INFO - PID 1648 | 05:49:28 ERROR - PID 1648 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:49:28 INFO - PID 1648 | Full stack: 05:49:28 INFO - PID 1648 | ************************* 05:49:28 INFO - PID 1648 | ************************* 05:49:28 INFO - PID 1648 | A coding exception was thrown and uncaught in a Task. 05:49:28 INFO - PID 1648 | 05:49:28 ERROR - PID 1648 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:49:28 INFO - PID 1648 | Full stack: 05:49:28 INFO - PID 1648 | ************************* 05:50:47 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 05:50:48 INFO - PID 1648 | RSS: Main: 263069696 05:50:48 INFO - PID 1648 | 05:53:50 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 05:53:51 INFO - PID 1648 | RSS: Main: 274698240 05:53:51 INFO - PID 1648 | 05:56:53 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 05:56:53 INFO - PID 1648 | RSS: Main: 266940416 05:56:53 INFO - PID 1648 | 05:59:56 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 05:59:57 INFO - PID 1648 | RSS: Main: 277577728 05:59:57 INFO - PID 1648 | 06:03:00 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 06:03:00 INFO - PID 1648 | RSS: Main: 271609856 06:03:00 INFO - PID 1648 | 06:03:00 INFO - PID 1648 | __start_tp_report 06:03:00 INFO - PID 1648 | _x_x_mozilla_page_load 06:03:00 INFO - PID 1648 | _x_x_mozilla_page_load_details 06:03:00 INFO - PID 1648 | |i|pagename|runs| 06:03:00 INFO - PID 1648 | |0;dojo.html;3121.3289142834287;3590.9461358079634;3611.9705883823117;3624.8187590620464;3648.2521083177953;6128.930927367272;6235.906461403077;6239.594426362283;6273.874522509553;6291.937080629193;5162.941596972621;6165.506759459244;6202.689865506718;6255.499560035198;6313.936860631392;5680.687562184078;5704.201411802351;5740.167675687023;5773.653580785166;5847.883042339151;5614.185943038257;5704.515116215106;5808.012637851575;5815.767369305223;5912.467877890989;5507.311586051744;5532.640378375393;5572.86067848303;5691.288588926384;5701.543876489871;5640.85897852557;5666.914996275059;5694.886102277973;5726.513246374063;5735.942640573582;5451.209574611659;5599.104143337087;5614.691191984439;5620.4098569650005;5667.518260947824;6086.239220097488;6112.6250671334565;6149.29283132441;6195.28754193269;6218.657973811439;6638.203415590112;6675.732970681167;6678.265390807007;6680.866382672368;6697.330266973312;6777.728890844361;6778.76274330401;6799.52403331767;6809.080774095483;6812.62530560819;6137.324894261627;6229.813105606839;6319.210098737658;6349.6507692077375;6356.110144579717;6875.965620171967;6908.171019477594;6933.1333583302085;6964.895526567106;7003.5797852129035;6340.302566717657;6405.263394709575;6411.96794016027;6548.27968923418;6623.834404139935;6532.542722009413;6580.835479112965;6632.9336706633285;6766.526343156031;6770.69531871067;6691.397774200248;6700.497462690318;6705.463562914955;6713.63075030878;6714.899276510856;6364.681765911686;6401.3598640135615;6477.514186436036;6515.837104072436;6599.274079851212;5339.46605339463;5384.326959130109;5390.946090539122;5402.243685883979;5431.83704488866;4093.713440059167;4741.028089241697;4763.857084287477;4778.259369797687;4822;5133.614978876599;6203.720832562455;6367.363263673596;6371.458426033839;6386.393292637192;6126.529632888164;6134.693265336716;6387.616742995435;6675.699593518303;6708.3291670833505;5280.9207861882105;6322;6335;6351.920173570504;6353.269374022047;5128.717920514333;5693.430656934356;5960.612560183574;5965.821025369246;5995.400459953969;6282.528810339151;6657.1012913257055;6955.513114081966;6995.580265184105;7014.0531028310525;6769.729210831511;6777.525573209828;6792.626405547742;6793.116894803644;6814.8637027260165;6871.5189936703955;6953.895691564529;6972.302769722987;6978.825529361807;6983.6857341418745;6369.331220221995;6439.388258115471;6578.802635920739;6643.169603799525;6656.667166641551;6171.290301615371;6336.524760642878;6755.594664320157;6774.390304872585;6784.423324017514;6316.147320111726;6354.332795056453;6482.384173503509;6542.607443553402;6546.738130474728;5788.710564471844;6508.15393998796;6529.444997175293;6533.150690410216;6538.640374779243;4951.702897826142;6333.936660633519;6397.392247736458;6416.550841441054;6422.935770642233;6303.338149494237;6542.149520562297;6563.638999855054;6564.868702625825;6566.671666416564;6209.0686397039;6289.245290564979;6328.968355158195;6348.269948955929;6356.77751278703 06:03:00 INFO - PID 1648 | |1;ext.html;733.1825015108157;753.6156560154321;837.137748119437;1283.2428866968487;1287.5686644973932;35491.112722181955;37120.62879371206;38976;39642.405363919526;39805.80097099518;38845.61154388455;39068.41397379042;39106.41340379889;39203.60796392035;39329.41005884914;8046.396520260974;8203.343732501386;8569.871451928226;8780.429272097294;8786.253168480687;8723.651053957834;8808.207261346477;8868.290536757077;8871.68949086782;8871.77820554488;8816.823663526726;8875.95562022189;8886.911130888673;8894.332925030616;8897.199252067312;7828.647710853025;8683.305335573139;8868.246199073086;8878.822423551494;8880.689175878877;8482.53346065966;8512.489250644981;8545.957270213672;8582.699605513839;8583.012953510364;38439.84680459591;38559.61440385616;38592.03519912025;38683.22633547313;38802.417963730564;3004;3013.087447947633;3015.5627434022163;3026.0770465008163;3030.10611869499;27586.68868917807;27743.45659116008;27974.580381294298;28092;28855.42289154205;2777.180731684158;2816.605675205473;2824.61867647867;2830.8301501909746;2876.985615071932;325166;326279.7372026297;327830;328374;328690.7130928684;48944;49087.75456122697;49252.522424327326;49303.753481233085;49315;50785.74607127014;50805.729856753875;51026.724331892;51107.744461277456;51154.74422627863;12185.817212741817;12196.756064878653;14487.855121448862;14535.055221410574;14561.927190364193;11602.013828824645;11801.586944457074;11804.822927656092;11814.704632384259;11817;10981.780364392827;11197.93619606136;13378.799318010238;13473.932630336982;13575.728485430236;5461.754221059982;5654.46282603152;5959.523238140939;6008.519318454513;6024.518038556905;970.5341436110706;981.6711401680517;985.5121714751244;993.1806259835664;1028.1620479309338;24070.87964560166;24154.637680434807;24398.390040249134;24509.509809803705;24519.877400612884;1048.2295512798087;1059.2055958031476;1077.6497638267595;1088.8638920134983;1135;11959.59394904455;12292.201006934522;12324.753504930031;12403.317817520123;12405.875941240653;394940;424798;439493;440228.7988560037;442308;53914.73042634762;54575;54629.45370546323;54917;55167.72416137894;1464.4288727396324;1498.8725958293448;1502.4741340530945;1506.9321880515404;1510.3731951240113;27920;28166.01418950327;28243.870245190785;28550.143495695163;28777.848886044325;37493.250134997696;37543;37603.81198094101;37673.62326376701;37684;360745;360922;362817;362935;363593;30372.24069398283;30854.382912342076;30926.845365773028;31044.534331984585;31133.53299700461;39656.801715991234;40914;40961.590384095776;40986.59013409947;41122.383164251965;23775;26963.034066253087;28895.711042889303;29163.408523800103;29168.70831291745;28263.858680706464;28502.42995140127;29175.854120729262;29184.41631167407;29466.558001630423;33485.45379278715;33683.66316336805;33900.152496187795;34082.636694532936;34155.14612134716;28289.57565636476;29032.83868645315;29688.40623187481;29767;29966.25084372908;19061.714074289175;21791.237306694824;23486.882565587064;23622.818859057323;23693.526129477657 06:03:00 INFO - PID 1648 | |2;jquery.html;422.8266410771584;423.1009202932459;432.37090034000533;442.42484769799256;446.5512160278921;19577.412677619675;21189.57620847582;21980.450488737788;22013.339599811992;22082.33752987409;18287;20318.69521957168;21113.47216319593;21264.46838829026;21579.352619421403;5927.288725352963;6217.84969780592;6229.221347331583;6232.6883655817255;6237.376262373749;5906.232189815318;6063.181470501492;6070.848228794293;6085.452309292163;6141.846453838645;5171.482851714836;6080.331163572004;6101.993171126759;6115.48018418435;6133.785317513888;5881.470667639912;6088.634681919099;6115.960286751263;6134.17188679527;6136.447719705226;5223.112070947948;5373.435789242151;5379.5696344292755;5424.83725488236;5433.510984011418;21654;26026.21921342363;26146.86926565374;26254;26573.734262657512;2043.6014977079476;2053.856230063896;2068.172730907634;2069.1516478244;2093.612681653899;15634.497533755237;15679;15899.44351947688;16119.27463264156;16592.502224933152;1346.3133801761173;1422.9003969722123;1568.3099436248015;1609.316040682705;1685.679720853034;153743;154808;156684.21657891752;163934.18032909758;164154;11977.700557485958;12164;12245.204061735958;12248;12305;15529.145896975775;15552.533423997298;15557.988730732466;15568.766468502981;15603.921980390025;8464.534450605275;8741.994670612961;8763.342749293828;8843.91156088431;8892;8261.876071858927;8392.20274073962;8407.789805254917;8554.486730796172;8562.015368232733;6343.5876668016435;7571.318581327597;7722.343600793995;7723.729669461654;7764;2175.9626464734406;2186.693862859201;2189.3103672343477;2191.5178660694623;2358.2689366296504;911.6216770040359;915.949622770754;917.348682435465;945.9290553208536;952.3381250031161;15502;18665.346712864986;19846.1069251884;20144.294949676692;20481.590368192552;908.6501696846761;919.6137622198693;964.8662821185079;983.5475681186591;1013.7364285285879;7380.5940673263485;9466;9926.354786938826;10231.7442063949;10310.432926188982;670850;679059.8141027888;705250;713453;724208;152311.23844380706;157475;160841;161524.19237903735;166631;1202.909781766371;1209.6371088673363;1245.8691837357128;1250.1249125612107;1258.4022589270023;15726.606834829221;20863.79136208679;21052.26317078895;21067.262645807325;21270.680939785605;54379.45620543744;61689;62314.68842655758;62786.686066569375;62988;88643.3406997791;89123.10876891407;89147.55426222828;89168;90625.54687226521;8545.529995850287;8694.434861733967;8790.252828509649;8851.955740221258;8919.777005574653;12176.695582610506;12558.748825023265;13932.303384830515;14058.929705351407;14156.362963666867;10768.784624307313;11636.650900473;11821.586244481401;11833;11943.641690749291;8289.95855020721;11047.116230701364;11126.998570128728;11296.49165787558;11343.546258149912;11729.941350293195;11820.408979550815;11954.222975506913;11962.700932476757;11980.28118312904;8303;8307.08622051586;8430.36772242072;8782.946046474464;8850.229954009095;10939;11353.978141967262;11849.585264515701;12049.277043377426;12081.275123492618 06:03:00 INFO - PID 1648 | |3;mootools.html;1176.3118575633255;1386.1336664584635;1398.6783039900822;1406.3882211238106;1425.2588653899975;10474.057334839863;10500.842487362695;10874.565017399294;10986.066184374327;10995.34027958321;9361.62553497861;10878.728031799208;11070.723231919206;11100.222984391095;11150.832737508943;2467.5064987002625;2473.923843128245;2493.613489909058;2494.6008638617823;2495.15164843953;2418.697662792151;2434.963475547859;2458.0782206672498;2470.789982851451;2473.196211231348;2172.750133734625;2330.3707998840305;2432.1609044879488;2442.8167887408426;2461.1016978802786;2358.021421110237;2418.4800267942387;2427.2232885476656;2431.9392015199583;2432.2946345559767;2333.7899589036897;2406.855588664668;2410.14439873844;2423.842450240729;2435.1233555919853;10949.069329107035;10994.725131871688;11055.22613417061;11061.391623460706;11078;5337.653052551572;6122.938770612282;6289.937100628981;6305.0542418637115;6348.873022539569;3032.302570408818;3904.730962437219;3936.744111632763;3944.6055394460614;3974.1058261890844;988.8430536272579;1045.5713157605346;1138.163449864349;1156.7917774800533;1208.5347141350555;10984.95642913922;12763.23420594758;13284.003699722463;15010.024348417428;15262.465813696468;5239.95200959802;5401.108817045222;5422.674639521641;5423.647462914898;5443.237946687467;11467.369294688871;11526.654200374001;11532.769344613062;11549.6535103947;11595.826062609067;5950.434708702753;6177.2587289525545;6240.157578726905;6250.843728906813;6255.155554000152;4959.7272150032095;5577.6653400796085;5716.885662286731;5777.0467872801355;5809.041508151192;3980.880573582739;4152;4182.100848317566;4194.664426845845;4227.0489139943265;1075.623531763892;1241.0005195576266;1261.1991385470292;1270.8157317188957;1272.6436597752645;722.1658983873634;728.1262485018;748.3489364253135;751.2562563062529;778.5250996891891;5643.5767317451355;5706.429357064343;5887.558433117448;5918.556108291896;6033.909491357633;549.8405462415855;564.6499170514226;565.2199964049586;862.5945805471418;907.6777743900952;3973.5430425501436;4051.2707712611423;4114.506259248849;4138.620293331346;4189.874303770892;13467.595972120853;13956.930215348859;14026.246719160104;14034.368453419416;14051.85948140526;13256.469741210436;13641.590752277447;13930;13967;13977.440902363995;10042.899571004196;10183;10187.235957303084;10192.745181370674;10302.690919272434;5617.775288988395;6311.873762524816;6514.706838192387;6625.238097618835;6760.357766012221;11951.760964780482;12065.09511786637;12178.025757939165;12237.571684991339;12246.755064898474;11316.340659944743;12355.25868447896;12444.253344798979;12456.626301210978;12507.312097834343;9036.593353298986;9278.304127190622;9282.118198771106;9289.6284148636;9296.814063718823;9905.257105717244;10062.647807326708;10175.440350780482;10179.134773544332;10213.131883789663;8465.967151010544;8656.307495400228;8679.913200868163;8726.65093396257;8839.779005524657;8419.11599282091;8442.620082096197;8457.196566326189;8540.487570745776;8666.610002550027;7615.5430674159725;7736;7804.765857024298;7814.101378341336;7822.826576013644;5764.279465066867;5782.161586570008;5837.387074357302;5891.528677705687;5976.521878249817;4984.800607975785;5845.649261044351;5960.403959604178;6064;6121.418465245794 06:03:00 INFO - PID 1648 | |4;prototype.html;355.9073643913186;361.85344935301197;388.98444062237513;400.46937807405186;405.9898502537438;5880.912031274217;6317.684115794209;6385.3933876281735;6411.871762564746;6415.711292991815;6140.7850725224625;6257.155284036654;6258.812235632927;6275.592086514384;6277.591956522823;3924.3721004639265;4278.550752171024;4681.321208424776;4683.7892294846815;4725.881852953669;4079.9592004079877;4579.587837094661;4652.604528615055;4665.183592871251;4708.952910470903;4355.586219309176;4608.870826647476;4613.1926912790295;4619.74591397473;4620.768961551909;4047.2107938951917;4226.133642603274;4344.717593356438;4529.411176547028;4624.537546245382;3824;3924.940266128129;3950.545687245946;3982.900427489307;3987.4218238355274;5278.947210527884;6085.9391406085815;6086.330503644595;6094.451499365034;6134.625955724334;4937.456879743261;5382.085045542267;5403.351597808288;5412.5940554458175;5427.43011983744;5955.642661440284;6242.875142497124;6455.870222711007;6636.867262654768;6658.234303055114;3702.9444558331647;4403.581659742319;4414.713043652152;4425.668074894395;4428.756418396987;6454.741810327629;6762.966185169043;6777.175295684582;7077;7154.356107950257;4391.626711729538;4489.708168969007;4499.212637788374;4516.006478574708;4517.593416592523;3832.2718683450057;3832.9616703832603;4601.240795268744;4605.976970115195;4610.216263235258;4563.95436045642;5285.788568457296;5440.020796256712;5440.455954404607;5452.127659574449;5222.947770522323;6148.754049837957;6697.631630260285;6724.428423583952;6760.18877734675;1549.6125968507872;1560.9921950390403;1565.295616972367;1574.8582627563574;1575.6297270141508;971.2327261463365;1021.0351218098974;1026.779242462874;1036.8030074285862;1037.201354956679;5598.46814552625;6292.685365731695;6293.937060629335;6330.335314791881;6395.328490508523;6241.438270555672;6747.325267473311;6960.408365289007;7008.0188773571745;7008.7546935857;5723.856903577361;6211.689415529206;6335.16280278714;6336.619802811846;6348.333424990402;3013.5630333601507;3590.6768390844427;3606.7294952878638;3621.9818900905325;3638.381475149231;6844.4182244509775;7099.219085900547;7114.750983715545;7129.440914358803;7185.317394847481;5977.820665380045;5979.9402005980255;6810.284920083419;7089.399374494195;7167.673980313659;6068.757249709962;6999.65511553625;7093.3970612498515;7137.678804453916;7138.785836424916;5523.7790488380015;5725.51333136688;6039.78860739872;6930.27232140618;6940.930590694028;5382.9192562112385;6434.581752186093;6900.861982760417;6910.341518035535;6918.377346038679;7256.4194864409665;7268.166669164946;7277.417806575355;7282.417406607353;7320.158181808947;4143.86043837947;4370.978145109254;4461.152381047591;4513.41183758919;4546.340780586863;6292.779752708633;6426.710798014195;6966.721331146698;6984.650767461708;6989.47578931572;5099;5642.589352661835;6735.720213159484;6738.966305168443;6767.289434609295;6652.201735791736;6698.933010669831;6717.966410167918;6722.932770672231;6756.560823546651;5709.885802284014;6561.278259391559;6565.277819439762;6581.354661334666;6608.240052393844;6265.310815810166;6650.3682150195655;6674.599524028574;6722.865542689021;6733.528652994243;6535.215774106948;6552.312007239362;6562.5890433557015;6574.967125164344;6648.700808463727 06:03:00 INFO - PID 1648 | |5;yui.html;716.3051839715474;728.0244472406977;731.3892899428977;734.3464316758085;738.217489461171;15313.391377070222;16026.118563479033;16089.597760056004;16296.51110466685;16306.510804675863;15114.773278400833;15115.546533603982;15127.168005759679;15146.16696081715;15150.090994540335;941.6186444489975;955.9378640388387;963.8216929867962;973.8393165127745;998.4408731110601;1007.642286988116;1012;1015.6902144845819;1017.3793985668736;1018.7809620931498;1047.3611097230682;1059.2691043180182;1063.6808957312815;1064.302881612545;1066.53072648035;1038.6157121864883;1052.7262911642952;1056.9894301057006;1057.608684786626;1063.2344711807564;774.6901239504188;784.9991261142089;786.2177133751896;786.7718361675107;788.3496115704567;16411.343546258136;16617.584560385967;16685.332586696517;16892.239849206817;17060.232289547;14646.194459304734;14652.560423187322;14778.852211477855;14843.703125937422;14914.403423863032;11808.350540720257;11877.584284550087;12002.51989920402;12140.968017718504;12260.81608775869;3030.8029978051354;3043.753331440329;3079.384123175374;3153.9053828385186;3187.1872672468603;97974.99999999929;102467;107753.99205076326;114119.42940285329;116609.41695291555;10873.456327183609;11395.829062564067;11442.427878606037;11573.132015098901;11968.281903085843;25034.248972530488;25372;25464.872675636503;25532.872335638203;25713.97144114252;6810.216825065082;7877.842443151219;7922.445428819928;7937.635701474137;7992.920070799218;4140.130572579732;4224.134052519226;4254.282133407618;4293.506246781598;4298.656107511391;4067.9389809152885;4133.483314585676;4158.4178215049915;4167.333226683716;4172.207280616673;776.4875182379602;779.6920216514445;785.4580339565681;786.5673879366326;801.9478733882279;535.3361831329124;558.8658721906794;582.1587805620837;584.4535359438938;600.5465873265656;9600.423974561409;9993.600255989679;10092.192624590161;10144.898551014543;10153.847692284622;550.3313474128872;556.2351766321308;568.5508448325859;582.356496071843;599.2628273498005;4514.932276015862;4578.748168850679;4639.234526303189;4658;4701.929471057937;91747;93613.59579606312;106976.46511767391;108719.36920946192;108798;91537;92802;93742.53128734312;94511.1449467092;94737.0526294742;53506.46493535093;72348.91476627855;72661.54676906433;81568;83008.75486867581;9243.630254789734;10844.728881778019;10984.890151098707;11102.125577819543;11205.43972801373;28141.859290703414;34943.65056349404;35439.645603543635;35642.46536301906;37327;64616.35383646291;65053;65433;66755;68906;10184.796304073729;11892.286462812259;11973.461194246454;12015;14076.366563504462;14621.92689036548;14645.560633181021;14854.257287135384;15091.320890560171;15412.69174616479;13197.274149921846;13693.178409295475;13745.587632371045;13952.58142255734;14254.786178207129;13394.866051339752;13561.86438135606;13697.657558560717;13946.930265349014;14797.778033329723;13089.738205236032;13277.601671949857;13379.732405352033;13390.732185356044;14020.439182432588;8895.866562001445;8970.686025989058;8979.82040359176;8981;9094;9362.115927504346;9760.707178784647;10348.823389588852;10819.19294508156;10911.563537458413 06:03:00 INFO - PID 1648 | __end_tp_report 06:03:00 INFO - PID 1648 | __start_cc_report 06:03:00 INFO - PID 1648 | _x_x_mozilla_cycle_collect,435 06:03:00 INFO - PID 1648 | __end_cc_report 06:03:00 INFO - PID 1648 | __startTimestamp1490878980680__endTimestamp 06:03:00 INFO - PID 1648 | 06:03:00 INFO - PID 1648 | ------- Summary: start ------- 06:03:00 INFO - PID 1648 | Number of tests: 6 06:03:00 INFO - PID 1648 | 06:03:00 INFO - PID 1648 | [#0] dojo.html Cycles:165 Average:6154.35 Median:6348.27 stddev:717.50 (11.3%) stddev-sans-first:679.11 06:03:00 INFO - PID 1648 | Values: 3121.3 3590.9 3612.0 3624.8 3648.3 6128.9 6235.9 6239.6 6273.9 6291.9 5162.9 6165.5 6202.7 6255.5 6313.9 5680.7 5704.2 5740.2 5773.7 5847.9 5614.2 5704.5 5808.0 5815.8 5912.5 5507.3 5532.6 5572.9 5691.3 5701.5 5640.9 5666.9 5694.9 5726.5 5735.9 5451.2 5599.1 5614.7 5620.4 5667.5 6086.2 6112.6 6149.3 6195.3 6218.7 6638.2 6675.7 6678.3 6680.9 6697.3 6777.7 6778.8 6799.5 6809.1 6812.6 6137.3 6229.8 6319.2 6349.7 6356.1 6876.0 6908.2 6933.1 6964.9 7003.6 6340.3 6405.3 6412.0 6548.3 6623.8 6532.5 6580.8 6632.9 6766.5 6770.7 6691.4 6700.5 6705.5 6713.6 6714.9 6364.7 6401.4 6477.5 6515.8 6599.3 5339.5 5384.3 5390.9 5402.2 5431.8 4093.7 4741.0 4763.9 4778.3 4822.0 5133.6 6203.7 6367.4 6371.5 6386.4 6126.5 6134.7 6387.6 6675.7 6708.3 5280.9 6322.0 6335.0 6351.9 6353.3 5128.7 5693.4 5960.6 5965.8 5995.4 6282.5 6657.1 6955.5 6995.6 7014.1 6769.7 6777.5 6792.6 6793.1 6814.9 6871.5 6953.9 6972.3 6978.8 6983.7 6369.3 6439.4 6578.8 6643.2 6656.7 6171.3 6336.5 6755.6 6774.4 6784.4 6316.1 6354.3 6482.4 6542.6 6546.7 5788.7 6508.2 6529.4 6533.2 6538.6 4951.7 6333.9 6397.4 6416.6 6422.9 6303.3 6542.1 6563.6 6564.9 6566.7 6209.1 6289.2 6329.0 6348.3 6356.8 06:03:00 INFO - PID 1648 | 06:03:00 INFO - PID 1648 | [#1] ext.html Cycles:180 Average:50839.96 Median:33991.39 stddev:99288.80 (292.1%) stddev-sans-first:99496.06 06:03:00 INFO - PID 1648 | Values: 733.2 753.6 837.1 1283.2 1287.6 35491.1 37120.6 38976.0 39642.4 39805.8 38845.6 39068.4 39106.4 39203.6 39329.4 8046.4 8203.3 8569.9 8780.4 8786.3 8723.7 8808.2 8868.3 8871.7 8871.8 8816.8 8876.0 8886.9 8894.3 8897.2 7828.6 8683.3 8868.2 8878.8 8880.7 8482.5 8512.5 8546.0 8582.7 8583.0 38439.8 38559.6 38592.0 38683.2 38802.4 3004.0 3013.1 3015.6 3026.1 3030.1 27586.7 27743.5 27974.6 28092.0 28855.4 2777.2 2816.6 2824.6 2830.8 2877.0 325166.0 326279.7 327830.0 328374.0 328690.7 48944.0 49087.8 49252.5 49303.8 49315.0 50785.7 50805.7 51026.7 51107.7 51154.7 12185.8 12196.8 14487.9 14535.1 14561.9 11602.0 11801.6 11804.8 11814.7 11817.0 10981.8 11197.9 13378.8 13473.9 13575.7 5461.8 5654.5 5959.5 6008.5 6024.5 970.5 981.7 985.5 993.2 1028.2 24070.9 24154.6 24398.4 24509.5 24519.9 1048.2 1059.2 1077.6 1088.9 1135.0 11959.6 12292.2 12324.8 12403.3 12405.9 394940.0 424798.0 439493.0 440228.8 442308.0 53914.7 54575.0 54629.5 54917.0 55167.7 1464.4 1498.9 1502.5 1506.9 1510.4 27920.0 28166.0 28243.9 28550.1 28777.8 37493.3 37543.0 37603.8 37673.6 37684.0 360745.0 360922.0 362817.0 362935.0 363593.0 30372.2 30854.4 30926.8 31044.5 31133.5 39656.8 40914.0 40961.6 40986.6 41122.4 23775.0 26963.0 28895.7 29163.4 29168.7 28263.9 28502.4 29175.9 29184.4 29466.6 33485.5 33683.7 33900.2 34082.6 34155.1 28289.6 29032.8 29688.4 29767.0 29966.3 19061.7 21791.2 23486.9 23622.8 23693.5 06:03:00 INFO - PID 1648 | 06:03:00 INFO - PID 1648 | [#2] jquery.html Cycles:180 Average:40735.54 Median:22047.84 stddev:117632.41 (533.5%) stddev-sans-first:117923.46 06:03:00 INFO - PID 1648 | Values: 422.8 423.1 432.4 442.4 446.6 19577.4 21189.6 21980.5 22013.3 22082.3 18287.0 20318.7 21113.5 21264.5 21579.4 5927.3 6217.8 6229.2 6232.7 6237.4 5906.2 6063.2 6070.8 6085.5 6141.8 5171.5 6080.3 6102.0 6115.5 6133.8 5881.5 6088.6 6116.0 6134.2 6136.4 5223.1 5373.4 5379.6 5424.8 5433.5 21654.0 26026.2 26146.9 26254.0 26573.7 2043.6 2053.9 2068.2 2069.2 2093.6 15634.5 15679.0 15899.4 16119.3 16592.5 1346.3 1422.9 1568.3 1609.3 1685.7 153743.0 154808.0 156684.2 163934.2 164154.0 11977.7 12164.0 12245.2 12248.0 12305.0 15529.1 15552.5 15558.0 15568.8 15603.9 8464.5 8742.0 8763.3 8843.9 8892.0 8261.9 8392.2 8407.8 8554.5 8562.0 6343.6 7571.3 7722.3 7723.7 7764.0 2176.0 2186.7 2189.3 2191.5 2358.3 911.6 915.9 917.3 945.9 952.3 15502.0 18665.3 19846.1 20144.3 20481.6 908.7 919.6 964.9 983.5 1013.7 7380.6 9466.0 9926.4 10231.7 10310.4 670850.0 679059.8 705250.0 713453.0 724208.0 152311.2 157475.0 160841.0 161524.2 166631.0 1202.9 1209.6 1245.9 1250.1 1258.4 15726.6 20863.8 21052.3 21067.3 21270.7 54379.5 61689.0 62314.7 62786.7 62988.0 88643.3 89123.1 89147.6 89168.0 90625.5 8545.5 8694.4 8790.3 8852.0 8919.8 12176.7 12558.7 13932.3 14058.9 14156.4 10768.8 11636.7 11821.6 11833.0 11943.6 8290.0 11047.1 11127.0 11296.5 11343.5 11729.9 11820.4 11954.2 11962.7 11980.3 8303.0 8307.1 8430.4 8782.9 8850.2 10939.0 11354.0 11849.6 12049.3 12081.3 06:03:00 INFO - PID 1648 | 06:03:00 INFO - PID 1648 | [#3] mootools.html Cycles:180 Average:6674.53 Median:2494.88 stddev:4112.87 (164.9%) stddev-sans-first:4103.65 06:03:00 INFO - PID 1648 | Values: 1176.3 1386.1 1398.7 1406.4 1425.3 10474.1 10500.8 10874.6 10986.1 10995.3 9361.6 10878.7 11070.7 11100.2 11150.8 2467.5 2473.9 2493.6 2494.6 2495.2 2418.7 2435.0 2458.1 2470.8 2473.2 2172.8 2330.4 2432.2 2442.8 2461.1 2358.0 2418.5 2427.2 2431.9 2432.3 2333.8 2406.9 2410.1 2423.8 2435.1 10949.1 10994.7 11055.2 11061.4 11078.0 5337.7 6122.9 6289.9 6305.1 6348.9 3032.3 3904.7 3936.7 3944.6 3974.1 988.8 1045.6 1138.2 1156.8 1208.5 10985.0 12763.2 13284.0 15010.0 15262.5 5240.0 5401.1 5422.7 5423.6 5443.2 11467.4 11526.7 11532.8 11549.7 11595.8 5950.4 6177.3 6240.2 6250.8 6255.2 4959.7 5577.7 5716.9 5777.0 5809.0 3980.9 4152.0 4182.1 4194.7 4227.0 1075.6 1241.0 1261.2 1270.8 1272.6 722.2 728.1 748.3 751.3 778.5 5643.6 5706.4 5887.6 5918.6 6033.9 549.8 564.6 565.2 862.6 907.7 3973.5 4051.3 4114.5 4138.6 4189.9 13467.6 13956.9 14026.2 14034.4 14051.9 13256.5 13641.6 13930.0 13967.0 13977.4 10042.9 10183.0 10187.2 10192.7 10302.7 5617.8 6311.9 6514.7 6625.2 6760.4 11951.8 12065.1 12178.0 12237.6 12246.8 11316.3 12355.3 12444.3 12456.6 12507.3 9036.6 9278.3 9282.1 9289.6 9296.8 9905.3 10062.6 10175.4 10179.1 10213.1 8466.0 8656.3 8679.9 8726.7 8839.8 8419.1 8442.6 8457.2 8540.5 8666.6 7615.5 7736.0 7804.8 7814.1 7822.8 5764.3 5782.2 5837.4 5891.5 5976.5 4984.8 5845.6 5960.4 6064.0 6121.4 06:03:00 INFO - PID 1648 | 06:03:00 INFO - PID 1648 | [#4] prototype.html Cycles:180 Average:5369.72 Median:6086.13 stddev:1710.80 (28.1%) stddev-sans-first:1673.70 06:03:00 INFO - PID 1648 | Values: 355.9 361.9 389.0 400.5 406.0 5880.9 6317.7 6385.4 6411.9 6415.7 6140.8 6257.2 6258.8 6275.6 6277.6 3924.4 4278.6 4681.3 4683.8 4725.9 4080.0 4579.6 4652.6 4665.2 4709.0 4355.6 4608.9 4613.2 4619.7 4620.8 4047.2 4226.1 4344.7 4529.4 4624.5 3824.0 3924.9 3950.5 3982.9 3987.4 5278.9 6085.9 6086.3 6094.5 6134.6 4937.5 5382.1 5403.4 5412.6 5427.4 5955.6 6242.9 6455.9 6636.9 6658.2 3702.9 4403.6 4414.7 4425.7 4428.8 6454.7 6763.0 6777.2 7077.0 7154.4 4391.6 4489.7 4499.2 4516.0 4517.6 3832.3 3833.0 4601.2 4606.0 4610.2 4564.0 5285.8 5440.0 5440.5 5452.1 5222.9 6148.8 6697.6 6724.4 6760.2 1549.6 1561.0 1565.3 1574.9 1575.6 971.2 1021.0 1026.8 1036.8 1037.2 5598.5 6292.7 6293.9 6330.3 6395.3 6241.4 6747.3 6960.4 7008.0 7008.8 5723.9 6211.7 6335.2 6336.6 6348.3 3013.6 3590.7 3606.7 3622.0 3638.4 6844.4 7099.2 7114.8 7129.4 7185.3 5977.8 5979.9 6810.3 7089.4 7167.7 6068.8 6999.7 7093.4 7137.7 7138.8 5523.8 5725.5 6039.8 6930.3 6940.9 5382.9 6434.6 6900.9 6910.3 6918.4 7256.4 7268.2 7277.4 7282.4 7320.2 4143.9 4371.0 4461.2 4513.4 4546.3 6292.8 6426.7 6966.7 6984.7 6989.5 5099.0 5642.6 6735.7 6739.0 6767.3 6652.2 6698.9 6718.0 6722.9 6756.6 5709.9 6561.3 6565.3 6581.4 6608.2 6265.3 6650.4 6674.6 6722.9 6733.5 6535.2 6552.3 6562.6 6575.0 6648.7 06:03:00 INFO - PID 1648 | 06:03:00 INFO - PID 1648 | [#5] yui.html Cycles:180 Average:20197.22 Median:25623.42 stddev:29185.43 (113.9%) stddev-sans-first:29230.65 06:03:00 INFO - PID 1648 | Values: 716.3 728.0 731.4 734.3 738.2 15313.4 16026.1 16089.6 16296.5 16306.5 15114.8 15115.5 15127.2 15146.2 15150.1 941.6 955.9 963.8 973.8 998.4 1007.6 1012.0 1015.7 1017.4 1018.8 1047.4 1059.3 1063.7 1064.3 1066.5 1038.6 1052.7 1057.0 1057.6 1063.2 774.7 785.0 786.2 786.8 788.3 16411.3 16617.6 16685.3 16892.2 17060.2 14646.2 14652.6 14778.9 14843.7 14914.4 11808.4 11877.6 12002.5 12141.0 12260.8 3030.8 3043.8 3079.4 3153.9 3187.2 97975.0 102467.0 107754.0 114119.4 116609.4 10873.5 11395.8 11442.4 11573.1 11968.3 25034.2 25372.0 25464.9 25532.9 25714.0 6810.2 7877.8 7922.4 7937.6 7992.9 4140.1 4224.1 4254.3 4293.5 4298.7 4067.9 4133.5 4158.4 4167.3 4172.2 776.5 779.7 785.5 786.6 801.9 535.3 558.9 582.2 584.5 600.5 9600.4 9993.6 10092.2 10144.9 10153.8 550.3 556.2 568.6 582.4 599.3 4514.9 4578.7 4639.2 4658.0 4701.9 91747.0 93613.6 106976.5 108719.4 108798.0 91537.0 92802.0 93742.5 94511.1 94737.1 53506.5 72348.9 72661.5 81568.0 83008.8 9243.6 10844.7 10984.9 11102.1 11205.4 28141.9 34943.7 35439.6 35642.5 37327.0 64616.4 65053.0 65433.0 66755.0 68906.0 10184.8 11892.3 11973.5 12015.0 14076.4 14621.9 14645.6 14854.3 15091.3 15412.7 13197.3 13693.2 13745.6 13952.6 14254.8 13394.9 13561.9 13697.7 13946.9 14797.8 13089.7 13277.6 13379.7 13390.7 14020.4 8895.9 8970.7 8979.8 8981.0 9094.0 9362.1 9760.7 10348.8 10819.2 10911.6 06:03:00 INFO - PID 1648 | -------- Summary: end -------- 06:03:00 INFO - PID 1648 | 06:03:01 INFO - TEST-INFO | 1648: exit 0 06:03:01 INFO - TEST-OK | dromaeo_css | took 1117735ms 06:03:01 INFO - TEST-START | kraken 06:03:01 INFO - operating with platform_type : mac_ 06:03:01 INFO - Initialising browser for kraken test... 06:03:01 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpr5NXQP/profile http://localhost:49248/getInfo.html) 06:03:04 INFO - PID 1656 | 2017-03-30 06:03:04.442 plugin-container[1657:11472] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 06:03:04 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:03:04 INFO - PID 1656 | 2017-03-30 06:03:04.446 plugin-container[1657:11472] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441f, name = 'com.apple.CFPasteboardClient' 06:03:04 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:03:04 INFO - PID 1656 | 2017-03-30 06:03:04.446 plugin-container[1657:11472] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:03:04 INFO - PID 1656 | __metrics Screen width/height:1600/1200 06:03:04 INFO - PID 1656 | colorDepth:24 06:03:04 INFO - PID 1656 | Browser inner width/height: 1024/689 06:03:04 INFO - PID 1656 | __metrics 06:03:04 ERROR - PID 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 06:03:04 ERROR - PID 1656 | 1490878984639 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpr5NXQP/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpr5NXQP/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpr5NXQP/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpr5NXQP/profile/extensions/talos-powers@TalosPowersService.js:55:9 06:03:05 INFO - TEST-INFO | 1656: exit 0 06:03:05 INFO - Browser initialized. 06:03:05 INFO - Running cycle 1/1 for kraken test... 06:03:05 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpr5NXQP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:03:07 INFO - PID 1658 | 2017-03-30 06:03:07.797 plugin-container[1659:11598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4f, name = 'com.apple.tsm.portname' 06:03:07 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:03:07 INFO - PID 1658 | 2017-03-30 06:03:07.797 plugin-container[1659:11598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 06:03:07 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:03:07 INFO - PID 1658 | 2017-03-30 06:03:07.797 plugin-container[1659:11598] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:03:17 INFO - PID 1658 | RSS: Main: 229982208 06:03:17 INFO - PID 1658 | 06:03:20 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 06:03:21 INFO - PID 1658 | RSS: Main: 244695040 06:03:21 INFO - PID 1658 | 06:03:24 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 06:03:24 INFO - PID 1658 | RSS: Main: 253485056 06:03:24 INFO - PID 1658 | 06:03:28 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 06:03:29 INFO - PID 1658 | RSS: Main: 254476288 06:03:29 INFO - PID 1658 | 06:03:31 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 06:03:31 INFO - PID 1658 | RSS: Main: 256499712 06:03:31 INFO - PID 1658 | 06:03:33 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:03:34 INFO - PID 1658 | RSS: Main: 257163264 06:03:34 INFO - PID 1658 | 06:03:37 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 06:03:38 INFO - PID 1658 | RSS: Main: 263081984 06:03:38 INFO - PID 1658 | 06:03:41 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 06:03:42 INFO - PID 1658 | RSS: Main: 262995968 06:03:42 INFO - PID 1658 | 06:03:45 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 06:03:46 INFO - PID 1658 | RSS: Main: 262852608 06:03:46 INFO - PID 1658 | 06:03:47 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:03:48 INFO - PID 1658 | RSS: Main: 262815744 06:03:48 INFO - PID 1658 | 06:03:50 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:03:51 INFO - PID 1658 | RSS: Main: 262656000 06:03:51 INFO - PID 1658 | 06:03:53 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:03:54 INFO - PID 1658 | RSS: Main: 262832128 06:03:54 INFO - PID 1658 | 06:03:57 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:03:58 INFO - PID 1658 | RSS: Main: 263225344 06:03:58 INFO - PID 1658 | 06:04:01 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:04:02 INFO - PID 1658 | RSS: Main: 260448256 06:04:02 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 06:04:04 INFO - PID 1658 | RSS: Main: 263708672 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | __start_tp_report 06:04:04 INFO - PID 1658 | _x_x_mozilla_page_load 06:04:04 INFO - PID 1658 | _x_x_mozilla_page_load_details 06:04:04 INFO - PID 1658 | |i|pagename|runs| 06:04:04 INFO - PID 1658 | |0;ai-astar;112;122;137;182;154;123;123;123;120;121 06:04:04 INFO - PID 1658 | |1;audio-beat-detection;154;116;121;157;117;125;161;160;149;155 06:04:04 INFO - PID 1658 | |2;audio-dft;226;305;252;244;221;216;222;239;219;306 06:04:04 INFO - PID 1658 | |3;audio-fft;85;61;73;119;61;61;58;50;67;54 06:04:04 INFO - PID 1658 | |4;audio-oscillator;81;72;92;72;74;72;84;73;73;90 06:04:04 INFO - PID 1658 | |5;imaging-gaussian-blur;91;109;143;139;93;91;91;92;92;137 06:04:04 INFO - PID 1658 | |6;imaging-darkroom;100;100;111;99;100;99;125;165;132;100 06:04:04 INFO - PID 1658 | |7;imaging-desaturate;104;100;104;112;117;109;154;147;101;101 06:04:04 INFO - PID 1658 | |8;json-parse-financial;92;66;67;67;68;68;67;69;69;67 06:04:04 INFO - PID 1658 | |9;json-stringify-tinderbox;52;102;85;72;65;78;77;83;51;60 06:04:04 INFO - PID 1658 | |10;stanford-crypto-aes;106;121;113;90;88;86;87;85;103;110 06:04:04 INFO - PID 1658 | |11;stanford-crypto-ccm;179;173;147;174;170;162;156;166;204;153 06:04:04 INFO - PID 1658 | |12;stanford-crypto-pbkdf2;229;180;220;254;232;198;201;190;204;246 06:04:04 INFO - PID 1658 | |13;stanford-crypto-sha256-iterative;71;70;71;70;69;66;72;74;71;71 06:04:04 INFO - PID 1658 | __end_tp_report 06:04:04 INFO - PID 1658 | __start_cc_report 06:04:04 INFO - PID 1658 | _x_x_mozilla_cycle_collect,1038 06:04:04 INFO - PID 1658 | __end_cc_report 06:04:04 INFO - PID 1658 | __startTimestamp1490879044399__endTimestamp 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | ------- Summary: start ------- 06:04:04 INFO - PID 1658 | Number of tests: 14 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#0] ai-astar Cycles:10 Average:131.70 Median:123.00 stddev:21.12 (17.2%) stddev-sans-first:21.17 06:04:04 INFO - PID 1658 | Values: 112.0 122.0 137.0 182.0 154.0 123.0 123.0 123.0 120.0 121.0 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:154.50 stddev:19.15 (12.4%) stddev-sans-first:19.77 06:04:04 INFO - PID 1658 | Values: 154.0 116.0 121.0 157.0 117.0 125.0 161.0 160.0 149.0 155.0 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#2] audio-dft Cycles:10 Average:245.00 Median:241.50 stddev:33.98 (14.1%) stddev-sans-first:35.34 06:04:04 INFO - PID 1658 | Values: 226.0 305.0 252.0 244.0 221.0 216.0 222.0 239.0 219.0 306.0 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#3] audio-fft Cycles:10 Average:68.90 Median:61.00 stddev:20.21 (33.1%) stddev-sans-first:20.58 06:04:04 INFO - PID 1658 | Values: 85.0 61.0 73.0 119.0 61.0 61.0 58.0 50.0 67.0 54.0 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:77.50 stddev:7.87 (10.2%) stddev-sans-first:8.29 06:04:04 INFO - PID 1658 | Values: 81.0 72.0 92.0 72.0 74.0 72.0 84.0 73.0 73.0 90.0 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:107.80 Median:91.00 stddev:22.69 (24.9%) stddev-sans-first:23.23 06:04:04 INFO - PID 1658 | Values: 91.0 109.0 143.0 139.0 93.0 91.0 91.0 92.0 92.0 137.0 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#6] imaging-darkroom Cycles:10 Average:113.10 Median:128.50 stddev:21.81 (17.0%) stddev-sans-first:22.61 06:04:04 INFO - PID 1658 | Values: 100.0 100.0 111.0 99.0 100.0 99.0 125.0 165.0 132.0 100.0 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#7] imaging-desaturate Cycles:10 Average:114.90 Median:110.50 stddev:19.59 (17.7%) stddev-sans-first:20.37 06:04:04 INFO - PID 1658 | Values: 104.0 100.0 104.0 112.0 117.0 109.0 154.0 147.0 101.0 101.0 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#8] json-parse-financial Cycles:10 Average:70.00 Median:68.00 stddev:7.79 (11.5%) stddev-sans-first:1.01 06:04:04 INFO - PID 1658 | Values: 92.0 66.0 67.0 67.0 68.0 68.0 67.0 69.0 69.0 67.0 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:74.50 stddev:15.93 (21.4%) stddev-sans-first:15.06 06:04:04 INFO - PID 1658 | Values: 52.0 102.0 85.0 72.0 65.0 78.0 77.0 83.0 51.0 60.0 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#10] stanford-crypto-aes Cycles:10 Average:98.90 Median:85.50 stddev:13.24 (15.5%) stddev-sans-first:13.79 06:04:04 INFO - PID 1658 | Values: 106.0 121.0 113.0 90.0 88.0 86.0 87.0 85.0 103.0 110.0 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:168.40 Median:171.50 stddev:16.09 (9.4%) stddev-sans-first:16.60 06:04:04 INFO - PID 1658 | Values: 179.0 173.0 147.0 174.0 170.0 162.0 156.0 166.0 204.0 153.0 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:215.40 Median:224.50 stddev:24.60 (11.0%) stddev-sans-first:25.60 06:04:04 INFO - PID 1658 | Values: 229.0 180.0 220.0 254.0 232.0 198.0 201.0 190.0 204.0 246.0 06:04:04 INFO - PID 1658 | 06:04:04 INFO - PID 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:71.00 stddev:2.07 (2.9%) stddev-sans-first:2.19 06:04:04 INFO - PID 1658 | Values: 71.0 70.0 71.0 70.0 69.0 66.0 72.0 74.0 71.0 71.0 06:04:04 INFO - PID 1658 | -------- Summary: end -------- 06:04:04 INFO - PID 1658 | 06:04:05 INFO - TEST-INFO | 1658: exit 0 06:04:05 INFO - TEST-OK | kraken | took 64022ms 06:04:05 INFO - SUITE-END | took 1181s 06:04:05 INFO - Completed test suite (00:19:41) 06:04:05 INFO - javascript benchmark 06:04:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7674.477823572268, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3121.3289142834287, 3590.9461358079634, 3611.9705883823117, 3624.8187590620464, 3648.2521083177953, 6128.930927367272, 6235.906461403077, 6239.594426362283, 6273.874522509553, 6291.937080629193, 5162.941596972621, 6165.506759459244, 6202.689865506718, 6255.499560035198, 6313.936860631392, 5680.687562184078, 5704.201411802351, 5740.167675687023, 5773.653580785166, 5847.883042339151, 5614.185943038257, 5704.515116215106, 5808.012637851575, 5815.767369305223, 5912.467877890989, 5507.311586051744, 5532.640378375393, 5572.86067848303, 5691.288588926384, 5701.543876489871, 5640.85897852557, 5666.914996275059, 5694.886102277973, 5726.513246374063, 5735.942640573582, 5451.209574611659, 5599.104143337087, 5614.691191984439, 5620.4098569650005, 5667.518260947824, 6086.239220097488, 6112.6250671334565, 6149.29283132441, 6195.28754193269, 6218.657973811439, 6638.203415590112, 6675.732970681167, 6678.265390807007, 6680.866382672368, 6697.330266973312, 6777.728890844361, 6778.76274330401, 6799.52403331767, 6809.080774095483, 6812.62530560819, 6137.324894261627, 6229.813105606839, 6319.210098737658, 6349.6507692077375, 6356.110144579717, 6875.965620171967, 6908.171019477594, 6933.1333583302085, 6964.895526567106, 7003.5797852129035, 6340.302566717657, 6405.263394709575, 6411.96794016027, 6548.27968923418, 6623.834404139935, 6532.542722009413, 6580.835479112965, 6632.9336706633285, 6766.526343156031, 6770.69531871067, 6691.397774200248, 6700.497462690318, 6705.463562914955, 6713.63075030878, 6714.899276510856, 6364.681765911686, 6401.3598640135615, 6477.514186436036, 6515.837104072436, 6599.274079851212, 5339.46605339463, 5384.326959130109, 5390.946090539122, 5402.243685883979, 5431.83704488866, 4093.713440059167, 4741.028089241697, 4763.857084287477, 4778.259369797687, 4822.0, 5133.614978876599, 6203.720832562455, 6367.363263673596, 6371.458426033839, 6386.393292637192, 6126.529632888164, 6134.693265336716, 6387.616742995435, 6675.699593518303, 6708.3291670833505, 5280.9207861882105, 6322.0, 6335.0, 6351.920173570504, 6353.269374022047, 5128.717920514333, 5693.430656934356, 5960.612560183574, 5965.821025369246, 5995.400459953969, 6282.528810339151, 6657.1012913257055, 6955.513114081966, 6995.580265184105, 7014.0531028310525, 6769.729210831511, 6777.525573209828, 6792.626405547742, 6793.116894803644, 6814.8637027260165, 6871.5189936703955, 6953.895691564529, 6972.302769722987, 6978.825529361807, 6983.6857341418745, 6369.331220221995, 6439.388258115471, 6578.802635920739, 6643.169603799525, 6656.667166641551, 6171.290301615371, 6336.524760642878, 6755.594664320157, 6774.390304872585, 6784.423324017514, 6316.147320111726, 6354.332795056453, 6482.384173503509, 6542.607443553402, 6546.738130474728, 5788.710564471844, 6508.15393998796, 6529.444997175293, 6533.150690410216, 6538.640374779243, 4951.702897826142, 6333.936660633519, 6397.392247736458, 6416.550841441054, 6422.935770642233, 6303.338149494237, 6542.149520562297, 6563.638999855054, 6564.868702625825, 6566.671666416564, 6209.0686397039, 6289.245290564979, 6328.968355158195, 6348.269948955929, 6356.77751278703], "value": 6108.676344896916, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.1825015108157, 753.6156560154321, 837.137748119437, 1283.2428866968487, 1287.5686644973932, 35491.112722181955, 37120.62879371206, 38976.0, 39642.405363919526, 39805.80097099518, 38845.61154388455, 39068.41397379042, 39106.41340379889, 39203.60796392035, 39329.41005884914, 8046.396520260974, 8203.343732501386, 8569.871451928226, 8780.429272097294, 8786.253168480687, 8723.651053957834, 8808.207261346477, 8868.290536757077, 8871.68949086782, 8871.77820554488, 8816.823663526726, 8875.95562022189, 8886.911130888673, 8894.332925030616, 8897.199252067312, 7828.647710853025, 8683.305335573139, 8868.246199073086, 8878.822423551494, 8880.689175878877, 8482.53346065966, 8512.489250644981, 8545.957270213672, 8582.699605513839, 8583.012953510364, 38439.84680459591, 38559.61440385616, 38592.03519912025, 38683.22633547313, 38802.417963730564, 3004.0, 3013.087447947633, 3015.5627434022163, 3026.0770465008163, 3030.10611869499, 27586.68868917807, 27743.45659116008, 27974.580381294298, 28092.0, 28855.42289154205, 2777.180731684158, 2816.605675205473, 2824.61867647867, 2830.8301501909746, 2876.985615071932, 325166.0, 326279.7372026297, 327830.0, 328374.0, 328690.7130928684, 48944.0, 49087.75456122697, 49252.522424327326, 49303.753481233085, 49315.0, 50785.74607127014, 50805.729856753875, 51026.724331892, 51107.744461277456, 51154.74422627863, 12185.817212741817, 12196.756064878653, 14487.855121448862, 14535.055221410574, 14561.927190364193, 11602.013828824645, 11801.586944457074, 11804.822927656092, 11814.704632384259, 11817.0, 10981.780364392827, 11197.93619606136, 13378.799318010238, 13473.932630336982, 13575.728485430236, 5461.754221059982, 5654.46282603152, 5959.523238140939, 6008.519318454513, 6024.518038556905, 970.5341436110706, 981.6711401680517, 985.5121714751244, 993.1806259835664, 1028.1620479309338, 24070.87964560166, 24154.637680434807, 24398.390040249134, 24509.509809803705, 24519.877400612884, 1048.2295512798087, 1059.2055958031476, 1077.6497638267595, 1088.8638920134983, 1135.0, 11959.59394904455, 12292.201006934522, 12324.753504930031, 12403.317817520123, 12405.875941240653, 394940.0, 424798.0, 439493.0, 440228.7988560037, 442308.0, 53914.73042634762, 54575.0, 54629.45370546323, 54917.0, 55167.72416137894, 1464.4288727396324, 1498.8725958293448, 1502.4741340530945, 1506.9321880515404, 1510.3731951240113, 27920.0, 28166.01418950327, 28243.870245190785, 28550.143495695163, 28777.848886044325, 37493.250134997696, 37543.0, 37603.81198094101, 37673.62326376701, 37684.0, 360745.0, 360922.0, 362817.0, 362935.0, 363593.0, 30372.24069398283, 30854.382912342076, 30926.845365773028, 31044.534331984585, 31133.53299700461, 39656.801715991234, 40914.0, 40961.590384095776, 40986.59013409947, 41122.383164251965, 23775.0, 26963.034066253087, 28895.711042889303, 29163.408523800103, 29168.70831291745, 28263.858680706464, 28502.42995140127, 29175.854120729262, 29184.41631167407, 29466.558001630423, 33485.45379278715, 33683.66316336805, 33900.152496187795, 34082.636694532936, 34155.14612134716, 28289.57565636476, 29032.83868645315, 29688.40623187481, 29767.0, 29966.25084372908, 19061.714074289175, 21791.237306694824, 23486.882565587064, 23622.818859057323, 23693.526129477657], "value": 17542.24803127674, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.8266410771584, 423.1009202932459, 432.37090034000533, 442.42484769799256, 446.5512160278921, 19577.412677619675, 21189.57620847582, 21980.450488737788, 22013.339599811992, 22082.33752987409, 18287.0, 20318.69521957168, 21113.47216319593, 21264.46838829026, 21579.352619421403, 5927.288725352963, 6217.84969780592, 6229.221347331583, 6232.6883655817255, 6237.376262373749, 5906.232189815318, 6063.181470501492, 6070.848228794293, 6085.452309292163, 6141.846453838645, 5171.482851714836, 6080.331163572004, 6101.993171126759, 6115.48018418435, 6133.785317513888, 5881.470667639912, 6088.634681919099, 6115.960286751263, 6134.17188679527, 6136.447719705226, 5223.112070947948, 5373.435789242151, 5379.5696344292755, 5424.83725488236, 5433.510984011418, 21654.0, 26026.21921342363, 26146.86926565374, 26254.0, 26573.734262657512, 2043.6014977079476, 2053.856230063896, 2068.172730907634, 2069.1516478244, 2093.612681653899, 15634.497533755237, 15679.0, 15899.44351947688, 16119.27463264156, 16592.502224933152, 1346.3133801761173, 1422.9003969722123, 1568.3099436248015, 1609.316040682705, 1685.679720853034, 153743.0, 154808.0, 156684.21657891752, 163934.18032909758, 164154.0, 11977.700557485958, 12164.0, 12245.204061735958, 12248.0, 12305.0, 15529.145896975775, 15552.533423997298, 15557.988730732466, 15568.766468502981, 15603.921980390025, 8464.534450605275, 8741.994670612961, 8763.342749293828, 8843.91156088431, 8892.0, 8261.876071858927, 8392.20274073962, 8407.789805254917, 8554.486730796172, 8562.015368232733, 6343.5876668016435, 7571.318581327597, 7722.343600793995, 7723.729669461654, 7764.0, 2175.9626464734406, 2186.693862859201, 2189.3103672343477, 2191.5178660694623, 2358.2689366296504, 911.6216770040359, 915.949622770754, 917.348682435465, 945.9290553208536, 952.3381250031161, 15502.0, 18665.346712864986, 19846.1069251884, 20144.294949676692, 20481.590368192552, 908.6501696846761, 919.6137622198693, 964.8662821185079, 983.5475681186591, 1013.7364285285879, 7380.5940673263485, 9466.0, 9926.354786938826, 10231.7442063949, 10310.432926188982, 670850.0, 679059.8141027888, 705250.0, 713453.0, 724208.0, 152311.23844380706, 157475.0, 160841.0, 161524.19237903735, 166631.0, 1202.909781766371, 1209.6371088673363, 1245.8691837357128, 1250.1249125612107, 1258.4022589270023, 15726.606834829221, 20863.79136208679, 21052.26317078895, 21067.262645807325, 21270.680939785605, 54379.45620543744, 61689.0, 62314.68842655758, 62786.686066569375, 62988.0, 88643.3406997791, 89123.10876891407, 89147.55426222828, 89168.0, 90625.54687226521, 8545.529995850287, 8694.434861733967, 8790.252828509649, 8851.955740221258, 8919.777005574653, 12176.695582610506, 12558.748825023265, 13932.303384830515, 14058.929705351407, 14156.362963666867, 10768.784624307313, 11636.650900473, 11821.586244481401, 11833.0, 11943.641690749291, 8289.95855020721, 11047.116230701364, 11126.998570128728, 11296.49165787558, 11343.546258149912, 11729.941350293195, 11820.408979550815, 11954.222975506913, 11962.700932476757, 11980.28118312904, 8303.0, 8307.08622051586, 8430.36772242072, 8782.946046474464, 8850.229954009095, 10939.0, 11353.978141967262, 11849.585264515701, 12049.277043377426, 12081.275123492618], "value": 10270.896247744779, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1176.3118575633255, 1386.1336664584635, 1398.6783039900822, 1406.3882211238106, 1425.2588653899975, 10474.057334839863, 10500.842487362695, 10874.565017399294, 10986.066184374327, 10995.34027958321, 9361.62553497861, 10878.728031799208, 11070.723231919206, 11100.222984391095, 11150.832737508943, 2467.5064987002625, 2473.923843128245, 2493.613489909058, 2494.6008638617823, 2495.15164843953, 2418.697662792151, 2434.963475547859, 2458.0782206672498, 2470.789982851451, 2473.196211231348, 2172.750133734625, 2330.3707998840305, 2432.1609044879488, 2442.8167887408426, 2461.1016978802786, 2358.021421110237, 2418.4800267942387, 2427.2232885476656, 2431.9392015199583, 2432.2946345559767, 2333.7899589036897, 2406.855588664668, 2410.14439873844, 2423.842450240729, 2435.1233555919853, 10949.069329107035, 10994.725131871688, 11055.22613417061, 11061.391623460706, 11078.0, 5337.653052551572, 6122.938770612282, 6289.937100628981, 6305.0542418637115, 6348.873022539569, 3032.302570408818, 3904.730962437219, 3936.744111632763, 3944.6055394460614, 3974.1058261890844, 988.8430536272579, 1045.5713157605346, 1138.163449864349, 1156.7917774800533, 1208.5347141350555, 10984.95642913922, 12763.23420594758, 13284.003699722463, 15010.024348417428, 15262.465813696468, 5239.95200959802, 5401.108817045222, 5422.674639521641, 5423.647462914898, 5443.237946687467, 11467.369294688871, 11526.654200374001, 11532.769344613062, 11549.6535103947, 11595.826062609067, 5950.434708702753, 6177.2587289525545, 6240.157578726905, 6250.843728906813, 6255.155554000152, 4959.7272150032095, 5577.6653400796085, 5716.885662286731, 5777.0467872801355, 5809.041508151192, 3980.880573582739, 4152.0, 4182.100848317566, 4194.664426845845, 4227.0489139943265, 1075.623531763892, 1241.0005195576266, 1261.1991385470292, 1270.8157317188957, 1272.6436597752645, 722.1658983873634, 728.1262485018, 748.3489364253135, 751.2562563062529, 778.5250996891891, 5643.5767317451355, 5706.429357064343, 5887.558433117448, 5918.556108291896, 6033.909491357633, 549.8405462415855, 564.6499170514226, 565.2199964049586, 862.5945805471418, 907.6777743900952, 3973.5430425501436, 4051.2707712611423, 4114.506259248849, 4138.620293331346, 4189.874303770892, 13467.595972120853, 13956.930215348859, 14026.246719160104, 14034.368453419416, 14051.85948140526, 13256.469741210436, 13641.590752277447, 13930.0, 13967.0, 13977.440902363995, 10042.899571004196, 10183.0, 10187.235957303084, 10192.745181370674, 10302.690919272434, 5617.775288988395, 6311.873762524816, 6514.706838192387, 6625.238097618835, 6760.357766012221, 11951.760964780482, 12065.09511786637, 12178.025757939165, 12237.571684991339, 12246.755064898474, 11316.340659944743, 12355.25868447896, 12444.253344798979, 12456.626301210978, 12507.312097834343, 9036.593353298986, 9278.304127190622, 9282.118198771106, 9289.6284148636, 9296.814063718823, 9905.257105717244, 10062.647807326708, 10175.440350780482, 10179.134773544332, 10213.131883789663, 8465.967151010544, 8656.307495400228, 8679.913200868163, 8726.65093396257, 8839.779005524657, 8419.11599282091, 8442.620082096197, 8457.196566326189, 8540.487570745776, 8666.610002550027, 7615.5430674159725, 7736.0, 7804.765857024298, 7814.101378341336, 7822.826576013644, 5764.279465066867, 5782.161586570008, 5837.387074357302, 5891.528677705687, 5976.521878249817, 4984.800607975785, 5845.649261044351, 5960.403959604178, 6064.0, 6121.418465245794], "value": 5057.36883909715, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.9073643913186, 361.85344935301197, 388.98444062237513, 400.46937807405186, 405.9898502537438, 5880.912031274217, 6317.684115794209, 6385.3933876281735, 6411.871762564746, 6415.711292991815, 6140.7850725224625, 6257.155284036654, 6258.812235632927, 6275.592086514384, 6277.591956522823, 3924.3721004639265, 4278.550752171024, 4681.321208424776, 4683.7892294846815, 4725.881852953669, 4079.9592004079877, 4579.587837094661, 4652.604528615055, 4665.183592871251, 4708.952910470903, 4355.586219309176, 4608.870826647476, 4613.1926912790295, 4619.74591397473, 4620.768961551909, 4047.2107938951917, 4226.133642603274, 4344.717593356438, 4529.411176547028, 4624.537546245382, 3824.0, 3924.940266128129, 3950.545687245946, 3982.900427489307, 3987.4218238355274, 5278.947210527884, 6085.9391406085815, 6086.330503644595, 6094.451499365034, 6134.625955724334, 4937.456879743261, 5382.085045542267, 5403.351597808288, 5412.5940554458175, 5427.43011983744, 5955.642661440284, 6242.875142497124, 6455.870222711007, 6636.867262654768, 6658.234303055114, 3702.9444558331647, 4403.581659742319, 4414.713043652152, 4425.668074894395, 4428.756418396987, 6454.741810327629, 6762.966185169043, 6777.175295684582, 7077.0, 7154.356107950257, 4391.626711729538, 4489.708168969007, 4499.212637788374, 4516.006478574708, 4517.593416592523, 3832.2718683450057, 3832.9616703832603, 4601.240795268744, 4605.976970115195, 4610.216263235258, 4563.95436045642, 5285.788568457296, 5440.020796256712, 5440.455954404607, 5452.127659574449, 5222.947770522323, 6148.754049837957, 6697.631630260285, 6724.428423583952, 6760.18877734675, 1549.6125968507872, 1560.9921950390403, 1565.295616972367, 1574.8582627563574, 1575.6297270141508, 971.2327261463365, 1021.0351218098974, 1026.779242462874, 1036.8030074285862, 1037.201354956679, 5598.46814552625, 6292.685365731695, 6293.937060629335, 6330.335314791881, 6395.328490508523, 6241.438270555672, 6747.325267473311, 6960.408365289007, 7008.0188773571745, 7008.7546935857, 5723.856903577361, 6211.689415529206, 6335.16280278714, 6336.619802811846, 6348.333424990402, 3013.5630333601507, 3590.6768390844427, 3606.7294952878638, 3621.9818900905325, 3638.381475149231, 6844.4182244509775, 7099.219085900547, 7114.750983715545, 7129.440914358803, 7185.317394847481, 5977.820665380045, 5979.9402005980255, 6810.284920083419, 7089.399374494195, 7167.673980313659, 6068.757249709962, 6999.65511553625, 7093.3970612498515, 7137.678804453916, 7138.785836424916, 5523.7790488380015, 5725.51333136688, 6039.78860739872, 6930.27232140618, 6940.930590694028, 5382.9192562112385, 6434.581752186093, 6900.861982760417, 6910.341518035535, 6918.377346038679, 7256.4194864409665, 7268.166669164946, 7277.417806575355, 7282.417406607353, 7320.158181808947, 4143.86043837947, 4370.978145109254, 4461.152381047591, 4513.41183758919, 4546.340780586863, 6292.779752708633, 6426.710798014195, 6966.721331146698, 6984.650767461708, 6989.47578931572, 5099.0, 5642.589352661835, 6735.720213159484, 6738.966305168443, 6767.289434609295, 6652.201735791736, 6698.933010669831, 6717.966410167918, 6722.932770672231, 6756.560823546651, 5709.885802284014, 6561.278259391559, 6565.277819439762, 6581.354661334666, 6608.240052393844, 6265.310815810166, 6650.3682150195655, 6674.599524028574, 6722.865542689021, 6733.528652994243, 6535.215774106948, 6552.312007239362, 6562.5890433557015, 6574.967125164344, 6648.700808463727], "value": 4831.088800197953, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.3051839715474, 728.0244472406977, 731.3892899428977, 734.3464316758085, 738.217489461171, 15313.391377070222, 16026.118563479033, 16089.597760056004, 16296.51110466685, 16306.510804675863, 15114.773278400833, 15115.546533603982, 15127.168005759679, 15146.16696081715, 15150.090994540335, 941.6186444489975, 955.9378640388387, 963.8216929867962, 973.8393165127745, 998.4408731110601, 1007.642286988116, 1012.0, 1015.6902144845819, 1017.3793985668736, 1018.7809620931498, 1047.3611097230682, 1059.2691043180182, 1063.6808957312815, 1064.302881612545, 1066.53072648035, 1038.6157121864883, 1052.7262911642952, 1056.9894301057006, 1057.608684786626, 1063.2344711807564, 774.6901239504188, 784.9991261142089, 786.2177133751896, 786.7718361675107, 788.3496115704567, 16411.343546258136, 16617.584560385967, 16685.332586696517, 16892.239849206817, 17060.232289547, 14646.194459304734, 14652.560423187322, 14778.852211477855, 14843.703125937422, 14914.403423863032, 11808.350540720257, 11877.584284550087, 12002.51989920402, 12140.968017718504, 12260.81608775869, 3030.8029978051354, 3043.753331440329, 3079.384123175374, 3153.9053828385186, 3187.1872672468603, 97974.99999999929, 102467.0, 107753.99205076326, 114119.42940285329, 116609.41695291555, 10873.456327183609, 11395.829062564067, 11442.427878606037, 11573.132015098901, 11968.281903085843, 25034.248972530488, 25372.0, 25464.872675636503, 25532.872335638203, 25713.97144114252, 6810.216825065082, 7877.842443151219, 7922.445428819928, 7937.635701474137, 7992.920070799218, 4140.130572579732, 4224.134052519226, 4254.282133407618, 4293.506246781598, 4298.656107511391, 4067.9389809152885, 4133.483314585676, 4158.4178215049915, 4167.333226683716, 4172.207280616673, 776.4875182379602, 779.6920216514445, 785.4580339565681, 786.5673879366326, 801.9478733882279, 535.3361831329124, 558.8658721906794, 582.1587805620837, 584.4535359438938, 600.5465873265656, 9600.423974561409, 9993.600255989679, 10092.192624590161, 10144.898551014543, 10153.847692284622, 550.3313474128872, 556.2351766321308, 568.5508448325859, 582.356496071843, 599.2628273498005, 4514.932276015862, 4578.748168850679, 4639.234526303189, 4658.0, 4701.929471057937, 91747.0, 93613.59579606312, 106976.46511767391, 108719.36920946192, 108798.0, 91537.0, 92802.0, 93742.53128734312, 94511.1449467092, 94737.0526294742, 53506.46493535093, 72348.91476627855, 72661.54676906433, 81568.0, 83008.75486867581, 9243.630254789734, 10844.728881778019, 10984.890151098707, 11102.125577819543, 11205.43972801373, 28141.859290703414, 34943.65056349404, 35439.645603543635, 35642.46536301906, 37327.0, 64616.35383646291, 65053.0, 65433.0, 66755.0, 68906.0, 10184.796304073729, 11892.286462812259, 11973.461194246454, 12015.0, 14076.366563504462, 14621.92689036548, 14645.560633181021, 14854.257287135384, 15091.320890560171, 15412.69174616479, 13197.274149921846, 13693.178409295475, 13745.587632371045, 13952.58142255734, 14254.786178207129, 13394.866051339752, 13561.86438135606, 13697.657558560717, 13946.930265349014, 14797.778033329723, 13089.738205236032, 13277.601671949857, 13379.732405352033, 13390.732185356044, 14020.439182432588, 8895.866562001445, 8970.686025989058, 8979.82040359176, 8981.0, 9094.0, 9362.115927504346, 9760.707178784647, 10348.823389588852, 10819.19294508156, 10911.563537458413], "value": 7597.157558852338, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1696.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 122.0, 137.0, 182.0, 154.0, 123.0, 123.0, 123.0, 120.0, 121.0], "value": 131.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 116.0, 121.0, 157.0, 117.0, 125.0, 161.0, 160.0, 149.0, 155.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [226.0, 305.0, 252.0, 244.0, 221.0, 216.0, 222.0, 239.0, 219.0, 306.0], "value": 245.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 61.0, 73.0, 119.0, 61.0, 61.0, 58.0, 50.0, 67.0, 54.0], "value": 68.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 72.0, 92.0, 72.0, 74.0, 72.0, 84.0, 73.0, 73.0, 90.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 109.0, 143.0, 139.0, 93.0, 91.0, 91.0, 92.0, 92.0, 137.0], "value": 107.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 111.0, 99.0, 100.0, 99.0, 125.0, 165.0, 132.0, 100.0], "value": 113.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 100.0, 104.0, 112.0, 117.0, 109.0, 154.0, 147.0, 101.0, 101.0], "value": 114.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 66.0, 67.0, 67.0, 68.0, 68.0, 67.0, 69.0, 69.0, 67.0], "value": 70.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 102.0, 85.0, 72.0, 65.0, 78.0, 77.0, 83.0, 51.0, 60.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 121.0, 113.0, 90.0, 88.0, 86.0, 87.0, 85.0, 103.0, 110.0], "value": 98.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 173.0, 147.0, 174.0, 170.0, 162.0, 156.0, 166.0, 204.0, 153.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [229.0, 180.0, 220.0, 254.0, 232.0, 198.0, 201.0, 190.0, 204.0, 246.0], "value": 215.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 71.0, 70.0, 69.0, 66.0, 72.0, 74.0, 71.0, 71.0], "value": 70.5, "unit": "score"}]}]} 06:04:05 INFO - Return code: 0 06:04:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:04:05 ERROR - # TBPL SUCCESS # 06:04:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:04:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:04:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:04:05 INFO - 2017-03-30 06:04:05.721 screenresolution[1663:11983] starting screenresolution argv=screenresolution get 06:04:05 INFO - 2017-03-30 06:04:05.724 screenresolution[1663:11983] Display 0: 1600x1200x32@60 06:04:05 INFO - 2017-03-30 06:04:05.730 screenresolution[1664:11986] starting screenresolution argv=screenresolution list 06:04:05 INFO - Available Modes on Display 0 06:04:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:04:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:04:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:04:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:04:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:04:05 INFO - Intel Iris: 06:04:05 INFO - Chipset Model: Intel Iris 06:04:05 INFO - Type: GPU 06:04:05 INFO - Bus: Built-In 06:04:05 INFO - VRAM (Dynamic, Max): 1536 MB 06:04:05 INFO - Vendor: Intel (0x8086) 06:04:05 INFO - Device ID: 0x0a2e 06:04:05 INFO - Revision ID: 0x0009 06:04:05 INFO - Displays: 06:04:05 INFO - Display: 06:04:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:04:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:04:05 INFO - Display Serial Number: 1600x1200 60 06:04:05 INFO - Main Display: Yes 06:04:05 INFO - Mirror: Off 06:04:05 INFO - Online: Yes 06:04:05 INFO - Rotation: Supported 06:04:05 INFO - Return code: 0 06:04:05 INFO - Running post-action listener: _resource_record_post_action 06:04:05 INFO - [mozharness: 2017-03-30 13:04:05.971184Z] Finished run-tests step (success) 06:04:05 INFO - Running post-run listener: _resource_record_post_run 06:04:06 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 35148288; Write bytes: 519240704; Read time: 325; Write time: 1722 06:04:06 INFO - TinderboxPrint: CPU usage
54.2% 06:04:06 INFO - TinderboxPrint: I/O read bytes / time
35,148,288 / 325 06:04:06 INFO - TinderboxPrint: I/O write bytes / time
519,240,704 / 1,722 06:04:06 INFO - TinderboxPrint: CPU idle
2,202.9 (45.8%) 06:04:06 INFO - TinderboxPrint: CPU system
970.6 (20.2%) 06:04:06 INFO - TinderboxPrint: CPU user
1,640.3 (34.1%) 06:04:06 INFO - TinderboxPrint: Swap in / out
243,695,616 / 0 06:04:06 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 198437376; Write bytes: 195115008; Read time: 15971; Write time: 438 06:04:06 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 34120192; Write bytes: 303248384; Read time: 303; Write time: 1224 06:04:06 INFO - Running post-run listener: _upload_blobber_files 06:04:06 INFO - Blob upload gear active. 06:04:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:04:06 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 06:04:06 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:04:06 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:04:06 INFO - (blobuploader) - INFO - Open directory for files ... 06:04:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:04:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:04:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:04:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:04:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:04:07 INFO - (blobuploader) - INFO - Done attempting. 06:04:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:04:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:04:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:04:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:04:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:04:08 INFO - (blobuploader) - INFO - Done attempting. 06:04:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:04:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:04:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:04:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:04:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:04:09 INFO - (blobuploader) - INFO - Done attempting. 06:04:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:04:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:04:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:04:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:04:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:04:12 INFO - (blobuploader) - INFO - Done attempting. 06:04:12 INFO - (blobuploader) - INFO - Iteration through files over. 06:04:12 INFO - Return code: 0 06:04:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:04:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:04:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39dc37978ec6f8bd9a4f44344280a4ff0d8591fdd1345171141cabae31362a087e10e598f026cf7810e6f4f16b332cf721f0110e09b7818d9743208aa24e1f73", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d4e66ea03575ee0d6131c61d34f4e75c4b6b649a8bc4a3dd6e46aebd66168cf79512a5c587b91ff756c17503e93342ca8a3fe4c25642fbcbd8dba4923b52889", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/033759dfa6982e36c2c488c240bea23cc40b754d33cbefc7e2430732bbbf9a6002248cb4f31ad1c3fceff75b8df6ff5034049d7477a174460c476a7f4b717554", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc1e6d51eadcbf3027e62a559f44547a30b4c3160092ea67a31a8a00530d44ddc6200b9c84f266f1961dd00dd35138fa45859231ec7b336b5d600a67b9ff20d1"} 06:04:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:04:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:04:12 INFO - Contents: 06:04:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39dc37978ec6f8bd9a4f44344280a4ff0d8591fdd1345171141cabae31362a087e10e598f026cf7810e6f4f16b332cf721f0110e09b7818d9743208aa24e1f73", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d4e66ea03575ee0d6131c61d34f4e75c4b6b649a8bc4a3dd6e46aebd66168cf79512a5c587b91ff756c17503e93342ca8a3fe4c25642fbcbd8dba4923b52889", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/033759dfa6982e36c2c488c240bea23cc40b754d33cbefc7e2430732bbbf9a6002248cb4f31ad1c3fceff75b8df6ff5034049d7477a174460c476a7f4b717554", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc1e6d51eadcbf3027e62a559f44547a30b4c3160092ea67a31a8a00530d44ddc6200b9c84f266f1961dd00dd35138fa45859231ec7b336b5d600a67b9ff20d1"} 06:04:12 INFO - Running post-run listener: copy_logs_to_upload_dir 06:04:12 INFO - Copying logs to upload dir... 06:04:12 INFO - mkdir: /builds/slave/test/build/upload/logs 06:04:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.118358 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-30 06:04:12.278170) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 06:04:12.281575) ========= 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.HUpyFxVnH3/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.A0iCWLojyl/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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/Firefox/sha512/39dc37978ec6f8bd9a4f44344280a4ff0d8591fdd1345171141cabae31362a087e10e598f026cf7810e6f4f16b332cf721f0110e09b7818d9743208aa24e1f73", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d4e66ea03575ee0d6131c61d34f4e75c4b6b649a8bc4a3dd6e46aebd66168cf79512a5c587b91ff756c17503e93342ca8a3fe4c25642fbcbd8dba4923b52889", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/033759dfa6982e36c2c488c240bea23cc40b754d33cbefc7e2430732bbbf9a6002248cb4f31ad1c3fceff75b8df6ff5034049d7477a174460c476a7f4b717554", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc1e6d51eadcbf3027e62a559f44547a30b4c3160092ea67a31a8a00530d44ddc6200b9c84f266f1961dd00dd35138fa45859231ec7b336b5d600a67b9ff20d1"} build_url:https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011188 build_url: 'https://queue.taskcluster.net/v1/task/OisT47R2SoySIpT-vhwEcQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39dc37978ec6f8bd9a4f44344280a4ff0d8591fdd1345171141cabae31362a087e10e598f026cf7810e6f4f16b332cf721f0110e09b7818d9743208aa24e1f73", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d4e66ea03575ee0d6131c61d34f4e75c4b6b649a8bc4a3dd6e46aebd66168cf79512a5c587b91ff756c17503e93342ca8a3fe4c25642fbcbd8dba4923b52889", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/033759dfa6982e36c2c488c240bea23cc40b754d33cbefc7e2430732bbbf9a6002248cb4f31ad1c3fceff75b8df6ff5034049d7477a174460c476a7f4b717554", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc1e6d51eadcbf3027e62a559f44547a30b4c3160092ea67a31a8a00530d44ddc6200b9c84f266f1961dd00dd35138fa45859231ec7b336b5d600a67b9ff20d1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 06:04:12.317212) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 06:04:12.317633) ========= 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.HUpyFxVnH3/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.A0iCWLojyl/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.005164 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 06:04:12.366791) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 06:04:12.367136) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 06:04:12.367579) ========= ========= Total master_lag: 0.21 =========