builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0358 starttime: 1490738226.75 results: success (0) buildid: 20170328133121 builduid: 13a996dcbaa34cf6adce8adcfe7813ec revision: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:06.749678) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:06.750103) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:06.750376) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:06.774774) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:06.775040) ========= 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.Atphqdw2af/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.9igYFpc9zo/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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-28 14:57:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.04M=0.001s 2017-03-28 14:57:06 (8.04 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136010 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:06.934034) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:06.934347) ========= 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.Atphqdw2af/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.9igYFpc9zo/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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.020262 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:06.982181) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-28 14:57:06.982567) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 272ce6c2572164f5f6a9fba2a980ba9ccf50770c --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 272ce6c2572164f5f6a9fba2a980ba9ccf50770c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Atphqdw2af/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.9igYFpc9zo/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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-28 14:57:07,070 truncating revision to first 12 chars 2017-03-28 14:57:07,070 Setting DEBUG logging. 2017-03-28 14:57:07,071 attempt 1/10 2017-03-28 14:57:07,071 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/272ce6c25721?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 14:57:09,220 unpacking tar archive at: mozilla-central-272ce6c25721/testing/mozharness/ program finished with exit code 0 elapsedTime=2.411383 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-28 14:57:09.418836) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:09.419344) ========= script_repo_revision: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:09.419757) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:09.420025) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 14:57:09.440636) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-28 14:57:09.441242) ========= /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.Atphqdw2af/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.9igYFpc9zo/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 14:57:09 INFO - MultiFileLogger online at 20170328 14:57:09 in /builds/slave/test 14:57:09 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 14:57:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:57:09 INFO - {'append_to_log': False, 14:57:09 INFO - 'base_work_dir': '/builds/slave/test', 14:57:09 INFO - 'blob_upload_branch': 'Firefox', 14:57:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:57:09 INFO - 'branch': 'Firefox', 14:57:09 INFO - 'buildbot_json_path': 'buildprops.json', 14:57:09 INFO - 'config_files': ('talos/mac_config.py',), 14:57:09 INFO - 'default_actions': ('clobber', 14:57:09 INFO - 'read-buildbot-config', 14:57:09 INFO - 'download-and-extract', 14:57:09 INFO - 'populate-webroot', 14:57:09 INFO - 'create-virtualenv', 14:57:09 INFO - 'install', 14:57:09 INFO - 'run-tests'), 14:57:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:57:09 INFO - 'download_minidump_stackwalk': True, 14:57:09 INFO - 'download_symbols': 'ondemand', 14:57:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:57:09 INFO - 'tooltool.py': '/tools/tooltool.py', 14:57:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:57:09 INFO - '/tools/misc-python/virtualenv.py')}, 14:57:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:57:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:57:09 INFO - 'gecko_profile': False, 14:57:09 INFO - 'gecko_profile_interval': 0, 14:57:09 INFO - 'installer_path': 'installer.exe', 14:57:09 INFO - 'log_level': 'info', 14:57:09 INFO - 'log_name': 'talos', 14:57:09 INFO - 'log_to_console': True, 14:57:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:57:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:57:09 INFO - 'opt_config_files': (), 14:57:09 INFO - 'pip_index': False, 14:57:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:57:09 INFO - 'cmd': ('bash', 14:57:09 INFO - '-c', 14:57:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:57:09 INFO - 'enabled': True, 14:57:09 INFO - 'halt_on_failure': False, 14:57:09 INFO - 'name': 'check_screen_resolution'},), 14:57:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:57:09 INFO - 'cmd': ('bash', 14:57:09 INFO - '-c', 14:57:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:57:09 INFO - 'enabled': True, 14:57:09 INFO - 'halt_on_failure': False, 14:57:09 INFO - 'name': 'check_screen_resolution'},), 14:57:09 INFO - 'run_cmd_checks_enabled': True, 14:57:09 INFO - 'suite': 'dromaeojs-e10s', 14:57:09 INFO - 'system_bits': '32', 14:57:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:57:09 INFO - 'title': 't-yosemite-r7-0358', 14:57:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:57:09 INFO - 'use_talos_json': True, 14:57:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:57:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:57:09 INFO - 'work_dir': 'build'} 14:57:09 INFO - [mozharness: 2017-03-28 21:57:09.646952Z] Running clobber step. 14:57:09 INFO - Running pre-action listener: _resource_record_pre_action 14:57:09 INFO - Running main action method: clobber 14:57:09 INFO - rmtree: /builds/slave/test/build 14:57:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:57:09 INFO - Running post-action listener: _resource_record_post_action 14:57:09 INFO - [mozharness: 2017-03-28 21:57:09.843626Z] Finished clobber step (success) 14:57:09 INFO - [mozharness: 2017-03-28 21:57:09.843750Z] Running read-buildbot-config step. 14:57:09 INFO - Running pre-action listener: _resource_record_pre_action 14:57:09 INFO - Running main action method: read_buildbot_config 14:57:09 INFO - Using buildbot properties: 14:57:09 INFO - { 14:57:09 INFO - "project": "", 14:57:09 INFO - "product": "firefox", 14:57:09 INFO - "script_repo_revision": "production", 14:57:09 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:57:09 INFO - "repository": "", 14:57:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:57:09 INFO - "buildid": "20170328133121", 14:57:09 INFO - "pgo_build": "False", 14:57:09 INFO - "basedir": "/builds/slave/test", 14:57:09 INFO - "buildnumber": 134, 14:57:09 INFO - "slavename": "t-yosemite-r7-0358", 14:57:09 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 14:57:09 INFO - "platform": "yosemite_r7", 14:57:09 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:57:09 INFO - "branch": "mozilla-central", 14:57:09 INFO - "repo_path": "mozilla-central", 14:57:09 INFO - "revision": "272ce6c2572164f5f6a9fba2a980ba9ccf50770c", 14:57:09 INFO - "stage_platform": "macosx64", 14:57:09 INFO - "builduid": "13a996dcbaa34cf6adce8adcfe7813ec", 14:57:09 INFO - "slavebuilddir": "test" 14:57:09 INFO - } 14:57:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 14:57:09 INFO - Running post-action listener: _resource_record_post_action 14:57:09 INFO - [mozharness: 2017-03-28 21:57:09.848057Z] Finished read-buildbot-config step (success) 14:57:09 INFO - [mozharness: 2017-03-28 21:57:09.848261Z] Running download-and-extract step. 14:57:09 INFO - Running pre-action listener: _resource_record_pre_action 14:57:09 INFO - Running main action method: download_and_extract 14:57:09 INFO - mkdir: /builds/slave/test/build/tests 14:57:09 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')]} 14:57:09 INFO - trying https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:57:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/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 14:57:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/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 14:57:11 INFO - Downloaded 1325 bytes. 14:57:11 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:57:11 INFO - Using the following test package requirements: 14:57:11 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:11 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:57:11 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:57:11 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:11 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:57:11 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:11 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:57:11 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:11 INFO - u'jsshell-mac.zip'], 14:57:11 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:11 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:57:11 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:57:11 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:11 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:57:11 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:11 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:57:11 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:11 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:57:11 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:57:11 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:57:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:57:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:57:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:57:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:57:12 INFO - Expected file size: 52507148 14:57:12 INFO - Obtained file size: 52507148 14:57:29 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 14:57:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:57:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:57:29 INFO - Fetch https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:57:30 INFO - Expected file size: 52507148 14:57:30 INFO - Obtained file size: 52507148 14:57:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:57:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:57:46 INFO - Fetch https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:57:47 INFO - Expected file size: 14404171 14:57:47 INFO - Obtained file size: 14404171 14:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:57:47 INFO - trying https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:57:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:57:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/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 14:57:49 INFO - Downloaded 75590826 bytes. 14:57:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:57:49 INFO - mkdir: /builds/slave/test/properties 14:57:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:57:49 INFO - Writing to file /builds/slave/test/properties/build_url 14:57:49 INFO - Contents: 14:57:49 INFO - build_url:https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:57:50 INFO - Running post-action listener: _resource_record_post_action 14:57:50 INFO - Running post-action listener: set_extra_try_arguments 14:57:50 INFO - [mozharness: 2017-03-28 21:57:50.582000Z] Finished download-and-extract step (success) 14:57:50 INFO - [mozharness: 2017-03-28 21:57:50.582131Z] Running populate-webroot step. 14:57:50 INFO - Running pre-action listener: _resource_record_pre_action 14:57:50 INFO - Running main action method: populate_webroot 14:57:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:57:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:57:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:57:50 INFO - '--tppagecycles', 14:57:50 INFO - '7'], 14:57:50 INFO - 'tests': ['tsvgm']}}, 14:57:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:57:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:57:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:57:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:57:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:57:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:57:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:57:50 INFO - 'talos_options': ['--disable-e10s'], 14:57:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:57:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:57:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:57:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:57:50 INFO - 'talos_options': ['--disable-e10s'], 14:57:50 INFO - 'tests': ['damp', 'tps']}, 14:57:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:57:50 INFO - 'tests': ['damp', 'tps']}, 14:57:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:57:50 INFO - 'tests': ['dromaeo_dom']}, 14:57:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:57:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:57:50 INFO - 'tests': ['basic_compositor_video']}, 14:57:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:57:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:57:50 INFO - 'tests': ['a11yr', 14:57:50 INFO - 'ts_paint', 14:57:50 INFO - 'tpaint', 14:57:50 INFO - 'sessionrestore', 14:57:50 INFO - 'sessionrestore_no_auto_restore', 14:57:50 INFO - 'tabpaint']}, 14:57:50 INFO - 'other-e10s': {'tests': ['a11yr', 14:57:50 INFO - 'ts_paint', 14:57:50 INFO - 'tpaint', 14:57:50 INFO - 'sessionrestore', 14:57:50 INFO - 'sessionrestore_no_auto_restore', 14:57:50 INFO - 'tabpaint']}, 14:57:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:57:50 INFO - 'ts_paint', 14:57:50 INFO - 'tpaint', 14:57:50 INFO - 'sessionrestore', 14:57:50 INFO - 'sessionrestore_no_auto_restore', 14:57:50 INFO - 'tabpaint']}, 14:57:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:57:50 INFO - 'ts_paint', 14:57:50 INFO - 'tpaint', 14:57:50 INFO - 'sessionrestore', 14:57:50 INFO - 'sessionrestore_no_auto_restore', 14:57:50 INFO - 'tabpaint']}, 14:57:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:57:50 INFO - 'tests': ['a11yr', 14:57:50 INFO - 'ts_paint', 14:57:50 INFO - 'tpaint', 14:57:50 INFO - 'sessionrestore', 14:57:50 INFO - 'sessionrestore_no_auto_restore', 14:57:50 INFO - 'tabpaint']}, 14:57:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:57:50 INFO - 'tests': ['a11yr', 14:57:50 INFO - 'ts_paint', 14:57:50 INFO - 'tpaint', 14:57:50 INFO - 'sessionrestore', 14:57:50 INFO - 'sessionrestore_no_auto_restore', 14:57:50 INFO - 'tabpaint']}, 14:57:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:57:50 INFO - 'tests': ['tsvgx', 14:57:50 INFO - 'tsvgr_opacity', 14:57:50 INFO - 'tart', 14:57:50 INFO - 'tscrollx', 14:57:50 INFO - 'cart', 14:57:50 INFO - 'tsvg_static']}, 14:57:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:57:50 INFO - 'tsvgr_opacity', 14:57:50 INFO - 'tart', 14:57:50 INFO - 'tscrollx', 14:57:50 INFO - 'cart', 14:57:50 INFO - 'tsvg_static']}, 14:57:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:57:50 INFO - 'talos_options': ['--disable-e10s'], 14:57:50 INFO - 'tests': ['tp5o']}, 14:57:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:57:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:57:50 INFO - 'talos_options': ['--disable-e10s', 14:57:50 INFO - '--xperf_path', 14:57:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:57:50 INFO - 'tests': ['tp5n']}, 14:57:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:57:50 INFO - 'talos_options': ['--xperf_path', 14:57:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:57:50 INFO - 'tests': ['tp5n']}}, 14:57:50 INFO - 'talos.zip': {'path': '', 14:57:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:57:50 INFO - Running post-action listener: _resource_record_post_action 14:57:50 INFO - [mozharness: 2017-03-28 21:57:50.600765Z] Finished populate-webroot step (success) 14:57:50 INFO - [mozharness: 2017-03-28 21:57:50.600899Z] Running create-virtualenv step. 14:57:50 INFO - Running pre-action listener: _resource_record_pre_action 14:57:50 INFO - Running main action method: create_virtualenv 14:57:50 INFO - Creating virtualenv /builds/slave/test/build/venv 14:57:50 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 14:57:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:57:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:57:50 INFO - Using real prefix '/tools/python27' 14:57:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:57:51 INFO - Installing distribute.............................................................................................................................................................................................done. 14:57:54 INFO - Installing pip.................done. 14:57:54 INFO - Return code: 0 14:57:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:57:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:57:54 INFO - Reading from file tmpfile_stdout 14:57:54 INFO - Output received: 14:57:54 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) 14:57:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:57:54 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')]} 14:57:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:57:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:54 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')]} 14:57:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:57:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:54 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 0x1018a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa682dac070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101685d30>, '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 0x1015acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10167abe8>, '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.Atphqdw2af/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.9igYFpc9zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 14:57:54 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 14:57:54 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 14:57:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Atphqdw2af/Render', 14:57:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:57:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:57:54 INFO - 'HOME': '/Users/cltbld', 14:57:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:57:54 INFO - 'LOGNAME': 'cltbld', 14:57:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:54 INFO - 'MOZ_NO_REMOTE': '1', 14:57:54 INFO - 'NO_EM_RESTART': '1', 14:57:54 INFO - 'PAGER': '/bin/cat', 14:57:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:57:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:57:54 INFO - 'PWD': '/builds/slave/test', 14:57:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:57:54 INFO - 'SHELL': '/bin/bash', 14:57:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9igYFpc9zo/Listeners', 14:57:54 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 14:57:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:57:54 INFO - 'USER': 'cltbld', 14:57:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:57:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:57:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:54 INFO - 'XPC_FLAGS': '0x0', 14:57:54 INFO - 'XPC_SERVICE_NAME': '0', 14:57:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:57:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:57:54 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 14:57:54 INFO - Cleaning up... 14:57:54 INFO - Return code: 0 14:57:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:57:54 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')]} 14:57:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:57:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:54 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')]} 14:57:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:57:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:54 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 0x1018a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa682dac070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101685d30>, '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 0x1015acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10167abe8>, '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.Atphqdw2af/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.9igYFpc9zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 14:57:54 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 14:57:54 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 14:57:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Atphqdw2af/Render', 14:57:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:57:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:57:54 INFO - 'HOME': '/Users/cltbld', 14:57:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:57:54 INFO - 'LOGNAME': 'cltbld', 14:57:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:54 INFO - 'MOZ_NO_REMOTE': '1', 14:57:54 INFO - 'NO_EM_RESTART': '1', 14:57:54 INFO - 'PAGER': '/bin/cat', 14:57:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:57:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:57:54 INFO - 'PWD': '/builds/slave/test', 14:57:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:57:54 INFO - 'SHELL': '/bin/bash', 14:57:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9igYFpc9zo/Listeners', 14:57:54 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 14:57:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:57:54 INFO - 'USER': 'cltbld', 14:57:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:57:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:57:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:54 INFO - 'XPC_FLAGS': '0x0', 14:57:54 INFO - 'XPC_SERVICE_NAME': '0', 14:57:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:57:55 INFO - Downloading/unpacking psutil>=3.1.1 14:57:55 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 14:57:55 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 14:57:55 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 14:57:55 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 14:57:55 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 14:57:55 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 14:57:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:57:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:57:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:57:58 INFO - Installing collected packages: psutil 14:57:58 INFO - Running setup.py install for psutil 14:57:58 INFO - building 'psutil._psutil_osx' extension 14:57:58 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 14:57:58 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 14:57:59 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 14:57:59 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 14:57:59 INFO - building 'psutil._psutil_posix' extension 14:57:59 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 14:57:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:57:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:57:59 INFO - ^ 14:57:59 INFO - 1 warning generated. 14:57:59 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 14:57:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:57:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:57:59 INFO - Successfully installed psutil 14:57:59 INFO - Cleaning up... 14:57:59 INFO - Return code: 0 14:57:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:57:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:57:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:57:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1018a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa682dac070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101685d30>, '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 0x1015acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10167abe8>, '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.Atphqdw2af/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.9igYFpc9zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 14:57:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:57:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:57:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Atphqdw2af/Render', 14:57:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:57:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:57:59 INFO - 'HOME': '/Users/cltbld', 14:57:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:57:59 INFO - 'LOGNAME': 'cltbld', 14:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:59 INFO - 'MOZ_NO_REMOTE': '1', 14:57:59 INFO - 'NO_EM_RESTART': '1', 14:57:59 INFO - 'PAGER': '/bin/cat', 14:57:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:57:59 INFO - 'PWD': '/builds/slave/test', 14:57:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:57:59 INFO - 'SHELL': '/bin/bash', 14:57:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9igYFpc9zo/Listeners', 14:57:59 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 14:57:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:57:59 INFO - 'USER': 'cltbld', 14:57:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:57:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:59 INFO - 'XPC_FLAGS': '0x0', 14:57:59 INFO - 'XPC_SERVICE_NAME': '0', 14:57:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:57:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:57:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:57:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:57:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:58:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:58:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:58:02 INFO - Installing collected packages: mozsystemmonitor 14:58:02 INFO - Running setup.py install for mozsystemmonitor 14:58:02 INFO - Successfully installed mozsystemmonitor 14:58:02 INFO - Cleaning up... 14:58:02 INFO - Return code: 0 14:58:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:02 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 0x1018a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa682dac070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101685d30>, '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 0x1015acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10167abe8>, '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.Atphqdw2af/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.9igYFpc9zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 14:58:02 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 14:58:02 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 14:58:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Atphqdw2af/Render', 14:58:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:02 INFO - 'HOME': '/Users/cltbld', 14:58:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:02 INFO - 'LOGNAME': 'cltbld', 14:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:02 INFO - 'MOZ_NO_REMOTE': '1', 14:58:02 INFO - 'NO_EM_RESTART': '1', 14:58:02 INFO - 'PAGER': '/bin/cat', 14:58:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:02 INFO - 'PWD': '/builds/slave/test', 14:58:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:02 INFO - 'SHELL': '/bin/bash', 14:58:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9igYFpc9zo/Listeners', 14:58:02 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 14:58:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:02 INFO - 'USER': 'cltbld', 14:58:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:02 INFO - 'XPC_FLAGS': '0x0', 14:58:02 INFO - 'XPC_SERVICE_NAME': '0', 14:58:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:02 INFO - Downloading/unpacking jsonschema==2.5.1 14:58:02 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 14:58:02 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 14:58:02 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 14:58:02 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 14:58:02 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 14:58:02 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 14:58:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:58:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:06 INFO - Downloading functools32-3.2.3-2.tar.gz 14:58:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:58:06 INFO - warning: no files found matching '*.txt' 14:58:06 INFO - no previously-included directories found matching 'build' 14:58:06 INFO - no previously-included directories found matching 'dist' 14:58:06 INFO - no previously-included directories found matching '.git*' 14:58:06 INFO - Installing collected packages: jsonschema, functools32 14:58:06 INFO - Running setup.py install for functools32 14:58:06 INFO - warning: no files found matching '*.txt' 14:58:06 INFO - no previously-included directories found matching 'build' 14:58:06 INFO - no previously-included directories found matching 'dist' 14:58:06 INFO - no previously-included directories found matching '.git*' 14:58:06 INFO - Successfully installed jsonschema functools32 14:58:06 INFO - Cleaning up... 14:58:06 INFO - Return code: 0 14:58:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:58:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1018a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa682dac070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101685d30>, '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 0x1015acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10167abe8>, '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.Atphqdw2af/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.9igYFpc9zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 14:58:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:58:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:58:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Atphqdw2af/Render', 14:58:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:07 INFO - 'HOME': '/Users/cltbld', 14:58:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:07 INFO - 'LOGNAME': 'cltbld', 14:58:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:07 INFO - 'MOZ_NO_REMOTE': '1', 14:58:07 INFO - 'NO_EM_RESTART': '1', 14:58:07 INFO - 'PAGER': '/bin/cat', 14:58:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:07 INFO - 'PWD': '/builds/slave/test', 14:58:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:07 INFO - 'SHELL': '/bin/bash', 14:58:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9igYFpc9zo/Listeners', 14:58:07 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 14:58:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:07 INFO - 'USER': 'cltbld', 14:58:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:07 INFO - 'XPC_FLAGS': '0x0', 14:58:07 INFO - 'XPC_SERVICE_NAME': '0', 14:58:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:58:07 INFO - Cleaning up... 14:58:07 INFO - Return code: 0 14:58:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1018a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa682dac070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101685d30>, '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 0x1015acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10167abe8>, '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.Atphqdw2af/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.9igYFpc9zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 14:58:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:58:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:58:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Atphqdw2af/Render', 14:58:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:07 INFO - 'HOME': '/Users/cltbld', 14:58:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:07 INFO - 'LOGNAME': 'cltbld', 14:58:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:07 INFO - 'MOZ_NO_REMOTE': '1', 14:58:07 INFO - 'NO_EM_RESTART': '1', 14:58:07 INFO - 'PAGER': '/bin/cat', 14:58:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:07 INFO - 'PWD': '/builds/slave/test', 14:58:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:07 INFO - 'SHELL': '/bin/bash', 14:58:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9igYFpc9zo/Listeners', 14:58:07 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 14:58:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:07 INFO - 'USER': 'cltbld', 14:58:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:07 INFO - 'XPC_FLAGS': '0x0', 14:58:07 INFO - 'XPC_SERVICE_NAME': '0', 14:58:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:07 INFO - Downloading/unpacking blobuploader==1.2.4 14:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:10 INFO - Downloading blobuploader-1.2.4.tar.gz 14:58:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:58:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:58:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:11 INFO - Downloading docopt-0.6.1.tar.gz 14:58:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:58:11 INFO - Installing collected packages: blobuploader, requests, docopt 14:58:11 INFO - Running setup.py install for blobuploader 14:58:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:58:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:58:11 INFO - Running setup.py install for requests 14:58:12 INFO - Running setup.py install for docopt 14:58:12 INFO - Successfully installed blobuploader requests docopt 14:58:12 INFO - Cleaning up... 14:58:12 INFO - Return code: 0 14:58:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:12 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 0x1018a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa682dac070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101685d30>, '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 0x1015acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10167abe8>, '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.Atphqdw2af/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.9igYFpc9zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 14:58:12 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 14:58:12 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 14:58:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Atphqdw2af/Render', 14:58:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:12 INFO - 'HOME': '/Users/cltbld', 14:58:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:12 INFO - 'LOGNAME': 'cltbld', 14:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:12 INFO - 'MOZ_NO_REMOTE': '1', 14:58:12 INFO - 'NO_EM_RESTART': '1', 14:58:12 INFO - 'PAGER': '/bin/cat', 14:58:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:12 INFO - 'PWD': '/builds/slave/test', 14:58:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:12 INFO - 'SHELL': '/bin/bash', 14:58:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9igYFpc9zo/Listeners', 14:58:12 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 14:58:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:12 INFO - 'USER': 'cltbld', 14:58:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:12 INFO - 'XPC_FLAGS': '0x0', 14:58:12 INFO - 'XPC_SERVICE_NAME': '0', 14:58:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:58:12 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-jopvfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:58:12 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-bffgWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:58:12 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-zuiR6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:58:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-7pHVLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:58:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-IwfXHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:58:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-JlFrDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:58:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-dMAKiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:58:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-LD6o0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:58:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-0lnGee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:58:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-DyhOAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:58:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-W4YZS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:58:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Qk6f6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:58:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-8Zo8D0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:58:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ftdTvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:58:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-b39jHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:58:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-I08FOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:58:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-O3Itfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:58:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:58:14 INFO - Running setup.py install for manifestparser 14:58:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:58:14 INFO - Running setup.py install for mozcrash 14:58:14 INFO - Running setup.py install for mozdebug 14:58:14 INFO - Running setup.py install for mozdevice 14:58:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:58:15 INFO - Running setup.py install for mozfile 14:58:15 INFO - Running setup.py install for mozhttpd 14:58:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:58:15 INFO - Running setup.py install for mozinfo 14:58:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:58:15 INFO - Running setup.py install for mozInstall 14:58:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:58:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:58:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:58:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:58:15 INFO - Running setup.py install for mozleak 14:58:15 INFO - Running setup.py install for mozlog 14:58:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:58:15 INFO - Running setup.py install for moznetwork 14:58:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:58:16 INFO - Running setup.py install for mozprocess 14:58:16 INFO - Running setup.py install for mozprofile 14:58:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:58:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:58:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:58:16 INFO - Running setup.py install for mozrunner 14:58:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:58:16 INFO - Running setup.py install for mozscreenshot 14:58:16 INFO - Running setup.py install for moztest 14:58:16 INFO - Running setup.py install for mozversion 14:58:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:58:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:58:16 INFO - Cleaning up... 14:58:17 INFO - Return code: 0 14:58:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:58:17 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')]} 14:58:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:17 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')]} 14:58:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:17 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 0x1018a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa682dac070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101685d30>, '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 0x1015acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10167abe8>, '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.Atphqdw2af/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.9igYFpc9zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 14:58:17 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 14:58:17 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 14:58:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Atphqdw2af/Render', 14:58:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:17 INFO - 'HOME': '/Users/cltbld', 14:58:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:17 INFO - 'LOGNAME': 'cltbld', 14:58:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:17 INFO - 'MOZ_NO_REMOTE': '1', 14:58:17 INFO - 'NO_EM_RESTART': '1', 14:58:17 INFO - 'PAGER': '/bin/cat', 14:58:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:17 INFO - 'PWD': '/builds/slave/test', 14:58:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:17 INFO - 'SHELL': '/bin/bash', 14:58:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9igYFpc9zo/Listeners', 14:58:17 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 14:58:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:17 INFO - 'USER': 'cltbld', 14:58:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:17 INFO - 'XPC_FLAGS': '0x0', 14:58:17 INFO - 'XPC_SERVICE_NAME': '0', 14:58:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:58:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-5S3JdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:58:17 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)) 14:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:58:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-lKXwk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:58:17 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)) 14:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:58:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-clHCbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:58:17 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)) 14:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:58:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Qv8GCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:58:17 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)) 14:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:58:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-nJMA19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:58:17 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)) 14:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:58:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-k0paQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:58:17 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)) 14:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:58:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-m3aERa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:58:18 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)) 14:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:58:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-QFeadO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:58:18 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)) 14:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:58:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-fdxlX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:58:18 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)) 14:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:58:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-8WOJmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:58:18 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)) 14:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:58:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-etPGb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:58:18 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)) 14:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:58:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-xI6lFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:58:18 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)) 14:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:58:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-6XSX4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:58:18 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)) 14:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:58:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Gc6DBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:58:18 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)) 14:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:58:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-yFioN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:58:18 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)) 14:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:58:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-8iwAdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:58:19 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)) 14:58:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:58:19 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Jzj3Th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:58:19 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)) 14:58:19 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)) 14:58:19 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)) 14:58:19 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)) 14:58:19 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)) 14:58:19 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)) 14:58:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:58:19 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 14:58:19 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 14:58:19 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 14:58:19 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 14:58:19 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 14:58:19 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 14:58:21 INFO - Downloading blessings-1.6.tar.gz 14:58:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:58:21 INFO - Installing collected packages: blessings 14:58:21 INFO - Running setup.py install for blessings 14:58:22 INFO - Successfully installed blessings 14:58:22 INFO - Cleaning up... 14:58:22 INFO - Return code: 0 14:58:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:58:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:58:22 INFO - Reading from file tmpfile_stdout 14:58:22 INFO - Current package versions: 14:58:22 INFO - blessings == 1.6 14:58:22 INFO - blobuploader == 1.2.4 14:58:22 INFO - docopt == 0.6.1 14:58:22 INFO - functools32 == 3.2.3-2 14:58:22 INFO - jsonschema == 2.5.1 14:58:22 INFO - manifestparser == 1.1 14:58:22 INFO - mozInstall == 1.12 14:58:22 INFO - mozcrash == 1.0 14:58:22 INFO - mozdebug == 0.1 14:58:22 INFO - mozdevice == 0.49 14:58:22 INFO - mozfile == 1.2 14:58:22 INFO - mozhttpd == 0.7 14:58:22 INFO - mozinfo == 0.9 14:58:22 INFO - mozleak == 0.1 14:58:22 INFO - mozlog == 3.4 14:58:22 INFO - moznetwork == 0.27 14:58:22 INFO - mozprocess == 0.25 14:58:22 INFO - mozprofile == 0.28 14:58:22 INFO - mozrunner == 6.13 14:58:22 INFO - mozscreenshot == 0.1 14:58:22 INFO - mozsystemmonitor == 0.3 14:58:22 INFO - moztest == 0.8 14:58:22 INFO - mozversion == 1.4 14:58:22 INFO - psutil == 3.1.1 14:58:22 INFO - requests == 1.2.3 14:58:22 INFO - wsgiref == 0.1.2 14:58:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x1018a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa682dac070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101685d30>, '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 0x1015acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10167abe8>, '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.Atphqdw2af/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.9igYFpc9zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 14:58:22 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 14:58:22 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 14:58:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Atphqdw2af/Render', 14:58:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:22 INFO - 'HOME': '/Users/cltbld', 14:58:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:22 INFO - 'LOGNAME': 'cltbld', 14:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:22 INFO - 'MOZ_NO_REMOTE': '1', 14:58:22 INFO - 'NO_EM_RESTART': '1', 14:58:22 INFO - 'PAGER': '/bin/cat', 14:58:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:22 INFO - 'PWD': '/builds/slave/test', 14:58:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:22 INFO - 'SHELL': '/bin/bash', 14:58:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9igYFpc9zo/Listeners', 14:58:22 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 14:58:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:22 INFO - 'USER': 'cltbld', 14:58:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:22 INFO - 'XPC_FLAGS': '0x0', 14:58:22 INFO - 'XPC_SERVICE_NAME': '0', 14:58:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:22 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)) 14:58:22 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)) 14:58:22 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)) 14:58:22 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)) 14:58:22 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)) 14:58:22 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)) 14:58:22 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)) 14:58:22 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)) 14:58:22 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)) 14:58:22 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)) 14:58:22 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)) 14:58:22 INFO - Cleaning up... 14:58:22 INFO - Return code: 0 14:58:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:58:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:22 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 0x1018a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018aa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa682dac070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101685d30>, '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 0x1015acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10167abe8>, '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.Atphqdw2af/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.9igYFpc9zo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 14:58:22 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 14:58:22 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 14:58:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Atphqdw2af/Render', 14:58:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:22 INFO - 'HOME': '/Users/cltbld', 14:58:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:22 INFO - 'LOGNAME': 'cltbld', 14:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:22 INFO - 'MOZ_NO_REMOTE': '1', 14:58:22 INFO - 'NO_EM_RESTART': '1', 14:58:22 INFO - 'PAGER': '/bin/cat', 14:58:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:22 INFO - 'PWD': '/builds/slave/test', 14:58:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:22 INFO - 'SHELL': '/bin/bash', 14:58:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9igYFpc9zo/Listeners', 14:58:22 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 14:58:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:22 INFO - 'USER': 'cltbld', 14:58:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:22 INFO - 'XPC_FLAGS': '0x0', 14:58:22 INFO - 'XPC_SERVICE_NAME': '0', 14:58:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:58:22 INFO - Cleaning up... 14:58:22 INFO - Return code: 0 14:58:22 INFO - Running post-action listener: _resource_record_post_action 14:58:22 INFO - Running post-action listener: _start_resource_monitoring 14:58:22 INFO - Starting resource monitoring. 14:58:22 INFO - [mozharness: 2017-03-28 21:58:22.964138Z] Finished create-virtualenv step (success) 14:58:22 INFO - [mozharness: 2017-03-28 21:58:22.965114Z] Running install step. 14:58:22 INFO - Running pre-action listener: _resource_record_pre_action 14:58:22 INFO - Running main action method: install 14:58:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:58:23 INFO - Reading from file tmpfile_stdout 14:58:23 INFO - Detecting whether we're running mozinstall >=1.0... 14:58:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:58:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:58:23 INFO - Reading from file tmpfile_stdout 14:58:23 INFO - Output received: 14:58:23 INFO - Usage: mozinstall [options] installer 14:58:23 INFO - Options: 14:58:23 INFO - -h, --help show this help message and exit 14:58:23 INFO - -d DEST, --destination=DEST 14:58:23 INFO - Directory to install application into. [default: 14:58:23 INFO - "/builds/slave/test"] 14:58:23 INFO - --app=APP Application being installed. [default: firefox] 14:58:23 INFO - mkdir: /builds/slave/test/build/application 14:58:23 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'] 14:58:23 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 14:58:43 INFO - Reading from file tmpfile_stdout 14:58:43 INFO - Output received: 14:58:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:58:43 INFO - Running post-action listener: _resource_record_post_action 14:58:43 INFO - [mozharness: 2017-03-28 21:58:43.671924Z] Finished install step (success) 14:58:43 INFO - [mozharness: 2017-03-28 21:58:43.672081Z] Running run-tests step. 14:58:43 INFO - Running pre-action listener: _resource_record_pre_action 14:58:43 INFO - Running main action method: run_tests 14:58:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:58:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:58:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:58:43 INFO - 2017-03-28 14:58:43.686 screenresolution[1891:9006] starting screenresolution argv=screenresolution get 14:58:43 INFO - 2017-03-28 14:58:43.689 screenresolution[1891:9006] Display 0: 1600x1200x32@60 14:58:43 INFO - 2017-03-28 14:58:43.694 screenresolution[1892:9008] starting screenresolution argv=screenresolution list 14:58:43 INFO - Available Modes on Display 0 14:58:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:58:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:58:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:58:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:58:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:58:43 INFO - Intel Iris: 14:58:43 INFO - Chipset Model: Intel Iris 14:58:43 INFO - Type: GPU 14:58:43 INFO - Bus: Built-In 14:58:43 INFO - VRAM (Dynamic, Max): 1536 MB 14:58:43 INFO - Vendor: Intel (0x8086) 14:58:43 INFO - Device ID: 0x0a2e 14:58:43 INFO - Revision ID: 0x0009 14:58:43 INFO - Displays: 14:58:43 INFO - Display: 14:58:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:58:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:58:43 INFO - Display Serial Number: 1600x1200 60 14:58:43 INFO - Main Display: Yes 14:58:43 INFO - Mirror: Off 14:58:43 INFO - Online: Yes 14:58:43 INFO - Rotation: Supported 14:58:43 INFO - Return code: 0 14:58:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:58:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:58:43 INFO - Python 2.7.3 14:58:43 INFO - Return code: 0 14:58:43 INFO - grabbing minidump binary from tooltool 14:58:43 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')]} 14:58:43 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 0x101685d30>, '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 0x1015acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10167abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:58:43 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 14:58:43 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 14:58:43 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 14:58:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:58:44 INFO - Return code: 0 14:58:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:58:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:58:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:58:44 INFO - ENV: RUST_BACKTRACE is now 1 14:58:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:58:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:58:44 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/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0358', '--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 14:58:44 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/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0358 --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 14:58:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Atphqdw2af/Render', 14:58:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:58:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:58:44 INFO - 'HOME': '/Users/cltbld', 14:58:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:58:44 INFO - 'LOGNAME': 'cltbld', 14:58:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:58:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:58:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:44 INFO - 'MOZ_NO_REMOTE': '1', 14:58:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:58:44 INFO - 'NO_EM_RESTART': '1', 14:58:44 INFO - 'PAGER': '/bin/cat', 14:58:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:58:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:44 INFO - 'PWD': '/builds/slave/test', 14:58:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:58:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:58:44 INFO - 'RUST_BACKTRACE': '1', 14:58:44 INFO - 'SHELL': '/bin/bash', 14:58:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9igYFpc9zo/Listeners', 14:58:44 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 14:58:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:58:44 INFO - 'USER': 'cltbld', 14:58:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:58:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:58:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:44 INFO - 'XPC_FLAGS': '0x0', 14:58:44 INFO - 'XPC_SERVICE_NAME': '0', 14:58:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:58:44 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/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0358', '--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 14:58:44 INFO - mozversion application_buildid: 20170328133121 14:58:44 INFO - mozversion application_changeset: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c 14:58:44 INFO - mozversion application_display_name: Nightly 14:58:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:58:44 INFO - mozversion application_name: Firefox 14:58:44 INFO - mozversion application_remotingname: firefox 14:58:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:58:44 INFO - mozversion application_vendor: Mozilla 14:58:44 INFO - mozversion application_version: 55.0a1 14:58:44 INFO - mozversion platform_buildid: 20170328133121 14:58:44 INFO - mozversion platform_changeset: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c 14:58:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:58:44 INFO - mozversion platform_version: 55.0a1 14:58:44 INFO - using testdate: 1490738324 14:58:44 INFO - actual date: 1490738324 14:58:44 INFO - starting webserver on 'localhost:49423' 14:58:44 INFO - SUITE-START | Running 2 tests 14:58:44 INFO - TEST-START | dromaeo_css 14:58:44 INFO - operating with platform_type : mac_ 14:58:44 INFO - Initialising browser for dromaeo_css test... 14:58:44 INFO - TEST-INFO | started process 1902 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpXskkAA/profile http://localhost:49423/getInfo.html) 14:58:47 INFO - PID 1902 | 2017-03-28 14:58:47.185 plugin-container[1904:9179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 14:58:47 INFO - PID 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:58:47 INFO - PID 1902 | 2017-03-28 14:58:47.188 plugin-container[1904:9179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 14:58:47 INFO - PID 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:58:47 INFO - PID 1902 | 2017-03-28 14:58:47.188 plugin-container[1904:9179] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:58:47 INFO - PID 1902 | __metrics Screen width/height:1600/1200 14:58:47 INFO - PID 1902 | colorDepth:24 14:58:47 INFO - PID 1902 | Browser inner width/height: 1024/689 14:58:47 INFO - PID 1902 | __metrics 14:58:47 ERROR - PID 1902 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 14:58:47 ERROR - PID 1902 | 1490738327438 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/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpXskkAA/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpXskkAA/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpXskkAA/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpXskkAA/profile/extensions/talos-powers@TalosPowersService.js:55:9 14:58:48 INFO - TEST-INFO | 1902: exit 0 14:58:48 INFO - Browser initialized. 14:58:48 INFO - Running cycle 1/1 for dromaeo_css test... 14:58:48 INFO - TEST-INFO | started process 1905 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpXskkAA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:58:50 INFO - PID 1905 | 2017-03-28 14:58:50.674 plugin-container[1906:9313] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b43, name = 'com.apple.tsm.portname' 14:58:50 INFO - PID 1905 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:58:50 INFO - PID 1905 | 2017-03-28 14:58:50.674 plugin-container[1906:9313] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 14:58:50 INFO - PID 1905 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:58:50 INFO - PID 1905 | 2017-03-28 14:58:50.674 plugin-container[1906:9313] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:59:00 INFO - PID 1905 | RSS: Main: 229396480 14:59:00 INFO - PID 1905 | 15:02:04 INFO - PID 1905 | Cycle 1(1): loaded http://localhost:49423/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49423/tests/dromaeo/cssquery-ext.html) 15:02:06 INFO - PID 1905 | RSS: Main: 256561152 15:02:06 INFO - PID 1905 | 15:03:48 INFO - PID 1905 | ************************* 15:03:48 INFO - PID 1905 | A coding exception was thrown and uncaught in a Task. 15:03:48 INFO - PID 1905 | 15:03:48 ERROR - PID 1905 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:03:48 INFO - PID 1905 | Full stack: 15:03:48 INFO - PID 1905 | ************************* 15:03:48 INFO - PID 1905 | ************************* 15:03:48 INFO - PID 1905 | A coding exception was thrown and uncaught in a Task. 15:03:48 INFO - PID 1905 | 15:03:48 ERROR - PID 1905 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:03:48 INFO - PID 1905 | Full stack: 15:03:48 INFO - PID 1905 | ************************* 15:03:48 INFO - PID 1905 | ************************* 15:03:48 INFO - PID 1905 | A coding exception was thrown and uncaught in a Task. 15:03:48 INFO - PID 1905 | 15:03:48 ERROR - PID 1905 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:03:48 INFO - PID 1905 | Full stack: 15:03:48 INFO - PID 1905 | ************************* 15:03:48 INFO - PID 1905 | ************************* 15:03:48 INFO - PID 1905 | A coding exception was thrown and uncaught in a Task. 15:03:48 INFO - PID 1905 | 15:03:48 ERROR - PID 1905 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:03:48 INFO - PID 1905 | Full stack: 15:03:48 INFO - PID 1905 | ************************* 15:05:08 INFO - PID 1905 | Cycle 1(1): loaded http://localhost:49423/tests/dromaeo/cssquery-ext.html (next: http://localhost:49423/tests/dromaeo/cssquery-jquery.html) 15:05:09 INFO - PID 1905 | RSS: Main: 261025792 15:05:09 INFO - PID 1905 | 15:08:11 INFO - PID 1905 | Cycle 1(1): loaded http://localhost:49423/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49423/tests/dromaeo/cssquery-mootools.html) 15:08:12 INFO - PID 1905 | RSS: Main: 272031744 15:08:12 INFO - PID 1905 | 15:11:14 INFO - PID 1905 | Cycle 1(1): loaded http://localhost:49423/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49423/tests/dromaeo/cssquery-prototype.html) 15:11:14 INFO - PID 1905 | RSS: Main: 265957376 15:11:14 INFO - PID 1905 | 15:14:17 INFO - PID 1905 | Cycle 1(1): loaded http://localhost:49423/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49423/tests/dromaeo/cssquery-yui.html) 15:14:18 INFO - PID 1905 | RSS: Main: 279314432 15:14:18 INFO - PID 1905 | 15:17:21 INFO - PID 1905 | Cycle 1(1): loaded http://localhost:49423/tests/dromaeo/cssquery-yui.html (next: http://localhost:49423/tests/dromaeo/cssquery-dojo.html) 15:17:21 INFO - PID 1905 | RSS: Main: 273485824 15:17:21 INFO - PID 1905 | 15:17:21 INFO - PID 1905 | __start_tp_report 15:17:21 INFO - PID 1905 | _x_x_mozilla_page_load 15:17:21 INFO - PID 1905 | _x_x_mozilla_page_load_details 15:17:21 INFO - PID 1905 | |i|pagename|runs| 15:17:21 INFO - PID 1905 | |0;dojo.html;3183.7930534515253;3542.1321776164846;3568.214992701605;3605.2969670914167;3606.747527673063;6119.66341851198;6172.722227499762;6186.226721659793;6201.379862013797;6222.035584484406;5046.243063540471;6149.13912052313;6174.783882564111;6208.937910620893;6274.466670333016;5698.857528561798;5710.657360558369;5792.68140252286;5867.911981320283;5892.970535147339;5559.777608895639;5689.943100569003;5830.912536311958;5841.415858414167;5892.351841297454;5497.120460726304;5562.3047119110115;5565.777368905239;5715.657060576358;5732;4490.910181796346;5554.1391084381985;5571.832845014656;5675.31896172462;5718.42815718429;5587.106063029936;5646.011947909141;5649.661020338793;5660.60375773696;5670.149477578355;5528.673118451583;6131.957567213584;6229.688515574203;6237.407446292595;6237.937620623781;6395.648239346834;6448.709808058602;6648.401643852077;6682.4319932805765;6713.865722685519;5699.800506982236;6753.628550429725;6756.121704178426;6803.65981700913;6814.080099186596;5292.629515933924;6278.340774218733;6332.4934005279465;6343.238811342622;6348.269948955837;6664.733410663616;6724.4284235840505;6922.06552115458;7020.157581090302;7030.2969703029285;6353.872922541524;6434.967825160844;6537.313582073813;6543.869122617521;6675.399214070757;6750.797476075725;6769.255381907986;6777.49168812341;6778.7288508459105;6781.864362712817;6515.120458738104;6670.232923213795;6677.29888361715;6682.866342673119;6685.632290223987;6368.681565921685;6440.098386225932;6451.1936007999;6681.766138185238;6686.264510903797;4537;5370.624056316098;5382.138857782736;5393.541548968303;5424;4703.717776933395;4726.1020406122725;4747.525247475294;4760.190767569521;4769.713817170981;6352.364763523703;6407.13503677007;6416.871662566816;6425;6434.453071488976;6140.504248697391;6484.935150648433;6595.307492713292;6722.563033402813;6737.59574425536;6018.608790428608;6385.616862988142;6386.9680651596445;6429.132067170872;6429.54993150475;6050.606710563799;6080.361562036011;6093.482054025458;6099.115628233882;6115.847103822351;6731.32686731323;6825.488088393489;6963.651817409008;6988.161420629557;6999.055127557816;6710.060591517094;6817.488688348493;6851.212110607344;6854.554453960476;6855.5886646801355;6970.059042029464;7025.648717564203;7028.718851246097;7035.577865328097;7066.964665176642;6404.871902562016;6467.482601391784;6638.103855979381;6684.8997265042035;6712.83217919556;6574.8356291091195;6782.982552617147;6804.897926531205;6809.761658341934;6820.590764554143;5635.323761148688;6276.278228003838;6514.250861151028;6528.771492997722;6540.901886471612;6335.588186767845;6466.838329041623;6508.926027205458;6537.084808126961;6574.309697481695;5550;6296.811095667137;6419.197600299963;6460.030995350735;6463;6573.375529324707;6595.373439523238;6606.735730570724;6607.702653380706;6622.503312251504;6180.072989051499;6232.719527621359;6347.71435285404;6392.552521323462;6399.008153736178 15:17:21 INFO - PID 1905 | |1;ext.html;734.2950767263427;809.8663720486119;838.2078935406042;1119.059989608729;1121.9102471802257;35952.64047359525;37179.32693028813;37223.25553488932;37231.255374892484;37272.25455490892;39426.81719548411;41032.38451423231;41300.38049429261;41524;41527.58472415275;8458.154184581523;8635.870461943061;8681.95659021704;8761.868571971425;8776.341774366916;8804.64781408743;8889.111088891124;8908.465492070463;8926;8937;8873.423227490224;8952.462852228855;8954.86567701485;8960.551972401387;8964.731058068268;8906.599203035814;8906.821863562725;8917.554122293925;8924.464532128093;8944.955275223614;8667.653293868238;8692.304615630734;8693.5218562979;8725.21473067427;8752.037275899645;39736.40395394072;39825.40261896074;40018.799906000575;40033;40119.39820902689;2618.581027035684;3043.2544026713376;3108.0520441265403;3109.8445077746023;3113.377324535102;28380.290492737848;28395.00617478399;28518.716657750243;28590.285242868886;29373.67818448175;2399.9920033585936;2457.938551536208;2572.3826281692445;2620.502104600139;2631.736826317372;218536;219790;265157;305351;332009;50675.493245067075;50769.238461423105;50865.23702144471;50937;51171.74414127906;52455;52804.47195527996;52850.12948316771;52866;53009.46990530045;13118;14236.003479756524;14790.890683198802;14893.255337233095;14905;12075.698107547207;12101.878981210075;12110.273383597012;12136.211146275464;12168.513259469522;13921.937173821743;13946.58160255194;13953.162810231419;13966.650833729238;14067.648308792363;5975.462208401264;6026.54800889935;6058.394160583995;6080.391960803972;6084.30030546484;923.6859467780987;931.3480563605503;948.6063283737171;961.6538046303325;1009.7526106104051;23407;24688;24933.0026798926;25017.74982250154;25035.499290014097;1060.188955449082;1072.5066469424153;1102.7518808268235;1107.7950579142885;1114.2423152256542;12023.579174729017;12633.873661263453;12644.936775316064;12651.177673451197;12724.681882952815;381557;381948;382269;383103;384466;57158.428415716146;57355.71322143362;57394.713026435435;57506.7124664374;57668.8466230673;1344.8318960129984;1397.4200706706806;1429.5711286613973;1520.9391624334903;1535.7773122897117;23848.88075559611;24171.274861754177;24253.849817431423;24381.634275486238;24397.3900652478;40112;40156;40195.79902100471;40249;40344.798276009606;421073;421125;421511;421621;422219;26297.07960221383;26659;31974.68025319717;32306.35387292194;34097.82951085328;40193.79903100466;40543.79728101341;40635.390469142396;41261.79369103135;41540.376894346;25824.483510330065;28360.57459138156;28913.277168070967;29251.7074829249;30432;28903.988360407286;29199.7080029197;29824.403511929206;29884.551731723608;30250.54624180595;35873.103172420895;35884.28231435409;36157;36197.63802362048;36422.08944776402;23028.654570181126;26724.46551068929;30386.84806575953;30613.846930765205;31061.53407698841;22194.334169974925;23882.40293992664;24190.51618967646;24630.384240394134;24640 15:17:21 INFO - PID 1905 | |2;jquery.html;425.1367042965626;430.9355890949358;435.90845922356294;446.57798380530403;455.11025944278936;18965.810341896595;20704.964751762407;21275;21456.46358841028;21502.462438439048;20495.795042049576;22097.889510552464;22238.332850014485;22241.44396390091;22481;6149.600275982058;6324.0513922911705;6329.651869147195;6332;6332.366763323676;6003.099535069731;6180.103884936681;6243.594166379194;6245.56281060326;6258.374162583751;6004.0693692477735;6172.5370597205165;6189.412005859436;6197.628142311453;6200.255969283669;6276.309605943343;6329.145565348665;6345.49236061114;6365.968170159166;6371.776987805404;5550.555955523549;5573.665580065169;5639.746211420496;5669.744861481243;5685.943140568624;21565;26409.73590264092;27022.189334320003;27052;28617.427651447062;1993.1728332741895;2014.738084049079;2014.7683016453002;2028.3712049264627;2028.377298161465;16202.75695864563;16212.75680864788;16491;16891.662166756596;17132.743008854875;1670.8868286349993;1681.865450763936;1693.4580934101093;1709.0941800845446;1730.2300476288058;127160;137312;137409;141344.5865541352;162485;10048.397096174253;12836.229826210458;13125.60623181306;13155.67110822218;13347.799783003262;16557.25492352847;16636;16636.50090497287;16646;16674.916625417038;7493.887591686129;8564;8887.777805554913;8979.775505612412;8993.955030224806;6884.4148247399535;8333.33333333332;8343.04055033679;8348.33213342931;8424.241818236265;6712.731490740413;8033.758987230392;8049.517028978163;8104;8179.79550511242;1991.621591897535;2254.7294324680815;2271.716035495563;2273.079402841852;2445.8532488050773;890.5992303463468;908.977275568116;911.5077857956762;941.0589410589411;953.1469334945324;17760.91119544394;21481.46296342604;21572.784272157078;21599.920003999738;22115;927.8886533615874;954.3891909178132;956.0917128727598;960.188640598693;970.3401686852867;8110.351171906352;10753.462326883624;11026.614068507564;11185.272957257916;11256.718582035515;538839;540918;544809;642437;715072;143510;147873;151175.24412378087;151244;151256;1165.4709280860552;1194.8887534592911;1219.1587804414926;1219.554862475208;1220.7070303127364;21021.57956840824;21953.451163721034;22044.448888777908;22129.01258330105;22158;44969.55030449654;53158.46841531535;55511.72244138754;56604.71697641486;56627.86744265174;76450.23549764432;78066;80352.59823700844;80692.59653701694;81444.59277703811;6788.76239331621;8853.73438796837;8950.955245223733;9038.141376569216;9115.589798459217;11109.611163609236;12012.459439325077;14097.929510352382;14430.927845361126;14528;11627.941860290644;11760.76478470443;11873.703157421134;11937.880621193677;11949.64151075469;9866.062724041205;11389.943050284695;11444.027257682857;11565.537378504765;11819.40902954866;11408.600698975495;11692.415379231175;11933.28400295985;12038.217515861441;12067.155299129328;8690.435121717068;8742.38803283764;8844.95577522134;9057.701986853963;9097.818043638958;11544.88455115438;11721.941390292994;11889;11902.404879755804;12024.158308918291 15:17:21 INFO - PID 1905 | |3;mootools.html;1298.9870101298986;1441.8053562769023;1449.2319070892431;1457.387897083225;1474.3807600807656;9872.259580531452;10616.681499555007;10936.835947460775;11074.501647425865;11092.833607495884;9170.724878253646;10430.16558675306;11140.944295278532;11149.163812714058;11152.665420037412;2461.237016524874;2509.372656835791;2510.146550172941;2514.1451906351836;2514.270861450177;2445.498672772086;2467.3584867934287;2495.8502489850575;2500.924972250826;2511.2842839790665;1994.7706013808452;2459.8155138364605;2464.2114523352534;2485.5774518331837;2512.623106534016;1926.8169523895278;2062.453449835787;2310.1336998625516;2442.5237078769646;2510.8870100845506;2056.95886082279;2133.690614860852;2260.2089268755803;2441.060191826142;2463.6550882876413;8796.47221166732;10632.627858024933;10722.785544289149;10813.567457301699;10860;6230.501559875199;6437.356264373572;6481.93518064818;6483.902741458882;6519.087327774116;3613.656702613274;3756.530433695788;3823.847046118152;3874.6319099685766;3928.3910993796007;1106.5960924262668;1116.8548088748491;1140.2189500192312;1154.4227886056972;1167.0313639679046;15416.691666166726;15428.3828646854;15573.922130389388;15574.610634734108;15582.298796554069;5060.974695126574;5432.91850622241;5542.11326187808;5580.246666700024;5588.189712491666;11451;11651.126165537618;11690.649280521598;11705.707357316136;11728.530858765724;6312.684365781693;6314.747410103636;6316.684165791692;6325.810225693236;6334.429901308904;5659.518940889988;5877;5960.225170727777;5972.880542389128;6041.8489537761025;4208.537060923296;4336.132773445323;4378.0806030733265;4394.12117576486;4418.911621767546;979.480875136179;1100.3342977498658;1106.673531308266;1274.7641686288066;1296.863829297929;750.6809605917463;752.8268498245434;780.7189411811743;783.5181363461544;807.0920214758397;5910.438508341701;5995.190649262294;6086.543509236825;6131.417515336036;6150.200473938359;585.2713371851986;590.0912594604347;890.8797312362877;902.6930843513237;922.9077092290718;4436.223660859337;4472.127935052698;4479.238529450001;4510.14307281622;4633.675642704979;13217.933910330386;13508;13515.567197904275;13549.187048777105;13590.660233494043;13307.26810025458;13486.595402137951;13512.25682587467;13764.036517443683;13879.514217002357;9981.50277458387;10240.897591024292;10241.539130739135;10261.384316941007;10275.948620257;6442.194725659293;6564.6389448580785;6819.215790184192;6863.176418829772;6884.208316043719;12212.633620991384;12249;12331.50673973031;12347.444365003776;12485.812712809497;10585.259031867696;10800.24398292112;13158.736825263633;13384.665383365103;13394.330283485591;9172.862407063765;9267.86098208514;9272.953635231781;9323.533823308942;9414.717558473258;10214.285000049926;10230.23273254494;10260;10271.537780799732;10323.793524129625;8600;8636.740897773077;8650.870236946577;8654.22112009922;8853.15894989975;8442.873356899774;8479.643257078838;8496.362772792188;8504.149585041447;8514.914850851412;7732.188120247518;7741.032370953631;7742.303192712683;7866.252705992922;7889.763307100796;4736.265878788793;5720.971395142998;5830.979578573818;5941.989861723604;6035.18524999137;5858.414158584107;5985.431384018511;6028.216331876828;6071.696415179312;6103.84740381494 15:17:21 INFO - PID 1905 | |4;prototype.html;373.59278386558657;386.3335745838429;400.83365403357607;408.2997659014789;408.46083170215326;5118.48815118488;6046.12331211974;6314.24229092509;6325.810225693225;6330.968345158273;6044.788432404866;6046.939530604693;6103.542234332421;6115.771015781509;6173.351798061195;4169.373944161779;4362.367456718774;4377.102693947749;4606.516315786845;4612.67711260211;3505.4216054351;3762.435634654796;3828.961710382888;4757.167495688241;4848;4739.241721324589;4763.927266912857;4785.803549112722;4802.2076357401165;4859.465458799529;4811.8075276988875;4829.348038014893;4839.911020020502;4841.3222148899185;4841.6034063138795;4041.1715598302276;4057.9391309130388;4190.266703326905;4225.9577404226175;4274.337477690931;4914.812908255828;5840.8247752567495;5922.674252916088;5939.584229103964;5953.851153721148;5531.976584331871;5544.390117087117;5547.057000309957;5550.333959924795;5584.5811564132855;5640.435956404368;5642.971785141048;6309.5898766580485;6564.507661925375;6568.507361947825;4703.741294228816;4710.31700403443;4713.882152946169;4719.339292499053;4723.103439662293;5539.446055394468;5837.577405376764;6278.591891527082;6336.778212762531;6456.483481321483;3703.925921481555;4539.432570928634;4841.661083724176;4869.391326084239;4893.388326459192;4589.242774942164;4711.586524042774;4934.1611925972675;4963.875903102451;4975.328330675313;5287.286216360819;5378.354597448253;5458.044842152607;5494.780208791683;5555.916661250085;6186.381361863778;6465.806025819233;6526.771562995273;6594;6606;1467.9853201468063;1606.2611198848426;1693.8221486743714;1694.6441247338198;1701.591618011693;1150.775598758251;1152.4122697424375;1172.8798996958055;1177.5466445418576;1194.343111288805;4958.628102892226;5489.984352894728;5947.375525569752;6286.559940804192;6294.181756371642;5685.635447492655;6088.847778805565;6784.321567843176;6822.371543476652;6824.522283440111;5420.783168673209;5686.772529098873;5984.222051133412;6037.796204385368;6258.687065646699;2930.7801914856473;3003.888561232358;3154.526820976872;3159.9684003160132;3227.306129182237;6370.55406121567;6552.967235163794;6583.736650533925;6972.337627925441;7085.92914070863;6187.752489900444;6391.913374726308;6527.053577231275;6873.862522749517;7227.385672217921;5918.852028699317;6111.236095488064;7028.824279393056;7030.859382812213;7066.858662826715;5944.8811023780145;6481.157449531531;6665.200175978914;6829.69266383004;6858.2455929848675;6083.878322433615;6561.868762624817;6649.301823308483;6664.3335666434905;6730.798076057726;6413.583117097373;6915.167480617741;7013.859722805618;7033.472489563405;7038.753643622448;4256.701381254088;4777.546133117348;4815.133276010211;4819.373481447389;4828.5412885775795;5358.4105748366865;6040.2751669798145;6084.239470066242;6251.780902724107;6757.695903684445;5762.740676669644;6554.787364337612;6563.671816409256;6572.375624315682;6601.636909969806;5915.290165180206;6284.371562843679;6459;6524.325026739204;6549.70526326326;5317;5944.197533332945;6400.591869788639;6421.935780642134;6427.646479443675;6213.720382582885;6520.771772988112;6527.836804079936;6545.952647576555;6569.441597464078;5313.68117912911;5557.970905856238;5887.3523912370465;6260.499160067275;6322.905156422566 15:17:21 INFO - PID 1905 | |5;yui.html;675.6959368284271;682.4199430484086;725.9745908893188;741.699611657279;744.657457569518;15280.617984550378;15577.143257120853;15655.608609784762;15666.764998525032;15669.373225071013;12205.694857628563;14291.857081429183;14603.050801697882;14641.414343426277;14679.559613211593;950.7147855643314;955.5556666150238;978.9818588667777;979.8824141103078;981.7790997025647;999.7700528878362;1011.7217765114578;1018.1803648063307;1019.3170575714289;1024.3802499487776;1045.7542477517777;1056.4400867540216;1060.6817954613623;1060.8514807926897;1061.5859814672283;1051.8211903976342;1052.8104941110596;1055.145332280855;1055.7835643693024;1058.1799105693071;779.6491578789511;785.5797148525511;787.1695361393728;787.7676085554717;787.8503084413943;16595.83404165955;16932.492025239262;16937.407190748265;16985.660286794195;17131.57171070721;14694.632634184123;14775.408983640642;14776.926115369355;14811.851881481154;15030.924845375812;10112.140468060225;11066.889331106666;11677.883221167764;11693.181477296592;11776.764464710743;2507.022261318087;2656.6147908553344;2751.435955629111;2904.8983285585114;2975.509041008231;84299;88443.55778221133;105996;111589;113057;11302.350273628126;11346.259619148525;11461.312321260752;11745.530178792753;11855.525778968917;20615.8969205153;24500;24729.629055564183;24891.377715556893;24892;7931.960340198262;8007.359411247086;8055.113937466874;8106.959465202636;8258.380621453414;4021.3967904813926;4092.0792821615496;4092.9386059209137;4098.118904435561;4099.8975025624595;3983.48214732083;4030.7380020299174;4078.6940979426067;4084.3873418986796;4091.97954010228;853.9316854651613;854.4360721923625;859.5959898847532;861.2205953611991;883.3551507399505;524.3602804578485;527.6122050293031;536.184998801819;549.4724268365487;568.408854791021;8042.879356809653;9640.142624571816;9710.152884744963;9728.854067188999;9771.706848794547;534.5856960855291;555.6332820338557;557.2282388891415;564.652738565779;569.3566270114744;4184.14225083857;4263.680223983214;4482.731036137842;4527.253003254492;4579.313103034571;76906.2309376899;96957.06085878242;98891.50554247183;98956.50521747344;99046.00953990512;78512.21487785163;79003;87896.12103879008;88303.55848220718;89931.65102523468;73958;77770.61114694504;78157;78406;79059;8740.994785599483;9082.727518174466;9102;9118.863217051618;9194.17252447283;28990.7100928988;35586;39308.80345598254;39751.60248397479;39991.2001759969;51282.74358628183;51692.74153629208;51743.22385164301;51884.877990262365;52475.475245248585;11631.88368116308;11703.122265830267;11738.119641026786;11807.822882656596;11828.876256755595;9968.55141518619;11397.772044558897;12440.215299885596;12596.748065038832;12614.607963445334;11132.888671113185;11723.706907327385;11807.23252988553;12201.69495762613;12378.009759219061;11422.885771142182;11626.18616696857;11862.466189021343;12143.332116733665;12154.513819447124;11495.425228738362;11566.47950842231;11585.584152114272;11686.18196726221;12047.698807529532;7645.120811106794;7951.562664053533;8534.44526105826;9011.954940225258;9324.393914395543;8496.277816385676;8777.3416993727;8976.326775491734;8977.953926182809;9558.378705384128 15:17:21 INFO - PID 1905 | __end_tp_report 15:17:21 INFO - PID 1905 | __start_cc_report 15:17:21 INFO - PID 1905 | _x_x_mozilla_cycle_collect,429 15:17:21 INFO - PID 1905 | __end_cc_report 15:17:21 INFO - PID 1905 | __startTimestamp1490739441684__endTimestamp 15:17:21 INFO - PID 1905 | 15:17:21 INFO - PID 1905 | ------- Summary: start ------- 15:17:21 INFO - PID 1905 | Number of tests: 6 15:17:21 INFO - PID 1905 | 15:17:21 INFO - PID 1905 | [#0] dojo.html Cycles:165 Average:6177.87 Median:6395.65 stddev:729.88 (11.4%) stddev-sans-first:693.30 15:17:21 INFO - PID 1905 | Values: 3183.8 3542.1 3568.2 3605.3 3606.7 6119.7 6172.7 6186.2 6201.4 6222.0 5046.2 6149.1 6174.8 6208.9 6274.5 5698.9 5710.7 5792.7 5867.9 5893.0 5559.8 5689.9 5830.9 5841.4 5892.4 5497.1 5562.3 5565.8 5715.7 5732.0 4490.9 5554.1 5571.8 5675.3 5718.4 5587.1 5646.0 5649.7 5660.6 5670.1 5528.7 6132.0 6229.7 6237.4 6237.9 6395.6 6448.7 6648.4 6682.4 6713.9 5699.8 6753.6 6756.1 6803.7 6814.1 5292.6 6278.3 6332.5 6343.2 6348.3 6664.7 6724.4 6922.1 7020.2 7030.3 6353.9 6435.0 6537.3 6543.9 6675.4 6750.8 6769.3 6777.5 6778.7 6781.9 6515.1 6670.2 6677.3 6682.9 6685.6 6368.7 6440.1 6451.2 6681.8 6686.3 4537.0 5370.6 5382.1 5393.5 5424.0 4703.7 4726.1 4747.5 4760.2 4769.7 6352.4 6407.1 6416.9 6425.0 6434.5 6140.5 6484.9 6595.3 6722.6 6737.6 6018.6 6385.6 6387.0 6429.1 6429.5 6050.6 6080.4 6093.5 6099.1 6115.8 6731.3 6825.5 6963.7 6988.2 6999.1 6710.1 6817.5 6851.2 6854.6 6855.6 6970.1 7025.6 7028.7 7035.6 7067.0 6404.9 6467.5 6638.1 6684.9 6712.8 6574.8 6783.0 6804.9 6809.8 6820.6 5635.3 6276.3 6514.3 6528.8 6540.9 6335.6 6466.8 6508.9 6537.1 6574.3 5550.0 6296.8 6419.2 6460.0 6463.0 6573.4 6595.4 6606.7 6607.7 6622.5 6180.1 6232.7 6347.7 6392.6 6399.0 15:17:21 INFO - PID 1905 | 15:17:21 INFO - PID 1905 | [#1] ext.html Cycles:180 Average:49994.39 Median:36054.82 stddev:96470.33 (267.6%) stddev-sans-first:96670.06 15:17:21 INFO - PID 1905 | Values: 734.3 809.9 838.2 1119.1 1121.9 35952.6 37179.3 37223.3 37231.3 37272.3 39426.8 41032.4 41300.4 41524.0 41527.6 8458.2 8635.9 8682.0 8761.9 8776.3 8804.6 8889.1 8908.5 8926.0 8937.0 8873.4 8952.5 8954.9 8960.6 8964.7 8906.6 8906.8 8917.6 8924.5 8945.0 8667.7 8692.3 8693.5 8725.2 8752.0 39736.4 39825.4 40018.8 40033.0 40119.4 2618.6 3043.3 3108.1 3109.8 3113.4 28380.3 28395.0 28518.7 28590.3 29373.7 2400.0 2457.9 2572.4 2620.5 2631.7 218536.0 219790.0 265157.0 305351.0 332009.0 50675.5 50769.2 50865.2 50937.0 51171.7 52455.0 52804.5 52850.1 52866.0 53009.5 13118.0 14236.0 14790.9 14893.3 14905.0 12075.7 12101.9 12110.3 12136.2 12168.5 13921.9 13946.6 13953.2 13966.7 14067.6 5975.5 6026.5 6058.4 6080.4 6084.3 923.7 931.3 948.6 961.7 1009.8 23407.0 24688.0 24933.0 25017.7 25035.5 1060.2 1072.5 1102.8 1107.8 1114.2 12023.6 12633.9 12644.9 12651.2 12724.7 381557.0 381948.0 382269.0 383103.0 384466.0 57158.4 57355.7 57394.7 57506.7 57668.8 1344.8 1397.4 1429.6 1520.9 1535.8 23848.9 24171.3 24253.8 24381.6 24397.4 40112.0 40156.0 40195.8 40249.0 40344.8 421073.0 421125.0 421511.0 421621.0 422219.0 26297.1 26659.0 31974.7 32306.4 34097.8 40193.8 40543.8 40635.4 41261.8 41540.4 25824.5 28360.6 28913.3 29251.7 30432.0 28904.0 29199.7 29824.4 29884.6 30250.5 35873.1 35884.3 36157.0 36197.6 36422.1 23028.7 26724.5 30386.8 30613.8 31061.5 22194.3 23882.4 24190.5 24630.4 24640.0 15:17:21 INFO - PID 1905 | 15:17:21 INFO - PID 1905 | [#2] jquery.html Cycles:180 Average:36917.34 Median:2263.22 stddev:101354.63 (4478.3%) stddev-sans-first:101601.92 15:17:21 INFO - PID 1905 | Values: 425.1 430.9 435.9 446.6 455.1 18965.8 20705.0 21275.0 21456.5 21502.5 20495.8 22097.9 22238.3 22241.4 22481.0 6149.6 6324.1 6329.7 6332.0 6332.4 6003.1 6180.1 6243.6 6245.6 6258.4 6004.1 6172.5 6189.4 6197.6 6200.3 6276.3 6329.1 6345.5 6366.0 6371.8 5550.6 5573.7 5639.7 5669.7 5685.9 21565.0 26409.7 27022.2 27052.0 28617.4 1993.2 2014.7 2014.8 2028.4 2028.4 16202.8 16212.8 16491.0 16891.7 17132.7 1670.9 1681.9 1693.5 1709.1 1730.2 127160.0 137312.0 137409.0 141344.6 162485.0 10048.4 12836.2 13125.6 13155.7 13347.8 16557.3 16636.0 16636.5 16646.0 16674.9 7493.9 8564.0 8887.8 8979.8 8994.0 6884.4 8333.3 8343.0 8348.3 8424.2 6712.7 8033.8 8049.5 8104.0 8179.8 1991.6 2254.7 2271.7 2273.1 2445.9 890.6 909.0 911.5 941.1 953.1 17760.9 21481.5 21572.8 21599.9 22115.0 927.9 954.4 956.1 960.2 970.3 8110.4 10753.5 11026.6 11185.3 11256.7 538839.0 540918.0 544809.0 642437.0 715072.0 143510.0 147873.0 151175.2 151244.0 151256.0 1165.5 1194.9 1219.2 1219.6 1220.7 21021.6 21953.5 22044.4 22129.0 22158.0 44969.6 53158.5 55511.7 56604.7 56627.9 76450.2 78066.0 80352.6 80692.6 81444.6 6788.8 8853.7 8951.0 9038.1 9115.6 11109.6 12012.5 14097.9 14430.9 14528.0 11627.9 11760.8 11873.7 11937.9 11949.6 9866.1 11389.9 11444.0 11565.5 11819.4 11408.6 11692.4 11933.3 12038.2 12067.2 8690.4 8742.4 8845.0 9057.7 9097.8 11544.9 11721.9 11889.0 11902.4 12024.2 15:17:21 INFO - PID 1905 | 15:17:21 INFO - PID 1905 | [#3] mootools.html Cycles:180 Average:6765.49 Median:3685.09 stddev:4171.25 (113.2%) stddev-sans-first:4162.73 15:17:21 INFO - PID 1905 | Values: 1299.0 1441.8 1449.2 1457.4 1474.4 9872.3 10616.7 10936.8 11074.5 11092.8 9170.7 10430.2 11140.9 11149.2 11152.7 2461.2 2509.4 2510.1 2514.1 2514.3 2445.5 2467.4 2495.9 2500.9 2511.3 1994.8 2459.8 2464.2 2485.6 2512.6 1926.8 2062.5 2310.1 2442.5 2510.9 2057.0 2133.7 2260.2 2441.1 2463.7 8796.5 10632.6 10722.8 10813.6 10860.0 6230.5 6437.4 6481.9 6483.9 6519.1 3613.7 3756.5 3823.8 3874.6 3928.4 1106.6 1116.9 1140.2 1154.4 1167.0 15416.7 15428.4 15573.9 15574.6 15582.3 5061.0 5432.9 5542.1 5580.2 5588.2 11451.0 11651.1 11690.6 11705.7 11728.5 6312.7 6314.7 6316.7 6325.8 6334.4 5659.5 5877.0 5960.2 5972.9 6041.8 4208.5 4336.1 4378.1 4394.1 4418.9 979.5 1100.3 1106.7 1274.8 1296.9 750.7 752.8 780.7 783.5 807.1 5910.4 5995.2 6086.5 6131.4 6150.2 585.3 590.1 890.9 902.7 922.9 4436.2 4472.1 4479.2 4510.1 4633.7 13217.9 13508.0 13515.6 13549.2 13590.7 13307.3 13486.6 13512.3 13764.0 13879.5 9981.5 10240.9 10241.5 10261.4 10275.9 6442.2 6564.6 6819.2 6863.2 6884.2 12212.6 12249.0 12331.5 12347.4 12485.8 10585.3 10800.2 13158.7 13384.7 13394.3 9172.9 9267.9 9273.0 9323.5 9414.7 10214.3 10230.2 10260.0 10271.5 10323.8 8600.0 8636.7 8650.9 8654.2 8853.2 8442.9 8479.6 8496.4 8504.1 8514.9 7732.2 7741.0 7742.3 7866.3 7889.8 4736.3 5721.0 5831.0 5942.0 6035.2 5858.4 5985.4 6028.2 6071.7 6103.8 15:17:21 INFO - PID 1905 | 15:17:21 INFO - PID 1905 | [#4] prototype.html Cycles:180 Average:5288.14 Median:5839.20 stddev:1590.94 (27.2%) stddev-sans-first:1552.05 15:17:21 INFO - PID 1905 | Values: 373.6 386.3 400.8 408.3 408.5 5118.5 6046.1 6314.2 6325.8 6331.0 6044.8 6046.9 6103.5 6115.8 6173.4 4169.4 4362.4 4377.1 4606.5 4612.7 3505.4 3762.4 3829.0 4757.2 4848.0 4739.2 4763.9 4785.8 4802.2 4859.5 4811.8 4829.3 4839.9 4841.3 4841.6 4041.2 4057.9 4190.3 4226.0 4274.3 4914.8 5840.8 5922.7 5939.6 5953.9 5532.0 5544.4 5547.1 5550.3 5584.6 5640.4 5643.0 6309.6 6564.5 6568.5 4703.7 4710.3 4713.9 4719.3 4723.1 5539.4 5837.6 6278.6 6336.8 6456.5 3703.9 4539.4 4841.7 4869.4 4893.4 4589.2 4711.6 4934.2 4963.9 4975.3 5287.3 5378.4 5458.0 5494.8 5555.9 6186.4 6465.8 6526.8 6594.0 6606.0 1468.0 1606.3 1693.8 1694.6 1701.6 1150.8 1152.4 1172.9 1177.5 1194.3 4958.6 5490.0 5947.4 6286.6 6294.2 5685.6 6088.8 6784.3 6822.4 6824.5 5420.8 5686.8 5984.2 6037.8 6258.7 2930.8 3003.9 3154.5 3160.0 3227.3 6370.6 6553.0 6583.7 6972.3 7085.9 6187.8 6391.9 6527.1 6873.9 7227.4 5918.9 6111.2 7028.8 7030.9 7066.9 5944.9 6481.2 6665.2 6829.7 6858.2 6083.9 6561.9 6649.3 6664.3 6730.8 6413.6 6915.2 7013.9 7033.5 7038.8 4256.7 4777.5 4815.1 4819.4 4828.5 5358.4 6040.3 6084.2 6251.8 6757.7 5762.7 6554.8 6563.7 6572.4 6601.6 5915.3 6284.4 6459.0 6524.3 6549.7 5317.0 5944.2 6400.6 6421.9 6427.6 6213.7 6520.8 6527.8 6546.0 6569.4 5313.7 5558.0 5887.4 6260.5 6322.9 15:17:21 INFO - PID 1905 | 15:17:21 INFO - PID 1905 | [#5] yui.html Cycles:180 Average:18872.50 Median:4088.18 stddev:27253.40 (666.6%) stddev-sans-first:27295.60 15:17:21 INFO - PID 1905 | Values: 675.7 682.4 726.0 741.7 744.7 15280.6 15577.1 15655.6 15666.8 15669.4 12205.7 14291.9 14603.1 14641.4 14679.6 950.7 955.6 979.0 979.9 981.8 999.8 1011.7 1018.2 1019.3 1024.4 1045.8 1056.4 1060.7 1060.9 1061.6 1051.8 1052.8 1055.1 1055.8 1058.2 779.6 785.6 787.2 787.8 787.9 16595.8 16932.5 16937.4 16985.7 17131.6 14694.6 14775.4 14776.9 14811.9 15030.9 10112.1 11066.9 11677.9 11693.2 11776.8 2507.0 2656.6 2751.4 2904.9 2975.5 84299.0 88443.6 105996.0 111589.0 113057.0 11302.4 11346.3 11461.3 11745.5 11855.5 20615.9 24500.0 24729.6 24891.4 24892.0 7932.0 8007.4 8055.1 8107.0 8258.4 4021.4 4092.1 4092.9 4098.1 4099.9 3983.5 4030.7 4078.7 4084.4 4092.0 853.9 854.4 859.6 861.2 883.4 524.4 527.6 536.2 549.5 568.4 8042.9 9640.1 9710.2 9728.9 9771.7 534.6 555.6 557.2 564.7 569.4 4184.1 4263.7 4482.7 4527.3 4579.3 76906.2 96957.1 98891.5 98956.5 99046.0 78512.2 79003.0 87896.1 88303.6 89931.7 73958.0 77770.6 78157.0 78406.0 79059.0 8741.0 9082.7 9102.0 9118.9 9194.2 28990.7 35586.0 39308.8 39751.6 39991.2 51282.7 51692.7 51743.2 51884.9 52475.5 11631.9 11703.1 11738.1 11807.8 11828.9 9968.6 11397.8 12440.2 12596.7 12614.6 11132.9 11723.7 11807.2 12201.7 12378.0 11422.9 11626.2 11862.5 12143.3 12154.5 11495.4 11566.5 11585.6 11686.2 12047.7 7645.1 7951.6 8534.4 9012.0 9324.4 8496.3 8777.3 8976.3 8978.0 9558.4 15:17:21 INFO - PID 1905 | -------- Summary: end -------- 15:17:21 INFO - PID 1905 | 15:17:22 INFO - TEST-INFO | 1905: exit 0 15:17:22 INFO - TEST-OK | dromaeo_css | took 1118092ms 15:17:22 INFO - TEST-START | kraken 15:17:22 INFO - operating with platform_type : mac_ 15:17:22 INFO - Initialising browser for kraken test... 15:17:22 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmp5Jub8b/profile http://localhost:49423/getInfo.html) 15:17:25 INFO - PID 1913 | 2017-03-28 15:17:25.224 plugin-container[1914:11447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 15:17:25 INFO - PID 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:25 INFO - PID 1913 | 2017-03-28 15:17:25.228 plugin-container[1914:11447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a23, name = 'com.apple.CFPasteboardClient' 15:17:25 INFO - PID 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:25 INFO - PID 1913 | 2017-03-28 15:17:25.228 plugin-container[1914:11447] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:17:25 INFO - PID 1913 | __metrics Screen width/height:1600/1200 15:17:25 INFO - PID 1913 | colorDepth:24 15:17:25 INFO - PID 1913 | Browser inner width/height: 1024/689 15:17:25 INFO - PID 1913 | __metrics 15:17:25 ERROR - PID 1913 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 15:17:25 ERROR - PID 1913 | 1490739445427 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/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmp5Jub8b/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmp5Jub8b/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmp5Jub8b/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmp5Jub8b/profile/extensions/talos-powers@TalosPowersService.js:55:9 15:17:26 INFO - TEST-INFO | 1913: exit 0 15:17:26 INFO - Browser initialized. 15:17:26 INFO - Running cycle 1/1 for kraken test... 15:17:26 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmp5Jub8b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:17:28 INFO - PID 1915 | 2017-03-28 15:17:28.659 plugin-container[1917:11582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 15:17:28 INFO - PID 1915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:28 INFO - PID 1915 | 2017-03-28 15:17:28.659 plugin-container[1917:11582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 15:17:28 INFO - PID 1915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:28 INFO - PID 1915 | 2017-03-28 15:17:28.659 plugin-container[1917:11582] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:17:38 INFO - PID 1915 | RSS: Main: 229584896 15:17:38 INFO - PID 1915 | 15:17:41 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49423/tests/kraken/driver.html?testName=audio-beat-detection) 15:17:42 INFO - PID 1915 | RSS: Main: 245428224 15:17:42 INFO - PID 1915 | 15:17:45 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49423/tests/kraken/driver.html?testName=audio-dft) 15:17:45 INFO - PID 1915 | RSS: Main: 253460480 15:17:45 INFO - PID 1915 | 15:17:49 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49423/tests/kraken/driver.html?testName=audio-fft) 15:17:50 INFO - PID 1915 | RSS: Main: 254406656 15:17:50 INFO - PID 1915 | 15:17:52 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49423/tests/kraken/driver.html?testName=audio-oscillator) 15:17:52 INFO - PID 1915 | RSS: Main: 255520768 15:17:52 INFO - PID 1915 | 15:17:54 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49423/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:17:55 INFO - PID 1915 | RSS: Main: 255877120 15:17:55 INFO - PID 1915 | 15:17:58 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49423/tests/kraken/driver.html?testName=imaging-darkroom) 15:17:59 INFO - PID 1915 | RSS: Main: 261287936 15:17:59 INFO - PID 1915 | 15:18:02 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49423/tests/kraken/driver.html?testName=imaging-desaturate) 15:18:03 INFO - PID 1915 | RSS: Main: 262873088 15:18:03 INFO - PID 1915 | 15:18:06 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49423/tests/kraken/driver.html?testName=json-parse-financial) 15:18:06 INFO - PID 1915 | RSS: Main: 263102464 15:18:06 INFO - PID 1915 | 15:18:08 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49423/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:18:09 INFO - PID 1915 | RSS: Main: 262643712 15:18:09 INFO - PID 1915 | 15:18:11 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49423/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:18:12 INFO - PID 1915 | RSS: Main: 262512640 15:18:12 INFO - PID 1915 | 15:18:14 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49423/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:18:14 INFO - PID 1915 | RSS: Main: 262602752 15:18:14 INFO - PID 1915 | 15:18:17 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49423/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:18:18 INFO - PID 1915 | RSS: Main: 263086080 15:18:18 INFO - PID 1915 | 15:18:22 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49423/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:18:22 INFO - PID 1915 | RSS: Main: 268193792 15:18:22 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | Cycle 1(1): loaded http://localhost:49423/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49423/tests/kraken/driver.html?testName=ai-astar) 15:18:24 INFO - PID 1915 | RSS: Main: 271048704 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | __start_tp_report 15:18:24 INFO - PID 1915 | _x_x_mozilla_page_load 15:18:24 INFO - PID 1915 | _x_x_mozilla_page_load_details 15:18:24 INFO - PID 1915 | |i|pagename|runs| 15:18:24 INFO - PID 1915 | |0;ai-astar;110;122;129;138;121;121;121;124;122;121 15:18:24 INFO - PID 1915 | |1;audio-beat-detection;140;124;132;153;112;133;155;155;175;143 15:18:24 INFO - PID 1915 | |2;audio-dft;218;216;223;248;248;235;284;288;224;228 15:18:24 INFO - PID 1915 | |3;audio-fft;84;63;58;88;68;86;85;89;76;62 15:18:24 INFO - PID 1915 | |4;audio-oscillator;92;109;126;110;73;74;87;73;72;86 15:18:24 INFO - PID 1915 | |5;imaging-gaussian-blur;92;104;90;87;135;138;91;92;95;95 15:18:24 INFO - PID 1915 | |6;imaging-darkroom;100;100;100;100;98;98;117;98;100;103 15:18:24 INFO - PID 1915 | |7;imaging-desaturate;107;101;100;122;101;100;101;98;103;102 15:18:24 INFO - PID 1915 | |8;json-parse-financial;62;67;106;106;108;83;70;70;69;69 15:18:24 INFO - PID 1915 | |9;json-stringify-tinderbox;64;75;56;78;100;88;86;60;60;61 15:18:24 INFO - PID 1915 | |10;stanford-crypto-aes;86;86;90;85;88;88;87;76;75;112 15:18:24 INFO - PID 1915 | |11;stanford-crypto-ccm;146;156;153;160;167;167;170;167;209;166 15:18:24 INFO - PID 1915 | |12;stanford-crypto-pbkdf2;194;226;251;206;240;221;194;191;189;237 15:18:24 INFO - PID 1915 | |13;stanford-crypto-sha256-iterative;73;77;72;72;72;69;70;68;69;70 15:18:24 INFO - PID 1915 | __end_tp_report 15:18:24 INFO - PID 1915 | __start_cc_report 15:18:24 INFO - PID 1915 | _x_x_mozilla_cycle_collect,1031 15:18:24 INFO - PID 1915 | __end_cc_report 15:18:24 INFO - PID 1915 | __startTimestamp1490739504812__endTimestamp 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | ------- Summary: start ------- 15:18:24 INFO - PID 1915 | Number of tests: 14 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#0] ai-astar Cycles:10 Average:122.90 Median:122.00 stddev:7.06 (5.8%) stddev-sans-first:5.74 15:18:24 INFO - PID 1915 | Values: 110.0 122.0 129.0 138.0 121.0 121.0 121.0 124.0 122.0 121.0 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:148.00 stddev:18.13 (12.2%) stddev-sans-first:19.21 15:18:24 INFO - PID 1915 | Values: 140.0 124.0 132.0 153.0 112.0 133.0 155.0 155.0 175.0 143.0 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#2] audio-dft Cycles:10 Average:241.20 Median:241.50 stddev:26.09 (10.8%) stddev-sans-first:26.29 15:18:24 INFO - PID 1915 | Values: 218.0 216.0 223.0 248.0 248.0 235.0 284.0 288.0 224.0 228.0 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#3] audio-fft Cycles:10 Average:75.90 Median:84.50 stddev:12.07 (14.3%) stddev-sans-first:12.44 15:18:24 INFO - PID 1915 | Values: 84.0 63.0 58.0 88.0 68.0 86.0 85.0 89.0 76.0 62.0 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:73.50 stddev:18.98 (25.8%) stddev-sans-first:20.12 15:18:24 INFO - PID 1915 | Values: 92.0 109.0 126.0 110.0 73.0 74.0 87.0 73.0 72.0 86.0 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#5] imaging-gaussian-blur Cycles:10 Average:101.90 Median:91.50 stddev:18.79 (20.5%) stddev-sans-first:19.58 15:18:24 INFO - PID 1915 | Values: 92.0 104.0 90.0 87.0 135.0 138.0 91.0 92.0 95.0 95.0 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:110.00 stddev:5.68 (5.2%) stddev-sans-first:6.00 15:18:24 INFO - PID 1915 | Values: 100.0 100.0 100.0 100.0 98.0 98.0 117.0 98.0 100.0 103.0 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#7] imaging-desaturate Cycles:10 Average:103.50 Median:102.50 stddev:6.92 (6.7%) stddev-sans-first:7.22 15:18:24 INFO - PID 1915 | Values: 107.0 101.0 100.0 122.0 101.0 100.0 101.0 98.0 103.0 102.0 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#8] json-parse-financial Cycles:10 Average:81.00 Median:69.00 stddev:18.47 (26.8%) stddev-sans-first:18.27 15:18:24 INFO - PID 1915 | Values: 62.0 67.0 106.0 106.0 108.0 83.0 70.0 70.0 69.0 69.0 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#9] json-stringify-tinderbox Cycles:10 Average:72.80 Median:69.50 stddev:14.92 (21.5%) stddev-sans-first:15.48 15:18:24 INFO - PID 1915 | Values: 64.0 75.0 56.0 78.0 100.0 88.0 86.0 60.0 60.0 61.0 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:86.50 stddev:10.03 (11.6%) stddev-sans-first:10.63 15:18:24 INFO - PID 1915 | Values: 86.0 86.0 90.0 85.0 88.0 88.0 87.0 76.0 75.0 112.0 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#11] stanford-crypto-ccm Cycles:10 Average:166.10 Median:167.00 stddev:16.91 (10.1%) stddev-sans-first:16.29 15:18:24 INFO - PID 1915 | Values: 146.0 156.0 153.0 160.0 167.0 167.0 170.0 167.0 209.0 166.0 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:214.90 Median:223.50 stddev:23.04 (10.3%) stddev-sans-first:23.16 15:18:24 INFO - PID 1915 | Values: 194.0 226.0 251.0 206.0 240.0 221.0 194.0 191.0 189.0 237.0 15:18:24 INFO - PID 1915 | 15:18:24 INFO - PID 1915 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:72.00 stddev:2.62 (3.6%) stddev-sans-first:2.69 15:18:24 INFO - PID 1915 | Values: 73.0 77.0 72.0 72.0 72.0 69.0 70.0 68.0 69.0 70.0 15:18:24 INFO - PID 1915 | -------- Summary: end -------- 15:18:24 INFO - PID 1915 | 15:18:25 INFO - TEST-INFO | 1915: exit 0 15:18:25 INFO - TEST-OK | kraken | took 63335ms 15:18:25 INFO - SUITE-END | took 1181s 15:18:25 INFO - Completed test suite (00:19:41) 15:18:25 INFO - javascript benchmark 15:18:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7638.781665428849, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3183.7930534515253, 3542.1321776164846, 3568.214992701605, 3605.2969670914167, 3606.747527673063, 6119.66341851198, 6172.722227499762, 6186.226721659793, 6201.379862013797, 6222.035584484406, 5046.243063540471, 6149.13912052313, 6174.783882564111, 6208.937910620893, 6274.466670333016, 5698.857528561798, 5710.657360558369, 5792.68140252286, 5867.911981320283, 5892.970535147339, 5559.777608895639, 5689.943100569003, 5830.912536311958, 5841.415858414167, 5892.351841297454, 5497.120460726304, 5562.3047119110115, 5565.777368905239, 5715.657060576358, 5732.0, 4490.910181796346, 5554.1391084381985, 5571.832845014656, 5675.31896172462, 5718.42815718429, 5587.106063029936, 5646.011947909141, 5649.661020338793, 5660.60375773696, 5670.149477578355, 5528.673118451583, 6131.957567213584, 6229.688515574203, 6237.407446292595, 6237.937620623781, 6395.648239346834, 6448.709808058602, 6648.401643852077, 6682.4319932805765, 6713.865722685519, 5699.800506982236, 6753.628550429725, 6756.121704178426, 6803.65981700913, 6814.080099186596, 5292.629515933924, 6278.340774218733, 6332.4934005279465, 6343.238811342622, 6348.269948955837, 6664.733410663616, 6724.4284235840505, 6922.06552115458, 7020.157581090302, 7030.2969703029285, 6353.872922541524, 6434.967825160844, 6537.313582073813, 6543.869122617521, 6675.399214070757, 6750.797476075725, 6769.255381907986, 6777.49168812341, 6778.7288508459105, 6781.864362712817, 6515.120458738104, 6670.232923213795, 6677.29888361715, 6682.866342673119, 6685.632290223987, 6368.681565921685, 6440.098386225932, 6451.1936007999, 6681.766138185238, 6686.264510903797, 4537.0, 5370.624056316098, 5382.138857782736, 5393.541548968303, 5424.0, 4703.717776933395, 4726.1020406122725, 4747.525247475294, 4760.190767569521, 4769.713817170981, 6352.364763523703, 6407.13503677007, 6416.871662566816, 6425.0, 6434.453071488976, 6140.504248697391, 6484.935150648433, 6595.307492713292, 6722.563033402813, 6737.59574425536, 6018.608790428608, 6385.616862988142, 6386.9680651596445, 6429.132067170872, 6429.54993150475, 6050.606710563799, 6080.361562036011, 6093.482054025458, 6099.115628233882, 6115.847103822351, 6731.32686731323, 6825.488088393489, 6963.651817409008, 6988.161420629557, 6999.055127557816, 6710.060591517094, 6817.488688348493, 6851.212110607344, 6854.554453960476, 6855.5886646801355, 6970.059042029464, 7025.648717564203, 7028.718851246097, 7035.577865328097, 7066.964665176642, 6404.871902562016, 6467.482601391784, 6638.103855979381, 6684.8997265042035, 6712.83217919556, 6574.8356291091195, 6782.982552617147, 6804.897926531205, 6809.761658341934, 6820.590764554143, 5635.323761148688, 6276.278228003838, 6514.250861151028, 6528.771492997722, 6540.901886471612, 6335.588186767845, 6466.838329041623, 6508.926027205458, 6537.084808126961, 6574.309697481695, 5550.0, 6296.811095667137, 6419.197600299963, 6460.030995350735, 6463.0, 6573.375529324707, 6595.373439523238, 6606.735730570724, 6607.702653380706, 6622.503312251504, 6180.072989051499, 6232.719527621359, 6347.71435285404, 6392.552521323462, 6399.008153736178], "value": 6130.345320075874, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.2950767263427, 809.8663720486119, 838.2078935406042, 1119.059989608729, 1121.9102471802257, 35952.64047359525, 37179.32693028813, 37223.25553488932, 37231.255374892484, 37272.25455490892, 39426.81719548411, 41032.38451423231, 41300.38049429261, 41524.0, 41527.58472415275, 8458.154184581523, 8635.870461943061, 8681.95659021704, 8761.868571971425, 8776.341774366916, 8804.64781408743, 8889.111088891124, 8908.465492070463, 8926.0, 8937.0, 8873.423227490224, 8952.462852228855, 8954.86567701485, 8960.551972401387, 8964.731058068268, 8906.599203035814, 8906.821863562725, 8917.554122293925, 8924.464532128093, 8944.955275223614, 8667.653293868238, 8692.304615630734, 8693.5218562979, 8725.21473067427, 8752.037275899645, 39736.40395394072, 39825.40261896074, 40018.799906000575, 40033.0, 40119.39820902689, 2618.581027035684, 3043.2544026713376, 3108.0520441265403, 3109.8445077746023, 3113.377324535102, 28380.290492737848, 28395.00617478399, 28518.716657750243, 28590.285242868886, 29373.67818448175, 2399.9920033585936, 2457.938551536208, 2572.3826281692445, 2620.502104600139, 2631.736826317372, 218536.0, 219790.0, 265157.0, 305351.0, 332009.0, 50675.493245067075, 50769.238461423105, 50865.23702144471, 50937.0, 51171.74414127906, 52455.0, 52804.47195527996, 52850.12948316771, 52866.0, 53009.46990530045, 13118.0, 14236.003479756524, 14790.890683198802, 14893.255337233095, 14905.0, 12075.698107547207, 12101.878981210075, 12110.273383597012, 12136.211146275464, 12168.513259469522, 13921.937173821743, 13946.58160255194, 13953.162810231419, 13966.650833729238, 14067.648308792363, 5975.462208401264, 6026.54800889935, 6058.394160583995, 6080.391960803972, 6084.30030546484, 923.6859467780987, 931.3480563605503, 948.6063283737171, 961.6538046303325, 1009.7526106104051, 23407.0, 24688.0, 24933.0026798926, 25017.74982250154, 25035.499290014097, 1060.188955449082, 1072.5066469424153, 1102.7518808268235, 1107.7950579142885, 1114.2423152256542, 12023.579174729017, 12633.873661263453, 12644.936775316064, 12651.177673451197, 12724.681882952815, 381557.0, 381948.0, 382269.0, 383103.0, 384466.0, 57158.428415716146, 57355.71322143362, 57394.713026435435, 57506.7124664374, 57668.8466230673, 1344.8318960129984, 1397.4200706706806, 1429.5711286613973, 1520.9391624334903, 1535.7773122897117, 23848.88075559611, 24171.274861754177, 24253.849817431423, 24381.634275486238, 24397.3900652478, 40112.0, 40156.0, 40195.79902100471, 40249.0, 40344.798276009606, 421073.0, 421125.0, 421511.0, 421621.0, 422219.0, 26297.07960221383, 26659.0, 31974.68025319717, 32306.35387292194, 34097.82951085328, 40193.79903100466, 40543.79728101341, 40635.390469142396, 41261.79369103135, 41540.376894346, 25824.483510330065, 28360.57459138156, 28913.277168070967, 29251.7074829249, 30432.0, 28903.988360407286, 29199.7080029197, 29824.403511929206, 29884.551731723608, 30250.54624180595, 35873.103172420895, 35884.28231435409, 36157.0, 36197.63802362048, 36422.08944776402, 23028.654570181126, 26724.46551068929, 30386.84806575953, 30613.846930765205, 31061.53407698841, 22194.334169974925, 23882.40293992664, 24190.51618967646, 24630.384240394134, 24640.0], "value": 17638.156071539655, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.1367042965626, 430.9355890949358, 435.90845922356294, 446.57798380530403, 455.11025944278936, 18965.810341896595, 20704.964751762407, 21275.0, 21456.46358841028, 21502.462438439048, 20495.795042049576, 22097.889510552464, 22238.332850014485, 22241.44396390091, 22481.0, 6149.600275982058, 6324.0513922911705, 6329.651869147195, 6332.0, 6332.366763323676, 6003.099535069731, 6180.103884936681, 6243.594166379194, 6245.56281060326, 6258.374162583751, 6004.0693692477735, 6172.5370597205165, 6189.412005859436, 6197.628142311453, 6200.255969283669, 6276.309605943343, 6329.145565348665, 6345.49236061114, 6365.968170159166, 6371.776987805404, 5550.555955523549, 5573.665580065169, 5639.746211420496, 5669.744861481243, 5685.943140568624, 21565.0, 26409.73590264092, 27022.189334320003, 27052.0, 28617.427651447062, 1993.1728332741895, 2014.738084049079, 2014.7683016453002, 2028.3712049264627, 2028.377298161465, 16202.75695864563, 16212.75680864788, 16491.0, 16891.662166756596, 17132.743008854875, 1670.8868286349993, 1681.865450763936, 1693.4580934101093, 1709.0941800845446, 1730.2300476288058, 127160.0, 137312.0, 137409.0, 141344.5865541352, 162485.0, 10048.397096174253, 12836.229826210458, 13125.60623181306, 13155.67110822218, 13347.799783003262, 16557.25492352847, 16636.0, 16636.50090497287, 16646.0, 16674.916625417038, 7493.887591686129, 8564.0, 8887.777805554913, 8979.775505612412, 8993.955030224806, 6884.4148247399535, 8333.33333333332, 8343.04055033679, 8348.33213342931, 8424.241818236265, 6712.731490740413, 8033.758987230392, 8049.517028978163, 8104.0, 8179.79550511242, 1991.621591897535, 2254.7294324680815, 2271.716035495563, 2273.079402841852, 2445.8532488050773, 890.5992303463468, 908.977275568116, 911.5077857956762, 941.0589410589411, 953.1469334945324, 17760.91119544394, 21481.46296342604, 21572.784272157078, 21599.920003999738, 22115.0, 927.8886533615874, 954.3891909178132, 956.0917128727598, 960.188640598693, 970.3401686852867, 8110.351171906352, 10753.462326883624, 11026.614068507564, 11185.272957257916, 11256.718582035515, 538839.0, 540918.0, 544809.0, 642437.0, 715072.0, 143510.0, 147873.0, 151175.24412378087, 151244.0, 151256.0, 1165.4709280860552, 1194.8887534592911, 1219.1587804414926, 1219.554862475208, 1220.7070303127364, 21021.57956840824, 21953.451163721034, 22044.448888777908, 22129.01258330105, 22158.0, 44969.55030449654, 53158.46841531535, 55511.72244138754, 56604.71697641486, 56627.86744265174, 76450.23549764432, 78066.0, 80352.59823700844, 80692.59653701694, 81444.59277703811, 6788.76239331621, 8853.73438796837, 8950.955245223733, 9038.141376569216, 9115.589798459217, 11109.611163609236, 12012.459439325077, 14097.929510352382, 14430.927845361126, 14528.0, 11627.941860290644, 11760.76478470443, 11873.703157421134, 11937.880621193677, 11949.64151075469, 9866.062724041205, 11389.943050284695, 11444.027257682857, 11565.537378504765, 11819.40902954866, 11408.600698975495, 11692.415379231175, 11933.28400295985, 12038.217515861441, 12067.155299129328, 8690.435121717068, 8742.38803283764, 8844.95577522134, 9057.701986853963, 9097.818043638958, 11544.88455115438, 11721.941390292994, 11889.0, 11902.404879755804, 12024.158308918291], "value": 10330.770521290775, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.9870101298986, 1441.8053562769023, 1449.2319070892431, 1457.387897083225, 1474.3807600807656, 9872.259580531452, 10616.681499555007, 10936.835947460775, 11074.501647425865, 11092.833607495884, 9170.724878253646, 10430.16558675306, 11140.944295278532, 11149.163812714058, 11152.665420037412, 2461.237016524874, 2509.372656835791, 2510.146550172941, 2514.1451906351836, 2514.270861450177, 2445.498672772086, 2467.3584867934287, 2495.8502489850575, 2500.924972250826, 2511.2842839790665, 1994.7706013808452, 2459.8155138364605, 2464.2114523352534, 2485.5774518331837, 2512.623106534016, 1926.8169523895278, 2062.453449835787, 2310.1336998625516, 2442.5237078769646, 2510.8870100845506, 2056.95886082279, 2133.690614860852, 2260.2089268755803, 2441.060191826142, 2463.6550882876413, 8796.47221166732, 10632.627858024933, 10722.785544289149, 10813.567457301699, 10860.0, 6230.501559875199, 6437.356264373572, 6481.93518064818, 6483.902741458882, 6519.087327774116, 3613.656702613274, 3756.530433695788, 3823.847046118152, 3874.6319099685766, 3928.3910993796007, 1106.5960924262668, 1116.8548088748491, 1140.2189500192312, 1154.4227886056972, 1167.0313639679046, 15416.691666166726, 15428.3828646854, 15573.922130389388, 15574.610634734108, 15582.298796554069, 5060.974695126574, 5432.91850622241, 5542.11326187808, 5580.246666700024, 5588.189712491666, 11451.0, 11651.126165537618, 11690.649280521598, 11705.707357316136, 11728.530858765724, 6312.684365781693, 6314.747410103636, 6316.684165791692, 6325.810225693236, 6334.429901308904, 5659.518940889988, 5877.0, 5960.225170727777, 5972.880542389128, 6041.8489537761025, 4208.537060923296, 4336.132773445323, 4378.0806030733265, 4394.12117576486, 4418.911621767546, 979.480875136179, 1100.3342977498658, 1106.673531308266, 1274.7641686288066, 1296.863829297929, 750.6809605917463, 752.8268498245434, 780.7189411811743, 783.5181363461544, 807.0920214758397, 5910.438508341701, 5995.190649262294, 6086.543509236825, 6131.417515336036, 6150.200473938359, 585.2713371851986, 590.0912594604347, 890.8797312362877, 902.6930843513237, 922.9077092290718, 4436.223660859337, 4472.127935052698, 4479.238529450001, 4510.14307281622, 4633.675642704979, 13217.933910330386, 13508.0, 13515.567197904275, 13549.187048777105, 13590.660233494043, 13307.26810025458, 13486.595402137951, 13512.25682587467, 13764.036517443683, 13879.514217002357, 9981.50277458387, 10240.897591024292, 10241.539130739135, 10261.384316941007, 10275.948620257, 6442.194725659293, 6564.6389448580785, 6819.215790184192, 6863.176418829772, 6884.208316043719, 12212.633620991384, 12249.0, 12331.50673973031, 12347.444365003776, 12485.812712809497, 10585.259031867696, 10800.24398292112, 13158.736825263633, 13384.665383365103, 13394.330283485591, 9172.862407063765, 9267.86098208514, 9272.953635231781, 9323.533823308942, 9414.717558473258, 10214.285000049926, 10230.23273254494, 10260.0, 10271.537780799732, 10323.793524129625, 8600.0, 8636.740897773077, 8650.870236946577, 8654.22112009922, 8853.15894989975, 8442.873356899774, 8479.643257078838, 8496.362772792188, 8504.149585041447, 8514.914850851412, 7732.188120247518, 7741.032370953631, 7742.303192712683, 7866.252705992922, 7889.763307100796, 4736.265878788793, 5720.971395142998, 5830.979578573818, 5941.989861723604, 6035.18524999137, 5858.414158584107, 5985.431384018511, 6028.216331876828, 6071.696415179312, 6103.84740381494], "value": 5134.173068244708, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.59278386558657, 386.3335745838429, 400.83365403357607, 408.2997659014789, 408.46083170215326, 5118.48815118488, 6046.12331211974, 6314.24229092509, 6325.810225693225, 6330.968345158273, 6044.788432404866, 6046.939530604693, 6103.542234332421, 6115.771015781509, 6173.351798061195, 4169.373944161779, 4362.367456718774, 4377.102693947749, 4606.516315786845, 4612.67711260211, 3505.4216054351, 3762.435634654796, 3828.961710382888, 4757.167495688241, 4848.0, 4739.241721324589, 4763.927266912857, 4785.803549112722, 4802.2076357401165, 4859.465458799529, 4811.8075276988875, 4829.348038014893, 4839.911020020502, 4841.3222148899185, 4841.6034063138795, 4041.1715598302276, 4057.9391309130388, 4190.266703326905, 4225.9577404226175, 4274.337477690931, 4914.812908255828, 5840.8247752567495, 5922.674252916088, 5939.584229103964, 5953.851153721148, 5531.976584331871, 5544.390117087117, 5547.057000309957, 5550.333959924795, 5584.5811564132855, 5640.435956404368, 5642.971785141048, 6309.5898766580485, 6564.507661925375, 6568.507361947825, 4703.741294228816, 4710.31700403443, 4713.882152946169, 4719.339292499053, 4723.103439662293, 5539.446055394468, 5837.577405376764, 6278.591891527082, 6336.778212762531, 6456.483481321483, 3703.925921481555, 4539.432570928634, 4841.661083724176, 4869.391326084239, 4893.388326459192, 4589.242774942164, 4711.586524042774, 4934.1611925972675, 4963.875903102451, 4975.328330675313, 5287.286216360819, 5378.354597448253, 5458.044842152607, 5494.780208791683, 5555.916661250085, 6186.381361863778, 6465.806025819233, 6526.771562995273, 6594.0, 6606.0, 1467.9853201468063, 1606.2611198848426, 1693.8221486743714, 1694.6441247338198, 1701.591618011693, 1150.775598758251, 1152.4122697424375, 1172.8798996958055, 1177.5466445418576, 1194.343111288805, 4958.628102892226, 5489.984352894728, 5947.375525569752, 6286.559940804192, 6294.181756371642, 5685.635447492655, 6088.847778805565, 6784.321567843176, 6822.371543476652, 6824.522283440111, 5420.783168673209, 5686.772529098873, 5984.222051133412, 6037.796204385368, 6258.687065646699, 2930.7801914856473, 3003.888561232358, 3154.526820976872, 3159.9684003160132, 3227.306129182237, 6370.55406121567, 6552.967235163794, 6583.736650533925, 6972.337627925441, 7085.92914070863, 6187.752489900444, 6391.913374726308, 6527.053577231275, 6873.862522749517, 7227.385672217921, 5918.852028699317, 6111.236095488064, 7028.824279393056, 7030.859382812213, 7066.858662826715, 5944.8811023780145, 6481.157449531531, 6665.200175978914, 6829.69266383004, 6858.2455929848675, 6083.878322433615, 6561.868762624817, 6649.301823308483, 6664.3335666434905, 6730.798076057726, 6413.583117097373, 6915.167480617741, 7013.859722805618, 7033.472489563405, 7038.753643622448, 4256.701381254088, 4777.546133117348, 4815.133276010211, 4819.373481447389, 4828.5412885775795, 5358.4105748366865, 6040.2751669798145, 6084.239470066242, 6251.780902724107, 6757.695903684445, 5762.740676669644, 6554.787364337612, 6563.671816409256, 6572.375624315682, 6601.636909969806, 5915.290165180206, 6284.371562843679, 6459.0, 6524.325026739204, 6549.70526326326, 5317.0, 5944.197533332945, 6400.591869788639, 6421.935780642134, 6427.646479443675, 6213.720382582885, 6520.771772988112, 6527.836804079936, 6545.952647576555, 6569.441597464078, 5313.68117912911, 5557.970905856238, 5887.3523912370465, 6260.499160067275, 6322.905156422566], "value": 4802.912401115593, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.6959368284271, 682.4199430484086, 725.9745908893188, 741.699611657279, 744.657457569518, 15280.617984550378, 15577.143257120853, 15655.608609784762, 15666.764998525032, 15669.373225071013, 12205.694857628563, 14291.857081429183, 14603.050801697882, 14641.414343426277, 14679.559613211593, 950.7147855643314, 955.5556666150238, 978.9818588667777, 979.8824141103078, 981.7790997025647, 999.7700528878362, 1011.7217765114578, 1018.1803648063307, 1019.3170575714289, 1024.3802499487776, 1045.7542477517777, 1056.4400867540216, 1060.6817954613623, 1060.8514807926897, 1061.5859814672283, 1051.8211903976342, 1052.8104941110596, 1055.145332280855, 1055.7835643693024, 1058.1799105693071, 779.6491578789511, 785.5797148525511, 787.1695361393728, 787.7676085554717, 787.8503084413943, 16595.83404165955, 16932.492025239262, 16937.407190748265, 16985.660286794195, 17131.57171070721, 14694.632634184123, 14775.408983640642, 14776.926115369355, 14811.851881481154, 15030.924845375812, 10112.140468060225, 11066.889331106666, 11677.883221167764, 11693.181477296592, 11776.764464710743, 2507.022261318087, 2656.6147908553344, 2751.435955629111, 2904.8983285585114, 2975.509041008231, 84299.0, 88443.55778221133, 105996.0, 111589.0, 113057.0, 11302.350273628126, 11346.259619148525, 11461.312321260752, 11745.530178792753, 11855.525778968917, 20615.8969205153, 24500.0, 24729.629055564183, 24891.377715556893, 24892.0, 7931.960340198262, 8007.359411247086, 8055.113937466874, 8106.959465202636, 8258.380621453414, 4021.3967904813926, 4092.0792821615496, 4092.9386059209137, 4098.118904435561, 4099.8975025624595, 3983.48214732083, 4030.7380020299174, 4078.6940979426067, 4084.3873418986796, 4091.97954010228, 853.9316854651613, 854.4360721923625, 859.5959898847532, 861.2205953611991, 883.3551507399505, 524.3602804578485, 527.6122050293031, 536.184998801819, 549.4724268365487, 568.408854791021, 8042.879356809653, 9640.142624571816, 9710.152884744963, 9728.854067188999, 9771.706848794547, 534.5856960855291, 555.6332820338557, 557.2282388891415, 564.652738565779, 569.3566270114744, 4184.14225083857, 4263.680223983214, 4482.731036137842, 4527.253003254492, 4579.313103034571, 76906.2309376899, 96957.06085878242, 98891.50554247183, 98956.50521747344, 99046.00953990512, 78512.21487785163, 79003.0, 87896.12103879008, 88303.55848220718, 89931.65102523468, 73958.0, 77770.61114694504, 78157.0, 78406.0, 79059.0, 8740.994785599483, 9082.727518174466, 9102.0, 9118.863217051618, 9194.17252447283, 28990.7100928988, 35586.0, 39308.80345598254, 39751.60248397479, 39991.2001759969, 51282.74358628183, 51692.74153629208, 51743.22385164301, 51884.877990262365, 52475.475245248585, 11631.88368116308, 11703.122265830267, 11738.119641026786, 11807.822882656596, 11828.876256755595, 9968.55141518619, 11397.772044558897, 12440.215299885596, 12596.748065038832, 12614.607963445334, 11132.888671113185, 11723.706907327385, 11807.23252988553, 12201.69495762613, 12378.009759219061, 11422.885771142182, 11626.18616696857, 11862.466189021343, 12143.332116733665, 12154.513819447124, 11495.425228738362, 11566.47950842231, 11585.584152114272, 11686.18196726221, 12047.698807529532, 7645.120811106794, 7951.562664053533, 8534.44526105826, 9011.954940225258, 9324.393914395543, 8496.277816385676, 8777.3416993727, 8976.326775491734, 8977.953926182809, 9558.378705384128], "value": 7212.186943322679, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1672.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 122.0, 129.0, 138.0, 121.0, 121.0, 121.0, 124.0, 122.0, 121.0], "value": 122.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 124.0, 132.0, 153.0, 112.0, 133.0, 155.0, 155.0, 175.0, 143.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 216.0, 223.0, 248.0, 248.0, 235.0, 284.0, 288.0, 224.0, 228.0], "value": 241.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 63.0, 58.0, 88.0, 68.0, 86.0, 85.0, 89.0, 76.0, 62.0], "value": 75.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 109.0, 126.0, 110.0, 73.0, 74.0, 87.0, 73.0, 72.0, 86.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 104.0, 90.0, 87.0, 135.0, 138.0, 91.0, 92.0, 95.0, 95.0], "value": 101.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 100.0, 100.0, 98.0, 98.0, 117.0, 98.0, 100.0, 103.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 101.0, 100.0, 122.0, 101.0, 100.0, 101.0, 98.0, 103.0, 102.0], "value": 103.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 67.0, 106.0, 106.0, 108.0, 83.0, 70.0, 70.0, 69.0, 69.0], "value": 81.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 56.0, 78.0, 100.0, 88.0, 86.0, 60.0, 60.0, 61.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 90.0, 85.0, 88.0, 88.0, 87.0, 76.0, 75.0, 112.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 156.0, 153.0, 160.0, 167.0, 167.0, 170.0, 167.0, 209.0, 166.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 226.0, 251.0, 206.0, 240.0, 221.0, 194.0, 191.0, 189.0, 237.0], "value": 214.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 77.0, 72.0, 72.0, 72.0, 69.0, 70.0, 68.0, 69.0, 70.0], "value": 71.2, "unit": "score"}]}]} 15:18:26 INFO - Return code: 0 15:18:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:18:26 ERROR - # TBPL SUCCESS # 15:18:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:18:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:18:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:18:26 INFO - 2017-03-28 15:18:26.142 screenresolution[1921:11993] starting screenresolution argv=screenresolution get 15:18:26 INFO - 2017-03-28 15:18:26.144 screenresolution[1921:11993] Display 0: 1600x1200x32@60 15:18:26 INFO - 2017-03-28 15:18:26.151 screenresolution[1922:11996] starting screenresolution argv=screenresolution list 15:18:26 INFO - Available Modes on Display 0 15:18:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:18:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:18:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:18:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:18:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:18:26 INFO - Intel Iris: 15:18:26 INFO - Chipset Model: Intel Iris 15:18:26 INFO - Type: GPU 15:18:26 INFO - Bus: Built-In 15:18:26 INFO - VRAM (Dynamic, Max): 1536 MB 15:18:26 INFO - Vendor: Intel (0x8086) 15:18:26 INFO - Device ID: 0x0a2e 15:18:26 INFO - Revision ID: 0x0009 15:18:26 INFO - Displays: 15:18:26 INFO - Display: 15:18:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:18:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:18:26 INFO - Display Serial Number: 1600x1200 60 15:18:26 INFO - Main Display: Yes 15:18:26 INFO - Mirror: Off 15:18:26 INFO - Online: Yes 15:18:26 INFO - Rotation: Supported 15:18:26 INFO - Return code: 0 15:18:26 INFO - Running post-action listener: _resource_record_post_action 15:18:26 INFO - [mozharness: 2017-03-28 22:18:26.387254Z] Finished run-tests step (success) 15:18:26 INFO - Running post-run listener: _resource_record_post_run 15:18:26 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 8234496; Write bytes: 516599808; Read time: 157; Write time: 1612 15:18:26 INFO - TinderboxPrint: CPU usage
54.2% 15:18:26 INFO - TinderboxPrint: I/O read bytes / time
8,234,496 / 157 15:18:26 INFO - TinderboxPrint: I/O write bytes / time
516,599,808 / 1,612 15:18:26 INFO - TinderboxPrint: CPU idle
2,204.5 (45.8%) 15:18:26 INFO - TinderboxPrint: CPU system
962.9 (20.0%) 15:18:26 INFO - TinderboxPrint: CPU user
1,642.2 (34.1%) 15:18:26 INFO - TinderboxPrint: Swap in / out
220,180,480 / 0 15:18:26 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204796416; Write bytes: 204355072; Read time: 15693; Write time: 418 15:18:26 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 8234496; Write bytes: 302217728; Read time: 157; Write time: 1157 15:18:26 INFO - Running post-run listener: _upload_blobber_files 15:18:26 INFO - Blob upload gear active. 15:18:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:18:26 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 15:18:26 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'] 15:18:26 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 15:18:27 INFO - (blobuploader) - INFO - Open directory for files ... 15:18:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:18:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:18:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:18:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:18:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:18:27 INFO - (blobuploader) - INFO - Done attempting. 15:18:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:18:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:18:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:18:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:18:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:18:28 INFO - (blobuploader) - INFO - Done attempting. 15:18:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:18:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:18:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:18:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:18:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:18:29 INFO - (blobuploader) - INFO - Done attempting. 15:18:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:18:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:18:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:18:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:18:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:18:31 INFO - (blobuploader) - INFO - Done attempting. 15:18:31 INFO - (blobuploader) - INFO - Iteration through files over. 15:18:31 INFO - Return code: 0 15:18:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:18:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:18:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe915b1daa8eaf5b129748d55d89b2efffa9ddf21f447ccd9710e8c785143302cdb17d6d696438160da8c1acd5964825c36ef69b10c3f1541eb742581f775315", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd64b2784ceb301ef2f4dd0ffa3661a52d15ad33a9e282749da0ad7d8c2ea436f49bfd0b2fdbb52534256fb19e5e5249a518f297a00268de77edab4a93c7bf1a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80d78d444506d0abefc94b0516ed745bb44a8434a3def9db38642691782b908e9f8d3d224e68b64efcdeb3e6271583b1283e4e342b38ce71de235c0e0264f1fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e772a70417e0354663504c50ed097fa097f85553bdeca4c7412266a0d63f27e6985804959b2714beee5626780a1a6b638d3569744d70ba6a2d77618f8e67d9b"} 15:18:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:18:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:18:31 INFO - Contents: 15:18:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe915b1daa8eaf5b129748d55d89b2efffa9ddf21f447ccd9710e8c785143302cdb17d6d696438160da8c1acd5964825c36ef69b10c3f1541eb742581f775315", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd64b2784ceb301ef2f4dd0ffa3661a52d15ad33a9e282749da0ad7d8c2ea436f49bfd0b2fdbb52534256fb19e5e5249a518f297a00268de77edab4a93c7bf1a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80d78d444506d0abefc94b0516ed745bb44a8434a3def9db38642691782b908e9f8d3d224e68b64efcdeb3e6271583b1283e4e342b38ce71de235c0e0264f1fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e772a70417e0354663504c50ed097fa097f85553bdeca4c7412266a0d63f27e6985804959b2714beee5626780a1a6b638d3569744d70ba6a2d77618f8e67d9b"} 15:18:31 INFO - Running post-run listener: copy_logs_to_upload_dir 15:18:31 INFO - Copying logs to upload dir... 15:18:31 INFO - mkdir: /builds/slave/test/build/upload/logs 15:18:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.455259 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-28 15:18:31.941329) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 15:18:31.944949) ========= 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.Atphqdw2af/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.9igYFpc9zo/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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/fe915b1daa8eaf5b129748d55d89b2efffa9ddf21f447ccd9710e8c785143302cdb17d6d696438160da8c1acd5964825c36ef69b10c3f1541eb742581f775315", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd64b2784ceb301ef2f4dd0ffa3661a52d15ad33a9e282749da0ad7d8c2ea436f49bfd0b2fdbb52534256fb19e5e5249a518f297a00268de77edab4a93c7bf1a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80d78d444506d0abefc94b0516ed745bb44a8434a3def9db38642691782b908e9f8d3d224e68b64efcdeb3e6271583b1283e4e342b38ce71de235c0e0264f1fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e772a70417e0354663504c50ed097fa097f85553bdeca4c7412266a0d63f27e6985804959b2714beee5626780a1a6b638d3569744d70ba6a2d77618f8e67d9b"} build_url:https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011194 build_url: 'https://queue.taskcluster.net/v1/task/Z4j4vSVuTECDoNDG7KmXDg/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/fe915b1daa8eaf5b129748d55d89b2efffa9ddf21f447ccd9710e8c785143302cdb17d6d696438160da8c1acd5964825c36ef69b10c3f1541eb742581f775315", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd64b2784ceb301ef2f4dd0ffa3661a52d15ad33a9e282749da0ad7d8c2ea436f49bfd0b2fdbb52534256fb19e5e5249a518f297a00268de77edab4a93c7bf1a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80d78d444506d0abefc94b0516ed745bb44a8434a3def9db38642691782b908e9f8d3d224e68b64efcdeb3e6271583b1283e4e342b38ce71de235c0e0264f1fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e772a70417e0354663504c50ed097fa097f85553bdeca4c7412266a0d63f27e6985804959b2714beee5626780a1a6b638d3569744d70ba6a2d77618f8e67d9b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 15:18:31.979318) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 15:18:31.979696) ========= 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.Atphqdw2af/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.9igYFpc9zo/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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.005214 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 15:18:32.028372) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 15:18:32.028749) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 15:18:32.029068) ========= ========= Total master_lag: 0.19 =========