builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0309 starttime: 1490907232.14 results: success (0) buildid: 20170330122106 builduid: c7e29e78e8cd439da53cc69445fcce91 revision: 8df9fabf2587b7020889755acb9e75b664fe13cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 13:53:52.137536) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 13:53:52.138036) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 13:53:52.138343) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 13:53:52.165725) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 13:53:52.166250) ========= 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.MCvJwaONay/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.Cxnyl55tEU/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-30 13:53:52-- 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% 204M=0s 2017-03-30 13:53:52 (204 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.190388 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 13:53:52.381085) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 13:53:52.381465) ========= 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.MCvJwaONay/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.Cxnyl55tEU/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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.025800 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 13:53:52.430361) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-30 13:53:52.430721) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8df9fabf2587b7020889755acb9e75b664fe13cf --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 8df9fabf2587b7020889755acb9e75b664fe13cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MCvJwaONay/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.Cxnyl55tEU/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-30 13:53:52,526 truncating revision to first 12 chars 2017-03-30 13:53:52,526 Setting DEBUG logging. 2017-03-30 13:53:52,526 attempt 1/10 2017-03-30 13:53:52,526 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8df9fabf2587?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-30 13:53:54,249 unpacking tar archive at: mozilla-central-8df9fabf2587/testing/mozharness/ program finished with exit code 0 elapsedTime=2.000368 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-30 13:53:54.456256) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 13:53:54.456853) ========= script_repo_revision: 8df9fabf2587b7020889755acb9e75b664fe13cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 13:53:54.457543) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 13:53:54.457874) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 13:53:54.479732) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-30 13:53:54.480449) ========= /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.MCvJwaONay/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.Cxnyl55tEU/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 13:53:54 INFO - MultiFileLogger online at 20170330 13:53:54 in /builds/slave/test 13:53:54 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 13:53:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:53:54 INFO - {'append_to_log': False, 13:53:54 INFO - 'base_work_dir': '/builds/slave/test', 13:53:54 INFO - 'blob_upload_branch': 'Firefox', 13:53:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:53:54 INFO - 'branch': 'Firefox', 13:53:54 INFO - 'buildbot_json_path': 'buildprops.json', 13:53:54 INFO - 'config_files': ('talos/mac_config.py',), 13:53:54 INFO - 'default_actions': ('clobber', 13:53:54 INFO - 'read-buildbot-config', 13:53:54 INFO - 'download-and-extract', 13:53:54 INFO - 'populate-webroot', 13:53:54 INFO - 'create-virtualenv', 13:53:54 INFO - 'install', 13:53:54 INFO - 'run-tests'), 13:53:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:53:54 INFO - 'download_minidump_stackwalk': True, 13:53:54 INFO - 'download_symbols': 'ondemand', 13:53:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:53:54 INFO - 'tooltool.py': '/tools/tooltool.py', 13:53:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:53:54 INFO - '/tools/misc-python/virtualenv.py')}, 13:53:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:53:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:53:54 INFO - 'gecko_profile': False, 13:53:54 INFO - 'gecko_profile_interval': 0, 13:53:54 INFO - 'installer_path': 'installer.exe', 13:53:54 INFO - 'log_level': 'info', 13:53:54 INFO - 'log_name': 'talos', 13:53:54 INFO - 'log_to_console': True, 13:53:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:53:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:53:54 INFO - 'opt_config_files': (), 13:53:54 INFO - 'pip_index': False, 13:53:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:53:54 INFO - 'cmd': ('bash', 13:53:54 INFO - '-c', 13:53:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:53:54 INFO - 'enabled': True, 13:53:54 INFO - 'halt_on_failure': False, 13:53:54 INFO - 'name': 'check_screen_resolution'},), 13:53:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:53:54 INFO - 'cmd': ('bash', 13:53:54 INFO - '-c', 13:53:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:53:54 INFO - 'enabled': True, 13:53:54 INFO - 'halt_on_failure': False, 13:53:54 INFO - 'name': 'check_screen_resolution'},), 13:53:54 INFO - 'run_cmd_checks_enabled': True, 13:53:54 INFO - 'suite': 'dromaeojs-e10s', 13:53:54 INFO - 'system_bits': '32', 13:53:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:53:54 INFO - 'title': 't-yosemite-r7-0309', 13:53:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:53:54 INFO - 'use_talos_json': True, 13:53:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:53:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:53:54 INFO - 'work_dir': 'build'} 13:53:54 INFO - [mozharness: 2017-03-30 20:53:54.684454Z] Running clobber step. 13:53:54 INFO - Running pre-action listener: _resource_record_pre_action 13:53:54 INFO - Running main action method: clobber 13:53:54 INFO - rmtree: /builds/slave/test/build 13:53:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:53:57 INFO - Running post-action listener: _resource_record_post_action 13:53:57 INFO - [mozharness: 2017-03-30 20:53:57.135761Z] Finished clobber step (success) 13:53:57 INFO - [mozharness: 2017-03-30 20:53:57.135902Z] Running read-buildbot-config step. 13:53:57 INFO - Running pre-action listener: _resource_record_pre_action 13:53:57 INFO - Running main action method: read_buildbot_config 13:53:57 INFO - Using buildbot properties: 13:53:57 INFO - { 13:53:57 INFO - "project": "", 13:53:57 INFO - "product": "firefox", 13:53:57 INFO - "script_repo_revision": "production", 13:53:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 13:53:57 INFO - "repository": "", 13:53:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 13:53:57 INFO - "buildid": "20170330122106", 13:53:57 INFO - "pgo_build": "False", 13:53:57 INFO - "basedir": "/builds/slave/test", 13:53:57 INFO - "buildnumber": 6, 13:53:57 INFO - "slavename": "t-yosemite-r7-0309", 13:53:57 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 13:53:57 INFO - "platform": "yosemite_r7", 13:53:57 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 13:53:57 INFO - "branch": "mozilla-central", 13:53:57 INFO - "repo_path": "mozilla-central", 13:53:57 INFO - "revision": "8df9fabf2587b7020889755acb9e75b664fe13cf", 13:53:57 INFO - "stage_platform": "macosx64", 13:53:57 INFO - "builduid": "c7e29e78e8cd439da53cc69445fcce91", 13:53:57 INFO - "slavebuilddir": "test" 13:53:57 INFO - } 13:53:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 13:53:57 INFO - Running post-action listener: _resource_record_post_action 13:53:57 INFO - [mozharness: 2017-03-30 20:53:57.139291Z] Finished read-buildbot-config step (success) 13:53:57 INFO - [mozharness: 2017-03-30 20:53:57.139392Z] Running download-and-extract step. 13:53:57 INFO - Running pre-action listener: _resource_record_pre_action 13:53:57 INFO - Running main action method: download_and_extract 13:53:57 INFO - mkdir: /builds/slave/test/build/tests 13:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:57 INFO - trying https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 13:53:57 INFO - Downloading https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/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 13:53:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/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 13:53:58 INFO - Downloaded 1325 bytes. 13:53:58 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 13:53:58 INFO - Using the following test package requirements: 13:53:58 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:53:58 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 13:53:58 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 13:53:58 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:53:58 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 13:53:58 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:53:58 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 13:53:58 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:53:58 INFO - u'jsshell-mac.zip'], 13:53:58 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:53:58 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 13:53:58 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 13:53:58 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:53:58 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 13:53:58 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:53:58 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 13:53:58 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:53:58 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 13:53:58 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:53:58 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 13:53:58 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:53:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 13:53:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:53:58 INFO - Fetch https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 13:53:59 INFO - Expected file size: 52510035 13:53:59 INFO - Obtained file size: 52510035 13:54:16 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 13:54:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 13:54:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:54:16 INFO - Fetch https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 13:54:18 INFO - Expected file size: 52510035 13:54:18 INFO - Obtained file size: 52510035 13:54:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 13:54:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:54:33 INFO - Fetch https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 13:54:34 INFO - Expected file size: 14404152 13:54:34 INFO - Obtained file size: 14404152 13:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:54:34 INFO - trying https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:54:34 INFO - Downloading https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 13:54:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/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 13:54:36 INFO - Downloaded 75542719 bytes. 13:54:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:54:36 INFO - mkdir: /builds/slave/test/properties 13:54:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:54:36 INFO - Writing to file /builds/slave/test/properties/build_url 13:54:36 INFO - Contents: 13:54:36 INFO - build_url:https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:54:37 INFO - Running post-action listener: _resource_record_post_action 13:54:37 INFO - Running post-action listener: set_extra_try_arguments 13:54:37 INFO - [mozharness: 2017-03-30 20:54:37.498051Z] Finished download-and-extract step (success) 13:54:37 INFO - [mozharness: 2017-03-30 20:54:37.498168Z] Running populate-webroot step. 13:54:37 INFO - Running pre-action listener: _resource_record_pre_action 13:54:37 INFO - Running main action method: populate_webroot 13:54:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:54:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:54:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:54:37 INFO - '--tppagecycles', 13:54:37 INFO - '7'], 13:54:37 INFO - 'tests': ['tsvgm']}}, 13:54:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:54:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:54:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:54:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:54:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:54:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:54:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:54:37 INFO - 'talos_options': ['--disable-e10s'], 13:54:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:54:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:54:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:54:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:54:37 INFO - 'talos_options': ['--disable-e10s'], 13:54:37 INFO - 'tests': ['damp', 'tps']}, 13:54:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:54:37 INFO - 'tests': ['damp', 'tps']}, 13:54:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:54:37 INFO - 'tests': ['dromaeo_dom']}, 13:54:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:54:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:54:37 INFO - 'tests': ['basic_compositor_video']}, 13:54:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:54:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:54:37 INFO - 'tests': ['a11yr', 13:54:37 INFO - 'ts_paint', 13:54:37 INFO - 'tpaint', 13:54:37 INFO - 'sessionrestore', 13:54:37 INFO - 'sessionrestore_no_auto_restore', 13:54:37 INFO - 'tabpaint']}, 13:54:37 INFO - 'other-e10s': {'tests': ['a11yr', 13:54:37 INFO - 'ts_paint', 13:54:37 INFO - 'tpaint', 13:54:37 INFO - 'sessionrestore', 13:54:37 INFO - 'sessionrestore_no_auto_restore', 13:54:37 INFO - 'tabpaint']}, 13:54:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:54:37 INFO - 'ts_paint', 13:54:37 INFO - 'tpaint', 13:54:37 INFO - 'sessionrestore', 13:54:37 INFO - 'sessionrestore_no_auto_restore', 13:54:37 INFO - 'tabpaint']}, 13:54:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:54:37 INFO - 'ts_paint', 13:54:37 INFO - 'tpaint', 13:54:37 INFO - 'sessionrestore', 13:54:37 INFO - 'sessionrestore_no_auto_restore', 13:54:37 INFO - 'tabpaint']}, 13:54:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:54:37 INFO - 'tests': ['a11yr', 13:54:37 INFO - 'ts_paint', 13:54:37 INFO - 'tpaint', 13:54:37 INFO - 'sessionrestore', 13:54:37 INFO - 'sessionrestore_no_auto_restore', 13:54:37 INFO - 'tabpaint']}, 13:54:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:54:37 INFO - 'tests': ['a11yr', 13:54:37 INFO - 'ts_paint', 13:54:37 INFO - 'tpaint', 13:54:37 INFO - 'sessionrestore', 13:54:37 INFO - 'sessionrestore_no_auto_restore', 13:54:37 INFO - 'tabpaint']}, 13:54:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:54:37 INFO - 'tests': ['tsvgx', 13:54:37 INFO - 'tsvgr_opacity', 13:54:37 INFO - 'tart', 13:54:37 INFO - 'tscrollx', 13:54:37 INFO - 'cart', 13:54:37 INFO - 'tsvg_static']}, 13:54:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:54:37 INFO - 'tsvgr_opacity', 13:54:37 INFO - 'tart', 13:54:37 INFO - 'tscrollx', 13:54:37 INFO - 'cart', 13:54:37 INFO - 'tsvg_static']}, 13:54:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:54:37 INFO - 'talos_options': ['--disable-e10s'], 13:54:37 INFO - 'tests': ['tp5o']}, 13:54:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:54:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:54:37 INFO - 'talos_options': ['--disable-e10s', 13:54:37 INFO - '--xperf_path', 13:54:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:54:37 INFO - 'tests': ['tp5n']}, 13:54:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:54:37 INFO - 'talos_options': ['--xperf_path', 13:54:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:54:37 INFO - 'tests': ['tp5n']}}, 13:54:37 INFO - 'talos.zip': {'path': '', 13:54:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:54:37 INFO - Running post-action listener: _resource_record_post_action 13:54:37 INFO - [mozharness: 2017-03-30 20:54:37.516585Z] Finished populate-webroot step (success) 13:54:37 INFO - [mozharness: 2017-03-30 20:54:37.516717Z] Running create-virtualenv step. 13:54:37 INFO - Running pre-action listener: _resource_record_pre_action 13:54:37 INFO - Running main action method: create_virtualenv 13:54:37 INFO - Creating virtualenv /builds/slave/test/build/venv 13:54:37 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 13:54:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:54:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:54:37 INFO - Using real prefix '/tools/python27' 13:54:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:54:38 INFO - Installing distribute.............................................................................................................................................................................................done. 13:54:41 INFO - Installing pip.................done. 13:54:41 INFO - Return code: 0 13:54:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:54:41 INFO - Reading from file tmpfile_stdout 13:54:41 INFO - Output received: 13:54:41 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) 13:54:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:54:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:41 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 0x107d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa31c72980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b73d30>, '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 0x107a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b68be8>, '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.MCvJwaONay/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.Cxnyl55tEU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 13:54:41 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 13:54:41 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 13:54:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MCvJwaONay/Render', 13:54:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:54:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:54:41 INFO - 'HOME': '/Users/cltbld', 13:54:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:54:41 INFO - 'LOGNAME': 'cltbld', 13:54:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:41 INFO - 'MOZ_NO_REMOTE': '1', 13:54:41 INFO - 'NO_EM_RESTART': '1', 13:54:41 INFO - 'PAGER': '/bin/cat', 13:54:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:54:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:41 INFO - 'PWD': '/builds/slave/test', 13:54:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:54:41 INFO - 'SHELL': '/bin/bash', 13:54:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cxnyl55tEU/Listeners', 13:54:41 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 13:54:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:54:41 INFO - 'USER': 'cltbld', 13:54:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:54:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:54:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:41 INFO - 'XPC_FLAGS': '0x0', 13:54:41 INFO - 'XPC_SERVICE_NAME': '0', 13:54:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:54:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:41 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 13:54:41 INFO - Cleaning up... 13:54:41 INFO - Return code: 0 13:54:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:54:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:41 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 0x107d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa31c72980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b73d30>, '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 0x107a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b68be8>, '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.MCvJwaONay/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.Cxnyl55tEU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 13:54:41 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 13:54:41 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 13:54:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MCvJwaONay/Render', 13:54:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:54:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:54:41 INFO - 'HOME': '/Users/cltbld', 13:54:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:54:41 INFO - 'LOGNAME': 'cltbld', 13:54:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:41 INFO - 'MOZ_NO_REMOTE': '1', 13:54:41 INFO - 'NO_EM_RESTART': '1', 13:54:41 INFO - 'PAGER': '/bin/cat', 13:54:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:54:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:41 INFO - 'PWD': '/builds/slave/test', 13:54:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:54:41 INFO - 'SHELL': '/bin/bash', 13:54:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cxnyl55tEU/Listeners', 13:54:41 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 13:54:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:54:41 INFO - 'USER': 'cltbld', 13:54:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:54:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:54:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:41 INFO - 'XPC_FLAGS': '0x0', 13:54:41 INFO - 'XPC_SERVICE_NAME': '0', 13:54:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:54:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:42 INFO - Downloading/unpacking psutil>=3.1.1 13:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:54:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:54:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:54:45 INFO - Installing collected packages: psutil 13:54:45 INFO - Running setup.py install for psutil 13:54:45 INFO - building 'psutil._psutil_osx' extension 13:54:45 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 13:54:46 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 13:54:46 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 13:54:46 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 13:54:46 INFO - building 'psutil._psutil_posix' extension 13:54:46 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 13:54:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:54:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:54:46 INFO - ^ 13:54:46 INFO - 1 warning generated. 13:54:46 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 13:54:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:54:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:54:46 INFO - Successfully installed psutil 13:54:46 INFO - Cleaning up... 13:54:46 INFO - Return code: 0 13:54:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54: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')]} 13:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:48 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 0x107d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa31c72980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b73d30>, '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 0x107a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b68be8>, '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.MCvJwaONay/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.Cxnyl55tEU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 13:54:48 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 13:54:48 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 13:54:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MCvJwaONay/Render', 13:54:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:54:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:54:48 INFO - 'HOME': '/Users/cltbld', 13:54:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:54:48 INFO - 'LOGNAME': 'cltbld', 13:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:48 INFO - 'MOZ_NO_REMOTE': '1', 13:54:48 INFO - 'NO_EM_RESTART': '1', 13:54:48 INFO - 'PAGER': '/bin/cat', 13:54:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:48 INFO - 'PWD': '/builds/slave/test', 13:54:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:54:48 INFO - 'SHELL': '/bin/bash', 13:54:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cxnyl55tEU/Listeners', 13:54:48 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 13:54:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:54:48 INFO - 'USER': 'cltbld', 13:54:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:54:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:48 INFO - 'XPC_FLAGS': '0x0', 13:54:48 INFO - 'XPC_SERVICE_NAME': '0', 13:54:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:54:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:54:48 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 13:54:48 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 13:54:48 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 13:54:48 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 13:54:48 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 13:54:48 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 13:54:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:54:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:54:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:54:51 INFO - Installing collected packages: mozsystemmonitor 13:54:51 INFO - Running setup.py install for mozsystemmonitor 13:54:51 INFO - Successfully installed mozsystemmonitor 13:54:51 INFO - Cleaning up... 13:54:51 INFO - Return code: 0 13:54:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:54:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:54:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa31c72980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b73d30>, '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 0x107a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b68be8>, '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.MCvJwaONay/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.Cxnyl55tEU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 13:54:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:54:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MCvJwaONay/Render', 13:54:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:54:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:54:51 INFO - 'HOME': '/Users/cltbld', 13:54:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:54:51 INFO - 'LOGNAME': 'cltbld', 13:54:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:51 INFO - 'MOZ_NO_REMOTE': '1', 13:54:51 INFO - 'NO_EM_RESTART': '1', 13:54:51 INFO - 'PAGER': '/bin/cat', 13:54:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:54:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:51 INFO - 'PWD': '/builds/slave/test', 13:54:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:54:51 INFO - 'SHELL': '/bin/bash', 13:54:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cxnyl55tEU/Listeners', 13:54:51 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 13:54:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:54:51 INFO - 'USER': 'cltbld', 13:54:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:54:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:54:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:51 INFO - 'XPC_FLAGS': '0x0', 13:54:51 INFO - 'XPC_SERVICE_NAME': '0', 13:54:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:54:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:51 INFO - Downloading/unpacking jsonschema==2.5.1 13:54:51 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 13:54:51 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 13:54:51 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 13:54:51 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 13:54:51 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 13:54:51 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 13:54:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:54:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:54:54 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 13:54:54 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 13:54:54 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 13:54:54 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 13:54:54 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 13:54:54 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 13:54:54 INFO - Downloading functools32-3.2.3-2.tar.gz 13:54:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:54:55 INFO - warning: no files found matching '*.txt' 13:54:55 INFO - no previously-included directories found matching 'build' 13:54:55 INFO - no previously-included directories found matching 'dist' 13:54:55 INFO - no previously-included directories found matching '.git*' 13:54:55 INFO - Installing collected packages: jsonschema, functools32 13:54:55 INFO - Running setup.py install for functools32 13:54:55 INFO - warning: no files found matching '*.txt' 13:54:55 INFO - no previously-included directories found matching 'build' 13:54:55 INFO - no previously-included directories found matching 'dist' 13:54:55 INFO - no previously-included directories found matching '.git*' 13:54:55 INFO - Successfully installed jsonschema functools32 13:54:55 INFO - Cleaning up... 13:54:55 INFO - Return code: 0 13:54:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:54:55 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')]} 13:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:55 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')]} 13:54:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:55 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 0x107d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa31c72980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b73d30>, '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 0x107a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b68be8>, '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.MCvJwaONay/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.Cxnyl55tEU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 13:54:55 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 13:54:55 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 13:54:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MCvJwaONay/Render', 13:54:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:54:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:54:55 INFO - 'HOME': '/Users/cltbld', 13:54:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:54:55 INFO - 'LOGNAME': 'cltbld', 13:54:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:55 INFO - 'MOZ_NO_REMOTE': '1', 13:54:55 INFO - 'NO_EM_RESTART': '1', 13:54:55 INFO - 'PAGER': '/bin/cat', 13:54:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:54:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:55 INFO - 'PWD': '/builds/slave/test', 13:54:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:54:55 INFO - 'SHELL': '/bin/bash', 13:54:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cxnyl55tEU/Listeners', 13:54:55 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 13:54:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:54:55 INFO - 'USER': 'cltbld', 13:54:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:54:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:54:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:55 INFO - 'XPC_FLAGS': '0x0', 13:54:55 INFO - 'XPC_SERVICE_NAME': '0', 13:54:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:54:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:54:55 INFO - Cleaning up... 13:54:55 INFO - Return code: 0 13:54:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:54:55 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')]} 13:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:55 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')]} 13:54:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:55 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 0x107d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa31c72980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b73d30>, '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 0x107a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b68be8>, '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.MCvJwaONay/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.Cxnyl55tEU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 13:54:55 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 13:54:55 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 13:54:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MCvJwaONay/Render', 13:54:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:54:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:54:55 INFO - 'HOME': '/Users/cltbld', 13:54:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:54:55 INFO - 'LOGNAME': 'cltbld', 13:54:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:55 INFO - 'MOZ_NO_REMOTE': '1', 13:54:55 INFO - 'NO_EM_RESTART': '1', 13:54:55 INFO - 'PAGER': '/bin/cat', 13:54:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:54:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:55 INFO - 'PWD': '/builds/slave/test', 13:54:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:54:55 INFO - 'SHELL': '/bin/bash', 13:54:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cxnyl55tEU/Listeners', 13:54:55 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 13:54:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:54:55 INFO - 'USER': 'cltbld', 13:54:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:54:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:54:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:55 INFO - 'XPC_FLAGS': '0x0', 13:54:55 INFO - 'XPC_SERVICE_NAME': '0', 13:54:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:54:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:55 INFO - Downloading/unpacking blobuploader==1.2.4 13:54: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 13:54: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 13:54: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 13:54: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 13:54: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 13:54: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 13:54:58 INFO - Downloading blobuploader-1.2.4.tar.gz 13:54:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:54:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:54:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:54: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 13:54: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 13:54: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 13:54: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 13:54: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 13:54: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 13:54:59 INFO - Downloading docopt-0.6.1.tar.gz 13:54:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:54:59 INFO - Installing collected packages: blobuploader, requests, docopt 13:54:59 INFO - Running setup.py install for blobuploader 13:54:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:54:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:54:59 INFO - Running setup.py install for requests 13:55:00 INFO - Running setup.py install for docopt 13:55:00 INFO - Successfully installed blobuploader requests docopt 13:55:00 INFO - Cleaning up... 13:55:00 INFO - Return code: 0 13:55:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:55:00 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')]} 13:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:00 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')]} 13:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:00 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 0x107d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa31c72980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b73d30>, '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 0x107a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b68be8>, '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.MCvJwaONay/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.Cxnyl55tEU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 13:55:00 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 13:55:00 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 13:55:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MCvJwaONay/Render', 13:55:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:55:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:55:00 INFO - 'HOME': '/Users/cltbld', 13:55:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:55:00 INFO - 'LOGNAME': 'cltbld', 13:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:00 INFO - 'MOZ_NO_REMOTE': '1', 13:55:00 INFO - 'NO_EM_RESTART': '1', 13:55:00 INFO - 'PAGER': '/bin/cat', 13:55:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:00 INFO - 'PWD': '/builds/slave/test', 13:55:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:55:00 INFO - 'SHELL': '/bin/bash', 13:55:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cxnyl55tEU/Listeners', 13:55:00 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 13:55:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:55:00 INFO - 'USER': 'cltbld', 13:55:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:55:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:00 INFO - 'XPC_FLAGS': '0x0', 13:55:00 INFO - 'XPC_SERVICE_NAME': '0', 13:55:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:55:00 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-2yNtqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:55:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-M9IUtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:55:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-zSf_Bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:55:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-XG5iCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:55:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-knxhyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:55:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-ngSMPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:55:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-bd4bSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:55:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-LScUoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:55:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-XvFMCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:55:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-p5U0NO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:55:01 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-CsB0Di-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:55:02 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-mMKTix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:55:02 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-ig9iAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:55:02 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-Bv40M5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:55:02 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-mLk6Ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:55:02 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-5MZoy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:55:02 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-lQvjaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:55:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:55:02 INFO - Running setup.py install for manifestparser 13:55:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:55:02 INFO - Running setup.py install for mozcrash 13:55:02 INFO - Running setup.py install for mozdebug 13:55:03 INFO - Running setup.py install for mozdevice 13:55:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:55:03 INFO - Running setup.py install for mozfile 13:55:03 INFO - Running setup.py install for mozhttpd 13:55:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:55:03 INFO - Running setup.py install for mozinfo 13:55:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:55:03 INFO - Running setup.py install for mozInstall 13:55:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:55:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:55:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:55:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:55:03 INFO - Running setup.py install for mozleak 13:55:03 INFO - Running setup.py install for mozlog 13:55:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:55:04 INFO - Running setup.py install for moznetwork 13:55:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:55:04 INFO - Running setup.py install for mozprocess 13:55:04 INFO - Running setup.py install for mozprofile 13:55:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:55:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:55:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:55:04 INFO - Running setup.py install for mozrunner 13:55:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:55:04 INFO - Running setup.py install for mozscreenshot 13:55:04 INFO - Running setup.py install for moztest 13:55:05 INFO - Running setup.py install for mozversion 13:55:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:55:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:55:05 INFO - Cleaning up... 13:55:05 INFO - Return code: 0 13:55:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:05 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 0x107d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa31c72980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b73d30>, '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 0x107a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b68be8>, '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.MCvJwaONay/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.Cxnyl55tEU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 13:55:05 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 13:55:05 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 13:55:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MCvJwaONay/Render', 13:55:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:55:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:55:05 INFO - 'HOME': '/Users/cltbld', 13:55:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:55:05 INFO - 'LOGNAME': 'cltbld', 13:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:05 INFO - 'MOZ_NO_REMOTE': '1', 13:55:05 INFO - 'NO_EM_RESTART': '1', 13:55:05 INFO - 'PAGER': '/bin/cat', 13:55:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:05 INFO - 'PWD': '/builds/slave/test', 13:55:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:55:05 INFO - 'SHELL': '/bin/bash', 13:55:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cxnyl55tEU/Listeners', 13:55:05 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 13:55:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:55:05 INFO - 'USER': 'cltbld', 13:55:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:55:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:05 INFO - 'XPC_FLAGS': '0x0', 13:55:05 INFO - 'XPC_SERVICE_NAME': '0', 13:55:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:55:05 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-C6x0Ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:55:05 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)) 13:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:55:05 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-TTHiFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:55:05 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)) 13:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:55:05 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-ghGf9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:55:05 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)) 13:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:55:05 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-mj2xC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:55:05 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)) 13:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:55:05 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-zFaCfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:55:05 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)) 13:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:55:05 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-oB2PTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:55:06 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)) 13:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:55:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-fpQIa9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:55:06 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)) 13:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:55:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-neupfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:55:06 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)) 13:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:55:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-jr5KZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:55:06 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)) 13:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:55:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-9XyugW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:55:06 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)) 13:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:55:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-KiARsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:55:06 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)) 13:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:55:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-jKwBO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:55:06 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)) 13:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:55:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-m8bI_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:55:06 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)) 13:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:55:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-9cNE9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:55:06 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)) 13:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:55:06 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-atEi1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:55:07 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)) 13:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:55:07 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-IQxk4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:55:07 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)) 13:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:55:07 INFO - Running setup.py (path:/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/pip-qzfh_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:55:07 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)) 13:55:07 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)) 13:55:07 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)) 13:55:07 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)) 13:55:07 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)) 13:55:07 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)) 13:55:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:55: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 13:55: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 13:55: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 13:55: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 13:55: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 13:55: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 13:55:09 INFO - Downloading blessings-1.6.tar.gz 13:55:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:55:09 INFO - Installing collected packages: blessings 13:55:09 INFO - Running setup.py install for blessings 13:55:10 INFO - Successfully installed blessings 13:55:10 INFO - Cleaning up... 13:55:10 INFO - Return code: 0 13:55:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:55:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:55:10 INFO - Reading from file tmpfile_stdout 13:55:10 INFO - Current package versions: 13:55:10 INFO - blessings == 1.6 13:55:10 INFO - blobuploader == 1.2.4 13:55:10 INFO - docopt == 0.6.1 13:55:10 INFO - functools32 == 3.2.3-2 13:55:10 INFO - jsonschema == 2.5.1 13:55:10 INFO - manifestparser == 1.1 13:55:10 INFO - mozInstall == 1.12 13:55:10 INFO - mozcrash == 1.0 13:55:10 INFO - mozdebug == 0.1 13:55:10 INFO - mozdevice == 0.49 13:55:10 INFO - mozfile == 1.2 13:55:10 INFO - mozhttpd == 0.7 13:55:10 INFO - mozinfo == 0.9 13:55:10 INFO - mozleak == 0.1 13:55:10 INFO - mozlog == 3.4 13:55:10 INFO - moznetwork == 0.27 13:55:10 INFO - mozprocess == 0.25 13:55:10 INFO - mozprofile == 0.28 13:55:10 INFO - mozrunner == 6.13 13:55:10 INFO - mozscreenshot == 0.1 13:55:10 INFO - mozsystemmonitor == 0.3 13:55:10 INFO - moztest == 0.8 13:55:10 INFO - mozversion == 1.4 13:55:10 INFO - psutil == 3.1.1 13:55:10 INFO - requests == 1.2.3 13:55:10 INFO - wsgiref == 0.1.2 13:55:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:10 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 0x107d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa31c72980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b73d30>, '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 0x107a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b68be8>, '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.MCvJwaONay/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.Cxnyl55tEU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 13:55:10 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 13:55:10 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 13:55:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MCvJwaONay/Render', 13:55:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:55:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:55:10 INFO - 'HOME': '/Users/cltbld', 13:55:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:55:10 INFO - 'LOGNAME': 'cltbld', 13:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:10 INFO - 'MOZ_NO_REMOTE': '1', 13:55:10 INFO - 'NO_EM_RESTART': '1', 13:55:10 INFO - 'PAGER': '/bin/cat', 13:55:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:10 INFO - 'PWD': '/builds/slave/test', 13:55:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:55:10 INFO - 'SHELL': '/bin/bash', 13:55:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cxnyl55tEU/Listeners', 13:55:10 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 13:55:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:55:10 INFO - 'USER': 'cltbld', 13:55:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:55:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:10 INFO - 'XPC_FLAGS': '0x0', 13:55:10 INFO - 'XPC_SERVICE_NAME': '0', 13:55:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:55:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:10 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)) 13:55:10 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)) 13:55:10 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)) 13:55:10 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)) 13:55:10 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)) 13:55:10 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)) 13:55:10 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)) 13:55:10 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)) 13:55:10 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)) 13:55:10 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)) 13:55:10 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)) 13:55:10 INFO - Cleaning up... 13:55:10 INFO - Return code: 0 13:55:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:10 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 0x107d95b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d98768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa31c72980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b73d30>, '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 0x107a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b68be8>, '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.MCvJwaONay/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.Cxnyl55tEU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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 13:55:10 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 13:55:10 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 13:55:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MCvJwaONay/Render', 13:55:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:55:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:55:10 INFO - 'HOME': '/Users/cltbld', 13:55:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:55:10 INFO - 'LOGNAME': 'cltbld', 13:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:10 INFO - 'MOZ_NO_REMOTE': '1', 13:55:10 INFO - 'NO_EM_RESTART': '1', 13:55:10 INFO - 'PAGER': '/bin/cat', 13:55:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:10 INFO - 'PWD': '/builds/slave/test', 13:55:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:55:10 INFO - 'SHELL': '/bin/bash', 13:55:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cxnyl55tEU/Listeners', 13:55:10 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 13:55:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:55:10 INFO - 'USER': 'cltbld', 13:55:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:55:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:10 INFO - 'XPC_FLAGS': '0x0', 13:55:10 INFO - 'XPC_SERVICE_NAME': '0', 13:55:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:55:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:55:10 INFO - Cleaning up... 13:55:10 INFO - Return code: 0 13:55:10 INFO - Running post-action listener: _resource_record_post_action 13:55:10 INFO - Running post-action listener: _start_resource_monitoring 13:55:10 INFO - Starting resource monitoring. 13:55:10 INFO - [mozharness: 2017-03-30 20:55:10.997666Z] Finished create-virtualenv step (success) 13:55:10 INFO - [mozharness: 2017-03-30 20:55:10.998607Z] Running install step. 13:55:10 INFO - Running pre-action listener: _resource_record_pre_action 13:55:10 INFO - Running main action method: install 13:55:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:55:11 INFO - Reading from file tmpfile_stdout 13:55:11 INFO - Detecting whether we're running mozinstall >=1.0... 13:55:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:55:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:55:11 INFO - Reading from file tmpfile_stdout 13:55:11 INFO - Output received: 13:55:11 INFO - Usage: mozinstall [options] installer 13:55:11 INFO - Options: 13:55:11 INFO - -h, --help show this help message and exit 13:55:11 INFO - -d DEST, --destination=DEST 13:55:11 INFO - Directory to install application into. [default: 13:55:11 INFO - "/builds/slave/test"] 13:55:11 INFO - --app=APP Application being installed. [default: firefox] 13:55:11 INFO - mkdir: /builds/slave/test/build/application 13:55:11 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'] 13:55:11 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 13:55:31 INFO - Reading from file tmpfile_stdout 13:55:31 INFO - Output received: 13:55:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:55:31 INFO - Running post-action listener: _resource_record_post_action 13:55:31 INFO - [mozharness: 2017-03-30 20:55:31.824189Z] Finished install step (success) 13:55:31 INFO - [mozharness: 2017-03-30 20:55:31.824360Z] Running run-tests step. 13:55:31 INFO - Running pre-action listener: _resource_record_pre_action 13:55:31 INFO - Running main action method: run_tests 13:55:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:55:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:55:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:55:31 INFO - 2017-03-30 13:55:31.838 screenresolution[1630:7501] starting screenresolution argv=screenresolution get 13:55:31 INFO - 2017-03-30 13:55:31.841 screenresolution[1630:7501] Display 0: 1600x1200x32@60 13:55:31 INFO - 2017-03-30 13:55:31.847 screenresolution[1631:7503] starting screenresolution argv=screenresolution list 13:55:31 INFO - Available Modes on Display 0 13:55:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:55:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:55:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:55:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:55:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:55:32 INFO - Intel Iris: 13:55:32 INFO - Chipset Model: Intel Iris 13:55:32 INFO - Type: GPU 13:55:32 INFO - Bus: Built-In 13:55:32 INFO - VRAM (Dynamic, Max): 1536 MB 13:55:32 INFO - Vendor: Intel (0x8086) 13:55:32 INFO - Device ID: 0x0a2e 13:55:32 INFO - Revision ID: 0x0009 13:55:32 INFO - Displays: 13:55:32 INFO - Display: 13:55:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:55:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:55:32 INFO - Display Serial Number: 1600x1200 60 13:55:32 INFO - Main Display: Yes 13:55:32 INFO - Mirror: Off 13:55:32 INFO - Online: Yes 13:55:32 INFO - Rotation: Supported 13:55:32 INFO - Return code: 0 13:55:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:55:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:55:32 INFO - Python 2.7.3 13:55:32 INFO - Return code: 0 13:55:32 INFO - grabbing minidump binary from tooltool 13:55:32 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')]} 13:55:32 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 0x107b73d30>, '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 0x107a9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b68be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:55:32 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 13:55:32 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 13:55:32 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 13:55:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:55:32 INFO - Return code: 0 13:55:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:55:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:55:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:55:32 INFO - ENV: RUST_BACKTRACE is now 1 13:55:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:55:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:55:32 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/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0309', '--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 13:55:32 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/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0309 --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 13:55:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MCvJwaONay/Render', 13:55:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:55:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:55:32 INFO - 'HOME': '/Users/cltbld', 13:55:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:55:32 INFO - 'LOGNAME': 'cltbld', 13:55:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:55:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:55:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:32 INFO - 'MOZ_NO_REMOTE': '1', 13:55:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:55:32 INFO - 'NO_EM_RESTART': '1', 13:55:32 INFO - 'PAGER': '/bin/cat', 13:55:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:55:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:32 INFO - 'PWD': '/builds/slave/test', 13:55:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:55:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:55:32 INFO - 'RUST_BACKTRACE': '1', 13:55:32 INFO - 'SHELL': '/bin/bash', 13:55:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Cxnyl55tEU/Listeners', 13:55:32 INFO - 'TMPDIR': '/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/', 13:55:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:55:32 INFO - 'USER': 'cltbld', 13:55:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:55:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:55:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:32 INFO - 'XPC_FLAGS': '0x0', 13:55:32 INFO - 'XPC_SERVICE_NAME': '0', 13:55:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:55:32 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/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0309', '--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 13:55:32 INFO - mozversion application_buildid: 20170330122106 13:55:32 INFO - mozversion application_changeset: 8df9fabf2587b7020889755acb9e75b664fe13cf 13:55:32 INFO - mozversion application_display_name: Nightly 13:55:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:55:32 INFO - mozversion application_name: Firefox 13:55:32 INFO - mozversion application_remotingname: firefox 13:55:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:55:32 INFO - mozversion application_vendor: Mozilla 13:55:32 INFO - mozversion application_version: 55.0a1 13:55:32 INFO - mozversion platform_buildid: 20170330122106 13:55:32 INFO - mozversion platform_changeset: 8df9fabf2587b7020889755acb9e75b664fe13cf 13:55:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:55:32 INFO - mozversion platform_version: 55.0a1 13:55:32 INFO - using testdate: 1490907332 13:55:32 INFO - actual date: 1490907332 13:55:32 INFO - starting webserver on 'localhost:49236' 13:55:32 INFO - SUITE-START | Running 2 tests 13:55:32 INFO - TEST-START | dromaeo_css 13:55:32 INFO - operating with platform_type : mac_ 13:55:32 INFO - Initialising browser for dromaeo_css test... 13:55:32 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpq3pGql/profile http://localhost:49236/getInfo.html) 13:55:35 INFO - PID 1641 | 2017-03-30 13:55:35.260 plugin-container[1643:7669] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 13:55:35 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:55:35 INFO - PID 1641 | 2017-03-30 13:55:35.263 plugin-container[1643:7669] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950f, name = 'com.apple.CFPasteboardClient' 13:55:35 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:55:35 INFO - PID 1641 | 2017-03-30 13:55:35.263 plugin-container[1643:7669] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:55:35 INFO - PID 1641 | __metrics Screen width/height:1600/1200 13:55:35 INFO - PID 1641 | colorDepth:24 13:55:35 INFO - PID 1641 | Browser inner width/height: 1024/689 13:55:35 INFO - PID 1641 | __metrics 13:55:35 ERROR - PID 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 13:55:35 ERROR - PID 1641 | 1490907335500 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/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpq3pGql/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpq3pGql/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpq3pGql/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpq3pGql/profile/extensions/talos-powers@TalosPowersService.js:55:9 13:55:36 INFO - TEST-INFO | 1641: exit 0 13:55:36 INFO - Browser initialized. 13:55:36 INFO - Running cycle 1/1 for dromaeo_css test... 13:55:36 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmpq3pGql/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:55:38 INFO - PID 1644 | 2017-03-30 13:55:38.783 plugin-container[1645:7806] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 13:55:38 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:55:38 INFO - PID 1644 | 2017-03-30 13:55:38.784 plugin-container[1645:7806] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 13:55:38 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:55:38 INFO - PID 1644 | 2017-03-30 13:55:38.784 plugin-container[1645:7806] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:55:48 INFO - PID 1644 | RSS: Main: 230137856 13:55:48 INFO - PID 1644 | 13:58:52 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 13:58:54 INFO - PID 1644 | RSS: Main: 252174336 13:58:54 INFO - PID 1644 | 14:00:36 INFO - PID 1644 | ************************* 14:00:36 INFO - PID 1644 | A coding exception was thrown and uncaught in a Task. 14:00:36 INFO - PID 1644 | 14:00:36 ERROR - PID 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:00:36 INFO - PID 1644 | Full stack: 14:00:36 INFO - PID 1644 | ************************* 14:00:36 INFO - PID 1644 | ************************* 14:00:36 INFO - PID 1644 | A coding exception was thrown and uncaught in a Task. 14:00:36 INFO - PID 1644 | 14:00:36 ERROR - PID 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:00:36 INFO - PID 1644 | Full stack: 14:00:36 INFO - PID 1644 | ************************* 14:00:36 INFO - PID 1644 | ************************* 14:00:36 INFO - PID 1644 | A coding exception was thrown and uncaught in a Task. 14:00:36 INFO - PID 1644 | 14:00:36 ERROR - PID 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:00:36 INFO - PID 1644 | Full stack: 14:00:36 INFO - PID 1644 | ************************* 14:00:36 INFO - PID 1644 | ************************* 14:00:36 INFO - PID 1644 | A coding exception was thrown and uncaught in a Task. 14:00:36 INFO - PID 1644 | 14:00:36 ERROR - PID 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:00:36 INFO - PID 1644 | Full stack: 14:00:36 INFO - PID 1644 | ************************* 14:01:56 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 14:01:57 INFO - PID 1644 | RSS: Main: 260464640 14:01:57 INFO - PID 1644 | 14:04:59 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 14:05:00 INFO - PID 1644 | RSS: Main: 271790080 14:05:00 INFO - PID 1644 | 14:08:01 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 14:08:02 INFO - PID 1644 | RSS: Main: 264871936 14:08:02 INFO - PID 1644 | 14:11:05 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 14:11:06 INFO - PID 1644 | RSS: Main: 275333120 14:11:06 INFO - PID 1644 | 14:14:09 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 14:14:09 INFO - PID 1644 | RSS: Main: 272183296 14:14:09 INFO - PID 1644 | 14:14:09 INFO - PID 1644 | __start_tp_report 14:14:09 INFO - PID 1644 | _x_x_mozilla_page_load 14:14:09 INFO - PID 1644 | _x_x_mozilla_page_load_details 14:14:09 INFO - PID 1644 | |i|pagename|runs| 14:14:09 INFO - PID 1644 | |0;dojo.html;3308.139883630256;3572.7677700949434;3615.313090512804;3642.307961487319;3647.8358473868675;6218.22272215973;6241.25104987401;6249.687515624223;6258.155149054876;6258.749650013994;6107.158472117341;6175.042868355412;6187.659678717669;6220.315765265817;6230.345813689554;5430.429804870491;5612.158176273561;5702.515286200678;5742.913856292159;5803.738831752581;4848.830290939818;5076.187809950408;5595.412481689405;5660.886782264352;5750.482456578907;5589.692566908819;5678.4605462480995;5700.942990570104;5716.514096301819;5728.484436400723;5621.437856214346;5635.774569017234;5674.290713660793;5694.145878118274;5744.74148663307;5594.58040646949;5623.240862483553;5626.718664066781;5640.266765320482;5651.604387692863;6146.969265153645;6154.384561543855;6243.125962365272;6249.312575616678;6345.968270158666;6663.300353462865;6753.3921947025;6765.594064356154;6778.694958726869;6784.151981002375;6793.966030169818;6855.760048398282;6855.897161542581;6869.313068693141;6870.587764734082;6155.553444940436;6387.616742995435;6404.167458230447;6407.711652975628;6410.583312084699;6864.347886950732;6949.096617439802;6976.7209311627985;6999.615021173884;6999.825004374829;6415.871682566322;6479.514036447191;6590.7693230736695;6659.9344104942975;6665.800025999228;6724.462043036546;6751.4261287790105;6790.558613690193;6839.487038472134;6842.213145488333;6487.481001519867;6633.303503132198;6714.194296684332;6719.798406047827;6725.899111513331;6534.836629084216;6660.433863121689;6672.766453174116;6730.663466826639;6768.932310676929;5402.51377376038;5403.459654034565;5404.729763511808;5415.458454154553;5415.756290966916;4760.381150450488;4808.35087263215;4817.8313759018265;4825.4933232010135;4840.540148685869;5255.448177941261;6373.203349581302;6373.904391434132;6403.775867844603;6446.484281257488;6884.173899132136;6906.171259448898;6913.066735990677;6914.723411063501;6924.515283930076;5595.972020139873;6349.984002559569;6412.3267056959285;6430.324815894263;6468.902966455507;5824.0390335595;5902.940970590325;5905.114232865105;5917.970410147922;6035.155078288955;6887.070245516791;7008.264132266038;7043.225245222919;7059.470539709439;7078;6853.554518956252;6884.793456196322;6895.207051189177;6904.723811047502;6915.135608048994;7033.261507541839;7061.152661680631;7067.328603782633;7091.64541772899;7101.786946391617;6155.0151975683675;6174.629522228681;6468.6442245676935;6525.2169739631545;6731.495137864582;6843.726250949906;6862.691178897061;6868.965655171693;6877.209120951155;6877.518573699793;5276.868078298074;6326.367363263821;6451.870962580816;6453.903191452226;6469.805905822833;6599.703013364506;6599.967000164968;6608.669566521751;6611.206655201407;6614.206295244601;6312.936870631235;6380.617162970237;6409.615423074631;6491.188601424822;6509.609423434609;6432.678366081771;6541.6402097885075;6589.5387322888855;6615.04081908111;6638.203415589967;5503.3120859892515;6295.905561416674;6428.228612566335;6451.935480645321;6500.934990650222 14:14:09 INFO - PID 1644 | |1;ext.html;645.8869697802885;764.8623247815393;1040.4485622620014;1074.8656417947757;1079.4656644960746;38560.614393856085;39365.8031709841;39374;39459.99999999993;39461.210775784464;32394.704211831497;38662.8401147966;38866.611333886656;38958.22083558334;39073.02317442058;8050.516968981865;8555.95722021389;8797.868031979526;8827.249683776887;8836.602352894135;8761;8794.648214071429;8836.911630883673;8866.335024873128;8867.157620026119;8815.77960550985;8834.293256539462;8890.110988901122;8901.332400070021;8908.465492070463;8766.211041006276;8843.823123537526;8870.42342247752;8876.778080548038;8883;8593.95703021481;8625.913740862574;8670.826583468359;8675.349348798865;8691.217790398894;38490.80754596237;38651;38746.41880371797;38835.02912427184;38858.61141388578;3004.474217012036;3012.412579546989;3016.4268788930253;3020.275133967832;3025.636923569186;27958.580621290697;28138;28191.731372088307;28994;29096.854515727497;2783.151138902634;2798.2444739920106;2813.4935711571907;2839.1482555233347;2865.298001989505;333040.33479832445;333782.33108834544;335030;335394;336081;40086.59913400887;41026.589734102876;47770.805729856336;48568.028639427015;48852.5114748848;50947;50980;50998.74500627473;51012.74493627508;51677.48322516802;14454.205018724071;14519.53167961449;14526.85473145255;14542.418303267963;14553.854461455461;11608.361540115206;11635;11646;11648.825267620992;11795.174337796443;13629.86370136286;13695;13712.52006179779;13737.725245495034;13827.79258311126;5136.871578210575;5967.104934259809;6035.456808887221;6045.21412216409;6055.364186760326;966.5795379010153;967.4002118686459;978.1734434402919;986.9013098690217;1007.7581380468635;20533;23845;24124.87937560336;24170.91230894614;24196.274111776675;1005.8943810899897;1071.2822408985999;1081.0054749630358;1108.9445527723742;1112.554978008803;11115.388653623966;11760.647180584596;12275.815862762065;12304.753904921852;15987.600309992344;483515.58242208563;488854;489546;490382;490517;58363.4163658358;58611;58922.11616825751;58986.705066474395;59105.704471477366;1408.6830463145711;1472.9116253024638;1476.3799204334205;1476.86708196265;1482.2885015192553;23568.410789730395;26440;28184.85907570449;29520.261993450335;30026.24934376658;38410.847674569806;38588.80705596454;38736.6126338733;38762;38773.612263877;449594;451653;452447;452495.73752132343;453891;33132.83433582816;34904.30191396209;36012.4598131023;36041.27917441584;36065.63934360623;43873.34189987217;44433;44768.55231447644;44968.55031449644;45221.32168017548;24261.75738242595;28914.698838552737;29046.709532905246;30571;30955;29981.70018299789;30737.38525229438;30795.230119246302;30823.147106611672;30930.69069309278;33444.498332525516;36306.63693363138;36405.63594364128;38130.61869381271;39696.80909572718;33512.49731253985;38913.61086389213;39235.62675306465;39970.2005959885;41281.793591031856;30337.544936826405;30604;31276.687233128287;31365.059048228588;31459.84270078635 14:14:09 INFO - PID 1644 | |2;jquery.html;349.5263917391934;416.1199063979682;418.97905104744757;431.96760242981765;443.23763127420847;21219.363419097434;21225.363239102833;21825.345239642797;22108.77891221089;22114.336569902887;21759.67360489594;21957.451063723416;22480.88759556204;22673.433164170903;22700.205492807756;5934.614250073742;6252.124702541648;6277.874442511147;6281.59169653971;6282.811515654537;5932.584719069667;6037.728302226388;6040.063790112517;6124.326324104367;6138.434699151732;5550.555955523549;5718.142278658193;6099.969500152493;6111.419415155576;6114;5856.590038697293;6111;6114.2968558615885;6126.8161955141195;6164.784232551884;5376.650517716375;5386.111291636876;5413.972930135364;5454.12733962568;5469.972650136764;26553.867230663916;26588.601170982452;26868.462630747472;26990.5951410729;27170;1665.4504013675457;1888.4523488188408;1971.0932970833435;2017.3241963942096;2085.436932028359;16214.108224047674;16257.756133658004;16272.51182464528;16662.50012499615;17084;1580.5890468478278;1595.9680806383924;1601.1113831823334;1629.144699033017;1645.3007471824546;146275.80586291215;153571;160109;161492.3850761489;163239.551406729;12072.456739446747;12261.203021803554;12305.56930507428;12348.197367171106;12548;16041.358345666044;16175.595610109842;16191;16200;16218.513444596681;8751.824963500694;8968.282537396992;8969.955150224207;9078.90921090794;9103.908960910307;7805.2194780521495;8089.191080891864;8598.957005214934;8620.00869899957;8852.291816654651;6361.840953976095;7883.2116788321855;7897.7619176137505;7917.683292668344;7930.722424715107;1966.8819870807797;2115.4605575578435;2129.9467513312293;2130.5632345369163;2318.36245032614;803.8030682482829;876.4346996187423;918.93108016899;933.5425641435647;945.8108378324363;18817.43547693544;19746.50633734168;20422.285220017227;20462.79537204639;20541.178352866016;845.9256743935168;945.6312038305067;971.5142428785607;985.1478471122427;990.069334825262;7861;10386.948065259625;10444.791104178026;10547.419891906018;10606.787864242671;648435;649302;660046;661423;667395.6411525573;133142;134737.3263133678;135260.32369838087;152289;159000;1217.573849152807;1236.8824961628632;1251.7684228417772;1267.31564954927;1274.1081243129847;17336.56658583495;17913.552161196076;19811.702824457934;20749.68875466839;20997.89501052485;42200;49001.5099848997;55041.44958550363;64494.38764030749;65444.345556545726;86429.1357086421;86805;87655;88368;89264;12754.170978886343;12825.615231543068;12840.229586224856;13152.605421837361;13229.272390018257;13685.31573421345;13908.930455347658;13970.860291396955;14175.503857364944;14350.282485875874;9921.65274215399;11838.348890810743;11850.525978960744;12059.397030148633;12191.390430478263;10879.891201087888;11105.444727763741;11168.832467512831;11381.829272560753;11557.537698491966;10083.344582602107;10366.222533309881;11995.460204291003;12225.144239903122;12229.633111006684;12645.620631381073;12681.936590317358;12735.55425560138;13036.283004434847;13040.217586944813;11789.830441288726;11997.280163189887;12101.818472723093;12116.697082572653;12143.392830358624 14:14:09 INFO - PID 1644 | |3;mootools.html;1233.9321337326446;1253.4672764075272;1314.7239079793244;1427.108057464085;1441.1929319581036;10765.300255483398;10969.890301096986;11158;11166.776664466706;11325.716857078578;10914.836277455845;11084.279521831095;11085;11092.33445993239;11101.167412444058;2427.4902270523166;2457.0908763757348;2464.7904928081043;2468.3088735154097;2469.1851688942697;2378.480975117887;2423.503181847726;2450.3996520852415;2464.137551856854;2468;2416.5408572371193;2437.270257311813;2457.287386657867;2462.569050416179;2463.9753602463925;2044.7341845560131;2363.2319496163814;2439.6462512935705;2446.4617784087473;2452.509498100378;2380.1193558383334;2441.035790862617;2453.1659235859715;2455.8772061396858;2478.7893029092547;9266.536673166314;11020.834687479693;11166.218364714474;11175.552977880876;11188.720281992933;6243.219597550306;6392.840178995562;6468.094466774655;6483.643399613019;6487.448566871774;4026.3155263604963;4044.0496483326397;4116.876493705194;4136.669066474675;4161.479815023122;1170.8302296167008;1188.5602327139013;1223.3760782001243;1223.9510419583296;1233.6669099343128;14029.368678409495;14499.347529361099;15298.847011529853;15401.152936588483;15404.768928466083;5265.236540701653;5338.119210330252;5499.367572729107;5527.640703354269;5547.223388725582;11410.828837567444;11610.94194529022;11618.186726929203;11623.709407264716;11630.709232269091;6356.586821856565;6360.777372792023;6362.554621176566;6370.522210834206;6371.203599550056;5793.681347525927;5847.298324201123;5920.79277225295;5995.370486098984;6046.3349031606485;3799.8290076946605;4021.235965166609;4168.374743788456;4230.132822771325;4239.0674051708575;1075.4138994248153;1261.3630116790962;1263.671445424178;1269.650846017334;1273.2233337664018;759.3052357093309;762.8474305138994;777.1994845309227;782.4952905376001;792.2631952284431;5094.77073531692;5585.273914391184;5982.9401705983255;6049.213602231682;6120.17377654011;572.2103497173956;576.8644368573382;586.1676419484343;592.8132638218947;593.2288025566746;3919.8240527841535;3929.390944403622;4179.6029377209115;4186.267403204427;4292.570742925683;13777.380017899219;14400.063995840237;14402.495912643217;14459.493917712829;14482.420703171754;14095.929520352332;14315;14337.85662143386;14345.282735863166;14375.28123593837;10006.599736010477;10244.385336879812;10463.94768026155;10490.00344967227;10493.790124197474;6206.751729930752;6310.52671049664;6379.330170332234;6819.420349270367;6864.279250678607;9575.281853860848;12074.939625301817;12270.938645306716;12337.814932776195;12338.75322493563;12721.236725796482;12959.352032398154;12966.286854223108;13010;13204.867951320364;8159.714409995621;8228.62971166287;8255.711050113217;8348.457350272212;8358.498490090615;10080.24398170155;10190.133838623504;10228.335158214692;10290.536925838505;10293.794124117625;8638.222559969632;8656.523891186045;8735.388522803596;8933.240674542736;8933.553322333728;8489.320854331761;8585.527795971282;8621.65513379483;8684.913150868411;8706.47761134334;5916.319623243382;7725.575093369694;7792.532448053135;7880;7898.763037108896;5748.166515855094;5813.76744930198;5898.852528686817;5932.970335148297;6050.394960504091;5891.646501209942;5916.201312822714;6077.908831367445;6109.419605137505;6195.16365290698 14:14:09 INFO - PID 1644 | |4;prototype.html;373.7796095747385;390.6308538431183;390.77530420008486;395.6617092386012;399.58443219052185;5963.761449542018;5999.010163323046;6183.4541364658835;6288.912207065409;6324.2727086385075;5381.632081883978;5918.822435326936;6088.756449742005;6146.907796383058;6151.538634602401;3786;4305.608279554852;4782.856514304576;4829.251466022754;4855.295982082596;3642.7267954903486;4263.253930562155;4361.433013708213;4618.145643056028;4624.97687511562;3689.2990331837;4033.35466325388;4162.97918510407;4571;4585.945232596505;4552.772361381934;4571.26859702446;4589.921368478422;4596.287575425781;4608.33179189019;3281.96718032819;3782.9432558511644;3971.344728119857;3973.8475842006073;3976;5612.017896868032;5799.739011744482;5957.255343082114;5981.282246130492;5993.250843644544;4704.882377940545;5324.387695415038;5364.839054828361;5374.032674118657;5381.569474442035;6185.536084793614;6440.969444888842;6506.772262970773;6547;6573.474122070223;4339.349097635349;4358.237308471005;4367.497737760166;4373.9125217495475;4383.824647014116;6891.138607674041;6988.79033628992;7014.001009712259;7043.857983671948;7076.186238582526;4347.152305300436;4421.557844215553;4431.889202769892;4451.888702782391;4454.265046267395;3986.9865717812318;4533.478649955231;4542.477615074243;4543.295789152687;4550;4325.545817689097;5332.173513105475;5333.826558157277;5349.946500535023;5352.839414817562;5808.448197421239;6357.427831495188;6560.671966401661;6636.515829189618;6674.866502669919;1259.2570383473794;1298.2275546050084;1340.3968214303602;1402.5021117503343;1531.2649928034605;974.3715303629119;995.8954309797512;1012.4633944009687;1016.0855230292796;1018.0684673523643;6296.811095667137;6310.968445157745;6338.30278669346;6359.236891573041;6415.294317625057;6666.166729158856;6814;6840.213375461786;6871.862562748717;6909.2745261746795;4852.8544143675745;6179.3202747697715;6224.62652240867;6232.283287422005;6363.268224154189;3119.8596063176756;3276.3774882772673;3432.090496018557;3555.3422616815974;3556.48430977512;5549.917766035665;6060.848478787977;6188.164597779242;7010.018597396369;7083.822904427328;6045.881511920309;6153.600015999035;6958.868509170893;6993.860122797516;6997.895031574429;5549.833504994856;5667.829965100971;6969.407600353925;7043.612601306875;7073.938909163667;5641.012822755919;6230.563860529901;6754.493412993947;6797.898031529432;6810.455163586802;6354;6470.6441145737435;6665.933450647873;6689.76585819494;6704.899426508508;5896.3514013457625;6966.760456387804;7021;7061.152661680631;7108.289171083057;3852.6339997700175;3879.96120038796;4396.868093957187;4421.911561768682;4447.132809102194;4731.858044258678;6449.7420103195345;6511.706973186313;6736;6788.694508747217;5626.718664066862;6040.214772079602;6384;6658.500612454182;6754.763583274562;6601.471882249504;6607.48027953583;6615.206175259;6618.768343107968;6659.300773418721;5051.621128415457;5303.310569625924;5482.698451585201;5664.405237450173;5679.403662615366;6009.609375390585;6551.475881929339;6561.80314590563;6573.737050517926;6578.967105164444;6213.5339849510565;6359.07793369968;6438.967805160944;6469.4824414045825;6492.675366231764 14:14:09 INFO - PID 1644 | |5;yui.html;574.7988204128555;716.5557354440244;719.6437763307164;745.7539012125999;778.4900889917104;12952.15810972286;13906.93046534766;15795.526134215977;16287.022778633276;16290.26693798779;14510.419583216684;14819.555413337617;15122.092674439515;15262.007969481976;15297.770533442008;947.2138125355938;955.7371722776222;964.961401543941;965.937214081086;975.873136492255;961.3078583419926;970.3644113105927;981.3228872078279;1010.5957616953241;1028.9794204115913;1046.5499835070916;1064.9520771565299;1070.4273213830602;1070.855434516342;1071.9249652524327;1060.581070477165;1061.1192710050639;1062.0706881478707;1062.5643486170673;1065.392726146097;774.7133560582621;785.2540086917451;786.2766255045315;789.3645615279697;790.6402586822982;15875;15998.120103394309;16225.594360140973;16378.83621163785;16384;14571.927140364336;14802.777958330633;14902.552923412315;14968.101913885093;15577.688446231012;9956.258697137608;11271;11736.178467507278;11916.34460104694;12111.87888121125;3015.1557563882147;3067.5245336972803;3102.6587075421685;3103.1466346754596;3198.056573310879;85324;109750.45124774404;113037.04592254973;113088;116588.41705791501;9699.272554558436;11349.446374565656;11573.594924177612;11691.883081169079;12060.577879774166;24549;24645;24873.751262487505;24913.62629560558;25052.749472505406;7705.768826935201;7844.450888437755;7854.803629909184;7879.842403151905;8079.990001249844;3576.5529308836394;3973.6225058619384;4024.9798751006642;4089.7750623715983;4092.1611069730634;4023;4112.650424713873;4136.296829538986;4143.97928010358;4158.792060397029;702.0662518849917;720.7333286683855;829.4691397505601;867.3191544637556;880.7886107334193;543.3344153412118;544.901917654826;569.3338793611484;577.4687287695331;608.4645511949457;10237;10362.637307694346;10372.27394082407;10565.313254638424;10572.629957951589;554.0691638048038;579.4466284698103;581.9650820950671;606.4390438844051;614.8708771158017;4689.929651055237;4759.881002974884;4781.880452988703;4800.35195248637;4801.7118972861745;100794;107244;107599;109309.45345273222;110952.89047109587;54841;58785.706071470224;67059.66470167619;73433;93786.53106734423;54695;57827.554311141714;64150.67924660347;77424.61287693525;78998;9398.95300523493;10083.798324033625;10366.069249884387;10789.946050270013;11042.171837112088;28940.42119157647;37376.6262337384;37905;38143.80928095453;38540.80729596334;49160.26259606037;49236.50763492319;49483.50516494789;49709.50290497049;50148.49851501584;13267.800982985456;13492.59522214335;13696.589102326947;14030.158190508602;14173.929130354281;13311.201327920357;13851.653708657364;14393.280335983369;14498;14529.927350363181;13479;13652.863471365159;13903.374348154559;14041.36813843359;14370.712585748017;12934.56159800343;13379.331033448483;13392.93303533515;13478.460861565427;14331.78502322487;12261.203021803554;12917.61247162549;13033.47866085346;13282.53511127106;13426.395812188279;9625.461474336216;9806.362586431858;9903.306768526134;10086.798264034825;10404.791904161722;10197.490125493843;10614.787704246026;11117.388543630179;11274.098072154373;11303.830442543533 14:14:09 INFO - PID 1644 | __end_tp_report 14:14:09 INFO - PID 1644 | __start_cc_report 14:14:09 INFO - PID 1644 | _x_x_mozilla_cycle_collect,446 14:14:09 INFO - PID 1644 | __end_cc_report 14:14:09 INFO - PID 1644 | __startTimestamp1490908449537__endTimestamp 14:14:09 INFO - PID 1644 | 14:14:09 INFO - PID 1644 | ------- Summary: start ------- 14:14:09 INFO - PID 1644 | Number of tests: 6 14:14:09 INFO - PID 1644 | 14:14:09 INFO - PID 1644 | [#0] dojo.html Cycles:165 Average:6229.62 Median:6428.23 stddev:737.75 (11.5%) stddev-sans-first:703.52 14:14:09 INFO - PID 1644 | Values: 3308.1 3572.8 3615.3 3642.3 3647.8 6218.2 6241.3 6249.7 6258.2 6258.7 6107.2 6175.0 6187.7 6220.3 6230.3 5430.4 5612.2 5702.5 5742.9 5803.7 4848.8 5076.2 5595.4 5660.9 5750.5 5589.7 5678.5 5700.9 5716.5 5728.5 5621.4 5635.8 5674.3 5694.1 5744.7 5594.6 5623.2 5626.7 5640.3 5651.6 6147.0 6154.4 6243.1 6249.3 6346.0 6663.3 6753.4 6765.6 6778.7 6784.2 6794.0 6855.8 6855.9 6869.3 6870.6 6155.6 6387.6 6404.2 6407.7 6410.6 6864.3 6949.1 6976.7 6999.6 6999.8 6415.9 6479.5 6590.8 6659.9 6665.8 6724.5 6751.4 6790.6 6839.5 6842.2 6487.5 6633.3 6714.2 6719.8 6725.9 6534.8 6660.4 6672.8 6730.7 6768.9 5402.5 5403.5 5404.7 5415.5 5415.8 4760.4 4808.4 4817.8 4825.5 4840.5 5255.4 6373.2 6373.9 6403.8 6446.5 6884.2 6906.2 6913.1 6914.7 6924.5 5596.0 6350.0 6412.3 6430.3 6468.9 5824.0 5902.9 5905.1 5918.0 6035.2 6887.1 7008.3 7043.2 7059.5 7078.0 6853.6 6884.8 6895.2 6904.7 6915.1 7033.3 7061.2 7067.3 7091.6 7101.8 6155.0 6174.6 6468.6 6525.2 6731.5 6843.7 6862.7 6869.0 6877.2 6877.5 5276.9 6326.4 6451.9 6453.9 6469.8 6599.7 6600.0 6608.7 6611.2 6614.2 6312.9 6380.6 6409.6 6491.2 6509.6 6432.7 6541.6 6589.5 6615.0 6638.2 5503.3 6295.9 6428.2 6451.9 6500.9 14:14:09 INFO - PID 1644 | 14:14:09 INFO - PID 1644 | [#1] ext.html Cycles:180 Average:56173.21 Median:38450.83 stddev:114287.13 (297.2%) stddev-sans-first:114531.69 14:14:09 INFO - PID 1644 | Values: 645.9 764.9 1040.4 1074.9 1079.5 38560.6 39365.8 39374.0 39460.0 39461.2 32394.7 38662.8 38866.6 38958.2 39073.0 8050.5 8556.0 8797.9 8827.2 8836.6 8761.0 8794.6 8836.9 8866.3 8867.2 8815.8 8834.3 8890.1 8901.3 8908.5 8766.2 8843.8 8870.4 8876.8 8883.0 8594.0 8625.9 8670.8 8675.3 8691.2 38490.8 38651.0 38746.4 38835.0 38858.6 3004.5 3012.4 3016.4 3020.3 3025.6 27958.6 28138.0 28191.7 28994.0 29096.9 2783.2 2798.2 2813.5 2839.1 2865.3 333040.3 333782.3 335030.0 335394.0 336081.0 40086.6 41026.6 47770.8 48568.0 48852.5 50947.0 50980.0 50998.7 51012.7 51677.5 14454.2 14519.5 14526.9 14542.4 14553.9 11608.4 11635.0 11646.0 11648.8 11795.2 13629.9 13695.0 13712.5 13737.7 13827.8 5136.9 5967.1 6035.5 6045.2 6055.4 966.6 967.4 978.2 986.9 1007.8 20533.0 23845.0 24124.9 24170.9 24196.3 1005.9 1071.3 1081.0 1108.9 1112.6 11115.4 11760.6 12275.8 12304.8 15987.6 483515.6 488854.0 489546.0 490382.0 490517.0 58363.4 58611.0 58922.1 58986.7 59105.7 1408.7 1472.9 1476.4 1476.9 1482.3 23568.4 26440.0 28184.9 29520.3 30026.2 38410.8 38588.8 38736.6 38762.0 38773.6 449594.0 451653.0 452447.0 452495.7 453891.0 33132.8 34904.3 36012.5 36041.3 36065.6 43873.3 44433.0 44768.6 44968.6 45221.3 24261.8 28914.7 29046.7 30571.0 30955.0 29981.7 30737.4 30795.2 30823.1 30930.7 33444.5 36306.6 36405.6 38130.6 39696.8 33512.5 38913.6 39235.6 39970.2 41281.8 30337.5 30604.0 31276.7 31365.1 31459.8 14:14:09 INFO - PID 1644 | 14:14:09 INFO - PID 1644 | [#2] jquery.html Cycles:180 Average:39306.99 Median:21222.36 stddev:110514.53 (520.7%) stddev-sans-first:110785.84 14:14:09 INFO - PID 1644 | Values: 349.5 416.1 419.0 432.0 443.2 21219.4 21225.4 21825.3 22108.8 22114.3 21759.7 21957.5 22480.9 22673.4 22700.2 5934.6 6252.1 6277.9 6281.6 6282.8 5932.6 6037.7 6040.1 6124.3 6138.4 5550.6 5718.1 6100.0 6111.4 6114.0 5856.6 6111.0 6114.3 6126.8 6164.8 5376.7 5386.1 5414.0 5454.1 5470.0 26553.9 26588.6 26868.5 26990.6 27170.0 1665.5 1888.5 1971.1 2017.3 2085.4 16214.1 16257.8 16272.5 16662.5 17084.0 1580.6 1596.0 1601.1 1629.1 1645.3 146275.8 153571.0 160109.0 161492.4 163239.6 12072.5 12261.2 12305.6 12348.2 12548.0 16041.4 16175.6 16191.0 16200.0 16218.5 8751.8 8968.3 8970.0 9078.9 9103.9 7805.2 8089.2 8599.0 8620.0 8852.3 6361.8 7883.2 7897.8 7917.7 7930.7 1966.9 2115.5 2129.9 2130.6 2318.4 803.8 876.4 918.9 933.5 945.8 18817.4 19746.5 20422.3 20462.8 20541.2 845.9 945.6 971.5 985.1 990.1 7861.0 10386.9 10444.8 10547.4 10606.8 648435.0 649302.0 660046.0 661423.0 667395.6 133142.0 134737.3 135260.3 152289.0 159000.0 1217.6 1236.9 1251.8 1267.3 1274.1 17336.6 17913.6 19811.7 20749.7 20997.9 42200.0 49001.5 55041.4 64494.4 65444.3 86429.1 86805.0 87655.0 88368.0 89264.0 12754.2 12825.6 12840.2 13152.6 13229.3 13685.3 13908.9 13970.9 14175.5 14350.3 9921.7 11838.3 11850.5 12059.4 12191.4 10879.9 11105.4 11168.8 11381.8 11557.5 10083.3 10366.2 11995.5 12225.1 12229.6 12645.6 12681.9 12735.6 13036.3 13040.2 11789.8 11997.3 12101.8 12116.7 12143.4 14:14:09 INFO - PID 1644 | 14:14:09 INFO - PID 1644 | [#3] mootools.html Cycles:180 Average:6784.90 Median:2468.75 stddev:4238.68 (171.7%) stddev-sans-first:4230.04 14:14:09 INFO - PID 1644 | Values: 1233.9 1253.5 1314.7 1427.1 1441.2 10765.3 10969.9 11158.0 11166.8 11325.7 10914.8 11084.3 11085.0 11092.3 11101.2 2427.5 2457.1 2464.8 2468.3 2469.2 2378.5 2423.5 2450.4 2464.1 2468.0 2416.5 2437.3 2457.3 2462.6 2464.0 2044.7 2363.2 2439.6 2446.5 2452.5 2380.1 2441.0 2453.2 2455.9 2478.8 9266.5 11020.8 11166.2 11175.6 11188.7 6243.2 6392.8 6468.1 6483.6 6487.4 4026.3 4044.0 4116.9 4136.7 4161.5 1170.8 1188.6 1223.4 1224.0 1233.7 14029.4 14499.3 15298.8 15401.2 15404.8 5265.2 5338.1 5499.4 5527.6 5547.2 11410.8 11610.9 11618.2 11623.7 11630.7 6356.6 6360.8 6362.6 6370.5 6371.2 5793.7 5847.3 5920.8 5995.4 6046.3 3799.8 4021.2 4168.4 4230.1 4239.1 1075.4 1261.4 1263.7 1269.7 1273.2 759.3 762.8 777.2 782.5 792.3 5094.8 5585.3 5982.9 6049.2 6120.2 572.2 576.9 586.2 592.8 593.2 3919.8 3929.4 4179.6 4186.3 4292.6 13777.4 14400.1 14402.5 14459.5 14482.4 14095.9 14315.0 14337.9 14345.3 14375.3 10006.6 10244.4 10463.9 10490.0 10493.8 6206.8 6310.5 6379.3 6819.4 6864.3 9575.3 12074.9 12270.9 12337.8 12338.8 12721.2 12959.4 12966.3 13010.0 13204.9 8159.7 8228.6 8255.7 8348.5 8358.5 10080.2 10190.1 10228.3 10290.5 10293.8 8638.2 8656.5 8735.4 8933.2 8933.6 8489.3 8585.5 8621.7 8684.9 8706.5 5916.3 7725.6 7792.5 7880.0 7898.8 5748.2 5813.8 5898.9 5933.0 6050.4 5891.6 5916.2 6077.9 6109.4 6195.2 14:14:09 INFO - PID 1644 | 14:14:09 INFO - PID 1644 | [#4] prototype.html Cycles:180 Average:5251.06 Median:5938.04 stddev:1680.24 (28.3%) stddev-sans-first:1644.60 14:14:09 INFO - PID 1644 | Values: 373.8 390.6 390.8 395.7 399.6 5963.8 5999.0 6183.5 6288.9 6324.3 5381.6 5918.8 6088.8 6146.9 6151.5 3786.0 4305.6 4782.9 4829.3 4855.3 3642.7 4263.3 4361.4 4618.1 4625.0 3689.3 4033.4 4163.0 4571.0 4585.9 4552.8 4571.3 4589.9 4596.3 4608.3 3282.0 3782.9 3971.3 3973.8 3976.0 5612.0 5799.7 5957.3 5981.3 5993.3 4704.9 5324.4 5364.8 5374.0 5381.6 6185.5 6441.0 6506.8 6547.0 6573.5 4339.3 4358.2 4367.5 4373.9 4383.8 6891.1 6988.8 7014.0 7043.9 7076.2 4347.2 4421.6 4431.9 4451.9 4454.3 3987.0 4533.5 4542.5 4543.3 4550.0 4325.5 5332.2 5333.8 5349.9 5352.8 5808.4 6357.4 6560.7 6636.5 6674.9 1259.3 1298.2 1340.4 1402.5 1531.3 974.4 995.9 1012.5 1016.1 1018.1 6296.8 6311.0 6338.3 6359.2 6415.3 6666.2 6814.0 6840.2 6871.9 6909.3 4852.9 6179.3 6224.6 6232.3 6363.3 3119.9 3276.4 3432.1 3555.3 3556.5 5549.9 6060.8 6188.2 7010.0 7083.8 6045.9 6153.6 6958.9 6993.9 6997.9 5549.8 5667.8 6969.4 7043.6 7073.9 5641.0 6230.6 6754.5 6797.9 6810.5 6354.0 6470.6 6665.9 6689.8 6704.9 5896.4 6966.8 7021.0 7061.2 7108.3 3852.6 3880.0 4396.9 4421.9 4447.1 4731.9 6449.7 6511.7 6736.0 6788.7 5626.7 6040.2 6384.0 6658.5 6754.8 6601.5 6607.5 6615.2 6618.8 6659.3 5051.6 5303.3 5482.7 5664.4 5679.4 6009.6 6551.5 6561.8 6573.7 6579.0 6213.5 6359.1 6439.0 6469.5 6492.7 14:14:09 INFO - PID 1644 | 14:14:09 INFO - PID 1644 | [#5] yui.html Cycles:180 Average:19069.87 Median:24597.00 stddev:27341.22 (111.2%) stddev-sans-first:27382.65 14:14:09 INFO - PID 1644 | Values: 574.8 716.6 719.6 745.8 778.5 12952.2 13906.9 15795.5 16287.0 16290.3 14510.4 14819.6 15122.1 15262.0 15297.8 947.2 955.7 965.0 965.9 975.9 961.3 970.4 981.3 1010.6 1029.0 1046.5 1065.0 1070.4 1070.9 1071.9 1060.6 1061.1 1062.1 1062.6 1065.4 774.7 785.3 786.3 789.4 790.6 15875.0 15998.1 16225.6 16378.8 16384.0 14571.9 14802.8 14902.6 14968.1 15577.7 9956.3 11271.0 11736.2 11916.3 12111.9 3015.2 3067.5 3102.7 3103.1 3198.1 85324.0 109750.5 113037.0 113088.0 116588.4 9699.3 11349.4 11573.6 11691.9 12060.6 24549.0 24645.0 24873.8 24913.6 25052.7 7705.8 7844.5 7854.8 7879.8 8080.0 3576.6 3973.6 4025.0 4089.8 4092.2 4023.0 4112.7 4136.3 4144.0 4158.8 702.1 720.7 829.5 867.3 880.8 543.3 544.9 569.3 577.5 608.5 10237.0 10362.6 10372.3 10565.3 10572.6 554.1 579.4 582.0 606.4 614.9 4689.9 4759.9 4781.9 4800.4 4801.7 100794.0 107244.0 107599.0 109309.5 110952.9 54841.0 58785.7 67059.7 73433.0 93786.5 54695.0 57827.6 64150.7 77424.6 78998.0 9399.0 10083.8 10366.1 10789.9 11042.2 28940.4 37376.6 37905.0 38143.8 38540.8 49160.3 49236.5 49483.5 49709.5 50148.5 13267.8 13492.6 13696.6 14030.2 14173.9 13311.2 13851.7 14393.3 14498.0 14529.9 13479.0 13652.9 13903.4 14041.4 14370.7 12934.6 13379.3 13392.9 13478.5 14331.8 12261.2 12917.6 13033.5 13282.5 13426.4 9625.5 9806.4 9903.3 10086.8 10404.8 10197.5 10614.8 11117.4 11274.1 11303.8 14:14:09 INFO - PID 1644 | -------- Summary: end -------- 14:14:09 INFO - PID 1644 | 14:14:10 INFO - TEST-INFO | 1644: exit 0 14:14:10 INFO - TEST-OK | dromaeo_css | took 1117768ms 14:14:10 INFO - TEST-START | kraken 14:14:10 INFO - operating with platform_type : mac_ 14:14:10 INFO - Initialising browser for kraken test... 14:14:10 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmp37Rn8S/profile http://localhost:49236/getInfo.html) 14:14:13 INFO - PID 1656 | 2017-03-30 14:14:13.124 plugin-container[1657:10442] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 14:14:13 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:14:13 INFO - PID 1656 | 2017-03-30 14:14:13.127 plugin-container[1657:10442] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 14:14:13 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:14:13 INFO - PID 1656 | 2017-03-30 14:14:13.127 plugin-container[1657:10442] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:14:13 INFO - PID 1656 | __metrics Screen width/height:1600/1200 14:14:13 INFO - PID 1656 | colorDepth:24 14:14:13 INFO - PID 1656 | Browser inner width/height: 1024/689 14:14:13 INFO - PID 1656 | __metrics 14:14:13 ERROR - PID 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 14:14:13 ERROR - PID 1656 | 1490908453311 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/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmp37Rn8S/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmp37Rn8S/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmp37Rn8S/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmp37Rn8S/profile/extensions/talos-powers@TalosPowersService.js:55:9 14:14:13 INFO - TEST-INFO | 1656: exit 0 14:14:13 INFO - Browser initialized. 14:14:13 INFO - Running cycle 1/1 for kraken test... 14:14:13 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/T/tmp37Rn8S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:14:16 INFO - PID 1658 | 2017-03-30 14:14:16.443 plugin-container[1659:10561] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 14:14:16 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:14:16 INFO - PID 1658 | 2017-03-30 14:14:16.443 plugin-container[1659:10561] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 14:14:16 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:14:16 INFO - PID 1658 | 2017-03-30 14:14:16.443 plugin-container[1659:10561] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:14:26 INFO - PID 1658 | RSS: Main: 229822464 14:14:26 INFO - PID 1658 | 14:14:29 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 14:14:30 INFO - PID 1658 | RSS: Main: 244711424 14:14:30 INFO - PID 1658 | 14:14:32 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 14:14:33 INFO - PID 1658 | RSS: Main: 253177856 14:14:33 INFO - PID 1658 | 14:14:37 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 14:14:37 INFO - PID 1658 | RSS: Main: 254435328 14:14:37 INFO - PID 1658 | 14:14:39 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 14:14:40 INFO - PID 1658 | RSS: Main: 256430080 14:14:40 INFO - PID 1658 | 14:14:42 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:14:43 INFO - PID 1658 | RSS: Main: 257122304 14:14:43 INFO - PID 1658 | 14:14:46 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 14:14:47 INFO - PID 1658 | RSS: Main: 263102464 14:14:47 INFO - PID 1658 | 14:14:50 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 14:14:51 INFO - PID 1658 | RSS: Main: 263036928 14:14:51 INFO - PID 1658 | 14:14:54 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 14:14:55 INFO - PID 1658 | RSS: Main: 262746112 14:14:55 INFO - PID 1658 | 14:14:57 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:14:57 INFO - PID 1658 | RSS: Main: 262778880 14:14:57 INFO - PID 1658 | 14:14:59 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:15:00 INFO - PID 1658 | RSS: Main: 262791168 14:15:00 INFO - PID 1658 | 14:15:02 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:15:03 INFO - PID 1658 | RSS: Main: 262815744 14:15:03 INFO - PID 1658 | 14:15:06 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:15:07 INFO - PID 1658 | RSS: Main: 263229440 14:15:07 INFO - PID 1658 | 14:15:10 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:15:10 INFO - PID 1658 | RSS: Main: 263229440 14:15:10 INFO - PID 1658 | 14:15:12 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 14:15:13 INFO - PID 1658 | RSS: Main: 266407936 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | __start_tp_report 14:15:13 INFO - PID 1658 | _x_x_mozilla_page_load 14:15:13 INFO - PID 1658 | _x_x_mozilla_page_load_details 14:15:13 INFO - PID 1658 | |i|pagename|runs| 14:15:13 INFO - PID 1658 | |0;ai-astar;112;122;122;130;126;122;123;103;182;182 14:15:13 INFO - PID 1658 | |1;audio-beat-detection;191;101;127;136;99;100;152;155;127;158 14:15:13 INFO - PID 1658 | |2;audio-dft;239;238;308;279;214;216;217;213;212;226 14:15:13 INFO - PID 1658 | |3;audio-fft;71;76;55;77;66;87;89;54;99;74 14:15:13 INFO - PID 1658 | |4;audio-oscillator;97;106;72;103;108;83;102;64;66;102 14:15:13 INFO - PID 1658 | |5;imaging-gaussian-blur;131;94;93;139;94;102;133;146;92;92 14:15:13 INFO - PID 1658 | |6;imaging-darkroom;120;138;100;103;159;101;100;101;101;99 14:15:13 INFO - PID 1658 | |7;imaging-desaturate;105;152;103;103;104;104;107;110;163;162 14:15:13 INFO - PID 1658 | |8;json-parse-financial;68;68;68;68;70;68;70;69;70;68 14:15:13 INFO - PID 1658 | |9;json-stringify-tinderbox;59;64;68;69;60;60;58;58;59;60 14:15:13 INFO - PID 1658 | |10;stanford-crypto-aes;88;88;89;89;89;86;88;91;88;96 14:15:13 INFO - PID 1658 | |11;stanford-crypto-ccm;150;155;166;158;145;181;181;190;282;192 14:15:13 INFO - PID 1658 | |12;stanford-crypto-pbkdf2;225;187;201;201;199;198;203;198;198;225 14:15:13 INFO - PID 1658 | |13;stanford-crypto-sha256-iterative;72;71;71;67;73;71;72;71;63;70 14:15:13 INFO - PID 1658 | __end_tp_report 14:15:13 INFO - PID 1658 | __start_cc_report 14:15:13 INFO - PID 1658 | _x_x_mozilla_cycle_collect,1037 14:15:13 INFO - PID 1658 | __end_cc_report 14:15:13 INFO - PID 1658 | __startTimestamp1490908513012__endTimestamp 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | ------- Summary: start ------- 14:15:13 INFO - PID 1658 | Number of tests: 14 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#0] ai-astar Cycles:10 Average:132.40 Median:124.50 stddev:27.20 (21.9%) stddev-sans-first:27.83 14:15:13 INFO - PID 1658 | Values: 112.0 122.0 122.0 130.0 126.0 122.0 123.0 103.0 182.0 182.0 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#1] audio-beat-detection Cycles:10 Average:134.60 Median:153.50 stddev:30.11 (19.6%) stddev-sans-first:24.04 14:15:13 INFO - PID 1658 | Values: 191.0 101.0 127.0 136.0 99.0 100.0 152.0 155.0 127.0 158.0 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#2] audio-dft Cycles:10 Average:236.20 Median:232.00 stddev:32.48 (14.0%) stddev-sans-first:34.44 14:15:13 INFO - PID 1658 | Values: 239.0 238.0 308.0 279.0 214.0 216.0 217.0 213.0 212.0 226.0 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#3] audio-fft Cycles:10 Average:74.80 Median:76.50 stddev:14.37 (18.8%) stddev-sans-first:15.18 14:15:13 INFO - PID 1658 | Values: 71.0 76.0 55.0 77.0 66.0 87.0 89.0 54.0 99.0 74.0 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:65.00 stddev:17.35 (26.7%) stddev-sans-first:18.24 14:15:13 INFO - PID 1658 | Values: 97.0 106.0 72.0 103.0 108.0 83.0 102.0 64.0 66.0 102.0 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:111.60 Median:92.00 stddev:22.59 (24.6%) stddev-sans-first:22.85 14:15:13 INFO - PID 1658 | Values: 131.0 94.0 93.0 139.0 94.0 102.0 133.0 146.0 92.0 92.0 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#6] imaging-darkroom Cycles:10 Average:112.20 Median:111.50 stddev:20.68 (18.5%) stddev-sans-first:21.74 14:15:13 INFO - PID 1658 | Values: 120.0 138.0 100.0 103.0 159.0 101.0 100.0 101.0 101.0 99.0 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#7] imaging-desaturate Cycles:10 Average:121.30 Median:108.50 stddev:26.26 (24.2%) stddev-sans-first:27.18 14:15:13 INFO - PID 1658 | Values: 105.0 152.0 103.0 103.0 104.0 104.0 107.0 110.0 163.0 162.0 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#8] json-parse-financial Cycles:10 Average:68.70 Median:68.50 stddev:0.95 (1.4%) stddev-sans-first:0.97 14:15:13 INFO - PID 1658 | Values: 68.0 68.0 68.0 68.0 70.0 68.0 70.0 69.0 70.0 68.0 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:60.00 stddev:4.06 (6.8%) stddev-sans-first:4.21 14:15:13 INFO - PID 1658 | Values: 59.0 64.0 68.0 69.0 60.0 60.0 58.0 58.0 59.0 60.0 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:89.00 stddev:2.70 (3.0%) stddev-sans-first:2.83 14:15:13 INFO - PID 1658 | Values: 88.0 88.0 89.0 89.0 89.0 86.0 88.0 91.0 88.0 96.0 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:180.00 Median:181.00 stddev:39.55 (21.9%) stddev-sans-first:40.44 14:15:13 INFO - PID 1658 | Values: 150.0 155.0 166.0 158.0 145.0 181.0 181.0 190.0 282.0 192.0 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:203.50 Median:201.00 stddev:12.11 (6.0%) stddev-sans-first:10.04 14:15:13 INFO - PID 1658 | Values: 225.0 187.0 201.0 201.0 199.0 198.0 203.0 198.0 198.0 225.0 14:15:13 INFO - PID 1658 | 14:15:13 INFO - PID 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:71.00 stddev:2.96 (4.2%) stddev-sans-first:3.06 14:15:13 INFO - PID 1658 | Values: 72.0 71.0 71.0 67.0 73.0 71.0 72.0 71.0 63.0 70.0 14:15:13 INFO - PID 1658 | -------- Summary: end -------- 14:15:13 INFO - PID 1658 | 14:15:13 INFO - TEST-INFO | 1658: exit 0 14:15:13 INFO - TEST-OK | kraken | took 63730ms 14:15:13 INFO - SUITE-END | took 1181s 14:15:14 INFO - Completed test suite (00:19:41) 14:15:14 INFO - javascript benchmark 14:15:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7734.174817171509, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.139883630256, 3572.7677700949434, 3615.313090512804, 3642.307961487319, 3647.8358473868675, 6218.22272215973, 6241.25104987401, 6249.687515624223, 6258.155149054876, 6258.749650013994, 6107.158472117341, 6175.042868355412, 6187.659678717669, 6220.315765265817, 6230.345813689554, 5430.429804870491, 5612.158176273561, 5702.515286200678, 5742.913856292159, 5803.738831752581, 4848.830290939818, 5076.187809950408, 5595.412481689405, 5660.886782264352, 5750.482456578907, 5589.692566908819, 5678.4605462480995, 5700.942990570104, 5716.514096301819, 5728.484436400723, 5621.437856214346, 5635.774569017234, 5674.290713660793, 5694.145878118274, 5744.74148663307, 5594.58040646949, 5623.240862483553, 5626.718664066781, 5640.266765320482, 5651.604387692863, 6146.969265153645, 6154.384561543855, 6243.125962365272, 6249.312575616678, 6345.968270158666, 6663.300353462865, 6753.3921947025, 6765.594064356154, 6778.694958726869, 6784.151981002375, 6793.966030169818, 6855.760048398282, 6855.897161542581, 6869.313068693141, 6870.587764734082, 6155.553444940436, 6387.616742995435, 6404.167458230447, 6407.711652975628, 6410.583312084699, 6864.347886950732, 6949.096617439802, 6976.7209311627985, 6999.615021173884, 6999.825004374829, 6415.871682566322, 6479.514036447191, 6590.7693230736695, 6659.9344104942975, 6665.800025999228, 6724.462043036546, 6751.4261287790105, 6790.558613690193, 6839.487038472134, 6842.213145488333, 6487.481001519867, 6633.303503132198, 6714.194296684332, 6719.798406047827, 6725.899111513331, 6534.836629084216, 6660.433863121689, 6672.766453174116, 6730.663466826639, 6768.932310676929, 5402.51377376038, 5403.459654034565, 5404.729763511808, 5415.458454154553, 5415.756290966916, 4760.381150450488, 4808.35087263215, 4817.8313759018265, 4825.4933232010135, 4840.540148685869, 5255.448177941261, 6373.203349581302, 6373.904391434132, 6403.775867844603, 6446.484281257488, 6884.173899132136, 6906.171259448898, 6913.066735990677, 6914.723411063501, 6924.515283930076, 5595.972020139873, 6349.984002559569, 6412.3267056959285, 6430.324815894263, 6468.902966455507, 5824.0390335595, 5902.940970590325, 5905.114232865105, 5917.970410147922, 6035.155078288955, 6887.070245516791, 7008.264132266038, 7043.225245222919, 7059.470539709439, 7078.0, 6853.554518956252, 6884.793456196322, 6895.207051189177, 6904.723811047502, 6915.135608048994, 7033.261507541839, 7061.152661680631, 7067.328603782633, 7091.64541772899, 7101.786946391617, 6155.0151975683675, 6174.629522228681, 6468.6442245676935, 6525.2169739631545, 6731.495137864582, 6843.726250949906, 6862.691178897061, 6868.965655171693, 6877.209120951155, 6877.518573699793, 5276.868078298074, 6326.367363263821, 6451.870962580816, 6453.903191452226, 6469.805905822833, 6599.703013364506, 6599.967000164968, 6608.669566521751, 6611.206655201407, 6614.206295244601, 6312.936870631235, 6380.617162970237, 6409.615423074631, 6491.188601424822, 6509.609423434609, 6432.678366081771, 6541.6402097885075, 6589.5387322888855, 6615.04081908111, 6638.203415589967, 5503.3120859892515, 6295.905561416674, 6428.228612566335, 6451.935480645321, 6500.934990650222], "value": 6180.62411643134, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.8869697802885, 764.8623247815393, 1040.4485622620014, 1074.8656417947757, 1079.4656644960746, 38560.614393856085, 39365.8031709841, 39374.0, 39459.99999999993, 39461.210775784464, 32394.704211831497, 38662.8401147966, 38866.611333886656, 38958.22083558334, 39073.02317442058, 8050.516968981865, 8555.95722021389, 8797.868031979526, 8827.249683776887, 8836.602352894135, 8761.0, 8794.648214071429, 8836.911630883673, 8866.335024873128, 8867.157620026119, 8815.77960550985, 8834.293256539462, 8890.110988901122, 8901.332400070021, 8908.465492070463, 8766.211041006276, 8843.823123537526, 8870.42342247752, 8876.778080548038, 8883.0, 8593.95703021481, 8625.913740862574, 8670.826583468359, 8675.349348798865, 8691.217790398894, 38490.80754596237, 38651.0, 38746.41880371797, 38835.02912427184, 38858.61141388578, 3004.474217012036, 3012.412579546989, 3016.4268788930253, 3020.275133967832, 3025.636923569186, 27958.580621290697, 28138.0, 28191.731372088307, 28994.0, 29096.854515727497, 2783.151138902634, 2798.2444739920106, 2813.4935711571907, 2839.1482555233347, 2865.298001989505, 333040.33479832445, 333782.33108834544, 335030.0, 335394.0, 336081.0, 40086.59913400887, 41026.589734102876, 47770.805729856336, 48568.028639427015, 48852.5114748848, 50947.0, 50980.0, 50998.74500627473, 51012.74493627508, 51677.48322516802, 14454.205018724071, 14519.53167961449, 14526.85473145255, 14542.418303267963, 14553.854461455461, 11608.361540115206, 11635.0, 11646.0, 11648.825267620992, 11795.174337796443, 13629.86370136286, 13695.0, 13712.52006179779, 13737.725245495034, 13827.79258311126, 5136.871578210575, 5967.104934259809, 6035.456808887221, 6045.21412216409, 6055.364186760326, 966.5795379010153, 967.4002118686459, 978.1734434402919, 986.9013098690217, 1007.7581380468635, 20533.0, 23845.0, 24124.87937560336, 24170.91230894614, 24196.274111776675, 1005.8943810899897, 1071.2822408985999, 1081.0054749630358, 1108.9445527723742, 1112.554978008803, 11115.388653623966, 11760.647180584596, 12275.815862762065, 12304.753904921852, 15987.600309992344, 483515.58242208563, 488854.0, 489546.0, 490382.0, 490517.0, 58363.4163658358, 58611.0, 58922.11616825751, 58986.705066474395, 59105.704471477366, 1408.6830463145711, 1472.9116253024638, 1476.3799204334205, 1476.86708196265, 1482.2885015192553, 23568.410789730395, 26440.0, 28184.85907570449, 29520.261993450335, 30026.24934376658, 38410.847674569806, 38588.80705596454, 38736.6126338733, 38762.0, 38773.612263877, 449594.0, 451653.0, 452447.0, 452495.73752132343, 453891.0, 33132.83433582816, 34904.30191396209, 36012.4598131023, 36041.27917441584, 36065.63934360623, 43873.34189987217, 44433.0, 44768.55231447644, 44968.55031449644, 45221.32168017548, 24261.75738242595, 28914.698838552737, 29046.709532905246, 30571.0, 30955.0, 29981.70018299789, 30737.38525229438, 30795.230119246302, 30823.147106611672, 30930.69069309278, 33444.498332525516, 36306.63693363138, 36405.63594364128, 38130.61869381271, 39696.80909572718, 33512.49731253985, 38913.61086389213, 39235.62675306465, 39970.2005959885, 41281.793591031856, 30337.544936826405, 30604.0, 31276.687233128287, 31365.059048228588, 31459.84270078635], "value": 18277.90353234087, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.5263917391934, 416.1199063979682, 418.97905104744757, 431.96760242981765, 443.23763127420847, 21219.363419097434, 21225.363239102833, 21825.345239642797, 22108.77891221089, 22114.336569902887, 21759.67360489594, 21957.451063723416, 22480.88759556204, 22673.433164170903, 22700.205492807756, 5934.614250073742, 6252.124702541648, 6277.874442511147, 6281.59169653971, 6282.811515654537, 5932.584719069667, 6037.728302226388, 6040.063790112517, 6124.326324104367, 6138.434699151732, 5550.555955523549, 5718.142278658193, 6099.969500152493, 6111.419415155576, 6114.0, 5856.590038697293, 6111.0, 6114.2968558615885, 6126.8161955141195, 6164.784232551884, 5376.650517716375, 5386.111291636876, 5413.972930135364, 5454.12733962568, 5469.972650136764, 26553.867230663916, 26588.601170982452, 26868.462630747472, 26990.5951410729, 27170.0, 1665.4504013675457, 1888.4523488188408, 1971.0932970833435, 2017.3241963942096, 2085.436932028359, 16214.108224047674, 16257.756133658004, 16272.51182464528, 16662.50012499615, 17084.0, 1580.5890468478278, 1595.9680806383924, 1601.1113831823334, 1629.144699033017, 1645.3007471824546, 146275.80586291215, 153571.0, 160109.0, 161492.3850761489, 163239.551406729, 12072.456739446747, 12261.203021803554, 12305.56930507428, 12348.197367171106, 12548.0, 16041.358345666044, 16175.595610109842, 16191.0, 16200.0, 16218.513444596681, 8751.824963500694, 8968.282537396992, 8969.955150224207, 9078.90921090794, 9103.908960910307, 7805.2194780521495, 8089.191080891864, 8598.957005214934, 8620.00869899957, 8852.291816654651, 6361.840953976095, 7883.2116788321855, 7897.7619176137505, 7917.683292668344, 7930.722424715107, 1966.8819870807797, 2115.4605575578435, 2129.9467513312293, 2130.5632345369163, 2318.36245032614, 803.8030682482829, 876.4346996187423, 918.93108016899, 933.5425641435647, 945.8108378324363, 18817.43547693544, 19746.50633734168, 20422.285220017227, 20462.79537204639, 20541.178352866016, 845.9256743935168, 945.6312038305067, 971.5142428785607, 985.1478471122427, 990.069334825262, 7861.0, 10386.948065259625, 10444.791104178026, 10547.419891906018, 10606.787864242671, 648435.0, 649302.0, 660046.0, 661423.0, 667395.6411525573, 133142.0, 134737.3263133678, 135260.32369838087, 152289.0, 159000.0, 1217.573849152807, 1236.8824961628632, 1251.7684228417772, 1267.31564954927, 1274.1081243129847, 17336.56658583495, 17913.552161196076, 19811.702824457934, 20749.68875466839, 20997.89501052485, 42200.0, 49001.5099848997, 55041.44958550363, 64494.38764030749, 65444.345556545726, 86429.1357086421, 86805.0, 87655.0, 88368.0, 89264.0, 12754.170978886343, 12825.615231543068, 12840.229586224856, 13152.605421837361, 13229.272390018257, 13685.31573421345, 13908.930455347658, 13970.860291396955, 14175.503857364944, 14350.282485875874, 9921.65274215399, 11838.348890810743, 11850.525978960744, 12059.397030148633, 12191.390430478263, 10879.891201087888, 11105.444727763741, 11168.832467512831, 11381.829272560753, 11557.537698491966, 10083.344582602107, 10366.222533309881, 11995.460204291003, 12225.144239903122, 12229.633111006684, 12645.620631381073, 12681.936590317358, 12735.55425560138, 13036.283004434847, 13040.217586944813, 11789.830441288726, 11997.280163189887, 12101.818472723093, 12116.697082572653, 12143.392830358624], "value": 10506.90423116163, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1233.9321337326446, 1253.4672764075272, 1314.7239079793244, 1427.108057464085, 1441.1929319581036, 10765.300255483398, 10969.890301096986, 11158.0, 11166.776664466706, 11325.716857078578, 10914.836277455845, 11084.279521831095, 11085.0, 11092.33445993239, 11101.167412444058, 2427.4902270523166, 2457.0908763757348, 2464.7904928081043, 2468.3088735154097, 2469.1851688942697, 2378.480975117887, 2423.503181847726, 2450.3996520852415, 2464.137551856854, 2468.0, 2416.5408572371193, 2437.270257311813, 2457.287386657867, 2462.569050416179, 2463.9753602463925, 2044.7341845560131, 2363.2319496163814, 2439.6462512935705, 2446.4617784087473, 2452.509498100378, 2380.1193558383334, 2441.035790862617, 2453.1659235859715, 2455.8772061396858, 2478.7893029092547, 9266.536673166314, 11020.834687479693, 11166.218364714474, 11175.552977880876, 11188.720281992933, 6243.219597550306, 6392.840178995562, 6468.094466774655, 6483.643399613019, 6487.448566871774, 4026.3155263604963, 4044.0496483326397, 4116.876493705194, 4136.669066474675, 4161.479815023122, 1170.8302296167008, 1188.5602327139013, 1223.3760782001243, 1223.9510419583296, 1233.6669099343128, 14029.368678409495, 14499.347529361099, 15298.847011529853, 15401.152936588483, 15404.768928466083, 5265.236540701653, 5338.119210330252, 5499.367572729107, 5527.640703354269, 5547.223388725582, 11410.828837567444, 11610.94194529022, 11618.186726929203, 11623.709407264716, 11630.709232269091, 6356.586821856565, 6360.777372792023, 6362.554621176566, 6370.522210834206, 6371.203599550056, 5793.681347525927, 5847.298324201123, 5920.79277225295, 5995.370486098984, 6046.3349031606485, 3799.8290076946605, 4021.235965166609, 4168.374743788456, 4230.132822771325, 4239.0674051708575, 1075.4138994248153, 1261.3630116790962, 1263.671445424178, 1269.650846017334, 1273.2233337664018, 759.3052357093309, 762.8474305138994, 777.1994845309227, 782.4952905376001, 792.2631952284431, 5094.77073531692, 5585.273914391184, 5982.9401705983255, 6049.213602231682, 6120.17377654011, 572.2103497173956, 576.8644368573382, 586.1676419484343, 592.8132638218947, 593.2288025566746, 3919.8240527841535, 3929.390944403622, 4179.6029377209115, 4186.267403204427, 4292.570742925683, 13777.380017899219, 14400.063995840237, 14402.495912643217, 14459.493917712829, 14482.420703171754, 14095.929520352332, 14315.0, 14337.85662143386, 14345.282735863166, 14375.28123593837, 10006.599736010477, 10244.385336879812, 10463.94768026155, 10490.00344967227, 10493.790124197474, 6206.751729930752, 6310.52671049664, 6379.330170332234, 6819.420349270367, 6864.279250678607, 9575.281853860848, 12074.939625301817, 12270.938645306716, 12337.814932776195, 12338.75322493563, 12721.236725796482, 12959.352032398154, 12966.286854223108, 13010.0, 13204.867951320364, 8159.714409995621, 8228.62971166287, 8255.711050113217, 8348.457350272212, 8358.498490090615, 10080.24398170155, 10190.133838623504, 10228.335158214692, 10290.536925838505, 10293.794124117625, 8638.222559969632, 8656.523891186045, 8735.388522803596, 8933.240674542736, 8933.553322333728, 8489.320854331761, 8585.527795971282, 8621.65513379483, 8684.913150868411, 8706.47761134334, 5916.319623243382, 7725.575093369694, 7792.532448053135, 7880.0, 7898.763037108896, 5748.166515855094, 5813.76744930198, 5898.852528686817, 5932.970335148297, 6050.394960504091, 5891.646501209942, 5916.201312822714, 6077.908831367445, 6109.419605137505, 6195.16365290698], "value": 5109.837482413136, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.7796095747385, 390.6308538431183, 390.77530420008486, 395.6617092386012, 399.58443219052185, 5963.761449542018, 5999.010163323046, 6183.4541364658835, 6288.912207065409, 6324.2727086385075, 5381.632081883978, 5918.822435326936, 6088.756449742005, 6146.907796383058, 6151.538634602401, 3786.0, 4305.608279554852, 4782.856514304576, 4829.251466022754, 4855.295982082596, 3642.7267954903486, 4263.253930562155, 4361.433013708213, 4618.145643056028, 4624.97687511562, 3689.2990331837, 4033.35466325388, 4162.97918510407, 4571.0, 4585.945232596505, 4552.772361381934, 4571.26859702446, 4589.921368478422, 4596.287575425781, 4608.33179189019, 3281.96718032819, 3782.9432558511644, 3971.344728119857, 3973.8475842006073, 3976.0, 5612.017896868032, 5799.739011744482, 5957.255343082114, 5981.282246130492, 5993.250843644544, 4704.882377940545, 5324.387695415038, 5364.839054828361, 5374.032674118657, 5381.569474442035, 6185.536084793614, 6440.969444888842, 6506.772262970773, 6547.0, 6573.474122070223, 4339.349097635349, 4358.237308471005, 4367.497737760166, 4373.9125217495475, 4383.824647014116, 6891.138607674041, 6988.79033628992, 7014.001009712259, 7043.857983671948, 7076.186238582526, 4347.152305300436, 4421.557844215553, 4431.889202769892, 4451.888702782391, 4454.265046267395, 3986.9865717812318, 4533.478649955231, 4542.477615074243, 4543.295789152687, 4550.0, 4325.545817689097, 5332.173513105475, 5333.826558157277, 5349.946500535023, 5352.839414817562, 5808.448197421239, 6357.427831495188, 6560.671966401661, 6636.515829189618, 6674.866502669919, 1259.2570383473794, 1298.2275546050084, 1340.3968214303602, 1402.5021117503343, 1531.2649928034605, 974.3715303629119, 995.8954309797512, 1012.4633944009687, 1016.0855230292796, 1018.0684673523643, 6296.811095667137, 6310.968445157745, 6338.30278669346, 6359.236891573041, 6415.294317625057, 6666.166729158856, 6814.0, 6840.213375461786, 6871.862562748717, 6909.2745261746795, 4852.8544143675745, 6179.3202747697715, 6224.62652240867, 6232.283287422005, 6363.268224154189, 3119.8596063176756, 3276.3774882772673, 3432.090496018557, 3555.3422616815974, 3556.48430977512, 5549.917766035665, 6060.848478787977, 6188.164597779242, 7010.018597396369, 7083.822904427328, 6045.881511920309, 6153.600015999035, 6958.868509170893, 6993.860122797516, 6997.895031574429, 5549.833504994856, 5667.829965100971, 6969.407600353925, 7043.612601306875, 7073.938909163667, 5641.012822755919, 6230.563860529901, 6754.493412993947, 6797.898031529432, 6810.455163586802, 6354.0, 6470.6441145737435, 6665.933450647873, 6689.76585819494, 6704.899426508508, 5896.3514013457625, 6966.760456387804, 7021.0, 7061.152661680631, 7108.289171083057, 3852.6339997700175, 3879.96120038796, 4396.868093957187, 4421.911561768682, 4447.132809102194, 4731.858044258678, 6449.7420103195345, 6511.706973186313, 6736.0, 6788.694508747217, 5626.718664066862, 6040.214772079602, 6384.0, 6658.500612454182, 6754.763583274562, 6601.471882249504, 6607.48027953583, 6615.206175259, 6618.768343107968, 6659.300773418721, 5051.621128415457, 5303.310569625924, 5482.698451585201, 5664.405237450173, 5679.403662615366, 6009.609375390585, 6551.475881929339, 6561.80314590563, 6573.737050517926, 6578.967105164444, 6213.5339849510565, 6359.07793369968, 6438.967805160944, 6469.4824414045825, 6492.675366231764], "value": 4720.928948959965, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.7988204128555, 716.5557354440244, 719.6437763307164, 745.7539012125999, 778.4900889917104, 12952.15810972286, 13906.93046534766, 15795.526134215977, 16287.022778633276, 16290.26693798779, 14510.419583216684, 14819.555413337617, 15122.092674439515, 15262.007969481976, 15297.770533442008, 947.2138125355938, 955.7371722776222, 964.961401543941, 965.937214081086, 975.873136492255, 961.3078583419926, 970.3644113105927, 981.3228872078279, 1010.5957616953241, 1028.9794204115913, 1046.5499835070916, 1064.9520771565299, 1070.4273213830602, 1070.855434516342, 1071.9249652524327, 1060.581070477165, 1061.1192710050639, 1062.0706881478707, 1062.5643486170673, 1065.392726146097, 774.7133560582621, 785.2540086917451, 786.2766255045315, 789.3645615279697, 790.6402586822982, 15875.0, 15998.120103394309, 16225.594360140973, 16378.83621163785, 16384.0, 14571.927140364336, 14802.777958330633, 14902.552923412315, 14968.101913885093, 15577.688446231012, 9956.258697137608, 11271.0, 11736.178467507278, 11916.34460104694, 12111.87888121125, 3015.1557563882147, 3067.5245336972803, 3102.6587075421685, 3103.1466346754596, 3198.056573310879, 85324.0, 109750.45124774404, 113037.04592254973, 113088.0, 116588.41705791501, 9699.272554558436, 11349.446374565656, 11573.594924177612, 11691.883081169079, 12060.577879774166, 24549.0, 24645.0, 24873.751262487505, 24913.62629560558, 25052.749472505406, 7705.768826935201, 7844.450888437755, 7854.803629909184, 7879.842403151905, 8079.990001249844, 3576.5529308836394, 3973.6225058619384, 4024.9798751006642, 4089.7750623715983, 4092.1611069730634, 4023.0, 4112.650424713873, 4136.296829538986, 4143.97928010358, 4158.792060397029, 702.0662518849917, 720.7333286683855, 829.4691397505601, 867.3191544637556, 880.7886107334193, 543.3344153412118, 544.901917654826, 569.3338793611484, 577.4687287695331, 608.4645511949457, 10237.0, 10362.637307694346, 10372.27394082407, 10565.313254638424, 10572.629957951589, 554.0691638048038, 579.4466284698103, 581.9650820950671, 606.4390438844051, 614.8708771158017, 4689.929651055237, 4759.881002974884, 4781.880452988703, 4800.35195248637, 4801.7118972861745, 100794.0, 107244.0, 107599.0, 109309.45345273222, 110952.89047109587, 54841.0, 58785.706071470224, 67059.66470167619, 73433.0, 93786.53106734423, 54695.0, 57827.554311141714, 64150.67924660347, 77424.61287693525, 78998.0, 9398.95300523493, 10083.798324033625, 10366.069249884387, 10789.946050270013, 11042.171837112088, 28940.42119157647, 37376.6262337384, 37905.0, 38143.80928095453, 38540.80729596334, 49160.26259606037, 49236.50763492319, 49483.50516494789, 49709.50290497049, 50148.49851501584, 13267.800982985456, 13492.59522214335, 13696.589102326947, 14030.158190508602, 14173.929130354281, 13311.201327920357, 13851.653708657364, 14393.280335983369, 14498.0, 14529.927350363181, 13479.0, 13652.863471365159, 13903.374348154559, 14041.36813843359, 14370.712585748017, 12934.56159800343, 13379.331033448483, 13392.93303533515, 13478.460861565427, 14331.78502322487, 12261.203021803554, 12917.61247162549, 13033.47866085346, 13282.53511127106, 13426.395812188279, 9625.461474336216, 9806.362586431858, 9903.306768526134, 10086.798264034825, 10404.791904161722, 10197.490125493843, 10614.787704246026, 11117.388543630179, 11274.098072154373, 11303.830442543533], "value": 7474.503349602651, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1686.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 122.0, 122.0, 130.0, 126.0, 122.0, 123.0, 103.0, 182.0, 182.0], "value": 132.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 101.0, 127.0, 136.0, 99.0, 100.0, 152.0, 155.0, 127.0, 158.0], "value": 134.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [239.0, 238.0, 308.0, 279.0, 214.0, 216.0, 217.0, 213.0, 212.0, 226.0], "value": 236.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 76.0, 55.0, 77.0, 66.0, 87.0, 89.0, 54.0, 99.0, 74.0], "value": 74.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 106.0, 72.0, 103.0, 108.0, 83.0, 102.0, 64.0, 66.0, 102.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 94.0, 93.0, 139.0, 94.0, 102.0, 133.0, 146.0, 92.0, 92.0], "value": 111.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 138.0, 100.0, 103.0, 159.0, 101.0, 100.0, 101.0, 101.0, 99.0], "value": 112.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 152.0, 103.0, 103.0, 104.0, 104.0, 107.0, 110.0, 163.0, 162.0], "value": 121.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 70.0, 68.0, 70.0, 69.0, 70.0, 68.0], "value": 68.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 68.0, 69.0, 60.0, 60.0, 58.0, 58.0, 59.0, 60.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 89.0, 89.0, 89.0, 86.0, 88.0, 91.0, 88.0, 96.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 155.0, 166.0, 158.0, 145.0, 181.0, 181.0, 190.0, 282.0, 192.0], "value": 180.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [225.0, 187.0, 201.0, 201.0, 199.0, 198.0, 203.0, 198.0, 198.0, 225.0], "value": 203.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 71.0, 67.0, 73.0, 71.0, 72.0, 71.0, 63.0, 70.0], "value": 70.1, "unit": "score"}]}]} 14:15:14 INFO - Return code: 0 14:15:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:15:14 ERROR - # TBPL SUCCESS # 14:15:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:15:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:15:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:15:14 INFO - 2017-03-30 14:15:14.355 screenresolution[1663:10966] starting screenresolution argv=screenresolution get 14:15:14 INFO - 2017-03-30 14:15:14.358 screenresolution[1663:10966] Display 0: 1600x1200x32@60 14:15:14 INFO - 2017-03-30 14:15:14.364 screenresolution[1664:10969] starting screenresolution argv=screenresolution list 14:15:14 INFO - Available Modes on Display 0 14:15:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:15:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:15:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:15:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:15:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:15:14 INFO - Intel Iris: 14:15:14 INFO - Chipset Model: Intel Iris 14:15:14 INFO - Type: GPU 14:15:14 INFO - Bus: Built-In 14:15:14 INFO - VRAM (Dynamic, Max): 1536 MB 14:15:14 INFO - Vendor: Intel (0x8086) 14:15:14 INFO - Device ID: 0x0a2e 14:15:14 INFO - Revision ID: 0x0009 14:15:14 INFO - Displays: 14:15:14 INFO - Display: 14:15:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:15:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:15:14 INFO - Display Serial Number: 1600x1200 60 14:15:14 INFO - Main Display: Yes 14:15:14 INFO - Mirror: Off 14:15:14 INFO - Online: Yes 14:15:14 INFO - Rotation: Supported 14:15:14 INFO - Return code: 0 14:15:14 INFO - Running post-action listener: _resource_record_post_action 14:15:14 INFO - [mozharness: 2017-03-30 21:15:14.601358Z] Finished run-tests step (success) 14:15:14 INFO - Running post-run listener: _resource_record_post_run 14:15:14 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 39463424; Write bytes: 519414272; Read time: 252; Write time: 1658 14:15:14 INFO - TinderboxPrint: CPU usage
54.2% 14:15:14 INFO - TinderboxPrint: I/O read bytes / time
39,463,424 / 252 14:15:14 INFO - TinderboxPrint: I/O write bytes / time
519,414,272 / 1,658 14:15:14 INFO - TinderboxPrint: CPU idle
2,202.5 (45.8%) 14:15:14 INFO - TinderboxPrint: CPU system
971.2 (20.2%) 14:15:14 INFO - TinderboxPrint: CPU user
1,640.0 (34.1%) 14:15:14 INFO - TinderboxPrint: Swap in / out
250,896,384 / 0 14:15:14 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203383296; Write bytes: 205264384; Read time: 15610; Write time: 460 14:15:14 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 38439424; Write bytes: 304180224; Read time: 240; Write time: 1164 14:15:15 INFO - Running post-run listener: _upload_blobber_files 14:15:15 INFO - Blob upload gear active. 14:15:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:15:15 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 14:15:15 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'] 14:15:15 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 14:15:15 INFO - (blobuploader) - INFO - Open directory for files ... 14:15:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:15:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:15:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:15:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:15:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:15:16 INFO - (blobuploader) - INFO - Done attempting. 14:15:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:15:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:15:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:15:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:15:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:15:17 INFO - (blobuploader) - INFO - Done attempting. 14:15:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:15:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:15:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:15:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:15:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:15:18 INFO - (blobuploader) - INFO - Done attempting. 14:15:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:15:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:15:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:15:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:15:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:15:20 INFO - (blobuploader) - INFO - Done attempting. 14:15:20 INFO - (blobuploader) - INFO - Iteration through files over. 14:15:20 INFO - Return code: 0 14:15:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:15:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:15:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc7bf06fc87f744a0c105bff9dd604cbc05b4cac1c347a18df2a8dec3e05d0bc94859915dc34b44eefd5597e25de987340b1f8629edb6ff234f6633a28558295", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa3f803b786306d812c85146f53dda5f9c158346b02935bfa6542995d1f9662a721341c54ff6fb83dc9decaee5a811dd48497a81526d084c43334b20fc4f9b86", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc3c215dae6422d58635469b10a4e079f56fb50c8cb563a2931f0b42cfabb07d4350bbd3f7748206fe5f30c93d62898f22d53f0ebd3ae29b06f0e799d86c077a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e9c49d0e0a5b2ee2732b6776a1110deabb683e61ca9aed7fa2a83d0edcfb0b41bd9c30216d3bdcc0ba944a5e38d294d8bfa54d03e7184d6a1fc34e1be6d762c"} 14:15:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:15:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:15:20 INFO - Contents: 14:15:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc7bf06fc87f744a0c105bff9dd604cbc05b4cac1c347a18df2a8dec3e05d0bc94859915dc34b44eefd5597e25de987340b1f8629edb6ff234f6633a28558295", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa3f803b786306d812c85146f53dda5f9c158346b02935bfa6542995d1f9662a721341c54ff6fb83dc9decaee5a811dd48497a81526d084c43334b20fc4f9b86", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc3c215dae6422d58635469b10a4e079f56fb50c8cb563a2931f0b42cfabb07d4350bbd3f7748206fe5f30c93d62898f22d53f0ebd3ae29b06f0e799d86c077a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e9c49d0e0a5b2ee2732b6776a1110deabb683e61ca9aed7fa2a83d0edcfb0b41bd9c30216d3bdcc0ba944a5e38d294d8bfa54d03e7184d6a1fc34e1be6d762c"} 14:15:20 INFO - Running post-run listener: copy_logs_to_upload_dir 14:15:20 INFO - Copying logs to upload dir... 14:15:20 INFO - mkdir: /builds/slave/test/build/upload/logs 14:15:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.189743 ========= master_lag: 1.54 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-30 14:15:22.212141) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 14:15:22.213309) ========= 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.MCvJwaONay/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.Cxnyl55tEU/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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/cc7bf06fc87f744a0c105bff9dd604cbc05b4cac1c347a18df2a8dec3e05d0bc94859915dc34b44eefd5597e25de987340b1f8629edb6ff234f6633a28558295", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa3f803b786306d812c85146f53dda5f9c158346b02935bfa6542995d1f9662a721341c54ff6fb83dc9decaee5a811dd48497a81526d084c43334b20fc4f9b86", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc3c215dae6422d58635469b10a4e079f56fb50c8cb563a2931f0b42cfabb07d4350bbd3f7748206fe5f30c93d62898f22d53f0ebd3ae29b06f0e799d86c077a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e9c49d0e0a5b2ee2732b6776a1110deabb683e61ca9aed7fa2a83d0edcfb0b41bd9c30216d3bdcc0ba944a5e38d294d8bfa54d03e7184d6a1fc34e1be6d762c"} build_url:https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011164 build_url: 'https://queue.taskcluster.net/v1/task/d2nYgIagRxOSHke-COGNdg/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/cc7bf06fc87f744a0c105bff9dd604cbc05b4cac1c347a18df2a8dec3e05d0bc94859915dc34b44eefd5597e25de987340b1f8629edb6ff234f6633a28558295", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa3f803b786306d812c85146f53dda5f9c158346b02935bfa6542995d1f9662a721341c54ff6fb83dc9decaee5a811dd48497a81526d084c43334b20fc4f9b86", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc3c215dae6422d58635469b10a4e079f56fb50c8cb563a2931f0b42cfabb07d4350bbd3f7748206fe5f30c93d62898f22d53f0ebd3ae29b06f0e799d86c077a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e9c49d0e0a5b2ee2732b6776a1110deabb683e61ca9aed7fa2a83d0edcfb0b41bd9c30216d3bdcc0ba944a5e38d294d8bfa54d03e7184d6a1fc34e1be6d762c"}' ========= master_lag: 0.22 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 14:15:22.446068) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 14:15:22.446383) ========= 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.MCvJwaONay/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.Cxnyl55tEU/Listeners TMPDIR=/var/folders/r7/1plmn1y93dqbchyb2n9pp0r800000w/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.005120 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 14:15:22.473671) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 14:15:22.473994) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 14:15:22.476572) ========= ========= Total master_lag: 1.86 =========