builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0130 starttime: 1490617062.92 results: success (0) buildid: 20170327035815 builduid: 8d6ece6e690f4b5aa184b584b8ee1533 revision: 9577ddeaafd85554c2a855f385a87472a089d5c0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:42.916553) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:42.917035) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:42.917337) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:42.944370) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:42.944739) ========= 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.4k1HsDY8th/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.utMEzQcEif/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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-27 05:17:42-- 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% 155M=0s 2017-03-27 05:17:43 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.196176 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:43.167296) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:43.167815) ========= 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.4k1HsDY8th/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.utMEzQcEif/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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.029086 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:43.220218) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 05:17:43.220622) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9577ddeaafd85554c2a855f385a87472a089d5c0 --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 9577ddeaafd85554c2a855f385a87472a089d5c0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4k1HsDY8th/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.utMEzQcEif/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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-27 05:17:43,328 truncating revision to first 12 chars 2017-03-27 05:17:43,328 Setting DEBUG logging. 2017-03-27 05:17:43,328 attempt 1/10 2017-03-27 05:17:43,328 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9577ddeaafd8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 05:17:44,979 unpacking tar archive at: mozilla-central-9577ddeaafd8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.926842 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 05:17:45.172457) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:45.172790) ========= script_repo_revision: 9577ddeaafd85554c2a855f385a87472a089d5c0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:45.173180) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:45.173443) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 05:17:45.193245) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-27 05:17:45.193560) ========= /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.4k1HsDY8th/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.utMEzQcEif/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:17:45 INFO - MultiFileLogger online at 20170327 05:17:45 in /builds/slave/test 05:17:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:17:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:17:45 INFO - {'append_to_log': False, 05:17:45 INFO - 'base_work_dir': '/builds/slave/test', 05:17:45 INFO - 'blob_upload_branch': 'Firefox', 05:17:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:17:45 INFO - 'branch': 'Firefox', 05:17:45 INFO - 'buildbot_json_path': 'buildprops.json', 05:17:45 INFO - 'config_files': ('talos/mac_config.py',), 05:17:45 INFO - 'default_actions': ('clobber', 05:17:45 INFO - 'read-buildbot-config', 05:17:45 INFO - 'download-and-extract', 05:17:45 INFO - 'populate-webroot', 05:17:45 INFO - 'create-virtualenv', 05:17:45 INFO - 'install', 05:17:45 INFO - 'run-tests'), 05:17:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:17:45 INFO - 'download_minidump_stackwalk': True, 05:17:45 INFO - 'download_symbols': 'ondemand', 05:17:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:17:45 INFO - 'tooltool.py': '/tools/tooltool.py', 05:17:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:17:45 INFO - '/tools/misc-python/virtualenv.py')}, 05:17:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:17:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:17:45 INFO - 'gecko_profile': False, 05:17:45 INFO - 'gecko_profile_interval': 0, 05:17:45 INFO - 'installer_path': 'installer.exe', 05:17:45 INFO - 'log_level': 'info', 05:17:45 INFO - 'log_name': 'talos', 05:17:45 INFO - 'log_to_console': True, 05:17:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:17:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:17:45 INFO - 'opt_config_files': (), 05:17:45 INFO - 'pip_index': False, 05:17:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:45 INFO - 'cmd': ('bash', 05:17:45 INFO - '-c', 05:17:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:45 INFO - 'enabled': True, 05:17:45 INFO - 'halt_on_failure': False, 05:17:45 INFO - 'name': 'check_screen_resolution'},), 05:17:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:45 INFO - 'cmd': ('bash', 05:17:45 INFO - '-c', 05:17:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:45 INFO - 'enabled': True, 05:17:45 INFO - 'halt_on_failure': False, 05:17:45 INFO - 'name': 'check_screen_resolution'},), 05:17:45 INFO - 'run_cmd_checks_enabled': True, 05:17:45 INFO - 'suite': 'dromaeojs-e10s', 05:17:45 INFO - 'system_bits': '32', 05:17:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:17:45 INFO - 'title': 't-yosemite-r7-0130', 05:17:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:17:45 INFO - 'use_talos_json': True, 05:17:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:17:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:17:45 INFO - 'work_dir': 'build'} 05:17:45 INFO - [mozharness: 2017-03-27 12:17:45.410179Z] Running clobber step. 05:17:45 INFO - Running pre-action listener: _resource_record_pre_action 05:17:45 INFO - Running main action method: clobber 05:17:45 INFO - rmtree: /builds/slave/test/build 05:17:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:17:47 INFO - Running post-action listener: _resource_record_post_action 05:17:47 INFO - [mozharness: 2017-03-27 12:17:47.858491Z] Finished clobber step (success) 05:17:47 INFO - [mozharness: 2017-03-27 12:17:47.858624Z] Running read-buildbot-config step. 05:17:47 INFO - Running pre-action listener: _resource_record_pre_action 05:17:47 INFO - Running main action method: read_buildbot_config 05:17:47 INFO - Using buildbot properties: 05:17:47 INFO - { 05:17:47 INFO - "project": "", 05:17:47 INFO - "product": "firefox", 05:17:47 INFO - "script_repo_revision": "production", 05:17:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:17:47 INFO - "repository": "", 05:17:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:17:47 INFO - "buildid": "20170327035815", 05:17:47 INFO - "pgo_build": "False", 05:17:47 INFO - "basedir": "/builds/slave/test", 05:17:47 INFO - "buildnumber": 31, 05:17:47 INFO - "slavename": "t-yosemite-r7-0130", 05:17:47 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:17:47 INFO - "platform": "yosemite_r7", 05:17:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:17:47 INFO - "branch": "mozilla-central", 05:17:47 INFO - "repo_path": "mozilla-central", 05:17:47 INFO - "revision": "9577ddeaafd85554c2a855f385a87472a089d5c0", 05:17:47 INFO - "stage_platform": "macosx64", 05:17:47 INFO - "builduid": "8d6ece6e690f4b5aa184b584b8ee1533", 05:17:47 INFO - "slavebuilddir": "test" 05:17:47 INFO - } 05:17:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:17:47 INFO - Running post-action listener: _resource_record_post_action 05:17:47 INFO - [mozharness: 2017-03-27 12:17:47.861994Z] Finished read-buildbot-config step (success) 05:17:47 INFO - [mozharness: 2017-03-27 12:17:47.862120Z] Running download-and-extract step. 05:17:47 INFO - Running pre-action listener: _resource_record_pre_action 05:17:47 INFO - Running main action method: download_and_extract 05:17:47 INFO - mkdir: /builds/slave/test/build/tests 05:17: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')]} 05:17:47 INFO - trying https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:17:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:17:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 05:17:49 INFO - Downloaded 1325 bytes. 05:17:49 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:17:49 INFO - Using the following test package requirements: 05:17:49 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:49 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:17:49 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:17:49 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:49 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:17:49 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:49 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:17:49 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:49 INFO - u'jsshell-mac.zip'], 05:17:49 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:49 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:17:49 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:17:49 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:49 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:17:49 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:49 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:17:49 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:49 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:17:49 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:17:49 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:17:49 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:17:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:17:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:17:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:17:50 INFO - Expected file size: 52418753 05:17:50 INFO - Obtained file size: 52418753 05:18:07 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:18:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:18:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:18:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:18:09 INFO - Expected file size: 52418753 05:18:09 INFO - Obtained file size: 52418753 05:18:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:18:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:18:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:18:26 INFO - Expected file size: 14404171 05:18:26 INFO - Obtained file size: 14404171 05:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:26 INFO - trying https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:18:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:18:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 05:18:28 INFO - Downloaded 75541648 bytes. 05:18:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:18:28 INFO - mkdir: /builds/slave/test/properties 05:18:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:18:28 INFO - Writing to file /builds/slave/test/properties/build_url 05:18:28 INFO - Contents: 05:18:28 INFO - build_url:https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:18:29 INFO - Running post-action listener: _resource_record_post_action 05:18:29 INFO - Running post-action listener: set_extra_try_arguments 05:18:29 INFO - [mozharness: 2017-03-27 12:18:29.539934Z] Finished download-and-extract step (success) 05:18:29 INFO - [mozharness: 2017-03-27 12:18:29.540054Z] Running populate-webroot step. 05:18:29 INFO - Running pre-action listener: _resource_record_pre_action 05:18:29 INFO - Running main action method: populate_webroot 05:18:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:18:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:18:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:18:29 INFO - '--tppagecycles', 05:18:29 INFO - '7'], 05:18:29 INFO - 'tests': ['tsvgm']}}, 05:18:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:18:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:18:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:18:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:18:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:18:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:18:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:18:29 INFO - 'talos_options': ['--disable-e10s'], 05:18:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:18:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:18:29 INFO - 'talos_options': ['--disable-e10s'], 05:18:29 INFO - 'tests': ['damp', 'tps']}, 05:18:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:18:29 INFO - 'tests': ['damp', 'tps']}, 05:18:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:18:29 INFO - 'tests': ['dromaeo_dom']}, 05:18:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:18:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:18:29 INFO - 'tests': ['basic_compositor_video']}, 05:18:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:18:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:18:29 INFO - 'tests': ['a11yr', 05:18:29 INFO - 'ts_paint', 05:18:29 INFO - 'tpaint', 05:18:29 INFO - 'sessionrestore', 05:18:29 INFO - 'sessionrestore_no_auto_restore', 05:18:29 INFO - 'tabpaint']}, 05:18:29 INFO - 'other-e10s': {'tests': ['a11yr', 05:18:29 INFO - 'ts_paint', 05:18:29 INFO - 'tpaint', 05:18:29 INFO - 'sessionrestore', 05:18:29 INFO - 'sessionrestore_no_auto_restore', 05:18:29 INFO - 'tabpaint']}, 05:18:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:18:29 INFO - 'ts_paint', 05:18:29 INFO - 'tpaint', 05:18:29 INFO - 'sessionrestore', 05:18:29 INFO - 'sessionrestore_no_auto_restore', 05:18:29 INFO - 'tabpaint']}, 05:18:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:18:29 INFO - 'ts_paint', 05:18:29 INFO - 'tpaint', 05:18:29 INFO - 'sessionrestore', 05:18:29 INFO - 'sessionrestore_no_auto_restore', 05:18:29 INFO - 'tabpaint']}, 05:18:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:18:29 INFO - 'tests': ['a11yr', 05:18:29 INFO - 'ts_paint', 05:18:29 INFO - 'tpaint', 05:18:29 INFO - 'sessionrestore', 05:18:29 INFO - 'sessionrestore_no_auto_restore', 05:18:29 INFO - 'tabpaint']}, 05:18:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:18:29 INFO - 'tests': ['a11yr', 05:18:29 INFO - 'ts_paint', 05:18:29 INFO - 'tpaint', 05:18:29 INFO - 'sessionrestore', 05:18:29 INFO - 'sessionrestore_no_auto_restore', 05:18:29 INFO - 'tabpaint']}, 05:18:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:18:29 INFO - 'tests': ['tsvgx', 05:18:29 INFO - 'tsvgr_opacity', 05:18:29 INFO - 'tart', 05:18:29 INFO - 'tscrollx', 05:18:29 INFO - 'cart', 05:18:29 INFO - 'tsvg_static']}, 05:18:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:18:29 INFO - 'tsvgr_opacity', 05:18:29 INFO - 'tart', 05:18:29 INFO - 'tscrollx', 05:18:29 INFO - 'cart', 05:18:29 INFO - 'tsvg_static']}, 05:18:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:18:29 INFO - 'talos_options': ['--disable-e10s'], 05:18:29 INFO - 'tests': ['tp5o']}, 05:18:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:18:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:18:29 INFO - 'talos_options': ['--disable-e10s', 05:18:29 INFO - '--xperf_path', 05:18:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:29 INFO - 'tests': ['tp5n']}, 05:18:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:18:29 INFO - 'talos_options': ['--xperf_path', 05:18:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:29 INFO - 'tests': ['tp5n']}}, 05:18:29 INFO - 'talos.zip': {'path': '', 05:18:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:18:29 INFO - Running post-action listener: _resource_record_post_action 05:18:29 INFO - [mozharness: 2017-03-27 12:18:29.558838Z] Finished populate-webroot step (success) 05:18:29 INFO - [mozharness: 2017-03-27 12:18:29.558970Z] Running create-virtualenv step. 05:18:29 INFO - Running pre-action listener: _resource_record_pre_action 05:18:29 INFO - Running main action method: create_virtualenv 05:18:29 INFO - Creating virtualenv /builds/slave/test/build/venv 05:18:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:18:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:18:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:18:29 INFO - Using real prefix '/tools/python27' 05:18:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:18:30 INFO - Installing distribute.............................................................................................................................................................................................done. 05:18:33 INFO - Installing pip.................done. 05:18:33 INFO - Return code: 0 05:18:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:18:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:18:33 INFO - Reading from file tmpfile_stdout 05:18:33 INFO - Output received: 05:18:33 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:18:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa378d50570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0fd30>, '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 0x104b36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c04be8>, '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.4k1HsDY8th/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.utMEzQcEif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:18:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:18:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4k1HsDY8th/Render', 05:18:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:33 INFO - 'HOME': '/Users/cltbld', 05:18:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:33 INFO - 'LOGNAME': 'cltbld', 05:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:33 INFO - 'MOZ_NO_REMOTE': '1', 05:18:33 INFO - 'NO_EM_RESTART': '1', 05:18:33 INFO - 'PAGER': '/bin/cat', 05:18:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:33 INFO - 'PWD': '/builds/slave/test', 05:18:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:33 INFO - 'SHELL': '/bin/bash', 05:18:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.utMEzQcEif/Listeners', 05:18:33 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 05:18:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:33 INFO - 'USER': 'cltbld', 05:18:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:33 INFO - 'XPC_FLAGS': '0x0', 05:18:33 INFO - 'XPC_SERVICE_NAME': '0', 05:18:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:18:33 INFO - Cleaning up... 05:18:33 INFO - Return code: 0 05:18:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa378d50570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0fd30>, '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 0x104b36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c04be8>, '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.4k1HsDY8th/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.utMEzQcEif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:18:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:18:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4k1HsDY8th/Render', 05:18:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:33 INFO - 'HOME': '/Users/cltbld', 05:18:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:33 INFO - 'LOGNAME': 'cltbld', 05:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:33 INFO - 'MOZ_NO_REMOTE': '1', 05:18:33 INFO - 'NO_EM_RESTART': '1', 05:18:33 INFO - 'PAGER': '/bin/cat', 05:18:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:33 INFO - 'PWD': '/builds/slave/test', 05:18:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:33 INFO - 'SHELL': '/bin/bash', 05:18:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.utMEzQcEif/Listeners', 05:18:33 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 05:18:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:33 INFO - 'USER': 'cltbld', 05:18:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:33 INFO - 'XPC_FLAGS': '0x0', 05:18:33 INFO - 'XPC_SERVICE_NAME': '0', 05:18:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:34 INFO - Downloading/unpacking psutil>=3.1.1 05:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:18:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:37 INFO - Installing collected packages: psutil 05:18:37 INFO - Running setup.py install for psutil 05:18:37 INFO - building 'psutil._psutil_osx' extension 05:18:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:18:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:18:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:18:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:18:38 INFO - building 'psutil._psutil_posix' extension 05:18:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:18:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:18:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:18:38 INFO - ^ 05:18:38 INFO - 1 warning generated. 05:18:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:18:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:38 INFO - Successfully installed psutil 05:18:38 INFO - Cleaning up... 05:18:38 INFO - Return code: 0 05:18:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa378d50570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0fd30>, '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 0x104b36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c04be8>, '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.4k1HsDY8th/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.utMEzQcEif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:18:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4k1HsDY8th/Render', 05:18:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:39 INFO - 'HOME': '/Users/cltbld', 05:18:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:39 INFO - 'LOGNAME': 'cltbld', 05:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:39 INFO - 'MOZ_NO_REMOTE': '1', 05:18:39 INFO - 'NO_EM_RESTART': '1', 05:18:39 INFO - 'PAGER': '/bin/cat', 05:18:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:39 INFO - 'PWD': '/builds/slave/test', 05:18:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:39 INFO - 'SHELL': '/bin/bash', 05:18:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.utMEzQcEif/Listeners', 05:18:39 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 05:18:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:39 INFO - 'USER': 'cltbld', 05:18:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:39 INFO - 'XPC_FLAGS': '0x0', 05:18:39 INFO - 'XPC_SERVICE_NAME': '0', 05:18:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:18:43 INFO - Installing collected packages: mozsystemmonitor 05:18:43 INFO - Running setup.py install for mozsystemmonitor 05:18:43 INFO - Successfully installed mozsystemmonitor 05:18:43 INFO - Cleaning up... 05:18:43 INFO - Return code: 0 05:18:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:43 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 0x104e31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa378d50570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0fd30>, '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 0x104b36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c04be8>, '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.4k1HsDY8th/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.utMEzQcEif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:18:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4k1HsDY8th/Render', 05:18:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:43 INFO - 'HOME': '/Users/cltbld', 05:18:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:43 INFO - 'LOGNAME': 'cltbld', 05:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:43 INFO - 'MOZ_NO_REMOTE': '1', 05:18:43 INFO - 'NO_EM_RESTART': '1', 05:18:43 INFO - 'PAGER': '/bin/cat', 05:18:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:43 INFO - 'PWD': '/builds/slave/test', 05:18:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:43 INFO - 'SHELL': '/bin/bash', 05:18:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.utMEzQcEif/Listeners', 05:18:43 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 05:18:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:43 INFO - 'USER': 'cltbld', 05:18:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:43 INFO - 'XPC_FLAGS': '0x0', 05:18:43 INFO - 'XPC_SERVICE_NAME': '0', 05:18:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:43 INFO - Downloading/unpacking jsonschema==2.5.1 05:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:18:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:46 INFO - Downloading functools32-3.2.3-2.tar.gz 05:18:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:18:46 INFO - warning: no files found matching '*.txt' 05:18:46 INFO - no previously-included directories found matching 'build' 05:18:46 INFO - no previously-included directories found matching 'dist' 05:18:46 INFO - no previously-included directories found matching '.git*' 05:18:46 INFO - Installing collected packages: jsonschema, functools32 05:18:47 INFO - Running setup.py install for functools32 05:18:47 INFO - warning: no files found matching '*.txt' 05:18:47 INFO - no previously-included directories found matching 'build' 05:18:47 INFO - no previously-included directories found matching 'dist' 05:18:47 INFO - no previously-included directories found matching '.git*' 05:18:47 INFO - Successfully installed jsonschema functools32 05:18:47 INFO - Cleaning up... 05:18:47 INFO - Return code: 0 05:18:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104e31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa378d50570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0fd30>, '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 0x104b36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c04be8>, '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.4k1HsDY8th/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.utMEzQcEif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:18:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4k1HsDY8th/Render', 05:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:47 INFO - 'HOME': '/Users/cltbld', 05:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:47 INFO - 'LOGNAME': 'cltbld', 05:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:47 INFO - 'MOZ_NO_REMOTE': '1', 05:18:47 INFO - 'NO_EM_RESTART': '1', 05:18:47 INFO - 'PAGER': '/bin/cat', 05:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:47 INFO - 'PWD': '/builds/slave/test', 05:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:47 INFO - 'SHELL': '/bin/bash', 05:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.utMEzQcEif/Listeners', 05:18:47 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 05:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:47 INFO - 'USER': 'cltbld', 05:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:47 INFO - 'XPC_FLAGS': '0x0', 05:18:47 INFO - 'XPC_SERVICE_NAME': '0', 05:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:18:47 INFO - Cleaning up... 05:18:47 INFO - Return code: 0 05:18:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104e31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa378d50570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0fd30>, '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 0x104b36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c04be8>, '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.4k1HsDY8th/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.utMEzQcEif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:18:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4k1HsDY8th/Render', 05:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:47 INFO - 'HOME': '/Users/cltbld', 05:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:47 INFO - 'LOGNAME': 'cltbld', 05:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:47 INFO - 'MOZ_NO_REMOTE': '1', 05:18:47 INFO - 'NO_EM_RESTART': '1', 05:18:47 INFO - 'PAGER': '/bin/cat', 05:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:47 INFO - 'PWD': '/builds/slave/test', 05:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:47 INFO - 'SHELL': '/bin/bash', 05:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.utMEzQcEif/Listeners', 05:18:47 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 05:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:47 INFO - 'USER': 'cltbld', 05:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:47 INFO - 'XPC_FLAGS': '0x0', 05:18:47 INFO - 'XPC_SERVICE_NAME': '0', 05:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:47 INFO - Downloading/unpacking blobuploader==1.2.4 05:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:50 INFO - Downloading blobuploader-1.2.4.tar.gz 05:18:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:18:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:18:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18:51 INFO - Downloading docopt-0.6.1.tar.gz 05:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:18:51 INFO - Installing collected packages: blobuploader, requests, docopt 05:18:51 INFO - Running setup.py install for blobuploader 05:18:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:18:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:18:51 INFO - Running setup.py install for requests 05:18:52 INFO - Running setup.py install for docopt 05:18:52 INFO - Successfully installed blobuploader requests docopt 05:18:52 INFO - Cleaning up... 05:18:52 INFO - Return code: 0 05:18:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:52 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 0x104e31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa378d50570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0fd30>, '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 0x104b36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c04be8>, '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.4k1HsDY8th/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.utMEzQcEif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4k1HsDY8th/Render', 05:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:52 INFO - 'HOME': '/Users/cltbld', 05:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:52 INFO - 'LOGNAME': 'cltbld', 05:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:52 INFO - 'MOZ_NO_REMOTE': '1', 05:18:52 INFO - 'NO_EM_RESTART': '1', 05:18:52 INFO - 'PAGER': '/bin/cat', 05:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:52 INFO - 'PWD': '/builds/slave/test', 05:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:52 INFO - 'SHELL': '/bin/bash', 05:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.utMEzQcEif/Listeners', 05:18:52 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 05:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:52 INFO - 'USER': 'cltbld', 05:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:52 INFO - 'XPC_FLAGS': '0x0', 05:18:52 INFO - 'XPC_SERVICE_NAME': '0', 05:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:52 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-sNwkuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:53 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-gNqk4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:53 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-1rqFDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:53 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-6fj1ZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:53 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-JCpXUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:53 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-tUjszh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:53 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-FcIUZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:53 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-PCdtOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:53 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-CJBfgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:53 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-0e8rzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:53 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-Tlm6TA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:54 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-87UNfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:54 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-cogfhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:54 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-6lGCsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:54 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-RekHQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:54 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-RQMW0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:54 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-bw7daG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:18:54 INFO - Running setup.py install for manifestparser 05:18:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:18:54 INFO - Running setup.py install for mozcrash 05:18:55 INFO - Running setup.py install for mozdebug 05:18:55 INFO - Running setup.py install for mozdevice 05:18:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:18:55 INFO - Running setup.py install for mozfile 05:18:55 INFO - Running setup.py install for mozhttpd 05:18:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:18:55 INFO - Running setup.py install for mozinfo 05:18:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:18:55 INFO - Running setup.py install for mozInstall 05:18:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:18:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:18:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:18:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:18:55 INFO - Running setup.py install for mozleak 05:18:56 INFO - Running setup.py install for mozlog 05:18:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:18:56 INFO - Running setup.py install for moznetwork 05:18:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:18:56 INFO - Running setup.py install for mozprocess 05:18:56 INFO - Running setup.py install for mozprofile 05:18:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:18:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:18:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:18:56 INFO - Running setup.py install for mozrunner 05:18:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:18:56 INFO - Running setup.py install for mozscreenshot 05:18:56 INFO - Running setup.py install for moztest 05:18:57 INFO - Running setup.py install for mozversion 05:18:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:18:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:18:57 INFO - Cleaning up... 05:18:57 INFO - Return code: 0 05:18:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:57 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 0x104e31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa378d50570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0fd30>, '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 0x104b36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c04be8>, '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.4k1HsDY8th/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.utMEzQcEif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:18:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4k1HsDY8th/Render', 05:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:57 INFO - 'HOME': '/Users/cltbld', 05:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:57 INFO - 'LOGNAME': 'cltbld', 05:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:57 INFO - 'MOZ_NO_REMOTE': '1', 05:18:57 INFO - 'NO_EM_RESTART': '1', 05:18:57 INFO - 'PAGER': '/bin/cat', 05:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:57 INFO - 'PWD': '/builds/slave/test', 05:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:57 INFO - 'SHELL': '/bin/bash', 05:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.utMEzQcEif/Listeners', 05:18:57 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 05:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:57 INFO - 'USER': 'cltbld', 05:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:57 INFO - 'XPC_FLAGS': '0x0', 05:18:57 INFO - 'XPC_SERVICE_NAME': '0', 05:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:57 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-eKT5ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:57 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-knKhWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:57 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-A0ld2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:57 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-_Wmq7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:57 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-g1eDoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:58 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-gcHM4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:58 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-2tnsCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:58 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-MUbVtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:58 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-PIzBPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:58 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-XxtsiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:58 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-I5_NWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:58 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-eGyGe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:58 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-KQ8BYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:58 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-v5j0N0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:59 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-oqv0u4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:59 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-1AoGCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:59 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-a3e6b5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:19:01 INFO - Downloading blessings-1.6.tar.gz 05:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:19:02 INFO - Installing collected packages: blessings 05:19:02 INFO - Running setup.py install for blessings 05:19:02 INFO - Successfully installed blessings 05:19:02 INFO - Cleaning up... 05:19:02 INFO - Return code: 0 05:19:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:19:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:02 INFO - Reading from file tmpfile_stdout 05:19:02 INFO - Current package versions: 05:19:02 INFO - blessings == 1.6 05:19:02 INFO - blobuploader == 1.2.4 05:19:02 INFO - docopt == 0.6.1 05:19:02 INFO - functools32 == 3.2.3-2 05:19:02 INFO - jsonschema == 2.5.1 05:19:02 INFO - manifestparser == 1.1 05:19:02 INFO - mozInstall == 1.12 05:19:02 INFO - mozcrash == 1.0 05:19:02 INFO - mozdebug == 0.1 05:19:02 INFO - mozdevice == 0.49 05:19:02 INFO - mozfile == 1.2 05:19:02 INFO - mozhttpd == 0.7 05:19:02 INFO - mozinfo == 0.9 05:19:02 INFO - mozleak == 0.1 05:19:02 INFO - mozlog == 3.4 05:19:02 INFO - moznetwork == 0.27 05:19:02 INFO - mozprocess == 0.25 05:19:02 INFO - mozprofile == 0.28 05:19:02 INFO - mozrunner == 6.13 05:19:02 INFO - mozscreenshot == 0.1 05:19:02 INFO - mozsystemmonitor == 0.3 05:19:02 INFO - moztest == 0.8 05:19:02 INFO - mozversion == 1.4 05:19:02 INFO - psutil == 3.1.1 05:19:02 INFO - requests == 1.2.3 05:19:02 INFO - wsgiref == 0.1.2 05:19:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:02 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 0x104e31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa378d50570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0fd30>, '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 0x104b36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c04be8>, '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.4k1HsDY8th/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.utMEzQcEif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4k1HsDY8th/Render', 05:19:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:02 INFO - 'HOME': '/Users/cltbld', 05:19:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:02 INFO - 'LOGNAME': 'cltbld', 05:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:02 INFO - 'MOZ_NO_REMOTE': '1', 05:19:02 INFO - 'NO_EM_RESTART': '1', 05:19:02 INFO - 'PAGER': '/bin/cat', 05:19:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:02 INFO - 'PWD': '/builds/slave/test', 05:19:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:02 INFO - 'SHELL': '/bin/bash', 05:19:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.utMEzQcEif/Listeners', 05:19:02 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 05:19:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:02 INFO - 'USER': 'cltbld', 05:19:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:02 INFO - 'XPC_FLAGS': '0x0', 05:19:02 INFO - 'XPC_SERVICE_NAME': '0', 05:19:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:02 INFO - Cleaning up... 05:19:02 INFO - Return code: 0 05:19:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa378d50570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0fd30>, '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 0x104b36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c04be8>, '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.4k1HsDY8th/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.utMEzQcEif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:19:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4k1HsDY8th/Render', 05:19:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:02 INFO - 'HOME': '/Users/cltbld', 05:19:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:02 INFO - 'LOGNAME': 'cltbld', 05:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:02 INFO - 'MOZ_NO_REMOTE': '1', 05:19:02 INFO - 'NO_EM_RESTART': '1', 05:19:02 INFO - 'PAGER': '/bin/cat', 05:19:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:02 INFO - 'PWD': '/builds/slave/test', 05:19:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:02 INFO - 'SHELL': '/bin/bash', 05:19:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.utMEzQcEif/Listeners', 05:19:02 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 05:19:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:02 INFO - 'USER': 'cltbld', 05:19:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:02 INFO - 'XPC_FLAGS': '0x0', 05:19:02 INFO - 'XPC_SERVICE_NAME': '0', 05:19:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:19:03 INFO - Cleaning up... 05:19:03 INFO - Return code: 0 05:19:03 INFO - Running post-action listener: _resource_record_post_action 05:19:03 INFO - Running post-action listener: _start_resource_monitoring 05:19:03 INFO - Starting resource monitoring. 05:19:03 INFO - [mozharness: 2017-03-27 12:19:03.193694Z] Finished create-virtualenv step (success) 05:19:03 INFO - [mozharness: 2017-03-27 12:19:03.194712Z] Running install step. 05:19:03 INFO - Running pre-action listener: _resource_record_pre_action 05:19:03 INFO - Running main action method: install 05:19:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:03 INFO - Reading from file tmpfile_stdout 05:19:03 INFO - Detecting whether we're running mozinstall >=1.0... 05:19:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:19:03 INFO - Reading from file tmpfile_stdout 05:19:03 INFO - Output received: 05:19:03 INFO - Usage: mozinstall [options] installer 05:19:03 INFO - Options: 05:19:03 INFO - -h, --help show this help message and exit 05:19:03 INFO - -d DEST, --destination=DEST 05:19:03 INFO - Directory to install application into. [default: 05:19:03 INFO - "/builds/slave/test"] 05:19:03 INFO - --app=APP Application being installed. [default: firefox] 05:19:03 INFO - mkdir: /builds/slave/test/build/application 05:19:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:19:23 INFO - Reading from file tmpfile_stdout 05:19:23 INFO - Output received: 05:19:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:19:23 INFO - Running post-action listener: _resource_record_post_action 05:19:23 INFO - [mozharness: 2017-03-27 12:19:23.845038Z] Finished install step (success) 05:19:23 INFO - [mozharness: 2017-03-27 12:19:23.845192Z] Running run-tests step. 05:19:23 INFO - Running pre-action listener: _resource_record_pre_action 05:19:23 INFO - Running main action method: run_tests 05:19:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:19:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:19:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:19:23 INFO - 2017-03-27 05:19:23.859 screenresolution[1623:7342] starting screenresolution argv=screenresolution get 05:19:23 INFO - 2017-03-27 05:19:23.862 screenresolution[1623:7342] Display 0: 1600x1200x32@60 05:19:23 INFO - 2017-03-27 05:19:23.868 screenresolution[1624:7345] starting screenresolution argv=screenresolution list 05:19:23 INFO - Available Modes on Display 0 05:19:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:19:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:19:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:19:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:19:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:19:24 INFO - Intel Iris: 05:19:24 INFO - Chipset Model: Intel Iris 05:19:24 INFO - Type: GPU 05:19:24 INFO - Bus: Built-In 05:19:24 INFO - VRAM (Dynamic, Max): 1536 MB 05:19:24 INFO - Vendor: Intel (0x8086) 05:19:24 INFO - Device ID: 0x0a2e 05:19:24 INFO - Revision ID: 0x0009 05:19:24 INFO - Displays: 05:19:24 INFO - Display: 05:19:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:19:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:19:24 INFO - Display Serial Number: 1600x1200 60 05:19:24 INFO - Main Display: Yes 05:19:24 INFO - Mirror: Off 05:19:24 INFO - Online: Yes 05:19:24 INFO - Rotation: Supported 05:19:24 INFO - Return code: 0 05:19:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:19:24 INFO - Python 2.7.3 05:19:24 INFO - Return code: 0 05:19:24 INFO - grabbing minidump binary from tooltool 05:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:24 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 0x104c0fd30>, '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 0x104b36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c04be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:19:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:19:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:19:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:19:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:19:24 INFO - Return code: 0 05:19:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:19:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:19:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:19:24 INFO - ENV: RUST_BACKTRACE is now 1 05:19:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:19:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:19:24 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/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:19:24 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/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0130 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:19:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4k1HsDY8th/Render', 05:19:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:24 INFO - 'HOME': '/Users/cltbld', 05:19:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:24 INFO - 'LOGNAME': 'cltbld', 05:19:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:19:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:24 INFO - 'MOZ_NO_REMOTE': '1', 05:19:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:19:24 INFO - 'NO_EM_RESTART': '1', 05:19:24 INFO - 'PAGER': '/bin/cat', 05:19:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:24 INFO - 'PWD': '/builds/slave/test', 05:19:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:19:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:24 INFO - 'RUST_BACKTRACE': '1', 05:19:24 INFO - 'SHELL': '/bin/bash', 05:19:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.utMEzQcEif/Listeners', 05:19:24 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', 05:19:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:24 INFO - 'USER': 'cltbld', 05:19:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:24 INFO - 'XPC_FLAGS': '0x0', 05:19:24 INFO - 'XPC_SERVICE_NAME': '0', 05:19:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:24 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/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:19:24 INFO - mozversion application_buildid: 20170327035815 05:19:24 INFO - mozversion application_changeset: 9577ddeaafd85554c2a855f385a87472a089d5c0 05:19:24 INFO - mozversion application_display_name: Nightly 05:19:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:19:24 INFO - mozversion application_name: Firefox 05:19:24 INFO - mozversion application_remotingname: firefox 05:19:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:19:24 INFO - mozversion application_vendor: Mozilla 05:19:24 INFO - mozversion application_version: 55.0a1 05:19:24 INFO - mozversion platform_buildid: 20170327035815 05:19:24 INFO - mozversion platform_changeset: 9577ddeaafd85554c2a855f385a87472a089d5c0 05:19:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:19:24 INFO - mozversion platform_version: 55.0a1 05:19:24 INFO - using testdate: 1490617164 05:19:24 INFO - actual date: 1490617164 05:19:24 INFO - starting webserver on 'localhost:49248' 05:19:24 INFO - SUITE-START | Running 2 tests 05:19:24 INFO - TEST-START | dromaeo_css 05:19:24 INFO - operating with platform_type : mac_ 05:19:24 INFO - Initialising browser for dromaeo_css test... 05:19:24 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpJ66LYB/profile http://localhost:49248/getInfo.html) 05:19:27 INFO - PID 1634 | 2017-03-27 05:19:27.458 plugin-container[1636:7528] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x484b, name = 'com.apple.tsm.portname' 05:19:27 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:27 INFO - PID 1634 | 2017-03-27 05:19:27.460 plugin-container[1636:7528] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9603, name = 'com.apple.CFPasteboardClient' 05:19:27 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:27 INFO - PID 1634 | 2017-03-27 05:19:27.460 plugin-container[1636:7528] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:19:27 INFO - PID 1634 | __metrics Screen width/height:1600/1200 05:19:27 INFO - PID 1634 | colorDepth:24 05:19:27 INFO - PID 1634 | Browser inner width/height: 1024/689 05:19:27 INFO - PID 1634 | __metrics 05:19:27 ERROR - PID 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 05:19:27 ERROR - PID 1634 | 1490617167689 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/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpJ66LYB/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpJ66LYB/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpJ66LYB/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpJ66LYB/profile/extensions/talos-powers@TalosPowersService.js:55:9 05:19:28 INFO - TEST-INFO | 1634: exit 0 05:19:28 INFO - Browser initialized. 05:19:28 INFO - Running cycle 1/1 for dromaeo_css test... 05:19:28 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpJ66LYB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:19:30 INFO - PID 1637 | 2017-03-27 05:19:30.945 plugin-container[1638:7656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 05:19:30 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:30 INFO - PID 1637 | 2017-03-27 05:19:30.945 plugin-container[1638:7656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 05:19:30 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:30 INFO - PID 1637 | 2017-03-27 05:19:30.945 plugin-container[1638:7656] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:19:40 INFO - PID 1637 | RSS: Main: 227569664 05:19:40 INFO - PID 1637 | 05:22:44 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 05:22:46 INFO - PID 1637 | RSS: Main: 250187776 05:22:46 INFO - PID 1637 | 05:24:28 INFO - PID 1637 | ************************* 05:24:28 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 05:24:28 INFO - PID 1637 | 05:24:28 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:24:28 INFO - PID 1637 | Full stack: 05:24:28 INFO - PID 1637 | ************************* 05:24:28 INFO - PID 1637 | ************************* 05:24:28 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 05:24:28 INFO - PID 1637 | 05:24:28 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:24:28 INFO - PID 1637 | Full stack: 05:24:28 INFO - PID 1637 | ************************* 05:24:28 INFO - PID 1637 | ************************* 05:24:28 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 05:24:28 INFO - PID 1637 | 05:24:28 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:24:28 INFO - PID 1637 | Full stack: 05:24:28 INFO - PID 1637 | ************************* 05:24:28 INFO - PID 1637 | ************************* 05:24:28 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 05:24:28 INFO - PID 1637 | 05:24:28 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:24:28 INFO - PID 1637 | Full stack: 05:24:28 INFO - PID 1637 | ************************* 05:25:48 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 05:25:49 INFO - PID 1637 | RSS: Main: 258777088 05:25:49 INFO - PID 1637 | 05:28:51 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 05:28:51 INFO - PID 1637 | RSS: Main: 270512128 05:28:51 INFO - PID 1637 | 05:31:53 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 05:31:54 INFO - PID 1637 | RSS: Main: 263278592 05:31:54 INFO - PID 1637 | 05:34:57 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 05:34:58 INFO - PID 1637 | RSS: Main: 273035264 05:34:58 INFO - PID 1637 | 05:38:01 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 05:38:01 INFO - PID 1637 | RSS: Main: 267227136 05:38:01 INFO - PID 1637 | 05:38:01 INFO - PID 1637 | __start_tp_report 05:38:01 INFO - PID 1637 | _x_x_mozilla_page_load 05:38:01 INFO - PID 1637 | _x_x_mozilla_page_load_details 05:38:01 INFO - PID 1637 | |i|pagename|runs| 05:38:01 INFO - PID 1637 | |0;dojo.html;3529.6470352964707;3560.2523470071283;3564.8217589120545;3568.4468907319374;3578.087587665145;6121;6167.4757645600175;6173.722182501787;6195.318514963356;6224.595401298912;6122.969385153068;6125.784909797661;6128.411459112353;6137.8366772192985;6217.658028808413;5555.50000499955;5637.8590535236635;5665.12190610456;5739;5746.310442746876;5539.41836107209;5663.546916246691;5764.279465066867;5768.019436695772;5797.536197104243;5711.971440142773;5719.571032172604;5793.217915581406;5794.39158888317;5819.185314056035;4522.525134860858;4553.544645535453;5514.338279406456;5615.803446879339;5673.574481913873;4546.227141385939;5526.861828454321;5546.251256080458;5573.27547418837;5579.497845193911;4881.974785295092;6059.091136329585;6123;6220.512846061501;6268.749250029993;6403.519736019771;6547.443467305237;6580.80257592273;6640.369164929324;6645.136132302818;6743.831404214885;6754.763583274562;6782;6782.626955517494;6784.118064651613;5175.353080864892;5250.8687282817855;6306.747730090791;6311.810645680637;6312.52656050798;5919.141724450017;6650.534462587572;6792.76225332111;6836.316368363124;6866.141732283465;6230.408111229426;6506.3818937200895;6510.934890651128;6562.212534495891;6575.605463672195;6541.803745887631;6569;6594.40650341462;6643.86712265752;6701.1288532491435;6602.438792702674;6636.933630663728;6680.0981867448245;6683.565568238049;6686.465082793365;5484.2596249505805;6327.810165695037;6398.168238129014;6672.232693240242;6690.364415380531;5278.076336641073;5300.814471493479;5301.1783173608155;5301.363836339664;5313.990341835039;4760.904781904343;4774.689645173053;4802.711837289774;4807.206810876236;4828.758562071883;6284.497240220771;6301.0863424804065;6315.084312774622;6320.178376810985;6323.30443651198;6486.091947127406;6593.8351541210895;6724.092247546617;6737.831554211184;6825.69284382204;6253.593516421418;6271.122042913996;6278.277998030194;6305.148804911279;6321.873562528724;5931.258592675916;5941.940580594225;5957.344692083867;5981.5214782817275;6000.849978750566;6563.113979612882;6807.897881531781;6865.897011544831;6949;6949.583025018515;5645.294338207724;6540.313267107075;6660.866782664417;6686.498512611476;6724.697388617426;5336.852576696029;6786.69459874297;6832.350926662158;6854.931450685429;6879.415249703831;6517.348265173634;6602.537822352581;6636.800895973129;6668.799936001928;6670.633115178518;6432.710528026344;6481.4166724994975;6647.734090636321;6657.267700553032;6670.099536562502;5593.804216852391;6317.526185536195;6533.509986750918;6561.573497722633;6563.704633291609;6294.464970477377;6341.972847241942;6342.492600592034;6516.250631177475;6563.310852360433;4727.834525791581;6388.552801303864;6398.0083087121575;6417.294097649348;6417.39034791694;6463.418292353711;6503.089567460654;6533.509986750918;6537;6545.443637290884;6143.051335359142;6198.473129784019;6410;6410.711517981609;6416.390442907917 05:38:01 INFO - PID 1637 | |1;ext.html;724.7318492157902;787.0869791042392;813.3899575318509;1281.9807702884452;1287.6201520551435;37709;38564.80717596415;38589.80705096471;38740.80629596848;38864;38928.02679933003;38938;39104.413433798516;39130;39251.803740981326;8086.595670216495;8265.256126948574;8517.74446766598;8722.956385218065;8746.518941458216;8550.70072547461;8653.221210091091;8668.306535477179;8686.56567171642;8696.565171741418;8738.694145704902;8794.340424468159;8807;8811.867821982643;8812.118788121168;8755.642875354299;8769.868451973225;8779.561021948877;8783.560821958908;8816.250618697419;8503.1921967413;8510.063892971768;8570.828583428296;8591.26974207193;8594.140585941419;39031.60968390308;39308.05189610791;39326;39353.80323098395;39440.408393873826;2536.467341858212;2948.7935844490894;2972.2272209225757;2992.4912764830033;2996.3258266889993;28050.579241311192;28178.01376951797;28284.43431131366;28697;29117.544122793777;2797.084672671542;2818.309514169021;2820.576913462997;2820.8025438219333;2880.495913215179;199639.00180499005;250803.74598127074;270151;281041;295020.09959800896;47840.76079619649;47841.76079119582;47881.52118478771;47893.04213915702;47904;49860;49879.25422610191;49880;49882;50057.24914126291;14208.218547979961;14312.642183945276;14313.069650472686;14333.211673357857;14499.275036248146;11693.649190524298;11888.643340699793;11898.405079745979;11903.34531600753;11909.702257443632;13991.370388332549;14006.299685015709;14015.50945716895;14041.438342466186;14130.505432310025;6134.969325153345;6165.815025549241;6183.876322473615;6203.410675985774;6213.689315534296;803.7468197517763;829.4484168028193;970.6117552978724;998.5271724206737;1006.6527048168371;22963.081476741077;23999;24187.7581224189;24293.75706242915;24350.90420931062;874.1127755328347;926.2451102351562;1078.805814953316;1116.7431490757172;1128.1933417606451;8977.461352318882;12150.33173175466;12152.635420937386;12285.38573071343;12656.430460629294;483349;487521;488784;488985.55507222237;489606;57262.42737572654;57481.8503629925;58070.7096464515;58104.709476453194;58183.836323273295;1439.2731670506328;1477.2613693153423;1512.6823367093034;1515.4014164405219;1518.5368462619008;23824.072652726045;24090.638640420057;24143.51712965766;24188.274351769476;24298.392540186636;40200.195996080496;40232.195356093296;40325.588604398705;40414.787556373354;40427.797861010506;450940;451738;451917.74041129585;452406;453072;26236.344091397867;26503.469930601666;29119;30717.385652287277;34123.48814767731;38260.04349891163;38785;39558.80220598879;39584.802075989435;40042;22887.88556057209;26619.66901654871;27683.861580691966;28216.73024713852;28846.71153288524;26187.476250475265;27589.31026724348;27590.724092759618;27701.44597108087;28449.57725707845;29919.354435506255;31722.36555268928;34935.9519214424;35005;35470;28340.433191335644;28735;29071;29417.705822941498;29455.852720737115;21084.367468975954;21724.348269551938;22151.49395423178;23338.949747261075;23622.17322393708 05:38:01 INFO - PID 1637 | |2;jquery.html;407.89394757363084;423.30789159723844;431.7884236724004;438.6512722385703;459.06121980549773;20635.793642063574;20856.165753369867;21420.03609837557;21962.231321903735;22063;21571;22236.444088897784;22588.66117008246;22960;23309.533809323802;4649.604783593399;6043.969780151104;6261.217347831521;6262.3111457739615;6265.686715664198;4919.901601967958;6135.87728245437;6172.567920245585;6179.969100154515;6199.628022318653;5889.852753681171;6149.2313460817395;6153.846153846145;6163.969180154093;6193.504519638418;6042.667653279068;6063.54523410746;6086.300075491331;6092.482139018189;6101.725422356005;5328.040952628526;5347.59893008022;5390.676559406448;5404.4325345838515;5404.5676345892625;26607;26753.86623066892;27126.05058822932;27230.31924201891;27507.58738618922;1957.500837286497;1968.803119688034;1971.0932970833435;1987.3640435060786;2016.9294074707461;13070.673233169247;15199;15594.9220253898;15987.960782549097;16422.507324780156;1407.9159047533444;1527.2363818090955;1610.2753760807684;1632.183908045977;1674.6315810521667;141593.2920335402;152765;156338.65492017628;157199.2140039304;158803;10043.397396156253;12466.376681165906;12589;12615.936920315524;12653.051021173449;14117.576472705836;16116.677666446605;16210.94628849121;16273.918630406772;16309.266083026292;7917.208279172037;8625.439346442461;9052;9074.364794464323;9130.543472826332;8690.304775617935;8728.301735861116;8744.956275218583;8906.955465222632;8907.51008694528;7812.023497062867;7905.288524032865;8025.9197408025175;8037.271986522809;8071.4753541019645;2104.5685634444903;2115.978840211609;2119.7562280337957;2121.1727426303755;2294;850.6214734443237;876.3471213945523;883.9878339301592;890.496869268861;915.5651065743718;18155.54611134732;19434.514137146685;19973.500662483264;20037.499062523555;20385.898070509553;911.9740292171307;931.6180366050022;937.8733870927474;947.5783276441917;950.9476978766096;9825.508724563742;10490.527926243338;10525.315854469434;10543.31468454548;10700;564728;567704;572981;686609;714531;150887;155808;156589;171033;174318;1194.3132698698282;1208.9335086570147;1253.0664654832026;1257.43415463042;1264.0393301091237;15952.680946381239;20258.278046366224;20309.28917487881;20659.690104648143;21063;51472;53695.6576085601;63330.68334658482;63775.72448551096;64478.355216447235;78423.60788196023;85054.57472712598;87665;90613;93915;7631.8855217172895;7670.386369090571;8012.95993520052;8437;8827.382083254368;12886.291253980746;13412.396442160321;13497.257650828906;13814.309284535933;13959.302034898417;11387.829182562435;11449.255798373411;11480.655580332603;11578.594749183738;11794.233374830608;10811;10847.240693151403;11012.889871101506;11190.888091118984;11285.71785705364;11823.349715765715;11841.9934305585;12073.154879158375;12098.576549820715;12156.574519891761;8543.829123417621;8638.222559969632;8663.31065442258;8749.956250218707;8800.16398442147;11300.378479183724;11554.768904621693;11835.526578936746;11888.227265227733;11891.10816688769 05:38:01 INFO - PID 1637 | |3;mootools.html;1409.6123566019346;1439.4242303078768;1440.4773132268772;1456.9781453278197;1486.910785352879;9217.76955576111;10874.45627718615;11002.394868282241;11013.28413653112;11189.888101118986;10796.352218866874;10901.945490272557;10905.127589792817;10905.509252083655;10943.398113103776;2416.709994800622;2468.5309791139625;2470.0613766768693;2470.72821989581;2475.5420247254224;2397.700287464067;2411.746766589509;2441.4872876695913;2457.4347899983013;2462.9876850615724;2354.776296251853;2435.366804630791;2437.366284765965;2444.168982545934;2452.0559584559983;2376.940576485584;2425.987870060638;2432.015033911269;2435.2450740270483;2449.8162637802147;2097.140172529271;2408.9397765055837;2420.2739178246634;2422.0432928993127;2446.522928029035;10849.186311026704;11022.724431889186;11064.17018723599;11069.948354906272;11148.888511114867;6210.1616281801835;6403.903941440882;6473.417392434703;6473.74105035798;6477.514186436036;3986.40203969404;4010.318245898204;4052.898677533056;4068.7355321903983;4072.3687828386646;1125.7073160978123;1152.377716033341;1169.0939521870532;1192.2131393280392;1220.8290839282508;10177.287589868713;11012.944935275273;14340.641483962776;15220.619484512865;15293.235338233155;5171.741412929414;5270.393904700932;5426.186072089139;5440.863978400572;5520.696361700065;11355.37545435009;11387.487563059682;11416.143789215675;11420.88713587989;11425.885741142649;6282;6293.842653933688;6302.369763023753;6310.990241561328;6327.114204011442;5797.4782269595935;5874.206982057453;5955.583109182402;6018.548608854266;6044.1840351552855;4254.446921900195;4307.5477074906685;4329.264025115738;4337.652987760972;4341.869743907688;1248.1013670157472;1264.4246867675186;1270.1109223543556;1277.6614197237739;1294.2622705058027;741.4290995933102;750.5046669198302;771.5717776634021;775.6897241103603;809.5426084262373;6523.902141467787;6730.360615741497;6949.617771022541;6952.339527744856;6966.268541803038;917.5183028909743;919.1727445299284;930.3719989007393;936.5176933879058;942.688912658821;4299.204647140289;4388.912221755547;4421.756803375845;4470.664700147502;4575.199340115467;13322.267275299757;13760.724785504233;13784.931075344559;13807.240601767;13818.309084545732;11327.546898124148;13649.590512284449;13687.315634218448;13690.315484225748;13715.588532344045;9965.05331993474;10214;10218.131458825934;10234.283600147919;10245.538950747234;5650.57620678459;6556.409923106943;6711.46308295345;6741.59550426976;6809.2509823918335;11950.641480755592;12067.637970860887;12128.454219559964;12140;12149.271043737404;12443.315617640754;12457.1902826316;12545.686357841127;12661.87338126607;12694.984401247693;9041.276697864287;9233.169014788702;9312.696222528986;9329.533523323671;9371.578278977326;10070.8489372658;10123.949380253052;10128;10195.949020254851;10234.897651023395;8393.24460798531;8440.788980275542;8462.492250464991;8477.872831907403;8618.353623478139;7135.6432178391915;8429.578521074045;8587;8589.957050214709;8680.001799793105;6069.9089513656445;6285.120083188449;7657.57883316423;7806.49257798264;7821.983142191479;5659.801906933238;5731.914021289767;5823.82528524145;5879.470847623564;5916.201312822714;5773.393793651606;5938.532028205127;6008.459238668541;6046.27444706638;6165.414285642857 05:38:01 INFO - PID 1637 | |4;prototype.html;359.2061543987787;390.45141576085575;397.0906623831426;409.57199726286046;411.51646814992375;6232.563720539564;6233.875322493547;6302.464290535304;6366.172397588312;6382.4255816976465;5344.877575709106;6141.096260159354;6237.313895471494;6240.282367527736;6274.152989346437;3515.59570649376;4128.50457945047;4572.817087316504;4714.693544919569;4721.150192965265;3500.492428597852;4521.434820647419;4705.411823522059;4733.7396443195785;4748.5251474852585;4197.391378250152;4320.56794320567;4673.696209746373;4688;4729.834455794048;4554.795034223468;4588.770561471913;4616.446026476828;4682;4695.201815691341;3566.7694645347574;3758.605346438639;4004.619561141716;4009.578994205596;4012.7391719538136;6113.786017489367;6140.84647883802;6159.275402887199;6168.784092556739;6177.3822617738315;4584.61030812378;4835.1296766582;5405.675659460406;5474.507294343488;5501.131498623747;6637.502187335921;6686.93313066868;6704.128463299739;6724.150858513948;6738.797836064926;4394.432456407;4445.310976798602;4445.777711114431;4451.799669014902;4487.461504619434;5907.911381329241;6172.907406388863;6757.391834734848;6843.07618471505;6923.2038315593345;3802;4231.772785892057;4310.719803212781;4370.38814565961;4501.864944051684;4588.384620766198;4602.815887364535;4615.769211539477;4629.791659375336;4633.212353899778;5249.790008399621;5346.385165705994;5377.5429088527135;5448.400675925645;5449.945500545023;6048.969755151196;6180.505559555315;6575.309592492815;6727.697253623501;6740.730370785212;1554.5880341709455;1570.670159266544;1570.89789163704;1574.4332040465424;1584.4612831637069;981.283662926068;1022.9539670714836;1029.3978022856563;1038.7638709935304;1041.8124737547314;5154.999999999925;5578.470045345685;5606.411326810626;6126.744017476407;6396.648184349905;5536.505143611203;6214.627122372672;6321.462675672619;7025;7055.435565154775;5842.912356314659;6242.313345531988;6335.303116657164;6366.9681651591445;6375.585586936834;3390.711789497871;3643.342279262929;3683.8158092095823;3698.537682789651;3704.3332200204222;6782;7116.64416779159;7185.56886586796;7221.386182174469;7235.276472352828;6821.761238356733;7171.96414017937;7184.892226616605;7202.279772022755;7219.169795473483;5647.576431767552;5963.449503129217;6716;7144.214136445091;7234.478450968145;6914.9654251730435;7068.6922919260105;7086.476407572397;7099.260681133155;7101.893471597827;6630;6662.600243985376;6788.355106264897;6927.965360173167;6992.230854606091;5452.236686863922;6402.1357116790505;6801.353871382211;6855.691493882688;7249.891251631335;4534.183846907588;4535.501094879494;4538.092381523643;4545.159145558082;4553.2942393929;5524.342697190747;6377.649229292433;6737.764178253738;7052.9647351762915;7102.360787529147;5243.895122097613;6759.797206083826;6772.018057381553;6782.70948660046;6804.251532331538;6570.226038969547;6744.426723728538;6751.864962700817;6752.561083529555;6780.593164410151;5001.349824522788;5644.745986430703;6495.447886929664;6538.482684166535;6616.338366163345;5495.560355171657;6723.26044135135;6758.695858686273;6776.830579235361;6798.164495586263;6236.937630623635;6253.343398943045;6525.445337146396;6579.111819904251;6627.1716035495565 05:38:01 INFO - PID 1637 | |5;yui.html;719.9136103667561;728.297193208554;742.5136535569201;747.49917555238;753.4499815134951;15037.699246015072;15214.54356369308;15554.688906221869;16192.919035404806;16346.264418101184;14668;14745.26273686314;14883.776743348859;14903.850961490381;15156.090634561933;892.5626443042895;960.5053397500258;964.802215545815;965.9613615455409;966.4539535162611;930.7347405989294;959.9376040557377;964.7057647417535;965.8309795785744;967.88869280033;841.6843683618642;1010.3836659637615;1011.3476807459222;1012.8936461671528;1016.1159790981856;1002.9498525073717;1009.6769033909152;1010.7725761703631;1010.9848352274722;1011.5245834457793;628.6542401679059;747.9813004674872;748.1097493982198;748.805310619243;750.0961341583434;15883.60290992723;16075.758863617055;16344.591385215346;16527.586810329718;16746.748798767905;13239.73520529584;14204.076735012295;14434.927825360912;14716.411343546246;14834.813214942778;11725.589604363891;11807;12121.818172727417;12143.635690929286;12341.07441941849;2955.349823038928;2988.222364141342;3069.4781887079025;3135.357251763383;3172.2228054126654;104017.95982040234;105059.41030505001;108015.45992270067;108471;110853;11357.829632555518;11362.147838912115;11547.538098476134;11552.364619945816;11641.708957275967;24722.876385617958;25225;25227.495450090897;25298;25351.873240634046;7699.384049276045;7719;7826.804329891798;7907.802304942307;7964.283214510722;4020.43713880057;4085.4484644573195;4091.9386209206887;4130.153318569686;4134.4831896013;4069.552349241581;4149.522804877417;4152.647025002848;4178.39413285072;4189.308764053897;684.260998122037;791.5606838204851;801.4429971169981;804.3364224514798;810.5987536169633;560.529155509374;560.5543592843699;573.7893045673625;575.6287194759357;581.5076601498887;9484.099010593982;9675.806483870283;10085.294029418017;10232.437215953045;10323.948380258202;519.1771042896975;550.840256325661;567.7218163100051;572.1903506538285;580.4717706886797;4554.612857907115;4649.767511624405;4687.7656117194;4720.291956206596;4726.621870250372;90307.54846225727;97637;98152;99790;101785.91059453727;87873.56063219643;87920.6811897822;89108.55445722729;89861;89897.55051224702;56428.153577696365;58243.70878145582;62515.37484625186;67825;71337.5732485337;9878;10172.08451239392;10260;10767.730806729895;11036.558537658724;33889.66110338865;35329;35927.28145437129;36642.8167859159;37073.6292637081;46398.76800615976;55520.167197492876;56147.71926140343;64868.35131648623;66831.66336673142;13427.328633568477;13566.118202316818;13887;13901.304934753423;13956.720865582834;14197.716045679235;14291.857081429052;14295.57113286603;14345.354459049577;14463.49377771773;13489.595312140651;13504;13551.661208469866;13820.208590111155;14261.073030253001;13213.471461141447;13250.867491324963;13266.403011864297;13742.65643358924;13985;13089.803652945024;13106.934465327613;13304.13523120994;13631.86368136306;13948.651283717989;8018.919810802051;9539.76150596213;9555.713328600154;9576.425414475154;10139.188864890939;8786.780330491789;9274.968108921139;9996.350237234847;11148.987567316524;11220.607278745205 05:38:01 INFO - PID 1637 | __end_tp_report 05:38:01 INFO - PID 1637 | __start_cc_report 05:38:01 INFO - PID 1637 | _x_x_mozilla_cycle_collect,422 05:38:01 INFO - PID 1637 | __end_cc_report 05:38:01 INFO - PID 1637 | __startTimestamp1490618281375__endTimestamp 05:38:01 INFO - PID 1637 | 05:38:01 INFO - PID 1637 | ------- Summary: start ------- 05:38:01 INFO - PID 1637 | Number of tests: 6 05:38:01 INFO - PID 1637 | 05:38:01 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6100.32 Median:6317.53 stddev:722.59 (11.4%) stddev-sans-first:696.09 05:38:01 INFO - PID 1637 | Values: 3529.6 3560.3 3564.8 3568.4 3578.1 6121.0 6167.5 6173.7 6195.3 6224.6 6123.0 6125.8 6128.4 6137.8 6217.7 5555.5 5637.9 5665.1 5739.0 5746.3 5539.4 5663.5 5764.3 5768.0 5797.5 5712.0 5719.6 5793.2 5794.4 5819.2 4522.5 4553.5 5514.3 5615.8 5673.6 4546.2 5526.9 5546.3 5573.3 5579.5 4882.0 6059.1 6123.0 6220.5 6268.7 6403.5 6547.4 6580.8 6640.4 6645.1 6743.8 6754.8 6782.0 6782.6 6784.1 5175.4 5250.9 6306.7 6311.8 6312.5 5919.1 6650.5 6792.8 6836.3 6866.1 6230.4 6506.4 6510.9 6562.2 6575.6 6541.8 6569.0 6594.4 6643.9 6701.1 6602.4 6636.9 6680.1 6683.6 6686.5 5484.3 6327.8 6398.2 6672.2 6690.4 5278.1 5300.8 5301.2 5301.4 5314.0 4760.9 4774.7 4802.7 4807.2 4828.8 6284.5 6301.1 6315.1 6320.2 6323.3 6486.1 6593.8 6724.1 6737.8 6825.7 6253.6 6271.1 6278.3 6305.1 6321.9 5931.3 5941.9 5957.3 5981.5 6000.8 6563.1 6807.9 6865.9 6949.0 6949.6 5645.3 6540.3 6660.9 6686.5 6724.7 5336.9 6786.7 6832.4 6854.9 6879.4 6517.3 6602.5 6636.8 6668.8 6670.6 6432.7 6481.4 6647.7 6657.3 6670.1 5593.8 6317.5 6533.5 6561.6 6563.7 6294.5 6342.0 6342.5 6516.3 6563.3 4727.8 6388.6 6398.0 6417.3 6417.4 6463.4 6503.1 6533.5 6537.0 6545.4 6143.1 6198.5 6410.0 6410.7 6416.4 05:38:01 INFO - PID 1637 | 05:38:01 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:52985.67 Median:37984.52 stddev:109996.08 (289.6%) stddev-sans-first:110234.66 05:38:01 INFO - PID 1637 | Values: 724.7 787.1 813.4 1282.0 1287.6 37709.0 38564.8 38589.8 38740.8 38864.0 38928.0 38938.0 39104.4 39130.0 39251.8 8086.6 8265.3 8517.7 8723.0 8746.5 8550.7 8653.2 8668.3 8686.6 8696.6 8738.7 8794.3 8807.0 8811.9 8812.1 8755.6 8769.9 8779.6 8783.6 8816.3 8503.2 8510.1 8570.8 8591.3 8594.1 39031.6 39308.1 39326.0 39353.8 39440.4 2536.5 2948.8 2972.2 2992.5 2996.3 28050.6 28178.0 28284.4 28697.0 29117.5 2797.1 2818.3 2820.6 2820.8 2880.5 199639.0 250803.7 270151.0 281041.0 295020.1 47840.8 47841.8 47881.5 47893.0 47904.0 49860.0 49879.3 49880.0 49882.0 50057.2 14208.2 14312.6 14313.1 14333.2 14499.3 11693.6 11888.6 11898.4 11903.3 11909.7 13991.4 14006.3 14015.5 14041.4 14130.5 6135.0 6165.8 6183.9 6203.4 6213.7 803.7 829.4 970.6 998.5 1006.7 22963.1 23999.0 24187.8 24293.8 24350.9 874.1 926.2 1078.8 1116.7 1128.2 8977.5 12150.3 12152.6 12285.4 12656.4 483349.0 487521.0 488784.0 488985.6 489606.0 57262.4 57481.9 58070.7 58104.7 58183.8 1439.3 1477.3 1512.7 1515.4 1518.5 23824.1 24090.6 24143.5 24188.3 24298.4 40200.2 40232.2 40325.6 40414.8 40427.8 450940.0 451738.0 451917.7 452406.0 453072.0 26236.3 26503.5 29119.0 30717.4 34123.5 38260.0 38785.0 39558.8 39584.8 40042.0 22887.9 26619.7 27683.9 28216.7 28846.7 26187.5 27589.3 27590.7 27701.4 28449.6 29919.4 31722.4 34936.0 35005.0 35470.0 28340.4 28735.0 29071.0 29417.7 29455.9 21084.4 21724.3 22151.5 23338.9 23622.2 05:38:01 INFO - PID 1637 | 05:38:01 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:38535.82 Median:12441.33 stddev:106004.44 (852.0%) stddev-sans-first:106263.16 05:38:01 INFO - PID 1637 | Values: 407.9 423.3 431.8 438.7 459.1 20635.8 20856.2 21420.0 21962.2 22063.0 21571.0 22236.4 22588.7 22960.0 23309.5 4649.6 6044.0 6261.2 6262.3 6265.7 4919.9 6135.9 6172.6 6180.0 6199.6 5889.9 6149.2 6153.8 6164.0 6193.5 6042.7 6063.5 6086.3 6092.5 6101.7 5328.0 5347.6 5390.7 5404.4 5404.6 26607.0 26753.9 27126.1 27230.3 27507.6 1957.5 1968.8 1971.1 1987.4 2016.9 13070.7 15199.0 15594.9 15988.0 16422.5 1407.9 1527.2 1610.3 1632.2 1674.6 141593.3 152765.0 156338.7 157199.2 158803.0 10043.4 12466.4 12589.0 12615.9 12653.1 14117.6 16116.7 16210.9 16273.9 16309.3 7917.2 8625.4 9052.0 9074.4 9130.5 8690.3 8728.3 8745.0 8907.0 8907.5 7812.0 7905.3 8025.9 8037.3 8071.5 2104.6 2116.0 2119.8 2121.2 2294.0 850.6 876.3 884.0 890.5 915.6 18155.5 19434.5 19973.5 20037.5 20385.9 912.0 931.6 937.9 947.6 950.9 9825.5 10490.5 10525.3 10543.3 10700.0 564728.0 567704.0 572981.0 686609.0 714531.0 150887.0 155808.0 156589.0 171033.0 174318.0 1194.3 1208.9 1253.1 1257.4 1264.0 15952.7 20258.3 20309.3 20659.7 21063.0 51472.0 53695.7 63330.7 63775.7 64478.4 78423.6 85054.6 87665.0 90613.0 93915.0 7631.9 7670.4 8013.0 8437.0 8827.4 12886.3 13412.4 13497.3 13814.3 13959.3 11387.8 11449.3 11480.7 11578.6 11794.2 10811.0 10847.2 11012.9 11190.9 11285.7 11823.3 11842.0 12073.2 12098.6 12156.6 8543.8 8638.2 8663.3 8750.0 8800.2 11300.4 11554.8 11835.5 11888.2 11891.1 05:38:01 INFO - PID 1637 | 05:38:01 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:6717.31 Median:2470.39 stddev:4041.38 (163.6%) stddev-sans-first:4033.03 05:38:01 INFO - PID 1637 | Values: 1409.6 1439.4 1440.5 1457.0 1486.9 9217.8 10874.5 11002.4 11013.3 11189.9 10796.4 10901.9 10905.1 10905.5 10943.4 2416.7 2468.5 2470.1 2470.7 2475.5 2397.7 2411.7 2441.5 2457.4 2463.0 2354.8 2435.4 2437.4 2444.2 2452.1 2376.9 2426.0 2432.0 2435.2 2449.8 2097.1 2408.9 2420.3 2422.0 2446.5 10849.2 11022.7 11064.2 11069.9 11148.9 6210.2 6403.9 6473.4 6473.7 6477.5 3986.4 4010.3 4052.9 4068.7 4072.4 1125.7 1152.4 1169.1 1192.2 1220.8 10177.3 11012.9 14340.6 15220.6 15293.2 5171.7 5270.4 5426.2 5440.9 5520.7 11355.4 11387.5 11416.1 11420.9 11425.9 6282.0 6293.8 6302.4 6311.0 6327.1 5797.5 5874.2 5955.6 6018.5 6044.2 4254.4 4307.5 4329.3 4337.7 4341.9 1248.1 1264.4 1270.1 1277.7 1294.3 741.4 750.5 771.6 775.7 809.5 6523.9 6730.4 6949.6 6952.3 6966.3 917.5 919.2 930.4 936.5 942.7 4299.2 4388.9 4421.8 4470.7 4575.2 13322.3 13760.7 13784.9 13807.2 13818.3 11327.5 13649.6 13687.3 13690.3 13715.6 9965.1 10214.0 10218.1 10234.3 10245.5 5650.6 6556.4 6711.5 6741.6 6809.3 11950.6 12067.6 12128.5 12140.0 12149.3 12443.3 12457.2 12545.7 12661.9 12695.0 9041.3 9233.2 9312.7 9329.5 9371.6 10070.8 10123.9 10128.0 10195.9 10234.9 8393.2 8440.8 8462.5 8477.9 8618.4 7135.6 8429.6 8587.0 8590.0 8680.0 6069.9 6285.1 7657.6 7806.5 7822.0 5659.8 5731.9 5823.8 5879.5 5916.2 5773.4 5938.5 6008.5 6046.3 6165.4 05:38:01 INFO - PID 1637 | 05:38:01 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5370.46 Median:6120.27 stddev:1698.85 (27.8%) stddev-sans-first:1661.46 05:38:01 INFO - PID 1637 | Values: 359.2 390.5 397.1 409.6 411.5 6232.6 6233.9 6302.5 6366.2 6382.4 5344.9 6141.1 6237.3 6240.3 6274.2 3515.6 4128.5 4572.8 4714.7 4721.2 3500.5 4521.4 4705.4 4733.7 4748.5 4197.4 4320.6 4673.7 4688.0 4729.8 4554.8 4588.8 4616.4 4682.0 4695.2 3566.8 3758.6 4004.6 4009.6 4012.7 6113.8 6140.8 6159.3 6168.8 6177.4 4584.6 4835.1 5405.7 5474.5 5501.1 6637.5 6686.9 6704.1 6724.2 6738.8 4394.4 4445.3 4445.8 4451.8 4487.5 5907.9 6172.9 6757.4 6843.1 6923.2 3802.0 4231.8 4310.7 4370.4 4501.9 4588.4 4602.8 4615.8 4629.8 4633.2 5249.8 5346.4 5377.5 5448.4 5449.9 6049.0 6180.5 6575.3 6727.7 6740.7 1554.6 1570.7 1570.9 1574.4 1584.5 981.3 1023.0 1029.4 1038.8 1041.8 5155.0 5578.5 5606.4 6126.7 6396.6 5536.5 6214.6 6321.5 7025.0 7055.4 5842.9 6242.3 6335.3 6367.0 6375.6 3390.7 3643.3 3683.8 3698.5 3704.3 6782.0 7116.6 7185.6 7221.4 7235.3 6821.8 7172.0 7184.9 7202.3 7219.2 5647.6 5963.4 6716.0 7144.2 7234.5 6915.0 7068.7 7086.5 7099.3 7101.9 6630.0 6662.6 6788.4 6928.0 6992.2 5452.2 6402.1 6801.4 6855.7 7249.9 4534.2 4535.5 4538.1 4545.2 4553.3 5524.3 6377.6 6737.8 7053.0 7102.4 5243.9 6759.8 6772.0 6782.7 6804.3 6570.2 6744.4 6751.9 6752.6 6780.6 5001.3 5644.7 6495.4 6538.5 6616.3 5495.6 6723.3 6758.7 6776.8 6798.2 6236.9 6253.3 6525.4 6579.1 6627.2 05:38:01 INFO - PID 1637 | 05:38:01 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:19449.14 Median:34609.33 stddev:27659.25 (79.9%) stddev-sans-first:27701.09 05:38:01 INFO - PID 1637 | Values: 719.9 728.3 742.5 747.5 753.4 15037.7 15214.5 15554.7 16192.9 16346.3 14668.0 14745.3 14883.8 14903.9 15156.1 892.6 960.5 964.8 966.0 966.5 930.7 959.9 964.7 965.8 967.9 841.7 1010.4 1011.3 1012.9 1016.1 1002.9 1009.7 1010.8 1011.0 1011.5 628.7 748.0 748.1 748.8 750.1 15883.6 16075.8 16344.6 16527.6 16746.7 13239.7 14204.1 14434.9 14716.4 14834.8 11725.6 11807.0 12121.8 12143.6 12341.1 2955.3 2988.2 3069.5 3135.4 3172.2 104018.0 105059.4 108015.5 108471.0 110853.0 11357.8 11362.1 11547.5 11552.4 11641.7 24722.9 25225.0 25227.5 25298.0 25351.9 7699.4 7719.0 7826.8 7907.8 7964.3 4020.4 4085.4 4091.9 4130.2 4134.5 4069.6 4149.5 4152.6 4178.4 4189.3 684.3 791.6 801.4 804.3 810.6 560.5 560.6 573.8 575.6 581.5 9484.1 9675.8 10085.3 10232.4 10323.9 519.2 550.8 567.7 572.2 580.5 4554.6 4649.8 4687.8 4720.3 4726.6 90307.5 97637.0 98152.0 99790.0 101785.9 87873.6 87920.7 89108.6 89861.0 89897.6 56428.2 58243.7 62515.4 67825.0 71337.6 9878.0 10172.1 10260.0 10767.7 11036.6 33889.7 35329.0 35927.3 36642.8 37073.6 46398.8 55520.2 56147.7 64868.4 66831.7 13427.3 13566.1 13887.0 13901.3 13956.7 14197.7 14291.9 14295.6 14345.4 14463.5 13489.6 13504.0 13551.7 13820.2 14261.1 13213.5 13250.9 13266.4 13742.7 13985.0 13089.8 13106.9 13304.1 13631.9 13948.7 8018.9 9539.8 9555.7 9576.4 10139.2 8786.8 9275.0 9996.4 11149.0 11220.6 05:38:01 INFO - PID 1637 | -------- Summary: end -------- 05:38:01 INFO - PID 1637 | 05:38:02 INFO - TEST-INFO | 1637: exit 0 05:38:02 INFO - TEST-OK | dromaeo_css | took 1117745ms 05:38:02 INFO - TEST-START | kraken 05:38:02 INFO - operating with platform_type : mac_ 05:38:02 INFO - Initialising browser for kraken test... 05:38:02 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpcjQNsu/profile http://localhost:49248/getInfo.html) 05:38:04 INFO - PID 1650 | 2017-03-27 05:38:04.988 plugin-container[1651:10085] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 05:38:04 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:04 INFO - PID 1650 | 2017-03-27 05:38:04.993 plugin-container[1651:10085] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470f, name = 'com.apple.CFPasteboardClient' 05:38:04 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:04 INFO - PID 1650 | 2017-03-27 05:38:04.993 plugin-container[1651:10085] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:38:05 INFO - PID 1650 | __metrics Screen width/height:1600/1200 05:38:05 INFO - PID 1650 | colorDepth:24 05:38:05 INFO - PID 1650 | Browser inner width/height: 1024/689 05:38:05 INFO - PID 1650 | __metrics 05:38:05 ERROR - PID 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 05:38:05 ERROR - PID 1650 | 1490618285155 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/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpcjQNsu/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpcjQNsu/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpcjQNsu/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpcjQNsu/profile/extensions/talos-powers@TalosPowersService.js:55:9 05:38:05 INFO - TEST-INFO | 1650: exit 0 05:38:05 INFO - Browser initialized. 05:38:05 INFO - Running cycle 1/1 for kraken test... 05:38:05 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpcjQNsu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:08 INFO - PID 1652 | 2017-03-27 05:38:08.498 plugin-container[1653:10207] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 05:38:08 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:08 INFO - PID 1652 | 2017-03-27 05:38:08.499 plugin-container[1653:10207] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 05:38:08 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:08 INFO - PID 1652 | 2017-03-27 05:38:08.499 plugin-container[1653:10207] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:38:18 INFO - PID 1652 | RSS: Main: 229089280 05:38:18 INFO - PID 1652 | 05:38:21 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 05:38:22 INFO - PID 1652 | RSS: Main: 241803264 05:38:22 INFO - PID 1652 | 05:38:24 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 05:38:25 INFO - PID 1652 | RSS: Main: 250232832 05:38:25 INFO - PID 1652 | 05:38:28 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 05:38:29 INFO - PID 1652 | RSS: Main: 253083648 05:38:29 INFO - PID 1652 | 05:38:31 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 05:38:31 INFO - PID 1652 | RSS: Main: 254251008 05:38:31 INFO - PID 1652 | 05:38:33 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:38:34 INFO - PID 1652 | RSS: Main: 254644224 05:38:34 INFO - PID 1652 | 05:38:37 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 05:38:38 INFO - PID 1652 | RSS: Main: 261197824 05:38:38 INFO - PID 1652 | 05:38:41 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 05:38:42 INFO - PID 1652 | RSS: Main: 261914624 05:38:42 INFO - PID 1652 | 05:38:45 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 05:38:46 INFO - PID 1652 | RSS: Main: 259477504 05:38:46 INFO - PID 1652 | 05:38:48 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:38:48 INFO - PID 1652 | RSS: Main: 260018176 05:38:48 INFO - PID 1652 | 05:38:50 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:38:51 INFO - PID 1652 | RSS: Main: 260382720 05:38:51 INFO - PID 1652 | 05:38:53 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:38:54 INFO - PID 1652 | RSS: Main: 260751360 05:38:54 INFO - PID 1652 | 05:38:57 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:38:58 INFO - PID 1652 | RSS: Main: 261005312 05:38:58 INFO - PID 1652 | 05:39:01 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:39:01 INFO - PID 1652 | RSS: Main: 260456448 05:39:01 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 05:39:03 INFO - PID 1652 | RSS: Main: 263708672 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | __start_tp_report 05:39:03 INFO - PID 1652 | _x_x_mozilla_page_load 05:39:03 INFO - PID 1652 | _x_x_mozilla_page_load_details 05:39:03 INFO - PID 1652 | |i|pagename|runs| 05:39:03 INFO - PID 1652 | |0;ai-astar;105;122;118;124;174;164;114;119;115;116 05:39:03 INFO - PID 1652 | |1;audio-beat-detection;145;111;124;150;112;114;114;114;130;116 05:39:03 INFO - PID 1652 | |2;audio-dft;194;209;210;219;280;259;197;199;198;194 05:39:03 INFO - PID 1652 | |3;audio-fft;85;56;58;83;61;87;81;75;71;57 05:39:03 INFO - PID 1652 | |4;audio-oscillator;80;70;85;72;73;84;117;71;72;81 05:39:03 INFO - PID 1652 | |5;imaging-gaussian-blur;116;90;134;94;128;85;135;132;89;90 05:39:03 INFO - PID 1652 | |6;imaging-darkroom;98;98;97;98;128;93;154;104;94;125 05:39:03 INFO - PID 1652 | |7;imaging-desaturate;91;108;88;150;109;85;149;80;144;147 05:39:03 INFO - PID 1652 | |8;json-parse-financial;66;69;78;105;100;78;64;68;66;68 05:39:03 INFO - PID 1652 | |9;json-stringify-tinderbox;57;61;61;65;60;56;58;57;52;55 05:39:03 INFO - PID 1652 | |10;stanford-crypto-aes;90;111;107;104;83;83;74;102;108;106 05:39:03 INFO - PID 1652 | |11;stanford-crypto-ccm;153;130;137;152;158;160;160;145;193;170 05:39:03 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;186;185;187;192;214;184;238;201;184;239 05:39:03 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;69;70;70;67;69;70;68;67;74;70 05:39:03 INFO - PID 1652 | __end_tp_report 05:39:03 INFO - PID 1652 | __start_cc_report 05:39:03 INFO - PID 1652 | _x_x_mozilla_cycle_collect,989 05:39:03 INFO - PID 1652 | __end_cc_report 05:39:03 INFO - PID 1652 | __startTimestamp1490618343873__endTimestamp 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | ------- Summary: start ------- 05:39:03 INFO - PID 1652 | Number of tests: 14 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:127.10 Median:120.50 stddev:22.79 (18.9%) stddev-sans-first:22.73 05:39:03 INFO - PID 1652 | Values: 105.0 122.0 118.0 124.0 174.0 164.0 114.0 119.0 115.0 116.0 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:123.00 Median:120.00 stddev:14.22 (11.9%) stddev-sans-first:12.66 05:39:03 INFO - PID 1652 | Values: 145.0 111.0 124.0 150.0 112.0 114.0 114.0 114.0 130.0 116.0 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:215.90 Median:209.50 stddev:29.78 (14.2%) stddev-sans-first:30.51 05:39:03 INFO - PID 1652 | Values: 194.0 209.0 210.0 219.0 280.0 259.0 197.0 199.0 198.0 194.0 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:71.40 Median:78.00 stddev:12.47 (16.0%) stddev-sans-first:12.22 05:39:03 INFO - PID 1652 | Values: 85.0 56.0 58.0 83.0 61.0 87.0 81.0 75.0 71.0 57.0 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:76.50 stddev:14.01 (18.3%) stddev-sans-first:14.86 05:39:03 INFO - PID 1652 | Values: 80.0 70.0 85.0 72.0 73.0 84.0 117.0 71.0 72.0 81.0 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:87.00 stddev:21.50 (24.7%) stddev-sans-first:22.67 05:39:03 INFO - PID 1652 | Values: 116.0 90.0 134.0 94.0 128.0 85.0 135.0 132.0 89.0 90.0 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:108.90 Median:95.50 stddev:20.15 (21.1%) stddev-sans-first:20.98 05:39:03 INFO - PID 1652 | Values: 98.0 98.0 97.0 98.0 128.0 93.0 154.0 104.0 94.0 125.0 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:115.10 Median:115.00 stddev:29.37 (25.5%) stddev-sans-first:29.82 05:39:03 INFO - PID 1652 | Values: 91.0 108.0 88.0 150.0 109.0 85.0 149.0 80.0 144.0 147.0 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:76.20 Median:68.00 stddev:14.70 (21.6%) stddev-sans-first:15.12 05:39:03 INFO - PID 1652 | Values: 66.0 69.0 78.0 105.0 100.0 78.0 64.0 68.0 66.0 68.0 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:58.20 Median:59.00 stddev:3.68 (6.2%) stddev-sans-first:3.87 05:39:03 INFO - PID 1652 | Values: 57.0 61.0 61.0 65.0 60.0 56.0 58.0 57.0 52.0 55.0 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:96.80 Median:92.50 stddev:13.09 (14.1%) stddev-sans-first:13.65 05:39:03 INFO - PID 1652 | Values: 90.0 111.0 107.0 104.0 83.0 83.0 74.0 102.0 108.0 106.0 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:155.80 Median:159.00 stddev:17.59 (11.1%) stddev-sans-first:18.62 05:39:03 INFO - PID 1652 | Values: 153.0 130.0 137.0 152.0 158.0 160.0 160.0 145.0 193.0 170.0 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.00 Median:196.50 stddev:21.90 (11.1%) stddev-sans-first:22.55 05:39:03 INFO - PID 1652 | Values: 186.0 185.0 187.0 192.0 214.0 184.0 238.0 201.0 184.0 239.0 05:39:03 INFO - PID 1652 | 05:39:03 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:70.00 stddev:2.01 (2.9%) stddev-sans-first:2.13 05:39:03 INFO - PID 1652 | Values: 69.0 70.0 70.0 67.0 69.0 70.0 68.0 67.0 74.0 70.0 05:39:03 INFO - PID 1652 | -------- Summary: end -------- 05:39:03 INFO - PID 1652 | 05:39:04 INFO - TEST-INFO | 1652: exit 0 05:39:04 INFO - TEST-OK | kraken | took 62590ms 05:39:04 INFO - SUITE-END | took 1180s 05:39:05 INFO - Completed test suite (00:19:40) 05:39:05 INFO - javascript benchmark 05:39:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7665.9797015030545, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3529.6470352964707, 3560.2523470071283, 3564.8217589120545, 3568.4468907319374, 3578.087587665145, 6121.0, 6167.4757645600175, 6173.722182501787, 6195.318514963356, 6224.595401298912, 6122.969385153068, 6125.784909797661, 6128.411459112353, 6137.8366772192985, 6217.658028808413, 5555.50000499955, 5637.8590535236635, 5665.12190610456, 5739.0, 5746.310442746876, 5539.41836107209, 5663.546916246691, 5764.279465066867, 5768.019436695772, 5797.536197104243, 5711.971440142773, 5719.571032172604, 5793.217915581406, 5794.39158888317, 5819.185314056035, 4522.525134860858, 4553.544645535453, 5514.338279406456, 5615.803446879339, 5673.574481913873, 4546.227141385939, 5526.861828454321, 5546.251256080458, 5573.27547418837, 5579.497845193911, 4881.974785295092, 6059.091136329585, 6123.0, 6220.512846061501, 6268.749250029993, 6403.519736019771, 6547.443467305237, 6580.80257592273, 6640.369164929324, 6645.136132302818, 6743.831404214885, 6754.763583274562, 6782.0, 6782.626955517494, 6784.118064651613, 5175.353080864892, 5250.8687282817855, 6306.747730090791, 6311.810645680637, 6312.52656050798, 5919.141724450017, 6650.534462587572, 6792.76225332111, 6836.316368363124, 6866.141732283465, 6230.408111229426, 6506.3818937200895, 6510.934890651128, 6562.212534495891, 6575.605463672195, 6541.803745887631, 6569.0, 6594.40650341462, 6643.86712265752, 6701.1288532491435, 6602.438792702674, 6636.933630663728, 6680.0981867448245, 6683.565568238049, 6686.465082793365, 5484.2596249505805, 6327.810165695037, 6398.168238129014, 6672.232693240242, 6690.364415380531, 5278.076336641073, 5300.814471493479, 5301.1783173608155, 5301.363836339664, 5313.990341835039, 4760.904781904343, 4774.689645173053, 4802.711837289774, 4807.206810876236, 4828.758562071883, 6284.497240220771, 6301.0863424804065, 6315.084312774622, 6320.178376810985, 6323.30443651198, 6486.091947127406, 6593.8351541210895, 6724.092247546617, 6737.831554211184, 6825.69284382204, 6253.593516421418, 6271.122042913996, 6278.277998030194, 6305.148804911279, 6321.873562528724, 5931.258592675916, 5941.940580594225, 5957.344692083867, 5981.5214782817275, 6000.849978750566, 6563.113979612882, 6807.897881531781, 6865.897011544831, 6949.0, 6949.583025018515, 5645.294338207724, 6540.313267107075, 6660.866782664417, 6686.498512611476, 6724.697388617426, 5336.852576696029, 6786.69459874297, 6832.350926662158, 6854.931450685429, 6879.415249703831, 6517.348265173634, 6602.537822352581, 6636.800895973129, 6668.799936001928, 6670.633115178518, 6432.710528026344, 6481.4166724994975, 6647.734090636321, 6657.267700553032, 6670.099536562502, 5593.804216852391, 6317.526185536195, 6533.509986750918, 6561.573497722633, 6563.704633291609, 6294.464970477377, 6341.972847241942, 6342.492600592034, 6516.250631177475, 6563.310852360433, 4727.834525791581, 6388.552801303864, 6398.0083087121575, 6417.294097649348, 6417.39034791694, 6463.418292353711, 6503.089567460654, 6533.509986750918, 6537.0, 6545.443637290884, 6143.051335359142, 6198.473129784019, 6410.0, 6410.711517981609, 6416.390442907917], "value": 6056.951798952142, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.7318492157902, 787.0869791042392, 813.3899575318509, 1281.9807702884452, 1287.6201520551435, 37709.0, 38564.80717596415, 38589.80705096471, 38740.80629596848, 38864.0, 38928.02679933003, 38938.0, 39104.413433798516, 39130.0, 39251.803740981326, 8086.595670216495, 8265.256126948574, 8517.74446766598, 8722.956385218065, 8746.518941458216, 8550.70072547461, 8653.221210091091, 8668.306535477179, 8686.56567171642, 8696.565171741418, 8738.694145704902, 8794.340424468159, 8807.0, 8811.867821982643, 8812.118788121168, 8755.642875354299, 8769.868451973225, 8779.561021948877, 8783.560821958908, 8816.250618697419, 8503.1921967413, 8510.063892971768, 8570.828583428296, 8591.26974207193, 8594.140585941419, 39031.60968390308, 39308.05189610791, 39326.0, 39353.80323098395, 39440.408393873826, 2536.467341858212, 2948.7935844490894, 2972.2272209225757, 2992.4912764830033, 2996.3258266889993, 28050.579241311192, 28178.01376951797, 28284.43431131366, 28697.0, 29117.544122793777, 2797.084672671542, 2818.309514169021, 2820.576913462997, 2820.8025438219333, 2880.495913215179, 199639.00180499005, 250803.74598127074, 270151.0, 281041.0, 295020.09959800896, 47840.76079619649, 47841.76079119582, 47881.52118478771, 47893.04213915702, 47904.0, 49860.0, 49879.25422610191, 49880.0, 49882.0, 50057.24914126291, 14208.218547979961, 14312.642183945276, 14313.069650472686, 14333.211673357857, 14499.275036248146, 11693.649190524298, 11888.643340699793, 11898.405079745979, 11903.34531600753, 11909.702257443632, 13991.370388332549, 14006.299685015709, 14015.50945716895, 14041.438342466186, 14130.505432310025, 6134.969325153345, 6165.815025549241, 6183.876322473615, 6203.410675985774, 6213.689315534296, 803.7468197517763, 829.4484168028193, 970.6117552978724, 998.5271724206737, 1006.6527048168371, 22963.081476741077, 23999.0, 24187.7581224189, 24293.75706242915, 24350.90420931062, 874.1127755328347, 926.2451102351562, 1078.805814953316, 1116.7431490757172, 1128.1933417606451, 8977.461352318882, 12150.33173175466, 12152.635420937386, 12285.38573071343, 12656.430460629294, 483349.0, 487521.0, 488784.0, 488985.55507222237, 489606.0, 57262.42737572654, 57481.8503629925, 58070.7096464515, 58104.709476453194, 58183.836323273295, 1439.2731670506328, 1477.2613693153423, 1512.6823367093034, 1515.4014164405219, 1518.5368462619008, 23824.072652726045, 24090.638640420057, 24143.51712965766, 24188.274351769476, 24298.392540186636, 40200.195996080496, 40232.195356093296, 40325.588604398705, 40414.787556373354, 40427.797861010506, 450940.0, 451738.0, 451917.74041129585, 452406.0, 453072.0, 26236.344091397867, 26503.469930601666, 29119.0, 30717.385652287277, 34123.48814767731, 38260.04349891163, 38785.0, 39558.80220598879, 39584.802075989435, 40042.0, 22887.88556057209, 26619.66901654871, 27683.861580691966, 28216.73024713852, 28846.71153288524, 26187.476250475265, 27589.31026724348, 27590.724092759618, 27701.44597108087, 28449.57725707845, 29919.354435506255, 31722.36555268928, 34935.9519214424, 35005.0, 35470.0, 28340.433191335644, 28735.0, 29071.0, 29417.705822941498, 29455.852720737115, 21084.367468975954, 21724.348269551938, 22151.49395423178, 23338.949747261075, 23622.17322393708], "value": 17478.08514328268, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.89394757363084, 423.30789159723844, 431.7884236724004, 438.6512722385703, 459.06121980549773, 20635.793642063574, 20856.165753369867, 21420.03609837557, 21962.231321903735, 22063.0, 21571.0, 22236.444088897784, 22588.66117008246, 22960.0, 23309.533809323802, 4649.604783593399, 6043.969780151104, 6261.217347831521, 6262.3111457739615, 6265.686715664198, 4919.901601967958, 6135.87728245437, 6172.567920245585, 6179.969100154515, 6199.628022318653, 5889.852753681171, 6149.2313460817395, 6153.846153846145, 6163.969180154093, 6193.504519638418, 6042.667653279068, 6063.54523410746, 6086.300075491331, 6092.482139018189, 6101.725422356005, 5328.040952628526, 5347.59893008022, 5390.676559406448, 5404.4325345838515, 5404.5676345892625, 26607.0, 26753.86623066892, 27126.05058822932, 27230.31924201891, 27507.58738618922, 1957.500837286497, 1968.803119688034, 1971.0932970833435, 1987.3640435060786, 2016.9294074707461, 13070.673233169247, 15199.0, 15594.9220253898, 15987.960782549097, 16422.507324780156, 1407.9159047533444, 1527.2363818090955, 1610.2753760807684, 1632.183908045977, 1674.6315810521667, 141593.2920335402, 152765.0, 156338.65492017628, 157199.2140039304, 158803.0, 10043.397396156253, 12466.376681165906, 12589.0, 12615.936920315524, 12653.051021173449, 14117.576472705836, 16116.677666446605, 16210.94628849121, 16273.918630406772, 16309.266083026292, 7917.208279172037, 8625.439346442461, 9052.0, 9074.364794464323, 9130.543472826332, 8690.304775617935, 8728.301735861116, 8744.956275218583, 8906.955465222632, 8907.51008694528, 7812.023497062867, 7905.288524032865, 8025.9197408025175, 8037.271986522809, 8071.4753541019645, 2104.5685634444903, 2115.978840211609, 2119.7562280337957, 2121.1727426303755, 2294.0, 850.6214734443237, 876.3471213945523, 883.9878339301592, 890.496869268861, 915.5651065743718, 18155.54611134732, 19434.514137146685, 19973.500662483264, 20037.499062523555, 20385.898070509553, 911.9740292171307, 931.6180366050022, 937.8733870927474, 947.5783276441917, 950.9476978766096, 9825.508724563742, 10490.527926243338, 10525.315854469434, 10543.31468454548, 10700.0, 564728.0, 567704.0, 572981.0, 686609.0, 714531.0, 150887.0, 155808.0, 156589.0, 171033.0, 174318.0, 1194.3132698698282, 1208.9335086570147, 1253.0664654832026, 1257.43415463042, 1264.0393301091237, 15952.680946381239, 20258.278046366224, 20309.28917487881, 20659.690104648143, 21063.0, 51472.0, 53695.6576085601, 63330.68334658482, 63775.72448551096, 64478.355216447235, 78423.60788196023, 85054.57472712598, 87665.0, 90613.0, 93915.0, 7631.8855217172895, 7670.386369090571, 8012.95993520052, 8437.0, 8827.382083254368, 12886.291253980746, 13412.396442160321, 13497.257650828906, 13814.309284535933, 13959.302034898417, 11387.829182562435, 11449.255798373411, 11480.655580332603, 11578.594749183738, 11794.233374830608, 10811.0, 10847.240693151403, 11012.889871101506, 11190.888091118984, 11285.71785705364, 11823.349715765715, 11841.9934305585, 12073.154879158375, 12098.576549820715, 12156.574519891761, 8543.829123417621, 8638.222559969632, 8663.31065442258, 8749.956250218707, 8800.16398442147, 11300.378479183724, 11554.768904621693, 11835.526578936746, 11888.227265227733, 11891.10816688769], "value": 10271.263792112546, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.6123566019346, 1439.4242303078768, 1440.4773132268772, 1456.9781453278197, 1486.910785352879, 9217.76955576111, 10874.45627718615, 11002.394868282241, 11013.28413653112, 11189.888101118986, 10796.352218866874, 10901.945490272557, 10905.127589792817, 10905.509252083655, 10943.398113103776, 2416.709994800622, 2468.5309791139625, 2470.0613766768693, 2470.72821989581, 2475.5420247254224, 2397.700287464067, 2411.746766589509, 2441.4872876695913, 2457.4347899983013, 2462.9876850615724, 2354.776296251853, 2435.366804630791, 2437.366284765965, 2444.168982545934, 2452.0559584559983, 2376.940576485584, 2425.987870060638, 2432.015033911269, 2435.2450740270483, 2449.8162637802147, 2097.140172529271, 2408.9397765055837, 2420.2739178246634, 2422.0432928993127, 2446.522928029035, 10849.186311026704, 11022.724431889186, 11064.17018723599, 11069.948354906272, 11148.888511114867, 6210.1616281801835, 6403.903941440882, 6473.417392434703, 6473.74105035798, 6477.514186436036, 3986.40203969404, 4010.318245898204, 4052.898677533056, 4068.7355321903983, 4072.3687828386646, 1125.7073160978123, 1152.377716033341, 1169.0939521870532, 1192.2131393280392, 1220.8290839282508, 10177.287589868713, 11012.944935275273, 14340.641483962776, 15220.619484512865, 15293.235338233155, 5171.741412929414, 5270.393904700932, 5426.186072089139, 5440.863978400572, 5520.696361700065, 11355.37545435009, 11387.487563059682, 11416.143789215675, 11420.88713587989, 11425.885741142649, 6282.0, 6293.842653933688, 6302.369763023753, 6310.990241561328, 6327.114204011442, 5797.4782269595935, 5874.206982057453, 5955.583109182402, 6018.548608854266, 6044.1840351552855, 4254.446921900195, 4307.5477074906685, 4329.264025115738, 4337.652987760972, 4341.869743907688, 1248.1013670157472, 1264.4246867675186, 1270.1109223543556, 1277.6614197237739, 1294.2622705058027, 741.4290995933102, 750.5046669198302, 771.5717776634021, 775.6897241103603, 809.5426084262373, 6523.902141467787, 6730.360615741497, 6949.617771022541, 6952.339527744856, 6966.268541803038, 917.5183028909743, 919.1727445299284, 930.3719989007393, 936.5176933879058, 942.688912658821, 4299.204647140289, 4388.912221755547, 4421.756803375845, 4470.664700147502, 4575.199340115467, 13322.267275299757, 13760.724785504233, 13784.931075344559, 13807.240601767, 13818.309084545732, 11327.546898124148, 13649.590512284449, 13687.315634218448, 13690.315484225748, 13715.588532344045, 9965.05331993474, 10214.0, 10218.131458825934, 10234.283600147919, 10245.538950747234, 5650.57620678459, 6556.409923106943, 6711.46308295345, 6741.59550426976, 6809.2509823918335, 11950.641480755592, 12067.637970860887, 12128.454219559964, 12140.0, 12149.271043737404, 12443.315617640754, 12457.1902826316, 12545.686357841127, 12661.87338126607, 12694.984401247693, 9041.276697864287, 9233.169014788702, 9312.696222528986, 9329.533523323671, 9371.578278977326, 10070.8489372658, 10123.949380253052, 10128.0, 10195.949020254851, 10234.897651023395, 8393.24460798531, 8440.788980275542, 8462.492250464991, 8477.872831907403, 8618.353623478139, 7135.6432178391915, 8429.578521074045, 8587.0, 8589.957050214709, 8680.001799793105, 6069.9089513656445, 6285.120083188449, 7657.57883316423, 7806.49257798264, 7821.983142191479, 5659.801906933238, 5731.914021289767, 5823.82528524145, 5879.470847623564, 5916.201312822714, 5773.393793651606, 5938.532028205127, 6008.459238668541, 6046.27444706638, 6165.414285642857], "value": 5173.913351130781, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.2061543987787, 390.45141576085575, 397.0906623831426, 409.57199726286046, 411.51646814992375, 6232.563720539564, 6233.875322493547, 6302.464290535304, 6366.172397588312, 6382.4255816976465, 5344.877575709106, 6141.096260159354, 6237.313895471494, 6240.282367527736, 6274.152989346437, 3515.59570649376, 4128.50457945047, 4572.817087316504, 4714.693544919569, 4721.150192965265, 3500.492428597852, 4521.434820647419, 4705.411823522059, 4733.7396443195785, 4748.5251474852585, 4197.391378250152, 4320.56794320567, 4673.696209746373, 4688.0, 4729.834455794048, 4554.795034223468, 4588.770561471913, 4616.446026476828, 4682.0, 4695.201815691341, 3566.7694645347574, 3758.605346438639, 4004.619561141716, 4009.578994205596, 4012.7391719538136, 6113.786017489367, 6140.84647883802, 6159.275402887199, 6168.784092556739, 6177.3822617738315, 4584.61030812378, 4835.1296766582, 5405.675659460406, 5474.507294343488, 5501.131498623747, 6637.502187335921, 6686.93313066868, 6704.128463299739, 6724.150858513948, 6738.797836064926, 4394.432456407, 4445.310976798602, 4445.777711114431, 4451.799669014902, 4487.461504619434, 5907.911381329241, 6172.907406388863, 6757.391834734848, 6843.07618471505, 6923.2038315593345, 3802.0, 4231.772785892057, 4310.719803212781, 4370.38814565961, 4501.864944051684, 4588.384620766198, 4602.815887364535, 4615.769211539477, 4629.791659375336, 4633.212353899778, 5249.790008399621, 5346.385165705994, 5377.5429088527135, 5448.400675925645, 5449.945500545023, 6048.969755151196, 6180.505559555315, 6575.309592492815, 6727.697253623501, 6740.730370785212, 1554.5880341709455, 1570.670159266544, 1570.89789163704, 1574.4332040465424, 1584.4612831637069, 981.283662926068, 1022.9539670714836, 1029.3978022856563, 1038.7638709935304, 1041.8124737547314, 5154.999999999925, 5578.470045345685, 5606.411326810626, 6126.744017476407, 6396.648184349905, 5536.505143611203, 6214.627122372672, 6321.462675672619, 7025.0, 7055.435565154775, 5842.912356314659, 6242.313345531988, 6335.303116657164, 6366.9681651591445, 6375.585586936834, 3390.711789497871, 3643.342279262929, 3683.8158092095823, 3698.537682789651, 3704.3332200204222, 6782.0, 7116.64416779159, 7185.56886586796, 7221.386182174469, 7235.276472352828, 6821.761238356733, 7171.96414017937, 7184.892226616605, 7202.279772022755, 7219.169795473483, 5647.576431767552, 5963.449503129217, 6716.0, 7144.214136445091, 7234.478450968145, 6914.9654251730435, 7068.6922919260105, 7086.476407572397, 7099.260681133155, 7101.893471597827, 6630.0, 6662.600243985376, 6788.355106264897, 6927.965360173167, 6992.230854606091, 5452.236686863922, 6402.1357116790505, 6801.353871382211, 6855.691493882688, 7249.891251631335, 4534.183846907588, 4535.501094879494, 4538.092381523643, 4545.159145558082, 4553.2942393929, 5524.342697190747, 6377.649229292433, 6737.764178253738, 7052.9647351762915, 7102.360787529147, 5243.895122097613, 6759.797206083826, 6772.018057381553, 6782.70948660046, 6804.251532331538, 6570.226038969547, 6744.426723728538, 6751.864962700817, 6752.561083529555, 6780.593164410151, 5001.349824522788, 5644.745986430703, 6495.447886929664, 6538.482684166535, 6616.338366163345, 5495.560355171657, 6723.26044135135, 6758.695858686273, 6776.830579235361, 6798.164495586263, 6236.937630623635, 6253.343398943045, 6525.445337146396, 6579.111819904251, 6627.1716035495565], "value": 4840.5183983928655, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.9136103667561, 728.297193208554, 742.5136535569201, 747.49917555238, 753.4499815134951, 15037.699246015072, 15214.54356369308, 15554.688906221869, 16192.919035404806, 16346.264418101184, 14668.0, 14745.26273686314, 14883.776743348859, 14903.850961490381, 15156.090634561933, 892.5626443042895, 960.5053397500258, 964.802215545815, 965.9613615455409, 966.4539535162611, 930.7347405989294, 959.9376040557377, 964.7057647417535, 965.8309795785744, 967.88869280033, 841.6843683618642, 1010.3836659637615, 1011.3476807459222, 1012.8936461671528, 1016.1159790981856, 1002.9498525073717, 1009.6769033909152, 1010.7725761703631, 1010.9848352274722, 1011.5245834457793, 628.6542401679059, 747.9813004674872, 748.1097493982198, 748.805310619243, 750.0961341583434, 15883.60290992723, 16075.758863617055, 16344.591385215346, 16527.586810329718, 16746.748798767905, 13239.73520529584, 14204.076735012295, 14434.927825360912, 14716.411343546246, 14834.813214942778, 11725.589604363891, 11807.0, 12121.818172727417, 12143.635690929286, 12341.07441941849, 2955.349823038928, 2988.222364141342, 3069.4781887079025, 3135.357251763383, 3172.2228054126654, 104017.95982040234, 105059.41030505001, 108015.45992270067, 108471.0, 110853.0, 11357.829632555518, 11362.147838912115, 11547.538098476134, 11552.364619945816, 11641.708957275967, 24722.876385617958, 25225.0, 25227.495450090897, 25298.0, 25351.873240634046, 7699.384049276045, 7719.0, 7826.804329891798, 7907.802304942307, 7964.283214510722, 4020.43713880057, 4085.4484644573195, 4091.9386209206887, 4130.153318569686, 4134.4831896013, 4069.552349241581, 4149.522804877417, 4152.647025002848, 4178.39413285072, 4189.308764053897, 684.260998122037, 791.5606838204851, 801.4429971169981, 804.3364224514798, 810.5987536169633, 560.529155509374, 560.5543592843699, 573.7893045673625, 575.6287194759357, 581.5076601498887, 9484.099010593982, 9675.806483870283, 10085.294029418017, 10232.437215953045, 10323.948380258202, 519.1771042896975, 550.840256325661, 567.7218163100051, 572.1903506538285, 580.4717706886797, 4554.612857907115, 4649.767511624405, 4687.7656117194, 4720.291956206596, 4726.621870250372, 90307.54846225727, 97637.0, 98152.0, 99790.0, 101785.91059453727, 87873.56063219643, 87920.6811897822, 89108.55445722729, 89861.0, 89897.55051224702, 56428.153577696365, 58243.70878145582, 62515.37484625186, 67825.0, 71337.5732485337, 9878.0, 10172.08451239392, 10260.0, 10767.730806729895, 11036.558537658724, 33889.66110338865, 35329.0, 35927.28145437129, 36642.8167859159, 37073.6292637081, 46398.76800615976, 55520.167197492876, 56147.71926140343, 64868.35131648623, 66831.66336673142, 13427.328633568477, 13566.118202316818, 13887.0, 13901.304934753423, 13956.720865582834, 14197.716045679235, 14291.857081429052, 14295.57113286603, 14345.354459049577, 14463.49377771773, 13489.595312140651, 13504.0, 13551.661208469866, 13820.208590111155, 14261.073030253001, 13213.471461141447, 13250.867491324963, 13266.403011864297, 13742.65643358924, 13985.0, 13089.803652945024, 13106.934465327613, 13304.13523120994, 13631.86368136306, 13948.651283717989, 8018.919810802051, 9539.76150596213, 9555.713328600154, 9576.425414475154, 10139.188864890939, 8786.780330491789, 9274.968108921139, 9996.350237234847, 11148.987567316524, 11220.607278745205], "value": 7452.3332025060345, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1608.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 122.0, 118.0, 124.0, 174.0, 164.0, 114.0, 119.0, 115.0, 116.0], "value": 127.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 124.0, 150.0, 112.0, 114.0, 114.0, 114.0, 130.0, 116.0], "value": 123.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 209.0, 210.0, 219.0, 280.0, 259.0, 197.0, 199.0, 198.0, 194.0], "value": 215.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 56.0, 58.0, 83.0, 61.0, 87.0, 81.0, 75.0, 71.0, 57.0], "value": 71.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 70.0, 85.0, 72.0, 73.0, 84.0, 117.0, 71.0, 72.0, 81.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 90.0, 134.0, 94.0, 128.0, 85.0, 135.0, 132.0, 89.0, 90.0], "value": 109.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 97.0, 98.0, 128.0, 93.0, 154.0, 104.0, 94.0, 125.0], "value": 108.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 108.0, 88.0, 150.0, 109.0, 85.0, 149.0, 80.0, 144.0, 147.0], "value": 115.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 78.0, 105.0, 100.0, 78.0, 64.0, 68.0, 66.0, 68.0], "value": 76.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 61.0, 65.0, 60.0, 56.0, 58.0, 57.0, 52.0, 55.0], "value": 58.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 111.0, 107.0, 104.0, 83.0, 83.0, 74.0, 102.0, 108.0, 106.0], "value": 96.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 130.0, 137.0, 152.0, 158.0, 160.0, 160.0, 145.0, 193.0, 170.0], "value": 155.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 187.0, 192.0, 214.0, 184.0, 238.0, 201.0, 184.0, 239.0], "value": 201.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 67.0, 69.0, 70.0, 68.0, 67.0, 74.0, 70.0], "value": 69.4, "unit": "score"}]}]} 05:39:05 INFO - Return code: 0 05:39:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:39:05 ERROR - # TBPL SUCCESS # 05:39:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:39:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:39:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:39:05 INFO - 2017-03-27 05:39:05.189 screenresolution[1657:10600] starting screenresolution argv=screenresolution get 05:39:05 INFO - 2017-03-27 05:39:05.192 screenresolution[1657:10600] Display 0: 1600x1200x32@60 05:39:05 INFO - 2017-03-27 05:39:05.198 screenresolution[1658:10603] starting screenresolution argv=screenresolution list 05:39:05 INFO - Available Modes on Display 0 05:39:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:39:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:39:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:39:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:39:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:39:05 INFO - Intel Iris: 05:39:05 INFO - Chipset Model: Intel Iris 05:39:05 INFO - Type: GPU 05:39:05 INFO - Bus: Built-In 05:39:05 INFO - VRAM (Dynamic, Max): 1536 MB 05:39:05 INFO - Vendor: Intel (0x8086) 05:39:05 INFO - Device ID: 0x0a2e 05:39:05 INFO - Revision ID: 0x0009 05:39:05 INFO - Displays: 05:39:05 INFO - Display: 05:39:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:39:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:39:05 INFO - Display Serial Number: 1600x1200 60 05:39:05 INFO - Main Display: Yes 05:39:05 INFO - Mirror: Off 05:39:05 INFO - Online: Yes 05:39:05 INFO - Rotation: Supported 05:39:05 INFO - Return code: 0 05:39:05 INFO - Running post-action listener: _resource_record_post_action 05:39:05 INFO - [mozharness: 2017-03-27 12:39:05.433425Z] Finished run-tests step (success) 05:39:05 INFO - Running post-run listener: _resource_record_post_run 05:39:05 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 37833216; Write bytes: 516402176; Read time: 399; Write time: 1636 05:39:05 INFO - TinderboxPrint: CPU usage
54.3% 05:39:05 INFO - TinderboxPrint: I/O read bytes / time
37,833,216 / 399 05:39:05 INFO - TinderboxPrint: I/O write bytes / time
516,402,176 / 1,636 05:39:05 INFO - TinderboxPrint: CPU idle
2,196.6 (45.7%) 05:39:05 INFO - TinderboxPrint: CPU system
971.9 (20.2%) 05:39:05 INFO - TinderboxPrint: CPU user
1,637.3 (34.1%) 05:39:05 INFO - TinderboxPrint: Swap in / out
249,430,016 / 0 05:39:05 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204927488; Write bytes: 204244480; Read time: 15725; Write time: 427 05:39:05 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 36006400; Write bytes: 301323776; Read time: 377; Write time: 1175 05:39:05 INFO - Running post-run listener: _upload_blobber_files 05:39:05 INFO - Blob upload gear active. 05:39:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:39:05 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 05:39:05 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'] 05:39:05 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 05:39:06 INFO - (blobuploader) - INFO - Open directory for files ... 05:39:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:39:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:39:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:07 INFO - (blobuploader) - INFO - Done attempting. 05:39:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:39:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:39:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:08 INFO - (blobuploader) - INFO - Done attempting. 05:39:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:39:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:39:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:09 INFO - (blobuploader) - INFO - Done attempting. 05:39:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:39:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:39:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:12 INFO - (blobuploader) - INFO - Done attempting. 05:39:12 INFO - (blobuploader) - INFO - Iteration through files over. 05:39:12 INFO - Return code: 0 05:39:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:39:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:39:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7687b131765603760a2c0ff7a1da99790260574a8e3e35ff2287a3b45ac8704ce50e503a8c1bb417c92881e92af2df604eed4ea28471448cf81d4ef34fc3426", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81aeb7450360e35bc541240659fccdd20fbefea1c0ac9bb78435a6ec9683553a4b58cbdbd676b160b3d6356da9ec2d483c127dcfd551d9fdbd5d1cedd27f8129", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d8cc28db6a9c43a4ac936f4ccf346373adf9bc620239f7317dbbfaa8bae7f84be5ca4d84630dd0b3410683a7c82b75435687268d063f87d7903ec04dc257ad4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c830201216464843fbf6f2d068f827df5716432063e6ea189fa6476296016d773ebe2cfa7c5baef96ddc0c8f3cd07e0bd724c9a969917162f59c8d975adfe54"} 05:39:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:39:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:39:12 INFO - Contents: 05:39:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7687b131765603760a2c0ff7a1da99790260574a8e3e35ff2287a3b45ac8704ce50e503a8c1bb417c92881e92af2df604eed4ea28471448cf81d4ef34fc3426", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81aeb7450360e35bc541240659fccdd20fbefea1c0ac9bb78435a6ec9683553a4b58cbdbd676b160b3d6356da9ec2d483c127dcfd551d9fdbd5d1cedd27f8129", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d8cc28db6a9c43a4ac936f4ccf346373adf9bc620239f7317dbbfaa8bae7f84be5ca4d84630dd0b3410683a7c82b75435687268d063f87d7903ec04dc257ad4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c830201216464843fbf6f2d068f827df5716432063e6ea189fa6476296016d773ebe2cfa7c5baef96ddc0c8f3cd07e0bd724c9a969917162f59c8d975adfe54"} 05:39:12 INFO - Running post-run listener: copy_logs_to_upload_dir 05:39:12 INFO - Copying logs to upload dir... 05:39:12 INFO - mkdir: /builds/slave/test/build/upload/logs 05:39:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.122975 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-27 05:39:12.364215) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 05:39:12.367513) ========= 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.4k1HsDY8th/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.utMEzQcEif/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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/e7687b131765603760a2c0ff7a1da99790260574a8e3e35ff2287a3b45ac8704ce50e503a8c1bb417c92881e92af2df604eed4ea28471448cf81d4ef34fc3426", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81aeb7450360e35bc541240659fccdd20fbefea1c0ac9bb78435a6ec9683553a4b58cbdbd676b160b3d6356da9ec2d483c127dcfd551d9fdbd5d1cedd27f8129", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d8cc28db6a9c43a4ac936f4ccf346373adf9bc620239f7317dbbfaa8bae7f84be5ca4d84630dd0b3410683a7c82b75435687268d063f87d7903ec04dc257ad4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c830201216464843fbf6f2d068f827df5716432063e6ea189fa6476296016d773ebe2cfa7c5baef96ddc0c8f3cd07e0bd724c9a969917162f59c8d975adfe54"} build_url:https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/Y4yaXVBqRW-tsEpIidbzuw/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/e7687b131765603760a2c0ff7a1da99790260574a8e3e35ff2287a3b45ac8704ce50e503a8c1bb417c92881e92af2df604eed4ea28471448cf81d4ef34fc3426", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81aeb7450360e35bc541240659fccdd20fbefea1c0ac9bb78435a6ec9683553a4b58cbdbd676b160b3d6356da9ec2d483c127dcfd551d9fdbd5d1cedd27f8129", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d8cc28db6a9c43a4ac936f4ccf346373adf9bc620239f7317dbbfaa8bae7f84be5ca4d84630dd0b3410683a7c82b75435687268d063f87d7903ec04dc257ad4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c830201216464843fbf6f2d068f827df5716432063e6ea189fa6476296016d773ebe2cfa7c5baef96ddc0c8f3cd07e0bd724c9a969917162f59c8d975adfe54"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 05:39:12.405719) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:39:12.406442) ========= 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.4k1HsDY8th/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.utMEzQcEif/Listeners TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/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.005044 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 05:39:12.455878) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 05:39:12.456204) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 05:39:12.456556) ========= ========= Total master_lag: 0.19 =========