builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0007 starttime: 1490664564.77 results: success (0) buildid: 20170327170422 builduid: a7148adcae8640b6a3373a82adc26187 revision: a8bb5c8e0e80e87e21ad63997f8881991b5347cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:24.769170) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:24.769636) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:24.769912) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:24.797195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:24.797628) ========= 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.stK1UBrrQp/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.CmEmREZ1YD/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:29:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.5M=0.001s 2017-03-27 18:29:24 (15.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.154507 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:24.976148) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:24.976477) ========= 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.stK1UBrrQp/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.CmEmREZ1YD/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.028084 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:25.027600) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 18:29:25.028216) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a8bb5c8e0e80e87e21ad63997f8881991b5347cf --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 a8bb5c8e0e80e87e21ad63997f8881991b5347cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.stK1UBrrQp/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.CmEmREZ1YD/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:29:25,130 truncating revision to first 12 chars 2017-03-27 18:29:25,130 Setting DEBUG logging. 2017-03-27 18:29:25,130 attempt 1/10 2017-03-27 18:29:25,131 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a8bb5c8e0e80?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 18:29:26,517 unpacking tar archive at: mozilla-central-a8bb5c8e0e80/testing/mozharness/ program finished with exit code 0 elapsedTime=1.663755 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 18:29:26.732783) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:26.733152) ========= script_repo_revision: a8bb5c8e0e80e87e21ad63997f8881991b5347cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:26.733559) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:26.733821) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 18:29:26.753613) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-27 18:29:26.754009) ========= /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.stK1UBrrQp/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.CmEmREZ1YD/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:29:26 INFO - MultiFileLogger online at 20170327 18:29:26 in /builds/slave/test 18:29:26 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 18:29:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:29:26 INFO - {'append_to_log': False, 18:29:26 INFO - 'base_work_dir': '/builds/slave/test', 18:29:26 INFO - 'blob_upload_branch': 'Firefox', 18:29:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:29:26 INFO - 'branch': 'Firefox', 18:29:26 INFO - 'buildbot_json_path': 'buildprops.json', 18:29:26 INFO - 'config_files': ('talos/mac_config.py',), 18:29:26 INFO - 'default_actions': ('clobber', 18:29:26 INFO - 'read-buildbot-config', 18:29:26 INFO - 'download-and-extract', 18:29:26 INFO - 'populate-webroot', 18:29:26 INFO - 'create-virtualenv', 18:29:26 INFO - 'install', 18:29:26 INFO - 'run-tests'), 18:29:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:29:26 INFO - 'download_minidump_stackwalk': True, 18:29:26 INFO - 'download_symbols': 'ondemand', 18:29:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:29:26 INFO - 'tooltool.py': '/tools/tooltool.py', 18:29:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:29:26 INFO - '/tools/misc-python/virtualenv.py')}, 18:29:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:29:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:29:26 INFO - 'gecko_profile': False, 18:29:26 INFO - 'gecko_profile_interval': 0, 18:29:26 INFO - 'installer_path': 'installer.exe', 18:29:26 INFO - 'log_level': 'info', 18:29:26 INFO - 'log_name': 'talos', 18:29:26 INFO - 'log_to_console': True, 18:29:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:29:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:29:26 INFO - 'opt_config_files': (), 18:29:26 INFO - 'pip_index': False, 18:29:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:29:26 INFO - 'cmd': ('bash', 18:29:26 INFO - '-c', 18:29:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:29:26 INFO - 'enabled': True, 18:29:26 INFO - 'halt_on_failure': False, 18:29:26 INFO - 'name': 'check_screen_resolution'},), 18:29:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:29:26 INFO - 'cmd': ('bash', 18:29:26 INFO - '-c', 18:29:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:29:26 INFO - 'enabled': True, 18:29:26 INFO - 'halt_on_failure': False, 18:29:26 INFO - 'name': 'check_screen_resolution'},), 18:29:26 INFO - 'run_cmd_checks_enabled': True, 18:29:26 INFO - 'suite': 'dromaeojs-e10s', 18:29:26 INFO - 'system_bits': '32', 18:29:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:29:26 INFO - 'title': 't-yosemite-r7-0007', 18:29:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:29:26 INFO - 'use_talos_json': True, 18:29:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:29:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:29:26 INFO - 'work_dir': 'build'} 18:29:26 INFO - [mozharness: 2017-03-28 01:29:26.970551Z] Running clobber step. 18:29:26 INFO - Running pre-action listener: _resource_record_pre_action 18:29:26 INFO - Running main action method: clobber 18:29:26 INFO - rmtree: /builds/slave/test/build 18:29:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:29:29 INFO - Running post-action listener: _resource_record_post_action 18:29:29 INFO - [mozharness: 2017-03-28 01:29:29.517849Z] Finished clobber step (success) 18:29:29 INFO - [mozharness: 2017-03-28 01:29:29.518042Z] Running read-buildbot-config step. 18:29:29 INFO - Running pre-action listener: _resource_record_pre_action 18:29:29 INFO - Running main action method: read_buildbot_config 18:29:29 INFO - Using buildbot properties: 18:29:29 INFO - { 18:29:29 INFO - "project": "", 18:29:29 INFO - "product": "firefox", 18:29:29 INFO - "script_repo_revision": "production", 18:29:29 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:29:29 INFO - "repository": "", 18:29:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:29:29 INFO - "buildid": "20170327170422", 18:29:29 INFO - "pgo_build": "False", 18:29:29 INFO - "basedir": "/builds/slave/test", 18:29:29 INFO - "buildnumber": 27, 18:29:29 INFO - "slavename": "t-yosemite-r7-0007", 18:29:29 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:29:29 INFO - "platform": "yosemite_r7", 18:29:29 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:29:29 INFO - "branch": "mozilla-central", 18:29:29 INFO - "repo_path": "mozilla-central", 18:29:29 INFO - "revision": "a8bb5c8e0e80e87e21ad63997f8881991b5347cf", 18:29:29 INFO - "stage_platform": "macosx64", 18:29:29 INFO - "builduid": "a7148adcae8640b6a3373a82adc26187", 18:29:29 INFO - "slavebuilddir": "test" 18:29:29 INFO - } 18:29:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:29:29 INFO - Running post-action listener: _resource_record_post_action 18:29:29 INFO - [mozharness: 2017-03-28 01:29:29.521276Z] Finished read-buildbot-config step (success) 18:29:29 INFO - [mozharness: 2017-03-28 01:29:29.521385Z] Running download-and-extract step. 18:29:29 INFO - Running pre-action listener: _resource_record_pre_action 18:29:29 INFO - Running main action method: download_and_extract 18:29:29 INFO - mkdir: /builds/slave/test/build/tests 18:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:29 INFO - trying https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:29:29 INFO - Downloading https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/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 18:29:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/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 18:29:30 INFO - Downloaded 1325 bytes. 18:29:30 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:29:30 INFO - Using the following test package requirements: 18:29:30 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:30 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:29:30 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:29:30 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:30 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:29:30 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:30 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:29:30 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:30 INFO - u'jsshell-mac.zip'], 18:29:30 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:30 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:29:30 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:29:30 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:30 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:29:30 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:30 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:29:30 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:30 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:29:30 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:29:30 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:29:30 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:29:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:29:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:29:30 INFO - Fetch https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:29:32 INFO - Expected file size: 52417303 18:29:32 INFO - Obtained file size: 52417303 18:29:48 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 18:29:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:29:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:29:48 INFO - Fetch https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:29:50 INFO - Expected file size: 52417303 18:29:50 INFO - Obtained file size: 52417303 18:30:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:30:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:30:06 INFO - Fetch https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:30:07 INFO - Expected file size: 14404171 18:30:07 INFO - Obtained file size: 14404171 18:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:07 INFO - trying https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:30:07 INFO - Downloading https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:30:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/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 18:30:09 INFO - Downloaded 75567943 bytes. 18:30:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:30:09 INFO - mkdir: /builds/slave/test/properties 18:30:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:30:09 INFO - Writing to file /builds/slave/test/properties/build_url 18:30:09 INFO - Contents: 18:30:09 INFO - build_url:https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:30:10 INFO - Running post-action listener: _resource_record_post_action 18:30:10 INFO - Running post-action listener: set_extra_try_arguments 18:30:10 INFO - [mozharness: 2017-03-28 01:30:10.777260Z] Finished download-and-extract step (success) 18:30:10 INFO - [mozharness: 2017-03-28 01:30:10.777379Z] Running populate-webroot step. 18:30:10 INFO - Running pre-action listener: _resource_record_pre_action 18:30:10 INFO - Running main action method: populate_webroot 18:30:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:30:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:30:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:30:10 INFO - '--tppagecycles', 18:30:10 INFO - '7'], 18:30:10 INFO - 'tests': ['tsvgm']}}, 18:30:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:30:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:30:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:30:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:30:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:30:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:30:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:30:10 INFO - 'talos_options': ['--disable-e10s'], 18:30:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:30:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:30:10 INFO - 'talos_options': ['--disable-e10s'], 18:30:10 INFO - 'tests': ['damp', 'tps']}, 18:30:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:30:10 INFO - 'tests': ['damp', 'tps']}, 18:30:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:30:10 INFO - 'tests': ['dromaeo_dom']}, 18:30:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:30:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:30:10 INFO - 'tests': ['basic_compositor_video']}, 18:30:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:30:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:30:10 INFO - 'tests': ['a11yr', 18:30:10 INFO - 'ts_paint', 18:30:10 INFO - 'tpaint', 18:30:10 INFO - 'sessionrestore', 18:30:10 INFO - 'sessionrestore_no_auto_restore', 18:30:10 INFO - 'tabpaint']}, 18:30:10 INFO - 'other-e10s': {'tests': ['a11yr', 18:30:10 INFO - 'ts_paint', 18:30:10 INFO - 'tpaint', 18:30:10 INFO - 'sessionrestore', 18:30:10 INFO - 'sessionrestore_no_auto_restore', 18:30:10 INFO - 'tabpaint']}, 18:30:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:30:10 INFO - 'ts_paint', 18:30:10 INFO - 'tpaint', 18:30:10 INFO - 'sessionrestore', 18:30:10 INFO - 'sessionrestore_no_auto_restore', 18:30:10 INFO - 'tabpaint']}, 18:30:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:30:10 INFO - 'ts_paint', 18:30:10 INFO - 'tpaint', 18:30:10 INFO - 'sessionrestore', 18:30:10 INFO - 'sessionrestore_no_auto_restore', 18:30:10 INFO - 'tabpaint']}, 18:30:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:30:10 INFO - 'tests': ['a11yr', 18:30:10 INFO - 'ts_paint', 18:30:10 INFO - 'tpaint', 18:30:10 INFO - 'sessionrestore', 18:30:10 INFO - 'sessionrestore_no_auto_restore', 18:30:10 INFO - 'tabpaint']}, 18:30:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:30:10 INFO - 'tests': ['a11yr', 18:30:10 INFO - 'ts_paint', 18:30:10 INFO - 'tpaint', 18:30:10 INFO - 'sessionrestore', 18:30:10 INFO - 'sessionrestore_no_auto_restore', 18:30:10 INFO - 'tabpaint']}, 18:30:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:30:10 INFO - 'tests': ['tsvgx', 18:30:10 INFO - 'tsvgr_opacity', 18:30:10 INFO - 'tart', 18:30:10 INFO - 'tscrollx', 18:30:10 INFO - 'cart', 18:30:10 INFO - 'tsvg_static']}, 18:30:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:30:10 INFO - 'tsvgr_opacity', 18:30:10 INFO - 'tart', 18:30:10 INFO - 'tscrollx', 18:30:10 INFO - 'cart', 18:30:10 INFO - 'tsvg_static']}, 18:30:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:30:10 INFO - 'talos_options': ['--disable-e10s'], 18:30:10 INFO - 'tests': ['tp5o']}, 18:30:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:30:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:30:10 INFO - 'talos_options': ['--disable-e10s', 18:30:10 INFO - '--xperf_path', 18:30:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:10 INFO - 'tests': ['tp5n']}, 18:30:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:30:10 INFO - 'talos_options': ['--xperf_path', 18:30:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:10 INFO - 'tests': ['tp5n']}}, 18:30:10 INFO - 'talos.zip': {'path': '', 18:30:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:30:10 INFO - Running post-action listener: _resource_record_post_action 18:30:10 INFO - [mozharness: 2017-03-28 01:30:10.795733Z] Finished populate-webroot step (success) 18:30:10 INFO - [mozharness: 2017-03-28 01:30:10.795867Z] Running create-virtualenv step. 18:30:10 INFO - Running pre-action listener: _resource_record_pre_action 18:30:10 INFO - Running main action method: create_virtualenv 18:30:10 INFO - Creating virtualenv /builds/slave/test/build/venv 18:30:10 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 18:30:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:30:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:30:10 INFO - Using real prefix '/tools/python27' 18:30:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:30:11 INFO - Installing distribute.............................................................................................................................................................................................done. 18:30:14 INFO - Installing pip.................done. 18:30:14 INFO - Return code: 0 18:30:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:30:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:30:14 INFO - Reading from file tmpfile_stdout 18:30:14 INFO - Output received: 18:30:14 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) 18:30:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:30:14 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')]} 18:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:14 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')]} 18:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:14 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 0x10e127b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e12a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1fb4d5a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df05d30>, '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 0x10de2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10defabe8>, '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.stK1UBrrQp/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.CmEmREZ1YD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:30:14 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 18:30:14 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 18:30:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.stK1UBrrQp/Render', 18:30:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:14 INFO - 'HOME': '/Users/cltbld', 18:30:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:14 INFO - 'LOGNAME': 'cltbld', 18:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:14 INFO - 'MOZ_NO_REMOTE': '1', 18:30:14 INFO - 'NO_EM_RESTART': '1', 18:30:14 INFO - 'PAGER': '/bin/cat', 18:30:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:14 INFO - 'PWD': '/builds/slave/test', 18:30:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:14 INFO - 'SHELL': '/bin/bash', 18:30:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CmEmREZ1YD/Listeners', 18:30:14 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:30:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:14 INFO - 'USER': 'cltbld', 18:30:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:14 INFO - 'XPC_FLAGS': '0x0', 18:30:14 INFO - 'XPC_SERVICE_NAME': '0', 18:30:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:15 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 18:30:15 INFO - Cleaning up... 18:30:15 INFO - Return code: 0 18:30:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:30:15 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')]} 18:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:15 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')]} 18:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:15 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 0x10e127b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e12a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1fb4d5a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df05d30>, '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 0x10de2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10defabe8>, '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.stK1UBrrQp/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.CmEmREZ1YD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:30:15 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 18:30:15 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 18:30:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.stK1UBrrQp/Render', 18:30:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:15 INFO - 'HOME': '/Users/cltbld', 18:30:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:15 INFO - 'LOGNAME': 'cltbld', 18:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:15 INFO - 'MOZ_NO_REMOTE': '1', 18:30:15 INFO - 'NO_EM_RESTART': '1', 18:30:15 INFO - 'PAGER': '/bin/cat', 18:30:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:15 INFO - 'PWD': '/builds/slave/test', 18:30:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:15 INFO - 'SHELL': '/bin/bash', 18:30:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CmEmREZ1YD/Listeners', 18:30:15 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:30:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:15 INFO - 'USER': 'cltbld', 18:30:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:15 INFO - 'XPC_FLAGS': '0x0', 18:30:15 INFO - 'XPC_SERVICE_NAME': '0', 18:30:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:15 INFO - Downloading/unpacking psutil>=3.1.1 18:30:15 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 18:30:15 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 18:30:15 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 18:30:15 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 18:30:15 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 18:30:15 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 18:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:30:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:18 INFO - Installing collected packages: psutil 18:30:18 INFO - Running setup.py install for psutil 18:30:18 INFO - building 'psutil._psutil_osx' extension 18:30:18 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 18:30:19 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 18:30:19 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 18:30:19 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 18:30:19 INFO - building 'psutil._psutil_posix' extension 18:30:19 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 18:30:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:30:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:30:19 INFO - ^ 18:30:19 INFO - 1 warning generated. 18:30:19 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 18:30:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:19 INFO - Successfully installed psutil 18:30:19 INFO - Cleaning up... 18:30:20 INFO - Return code: 0 18:30:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:30:20 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')]} 18:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:20 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')]} 18:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:20 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 0x10e127b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e12a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1fb4d5a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df05d30>, '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 0x10de2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10defabe8>, '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.stK1UBrrQp/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.CmEmREZ1YD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:30:20 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 18:30:20 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 18:30:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.stK1UBrrQp/Render', 18:30:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:20 INFO - 'HOME': '/Users/cltbld', 18:30:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:20 INFO - 'LOGNAME': 'cltbld', 18:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:20 INFO - 'MOZ_NO_REMOTE': '1', 18:30:20 INFO - 'NO_EM_RESTART': '1', 18:30:20 INFO - 'PAGER': '/bin/cat', 18:30:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:20 INFO - 'PWD': '/builds/slave/test', 18:30:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:20 INFO - 'SHELL': '/bin/bash', 18:30:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CmEmREZ1YD/Listeners', 18:30:20 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:30:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:20 INFO - 'USER': 'cltbld', 18:30:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:20 INFO - 'XPC_FLAGS': '0x0', 18:30:20 INFO - 'XPC_SERVICE_NAME': '0', 18:30:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:30:21 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 18:30:21 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 18:30:21 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 18:30:21 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 18:30:21 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 18:30:21 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 18:30:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:30:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:30:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:30:24 INFO - Installing collected packages: mozsystemmonitor 18:30:24 INFO - Running setup.py install for mozsystemmonitor 18:30:24 INFO - Successfully installed mozsystemmonitor 18:30:24 INFO - Cleaning up... 18:30:24 INFO - Return code: 0 18:30:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:24 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 0x10e127b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e12a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1fb4d5a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df05d30>, '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 0x10de2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10defabe8>, '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.stK1UBrrQp/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.CmEmREZ1YD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:30:24 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 18:30:24 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 18:30:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.stK1UBrrQp/Render', 18:30:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:24 INFO - 'HOME': '/Users/cltbld', 18:30:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:24 INFO - 'LOGNAME': 'cltbld', 18:30:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:24 INFO - 'MOZ_NO_REMOTE': '1', 18:30:24 INFO - 'NO_EM_RESTART': '1', 18:30:24 INFO - 'PAGER': '/bin/cat', 18:30:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:24 INFO - 'PWD': '/builds/slave/test', 18:30:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:24 INFO - 'SHELL': '/bin/bash', 18:30:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CmEmREZ1YD/Listeners', 18:30:24 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:30:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:24 INFO - 'USER': 'cltbld', 18:30:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:24 INFO - 'XPC_FLAGS': '0x0', 18:30:24 INFO - 'XPC_SERVICE_NAME': '0', 18:30:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:24 INFO - Downloading/unpacking jsonschema==2.5.1 18:30:24 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 18:30:24 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 18:30:24 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 18:30:24 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 18:30:24 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 18:30:24 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 18:30:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:30:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:30:27 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 18:30:27 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 18:30:27 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 18:30:27 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 18:30:27 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 18:30:27 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 18:30:27 INFO - Downloading functools32-3.2.3-2.tar.gz 18:30:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:30:27 INFO - warning: no files found matching '*.txt' 18:30:27 INFO - no previously-included directories found matching 'build' 18:30:27 INFO - no previously-included directories found matching 'dist' 18:30:27 INFO - no previously-included directories found matching '.git*' 18:30:27 INFO - Installing collected packages: jsonschema, functools32 18:30:27 INFO - Running setup.py install for functools32 18:30:27 INFO - warning: no files found matching '*.txt' 18:30:27 INFO - no previously-included directories found matching 'build' 18:30:27 INFO - no previously-included directories found matching 'dist' 18:30:27 INFO - no previously-included directories found matching '.git*' 18:30:27 INFO - Successfully installed jsonschema functools32 18:30:27 INFO - Cleaning up... 18:30:28 INFO - Return code: 0 18:30:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:30:28 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')]} 18:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:28 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')]} 18:30:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:28 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 0x10e127b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e12a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1fb4d5a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df05d30>, '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 0x10de2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10defabe8>, '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.stK1UBrrQp/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.CmEmREZ1YD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:30:28 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 18:30:28 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 18:30:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.stK1UBrrQp/Render', 18:30:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:28 INFO - 'HOME': '/Users/cltbld', 18:30:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:28 INFO - 'LOGNAME': 'cltbld', 18:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:28 INFO - 'MOZ_NO_REMOTE': '1', 18:30:28 INFO - 'NO_EM_RESTART': '1', 18:30:28 INFO - 'PAGER': '/bin/cat', 18:30:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:28 INFO - 'PWD': '/builds/slave/test', 18:30:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:28 INFO - 'SHELL': '/bin/bash', 18:30:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CmEmREZ1YD/Listeners', 18:30:28 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:30:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:28 INFO - 'USER': 'cltbld', 18:30:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:28 INFO - 'XPC_FLAGS': '0x0', 18:30:28 INFO - 'XPC_SERVICE_NAME': '0', 18:30:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:30:28 INFO - Cleaning up... 18:30:28 INFO - Return code: 0 18:30:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:30:28 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')]} 18:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:28 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')]} 18:30:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:28 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 0x10e127b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e12a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1fb4d5a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df05d30>, '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 0x10de2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10defabe8>, '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.stK1UBrrQp/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.CmEmREZ1YD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:30:28 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 18:30:28 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 18:30:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.stK1UBrrQp/Render', 18:30:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:28 INFO - 'HOME': '/Users/cltbld', 18:30:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:28 INFO - 'LOGNAME': 'cltbld', 18:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:28 INFO - 'MOZ_NO_REMOTE': '1', 18:30:28 INFO - 'NO_EM_RESTART': '1', 18:30:28 INFO - 'PAGER': '/bin/cat', 18:30:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:28 INFO - 'PWD': '/builds/slave/test', 18:30:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:28 INFO - 'SHELL': '/bin/bash', 18:30:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CmEmREZ1YD/Listeners', 18:30:28 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:30:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:28 INFO - 'USER': 'cltbld', 18:30:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:28 INFO - 'XPC_FLAGS': '0x0', 18:30:28 INFO - 'XPC_SERVICE_NAME': '0', 18:30:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:28 INFO - Downloading/unpacking blobuploader==1.2.4 18:30:28 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 18:30:28 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 18:30:28 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 18:30:28 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 18:30:28 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 18:30:28 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 18:30:31 INFO - Downloading blobuploader-1.2.4.tar.gz 18:30:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:30:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:30:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:32 INFO - Downloading docopt-0.6.1.tar.gz 18:30:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:30:32 INFO - Installing collected packages: blobuploader, requests, docopt 18:30:32 INFO - Running setup.py install for blobuploader 18:30:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:30:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:30:32 INFO - Running setup.py install for requests 18:30:33 INFO - Running setup.py install for docopt 18:30:33 INFO - Successfully installed blobuploader requests docopt 18:30:33 INFO - Cleaning up... 18:30:33 INFO - Return code: 0 18:30:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:33 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 0x10e127b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e12a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1fb4d5a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df05d30>, '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 0x10de2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10defabe8>, '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.stK1UBrrQp/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.CmEmREZ1YD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:30:33 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 18:30:33 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 18:30:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.stK1UBrrQp/Render', 18:30:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:33 INFO - 'HOME': '/Users/cltbld', 18:30:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:33 INFO - 'LOGNAME': 'cltbld', 18:30:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:33 INFO - 'MOZ_NO_REMOTE': '1', 18:30:33 INFO - 'NO_EM_RESTART': '1', 18:30:33 INFO - 'PAGER': '/bin/cat', 18:30:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:33 INFO - 'PWD': '/builds/slave/test', 18:30:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:33 INFO - 'SHELL': '/bin/bash', 18:30:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CmEmREZ1YD/Listeners', 18:30:33 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:30:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:33 INFO - 'USER': 'cltbld', 18:30:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:33 INFO - 'XPC_FLAGS': '0x0', 18:30:33 INFO - 'XPC_SERVICE_NAME': '0', 18:30:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:33 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-jWkXWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:33 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-g4pZ5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:33 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-zVLjuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:33 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-HF3Onk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:34 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-66N9ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:34 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-kdFRnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:34 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-f9xsax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:34 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-jjMuZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:34 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-I5BAWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:34 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-q4NqsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:34 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-dxjulM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:34 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-vcq0j8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:34 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-SXsZul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:34 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-AQrMas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-UoEv0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-g3urUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:35 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-id5_F6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:30:35 INFO - Running setup.py install for manifestparser 18:30:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:30:35 INFO - Running setup.py install for mozcrash 18:30:35 INFO - Running setup.py install for mozdebug 18:30:35 INFO - Running setup.py install for mozdevice 18:30:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:30:35 INFO - Running setup.py install for mozfile 18:30:36 INFO - Running setup.py install for mozhttpd 18:30:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:30:36 INFO - Running setup.py install for mozinfo 18:30:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:30:36 INFO - Running setup.py install for mozInstall 18:30:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:30:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:30:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:30:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:30:36 INFO - Running setup.py install for mozleak 18:30:36 INFO - Running setup.py install for mozlog 18:30:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:30:36 INFO - Running setup.py install for moznetwork 18:30:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:30:37 INFO - Running setup.py install for mozprocess 18:30:37 INFO - Running setup.py install for mozprofile 18:30:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:30:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:30:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:30:37 INFO - Running setup.py install for mozrunner 18:30:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:30:37 INFO - Running setup.py install for mozscreenshot 18:30:37 INFO - Running setup.py install for moztest 18:30:37 INFO - Running setup.py install for mozversion 18:30:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:30:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:30:37 INFO - Cleaning up... 18:30:37 INFO - Return code: 0 18:30:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:37 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 0x10e127b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e12a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1fb4d5a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df05d30>, '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 0x10de2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10defabe8>, '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.stK1UBrrQp/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.CmEmREZ1YD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:30:37 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 18:30:37 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 18:30:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.stK1UBrrQp/Render', 18:30:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:37 INFO - 'HOME': '/Users/cltbld', 18:30:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:37 INFO - 'LOGNAME': 'cltbld', 18:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:37 INFO - 'MOZ_NO_REMOTE': '1', 18:30:37 INFO - 'NO_EM_RESTART': '1', 18:30:37 INFO - 'PAGER': '/bin/cat', 18:30:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:37 INFO - 'PWD': '/builds/slave/test', 18:30:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:37 INFO - 'SHELL': '/bin/bash', 18:30:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CmEmREZ1YD/Listeners', 18:30:37 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:30:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:37 INFO - 'USER': 'cltbld', 18:30:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:37 INFO - 'XPC_FLAGS': '0x0', 18:30:37 INFO - 'XPC_SERVICE_NAME': '0', 18:30:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-d9ZB95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:38 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)) 18:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-guNYbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:38 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)) 18:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-YjIYTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:38 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)) 18:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-j7mnoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:38 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)) 18:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-5T3y8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:38 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)) 18:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-QtR8Ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:38 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)) 18:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-GIBff_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:38 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)) 18:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-byV2uN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:39 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)) 18:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-BYbQxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:39 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)) 18:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-S031_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:39 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)) 18:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-mJZjNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:39 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)) 18:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-dt2raJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:39 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)) 18:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-_O9Pau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:39 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)) 18:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-iFduhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:39 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)) 18:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-1qpvyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:39 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)) 18:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-6pC7Yi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:39 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)) 18:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Msodjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:40 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)) 18:30:40 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)) 18:30:40 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)) 18:30:40 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)) 18:30:40 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)) 18:30:40 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)) 18:30:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30: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 18:30:42 INFO - Downloading blessings-1.6.tar.gz 18:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:30:42 INFO - Installing collected packages: blessings 18:30:42 INFO - Running setup.py install for blessings 18:30:42 INFO - Successfully installed blessings 18:30:42 INFO - Cleaning up... 18:30:43 INFO - Return code: 0 18:30:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:30:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:43 INFO - Reading from file tmpfile_stdout 18:30:43 INFO - Current package versions: 18:30:43 INFO - blessings == 1.6 18:30:43 INFO - blobuploader == 1.2.4 18:30:43 INFO - docopt == 0.6.1 18:30:43 INFO - functools32 == 3.2.3-2 18:30:43 INFO - jsonschema == 2.5.1 18:30:43 INFO - manifestparser == 1.1 18:30:43 INFO - mozInstall == 1.12 18:30:43 INFO - mozcrash == 1.0 18:30:43 INFO - mozdebug == 0.1 18:30:43 INFO - mozdevice == 0.49 18:30:43 INFO - mozfile == 1.2 18:30:43 INFO - mozhttpd == 0.7 18:30:43 INFO - mozinfo == 0.9 18:30:43 INFO - mozleak == 0.1 18:30:43 INFO - mozlog == 3.4 18:30:43 INFO - moznetwork == 0.27 18:30:43 INFO - mozprocess == 0.25 18:30:43 INFO - mozprofile == 0.28 18:30:43 INFO - mozrunner == 6.13 18:30:43 INFO - mozscreenshot == 0.1 18:30:43 INFO - mozsystemmonitor == 0.3 18:30:43 INFO - moztest == 0.8 18:30:43 INFO - mozversion == 1.4 18:30:43 INFO - psutil == 3.1.1 18:30:43 INFO - requests == 1.2.3 18:30:43 INFO - wsgiref == 0.1.2 18:30:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:43 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 0x10e127b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e12a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1fb4d5a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df05d30>, '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 0x10de2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10defabe8>, '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.stK1UBrrQp/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.CmEmREZ1YD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:30:43 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 18:30:43 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 18:30:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.stK1UBrrQp/Render', 18:30:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:43 INFO - 'HOME': '/Users/cltbld', 18:30:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:43 INFO - 'LOGNAME': 'cltbld', 18:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:43 INFO - 'MOZ_NO_REMOTE': '1', 18:30:43 INFO - 'NO_EM_RESTART': '1', 18:30:43 INFO - 'PAGER': '/bin/cat', 18:30:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:43 INFO - 'PWD': '/builds/slave/test', 18:30:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:43 INFO - 'SHELL': '/bin/bash', 18:30:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CmEmREZ1YD/Listeners', 18:30:43 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:30:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:43 INFO - 'USER': 'cltbld', 18:30:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:43 INFO - 'XPC_FLAGS': '0x0', 18:30:43 INFO - 'XPC_SERVICE_NAME': '0', 18:30:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:43 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)) 18:30:43 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)) 18:30:43 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)) 18:30:43 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)) 18:30:43 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)) 18:30:43 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)) 18:30:43 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)) 18:30:43 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)) 18:30:43 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)) 18:30:43 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)) 18:30:43 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)) 18:30:43 INFO - Cleaning up... 18:30:43 INFO - Return code: 0 18:30:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e127b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e12a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1fb4d5a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df05d30>, '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 0x10de2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10defabe8>, '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.stK1UBrrQp/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.CmEmREZ1YD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:30: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'] in /builds/slave/test/build 18:30: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 18:30:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.stK1UBrrQp/Render', 18:30:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:43 INFO - 'HOME': '/Users/cltbld', 18:30:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:43 INFO - 'LOGNAME': 'cltbld', 18:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:43 INFO - 'MOZ_NO_REMOTE': '1', 18:30:43 INFO - 'NO_EM_RESTART': '1', 18:30:43 INFO - 'PAGER': '/bin/cat', 18:30:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:43 INFO - 'PWD': '/builds/slave/test', 18:30:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:43 INFO - 'SHELL': '/bin/bash', 18:30:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CmEmREZ1YD/Listeners', 18:30:43 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:30:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:43 INFO - 'USER': 'cltbld', 18:30:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:43 INFO - 'XPC_FLAGS': '0x0', 18:30:43 INFO - 'XPC_SERVICE_NAME': '0', 18:30:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:30:43 INFO - Cleaning up... 18:30:43 INFO - Return code: 0 18:30:43 INFO - Running post-action listener: _resource_record_post_action 18:30:43 INFO - Running post-action listener: _start_resource_monitoring 18:30:43 INFO - Starting resource monitoring. 18:30:43 INFO - [mozharness: 2017-03-28 01:30:43.911294Z] Finished create-virtualenv step (success) 18:30:43 INFO - [mozharness: 2017-03-28 01:30:43.912306Z] Running install step. 18:30:43 INFO - Running pre-action listener: _resource_record_pre_action 18:30:43 INFO - Running main action method: install 18:30:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:44 INFO - Reading from file tmpfile_stdout 18:30:44 INFO - Detecting whether we're running mozinstall >=1.0... 18:30:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:30:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:30:44 INFO - Reading from file tmpfile_stdout 18:30:44 INFO - Output received: 18:30:44 INFO - Usage: mozinstall [options] installer 18:30:44 INFO - Options: 18:30:44 INFO - -h, --help show this help message and exit 18:30:44 INFO - -d DEST, --destination=DEST 18:30:44 INFO - Directory to install application into. [default: 18:30:44 INFO - "/builds/slave/test"] 18:30:44 INFO - --app=APP Application being installed. [default: firefox] 18:30:44 INFO - mkdir: /builds/slave/test/build/application 18:30:44 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'] 18:30:44 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 18:31:05 INFO - Reading from file tmpfile_stdout 18:31:05 INFO - Output received: 18:31:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:31:05 INFO - Running post-action listener: _resource_record_post_action 18:31:05 INFO - [mozharness: 2017-03-28 01:31:05.120443Z] Finished install step (success) 18:31:05 INFO - [mozharness: 2017-03-28 01:31:05.120561Z] Running run-tests step. 18:31:05 INFO - Running pre-action listener: _resource_record_pre_action 18:31:05 INFO - Running main action method: run_tests 18:31:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:31:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:31:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:31:05 INFO - 2017-03-27 18:31:05.133 screenresolution[1629:7493] starting screenresolution argv=screenresolution get 18:31:05 INFO - 2017-03-27 18:31:05.135 screenresolution[1629:7493] Display 0: 1600x1200x32@60 18:31:05 INFO - 2017-03-27 18:31:05.140 screenresolution[1630:7495] starting screenresolution argv=screenresolution list 18:31:05 INFO - Available Modes on Display 0 18:31:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:31:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:31:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:31:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:31:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:31:05 INFO - Intel Iris: 18:31:05 INFO - Chipset Model: Intel Iris 18:31:05 INFO - Type: GPU 18:31:05 INFO - Bus: Built-In 18:31:05 INFO - VRAM (Dynamic, Max): 1536 MB 18:31:05 INFO - Vendor: Intel (0x8086) 18:31:05 INFO - Device ID: 0x0a2e 18:31:05 INFO - Revision ID: 0x0009 18:31:05 INFO - Displays: 18:31:05 INFO - Display: 18:31:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:31:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:31:05 INFO - Display Serial Number: 1600x1200 60 18:31:05 INFO - Main Display: Yes 18:31:05 INFO - Mirror: Off 18:31:05 INFO - Online: Yes 18:31:05 INFO - Rotation: Supported 18:31:05 INFO - Return code: 0 18:31:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:31:05 INFO - Python 2.7.3 18:31:05 INFO - Return code: 0 18:31:05 INFO - grabbing minidump binary from tooltool 18:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:05 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 0x10df05d30>, '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 0x10de2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10defabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:31:05 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 18:31:05 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 18:31:05 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 18:31:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:31:05 INFO - Return code: 0 18:31:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:31:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:31:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:31:05 INFO - ENV: RUST_BACKTRACE is now 1 18:31:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:31:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:31:05 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/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 18:31:05 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/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0007 --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 18:31:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.stK1UBrrQp/Render', 18:31:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:05 INFO - 'HOME': '/Users/cltbld', 18:31:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:05 INFO - 'LOGNAME': 'cltbld', 18:31:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:31:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:05 INFO - 'MOZ_NO_REMOTE': '1', 18:31:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:31:05 INFO - 'NO_EM_RESTART': '1', 18:31:05 INFO - 'PAGER': '/bin/cat', 18:31:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:05 INFO - 'PWD': '/builds/slave/test', 18:31:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:31:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:05 INFO - 'RUST_BACKTRACE': '1', 18:31:05 INFO - 'SHELL': '/bin/bash', 18:31:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CmEmREZ1YD/Listeners', 18:31:05 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:31:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:05 INFO - 'USER': 'cltbld', 18:31:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:05 INFO - 'XPC_FLAGS': '0x0', 18:31:05 INFO - 'XPC_SERVICE_NAME': '0', 18:31:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:05 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/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 18:31:05 INFO - mozversion application_buildid: 20170327170422 18:31:05 INFO - mozversion application_changeset: a8bb5c8e0e80e87e21ad63997f8881991b5347cf 18:31:05 INFO - mozversion application_display_name: Nightly 18:31:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:31:05 INFO - mozversion application_name: Firefox 18:31:05 INFO - mozversion application_remotingname: firefox 18:31:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:31:05 INFO - mozversion application_vendor: Mozilla 18:31:05 INFO - mozversion application_version: 55.0a1 18:31:05 INFO - mozversion platform_buildid: 20170327170422 18:31:05 INFO - mozversion platform_changeset: a8bb5c8e0e80e87e21ad63997f8881991b5347cf 18:31:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:31:05 INFO - mozversion platform_version: 55.0a1 18:31:05 INFO - using testdate: 1490664665 18:31:05 INFO - actual date: 1490664665 18:31:05 INFO - starting webserver on 'localhost:49250' 18:31:05 INFO - SUITE-START | Running 2 tests 18:31:05 INFO - TEST-START | dromaeo_css 18:31:05 INFO - operating with platform_type : mac_ 18:31:05 INFO - Initialising browser for dromaeo_css test... 18:31:05 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpkfE_ss/profile http://localhost:49250/getInfo.html) 18:31:08 INFO - PID 1640 | 2017-03-27 18:31:08.635 plugin-container[1642:7677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f43, name = 'com.apple.tsm.portname' 18:31:08 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:31:08 INFO - PID 1640 | 2017-03-27 18:31:08.638 plugin-container[1642:7677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 18:31:08 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:31:08 INFO - PID 1640 | 2017-03-27 18:31:08.638 plugin-container[1642:7677] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:31:08 INFO - PID 1640 | __metrics Screen width/height:1600/1200 18:31:08 INFO - PID 1640 | colorDepth:24 18:31:08 INFO - PID 1640 | Browser inner width/height: 1024/689 18:31:08 INFO - PID 1640 | __metrics 18:31:08 ERROR - PID 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 18:31:08 ERROR - PID 1640 | 1490664668887 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/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpkfE_ss/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpkfE_ss/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpkfE_ss/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpkfE_ss/profile/extensions/talos-powers@TalosPowersService.js:55:9 18:31:09 INFO - TEST-INFO | 1640: exit 0 18:31:09 INFO - Browser initialized. 18:31:09 INFO - Running cycle 1/1 for dromaeo_css test... 18:31:09 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpkfE_ss/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:31:12 INFO - PID 1643 | 2017-03-27 18:31:12.111 plugin-container[1644:7810] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 18:31:12 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:31:12 INFO - PID 1643 | 2017-03-27 18:31:12.111 plugin-container[1644:7810] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 18:31:12 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:31:12 INFO - PID 1643 | 2017-03-27 18:31:12.111 plugin-container[1644:7810] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:31:21 INFO - PID 1643 | RSS: Main: 227872768 18:31:21 INFO - PID 1643 | 18:34:27 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 18:34:28 INFO - PID 1643 | RSS: Main: 256282624 18:34:28 INFO - PID 1643 | 18:36:10 INFO - PID 1643 | ************************* 18:36:10 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 18:36:10 INFO - PID 1643 | 18:36:10 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:36:10 INFO - PID 1643 | Full stack: 18:36:10 INFO - PID 1643 | ************************* 18:36:10 INFO - PID 1643 | ************************* 18:36:10 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 18:36:10 INFO - PID 1643 | 18:36:10 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:36:10 INFO - PID 1643 | Full stack: 18:36:10 INFO - PID 1643 | ************************* 18:36:10 INFO - PID 1643 | ************************* 18:36:10 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 18:36:10 INFO - PID 1643 | 18:36:10 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:36:10 INFO - PID 1643 | Full stack: 18:36:10 INFO - PID 1643 | ************************* 18:36:10 INFO - PID 1643 | ************************* 18:36:10 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 18:36:10 INFO - PID 1643 | 18:36:10 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:36:10 INFO - PID 1643 | Full stack: 18:36:10 INFO - PID 1643 | ************************* 18:37:31 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 18:37:31 INFO - PID 1643 | RSS: Main: 264589312 18:37:31 INFO - PID 1643 | 18:40:33 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 18:40:34 INFO - PID 1643 | RSS: Main: 275783680 18:40:34 INFO - PID 1643 | 18:43:36 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 18:43:36 INFO - PID 1643 | RSS: Main: 268644352 18:43:36 INFO - PID 1643 | 18:46:39 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 18:46:40 INFO - PID 1643 | RSS: Main: 279064576 18:46:40 INFO - PID 1643 | 18:49:43 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 18:49:44 INFO - PID 1643 | RSS: Main: 272695296 18:49:44 INFO - PID 1643 | 18:49:44 INFO - PID 1643 | __start_tp_report 18:49:44 INFO - PID 1643 | _x_x_mozilla_page_load 18:49:44 INFO - PID 1643 | _x_x_mozilla_page_load_details 18:49:44 INFO - PID 1643 | |i|pagename|runs| 18:49:44 INFO - PID 1643 | |0;dojo.html;3559.252556963036;3564.966159813654;3565.2156525564374;3604.855805767769;3609.0616439725673;6134.141220229171;6157.568970172089;6205.162303089087;6209.782657606979;6240.594361366508;6099.703252314929;6118.893302669174;6133.763142530646;6135.570510064297;6180.845478863031;5570.6657600544095;5649.830505084854;5737.454941780524;5760.971195144019;5844.707764611774;5617.241672374238;5681.744321505522;5812.186293918855;5823.272090988627;5840.561957853157;5569.944300556983;5724.742386592572;5727.11229759388;5814.389489103647;5831.941680583203;5604.075327570946;5661.34894487131;5689.231953686241;5689.516391106719;5756.453136951983;5532.889342213133;5573.136163894603;5591.832245032656;5624.943750562524;5676.772929082832;4580.610648094916;5054.570361519275;6108.175396321484;6125.111858780497;6142.6314421134875;5757.366689664133;6491.545591808575;6534.1505604271615;6620.536562440631;6655.966720166417;6689.832754181136;6727;6748.6288254146;6749.19009718832;6766.5263431559315;5160.251763494272;6221.47117495018;6292.182016337847;6300.275468321156;6304;6628.436582890412;6727.66361681914;6794.014867844234;6868.175818901764;6868.347506986828;5749.626274292157;6149.86227547905;6365.108884756138;6494.64279464634;6572.474202063823;6422.068800024063;6475.222973243147;6490.643014634146;6503.154589903283;6632.336766323426;6534.477241820643;6586.70359833799;6646.26891041985;6646.401823835878;6687.297833727486;6624.337566243337;6624.337566243434;6636.867262654817;6638.070670106189;6651.700673469705;5226.5818734501145;5290.550303224192;5292.285541451932;5319.38827034896;5352.357717073976;4731.266653668687;4780.593649539758;4817.0365926814775;4822.493638168012;4840.273958906192;6334.334894835976;6355.586886852339;6370.713317900706;6373.71318290678;6381.64900930444;6438.098666186737;6756.121704178426;6758.526903116833;6768.255491895888;6770.289119642465;5386.007177344408;6041.154238406627;6285.748570057238;6323.241211054703;6326.2092238470195;5951.702414879239;5973.313068997034;5978.880422391528;5982.192404025488;6004.489618382399;6756.391924726799;6828.692708828015;6861.073755043005;6867.244603093556;6894;6412.551121421456;6540.542162048611;6639.170103737033;6670.199576050905;6755.290694477206;6660.666966651745;6705.899411508929;6716.429103526255;6726.6636668167375;6727.8990815138795;5478.753456094406;6475.449586785176;6490.707918143789;6515.837104072247;6517.7067031984625;6375.394337537927;6459.289478157493;6608.240052394036;6649.800505984828;6669.633170175687;6482.611043337415;6525.608463492205;6532.183477065367;6544.247411547733;6560.901586476302;6335;6406.647634380154;6491.805245842632;6511.055896894829;6520.18497687789;6121.239585875057;6324.272708638566;6373.681315934277;6378.74485020594;6393.200849893763;6293.590916590407;6348.396902294275;6406.487481001602;6481.384268494486;6507.544471886922;5678;6282.842928926813;6340.524460665561;6363.809085727436;6396.712147953446 18:49:44 INFO - PID 1643 | |1;ext.html;615.5660259517041;801.555136899021;824.5877061469265;1233.407964177195;1283.2877752847166;36672.266554668924;37793.62206377939;37872;37875.43186852199;38015;30640.69359306406;37069;37569.87290381293;37613;37686;8088.95955520223;8248.422610417274;8517.148285171495;8723.607437665289;8766.780830479258;8691.56542172892;8806.251468625174;8806.603702833389;8825.779355516132;8831.337649676268;8766.649334026662;8803.383763136582;8839.116088391174;8850.424722393023;8859.512726800023;7642.770716878475;8734.039255681902;8759.948806143304;8771.16673915977;8917.777055573597;7702.730404435874;8685.956570217108;8712.78218044554;8715.997660269088;8737.082606326372;38648.42027369592;38858.80570597157;38883.416748748794;38927;38975.41536876949;2959.5708622249654;2964.6294213223346;2967.317516971087;2990.7756918231003;3002.6096607441114;20620.79379206204;27577.44845103107;27685.861570692217;28605.856970715013;28643.14070577886;2740.174997765345;2788.1774876411305;2853.8287702737903;2887.6101726266893;2901.3907079513115;242973;247045.7647711768;247910.76044619843;342141;343146.28426857956;46633;46692.533074669496;49459.50540494549;49498.75250623724;49501.257481137814;51673.48326516762;51722.224166637534;51754.96490070177;51777.22334164991;51869;13730.862691373159;13828.23944683032;13846.723065538632;13874.236916969663;13880.583582492542;11203.21577489568;11218.382988935688;11294.774104517863;11300.94349528247;11306.265092769108;13729.313534323244;13736.862631373759;13746.312684365741;13752.174869507662;13864.792028119586;5921.851953701192;5942.930272550982;5987.730552125077;6002.429769171922;6009.789657362059;972.5963725054023;977.7457860956202;978.4618459847055;979.2802290316612;1017.7150397888603;18574.907125464288;24017.03931842707;24085;24111.7588824113;24179.39551511191;1080.2978064258139;1090.0897750378365;1097.91765617579;1126.3298337489346;1126.357975953715;11330.490127944262;11771.352575608253;12229.205101668364;12229.816552751716;12335.013198944063;435237;437683;437841;439977;441450;55312.44687553153;55504;55526;55526.88946221053;55534.72232638811;1447.7900704397803;1489.5010171592637;1498.1086253679043;1499.4976682811161;1516.8558986896228;23614.52770944537;23762.405939851644;23811.523769524858;23951.16170934009;23957.52084958256;38269;46709;48648.75675621741;48676.51323486862;48809;332421;334676;335390;402793;406509;25934.87032564825;26209.73790262073;31234.375312493168;31879.203019924687;32674.183145421553;31573.73705051772;40026.1988210537;42231.7888410556;42939;42991;29002.854985724935;29145.562816557343;29664;29705.85147074251;30095;28803.61173814865;29583.51652743973;29745.702542974293;29757.851210743807;30092.849535752182;34794;35455.82272088726;35661.46507802437;35823.64176358307;36345;37455.876323710334;37844.864654060424;37871;38467.2306553873;38672.22655546817;28252.85873570619;30083.24791880133;30475.847620761753;30694.817045806114;30822.229444264074 18:49:44 INFO - PID 1643 | |2;jquery.html;415.9126583417482;428.6270944278478;434.41825519905217;442.7874620182312;459.14369700508564;21510.784892151092;21556.89221553892;22225.44436389089;22483;22742;19905.203791848307;20753.066112024957;22509.324720258377;22642.66036009461;22879;4637.744924029177;5644.887102257952;6224.8443788905415;6485.254195767477;6505.381988711065;4976.850694479172;6348.492120630362;6382.106505089291;6391.360863913618;6393.360663933616;5749.223854779614;6205;6235.469985051276;6340.2708688500725;6341.8097457076365;6258.593191442542;6261.686915654199;6284.622922624657;6290.685465726719;6296.496280297611;5582.525485333752;5620.269364982526;5695.943040569583;5706.80026199081;5727;27147;27219;27359;27397.767100480327;27506.174814755588;2065.101680768855;2069.5343547701796;2094.0472085201195;2095.434232757147;2166.014463419156;15576.454824081104;15958.60103497422;15997.92001039999;16230.594235144215;16560.840741148124;1603.2544866636954;1640.4176517336293;1650.745433470559;1651.8926269792426;1666.441742016426;109189;133260.6673933258;134385.65614343926;134410;137372.999999999;11796.823047654292;12226.572069977508;12513.624591262276;12601.243925364508;12611.180273282207;16033.839661603235;16041.839581604268;16058.75911861323;16099.275532601061;16106.677866442606;8775.297976161892;8986.281097512183;9010.86483702745;9053.275737940949;9115.908840911507;7169.426445884316;8436.45163064399;8504.914950850536;8516.148385161558;8673.306135509145;7701.422393320523;7982.241687039722;8011.198880111942;8022.638981245858;8042.758717238492;2191.29878438898;2201.2625770366785;2201.306588424641;2207.768184340653;2212.391592312127;950.2872845365976;952.5284983932997;954.5799848066829;991.7718924647371;992.2855544008303;19509.707354389695;20662.586748264952;20761.792382075986;20801.375958721263;21130.154793808382;990.7170214572146;991.7768502086973;1020;1027.8406846938587;1030.922680798928;9886.505674716236;10412.334026555714;10645.78708425827;10781.137508999262;10792.946035269773;705748;716934;721935.390323045;736882;741081;109190;132853;133471.3326433381;136664.95002574968;137537;1186.2467333243453;1194.8148037054098;1204.403820109034;1209.6129238643725;1209.9455524501418;17586.38447654326;17837;17866.642667146843;17920.462386128438;17977.820221797614;56462.43537564736;56997;57779.13331300118;58134.7093264531;58443;79313.2068679329;80135;80533;81565.368692627;81694;7424.665890034853;7526.285002924714;8115.472494287852;8376.706815261437;8618.569071546523;11110.44447777624;12991.610251692464;13424.664383390493;13539.59381218565;13599.932000339935;11221.71945701364;11339.09287257034;11427;11462.254953428;11581.189316747746;10471.584881792644;10713.785724285626;10790;11046.723831903944;11120.99911008013;11601.013913817082;11718.707032323919;11808.82286765682;11861.169718119994;11913.285202887855;8344.499330040217;8597.398182127441;8624.956875215585;8794.692185773467;8818.294536437197;9452.669156579486;11490.138239631893;11726.530938762355;11785;11788 18:49:44 INFO - PID 1643 | |3;mootools.html;1217.4643157010917;1397.2734178227322;1410.5627255550773;1423.7684402991408;1447.7032208397277;10471.424071676054;10826.566937322517;10900;10909.509072091754;11146.66560003199;10901.890981090186;10972.561097556109;11087.667369978913;11114.166437517199;11135.554577816898;2451.3503921460783;2491.838030528019;2492.987535062322;2495.2763698527406;2496.151308555091;1823.772028496438;2360.9881950590225;2416.1060407649193;2436.2204094689705;2461.6676748638974;2393.461471168982;2417.9274621761374;2450.1669432392982;2474.591692370757;2478.9008439662393;2375.9406014849596;2427.2232885476656;2440.804735621146;2453.33759884831;2482.404222986488;2373;2460.0282888259217;2461.249318957717;2472.097684345219;2477.7274499805008;9353.438793672402;11001;11084.279521831137;11084.833727494093;11096.611618593392;6218.22272215973;6441.645709485976;6442.098106265126;6444.516661250425;6456.386643268882;3394;4016.879493615196;4073;4100.097978444737;4143.937840932388;1169.9801192842942;1172.6364826903687;1184.1178322149967;1213.1447329632588;1225.6323103069044;12581.74836503274;12938.611841644766;15426.922865385714;15502.767458488132;15523.223838808128;4397.472303323621;5245.160774276097;5499.477549632778;5500.009998200362;5507.807226747044;11531.711707207387;11555.826662600068;11572;11596.942015290038;11644;6269.717862696099;6338.271098823694;6348.682565871688;6356.173697419306;6358.078078678565;5763.135529670499;5972.880542389128;5980.252468441445;5984.401559844068;6014.879702405928;2888.9711102889123;4252.596003379674;4306.935395969063;4330.545292744217;4343.19650864591;1276.183242724657;1276.3809552367095;1277.961661150167;1283.3583208395803;1292.5863723608543;701.2916953876519;741.0514541387033;747.1892996099184;757.9583122928182;808.0787901791962;5255.369355677343;6117.632942023492;6657.500687448461;6765.1205343305055;6862.416694581017;554.8169104195606;564.5060571999501;579.9647628982246;584.3455330030393;590.1442907783645;4014.397840323975;4223.873283801491;4332.393464914914;4376.212281789309;4409;13441.462341506425;13662.863371366358;14061.71876562463;14087.013909026475;14108.224047677477;11440.885591144148;14152.504662336769;14162.575122746333;14198.290085495684;14211.715765684836;10060.24548158891;10289.639862604772;10363.585456581803;10399.688009359732;10410.947945260225;5651.350094738993;6108.847278817887;6292.653904035139;6953.478489113236;7000.614966176706;12098.092643051983;12352.949999249804;12435.440405181607;12460.190087644274;12574.182678125893;11226.607068752555;12314.445849936596;12557;12697.93651031739;12960.740785184433;8238.176182381954;8396.454230475;8421.663133474838;8422.578871056545;8442.197991190827;10093.444860532447;10157.542910568895;10256.692299231036;10278.948605256926;10348.948255258676;8763.82986109376;8845.02704702495;8873.112688731075;8990.370674052754;9042.095790420904;8544.273736732448;8621.87067194005;8661.437006594551;8693.08722584145;8708.521031343336;6942.097527321416;7886.566238856918;7903.3677305816545;7987.960060199662;7996.20037996219;5866.882662346644;5941.31674857397;5953.672548009901;6091.116788065794;6101.145839582373;5221.242027258127;5938.168656388023;6177.567570270038;6196.318404975359;6323.114763933145 18:49:44 INFO - PID 1643 | |4;prototype.html;340.85343302379977;376.2062049076448;406.5954375396479;409.0101953273079;410.345498929208;5980.940190598093;6111.419415155553;6199.659018753967;6279.591826531278;6280.963640999235;5925.822225333236;6114.1745864308305;6176.629402235869;6183.75264989401;6211.378862113797;4244.832671015464;4367.410399596046;4850.8059677612855;4855.465898751135;4863.708177509343;4557.154352487248;4816.109019831325;4832.71003739775;4844.854654360375;4868.756562171895;4510.661700372469;4589.65577581681;4746.715197088186;4770.880727981811;4782.952170478286;3771.7736935783805;4764.237721964469;4767.046590681877;4790.114083634673;4794.2329227323635;4038.172174704179;4079.7552146870985;4260.893477663052;4267;4284.507281662618;5726.713664316809;5994.580379373446;6005.039193728982;6015.699215039274;6025.427584379477;4607.861764147081;4608.9308660370125;5571.9721401393135;5585.106382978704;5585.301837270341;5440.347158340985;6219.8182345354235;6496.93503064968;6607.603543787388;6684.431823295025;4280.486341638992;4309.525952145262;4470.575295346936;4722.126406614787;4731.172044892164;6951.582905025664;7022.754203602901;7023.859522809566;7046.612436316001;7059.423310491685;3826.521684789401;4836.975815120902;4877.853664390074;4880.097182021337;4885.218365061644;4873.975630121827;4875.634327425386;4914.680545764514;4935.531124543163;4966.106100901872;4335.913281734411;4565.748883811356;5568.832935011957;5580.274564306615;5598.188262701886;5427.701476418835;5969.313528944227;6514.478841692653;6593.6703164841565;6631.617874883844;1435.8205224346957;1654.9586260343588;1686.5362025442855;1691.4418241980118;1698.4819630012717;976.5068640336584;1164.1152723929874;1174.448009435564;1174.9718995878607;1175.9176857620055;6202.782902598477;6230.158928544679;6233.812985610348;6245.718942647592;6294.276158241769;6770.83072923171;6780.660966951633;6810.31896810315;6822.8635427292165;6858.211305699809;5796.21751063601;5990.670513121733;6077.511009802627;6284.308726040132;6366.389780190192;2876.036527763181;3295.044437113258;3423.400904841643;3560.590532088791;3597.1726502903957;5525.6408333459;6200.689965501707;6584.715980383779;7010.334018268256;7065.89401158983;5608.859778505488;6920;7074.540154889915;7078.362947334662;7115.5730656160795;6699.827530182199;6877.65611719412;6986.231514533499;7009.404200642901;7051.717931282691;5713.457221563945;6657.7669781557415;6862.107925969592;6874;6903;6183.288921773874;6456.935430645822;6631.900521492278;6672.299408562225;6786.59280443175;5875.588708790344;6204.859323533374;6718.860072044988;6984.253936515871;6989.231184569591;4430.411366792908;4438.7114837535455;4451.53258907823;4464.642828573771;4468.106378724203;6084.756609735561;6437.163168788027;6495.480361571157;6731.360520750521;6885.449164066762;6235.53696289972;6261.186045814015;6596.109525214035;6610.4050635443045;6653.401193892573;5144.6109550421925;6554.442872355903;6560.671966401756;6565.573237739532;6601.438877695449;5348.893022139613;5365.678059316453;6414.166158399378;6428.8071357859335;6444.806655800334;5097.445279189883;5453.64551304164;6544.247411547733;6564.737410503717;6598.37315455031;6164.815055548341;6346;6357.427831495188;6380.93619063822;6409.807705768834 18:49:44 INFO - PID 1643 | |5;yui.html;698.9825254368643;727.3780917315695;729.2525161709248;730.7077169132347;730.7771129805409;14754.852451475483;15616.984895981754;15617.84382156178;15629;15702.921485392571;14350.497732579362;14431.134131952063;14546.418143274283;14617.342219600117;15105.62235944102;907.7049958763412;916.2990312411011;917.8393781088316;926.9582868770922;931.2922179143866;942.5334459442586;950.6292545907102;962.0091305954878;963.1957315641413;964.2575217082842;975.7511834482198;989.9059589339038;994.3089552760835;995.1392045880334;997.1922742578498;983.066087217143;984.8375018122001;987.8024395121005;991.2367477042673;995.1590905684685;733.0580204437273;735.4888352594949;736.2710916192984;737.0676094740159;741.6551303643778;16323.836761632469;16632.33470661172;16718.58203544909;16763.83236167635;16798.412055577995;11337.603183888525;13767;14516.056456330305;15224.695506089816;15692.293846776922;10069;10483.47582620866;11241.66275011751;11352.829707554394;11751.647450576496;2674.424998625305;2974.3902499987453;3034.9848250758605;3073.861676224575;3118.8128712277107;88051;92292.53853730689;93862;94701;110477.89522104837;10767.461626918623;11040.723981900355;11422.828657569977;11684.47419866108;11777.23447975896;23769.643455348185;24231.39421514441;24258;24263.393415164763;24287.757122428902;7019.543729657556;7665.7351536995775;7808.375329973588;7915.970923779987;8211.425200236046;3394;3412.4028295048265;3991.560928297885;4177.143685544456;4218.620324170778;4056.492938382702;4152.314868046798;4152.750834949913;4165.437665915123;4166.229247589205;709.8402859356706;835.6698556253326;841.6591280531395;856.2550580994574;857.4340934982881;535.9035373632784;545.73531837059;561.102236421722;561.5563704673344;562.9268195134606;9395.671151509665;10086.688730465139;10155.949220253851;10193;10579.94710026445;529.1982646290872;570.8525863015369;574.5403677058337;600.2406955201674;604.380509977268;4351.282038463682;4494.910101798011;4551.180787458289;4719.716816990992;4751.714897106184;92912;97421.5128924365;97657;98125;99468;65824.67087664531;72199.53106799485;73735;74282.62858685672;76143.61928190323;59705;69839.6508017477;73491.89762153573;75481;77174.91300348035;10845.078168355463;10863.402512861869;10905.781884362426;11067.72330691739;11098.667039988813;20415.18339266472;34882.47676284909;35023.29953400867;36870.26259474849;37146.8142659285;43567.56432435635;44876.77561612301;44947.876303091376;45220;45565.772171140256;13133.212007279211;13306.268155251553;13765.311734413039;13894;14031.754919212877;12564.748705026032;14178.220197889215;14180.716385672022;14495.985281030227;15348.386064557739;13146.80279795823;13426.530071447452;13455.86544134546;13526.79709804334;13908.235047072509;13249.470021199044;13282.468701251843;13309.66725831862;13448.529301474402;14003.22982235946;12791.360431978177;13139.934300328438;13215.339233038501;13766.65583360424;13837.100588461717;9586.66446674363;9673.698664336538;9742.610295588098;10223.335483193569;10709.196810239107;9687.41875487473;9728.070872397857;10554;11255.268407553483;11257.549698012315 18:49:44 INFO - PID 1643 | __end_tp_report 18:49:44 INFO - PID 1643 | __start_cc_report 18:49:44 INFO - PID 1643 | _x_x_mozilla_cycle_collect,424 18:49:44 INFO - PID 1643 | __end_cc_report 18:49:44 INFO - PID 1643 | __startTimestamp1490665784096__endTimestamp 18:49:44 INFO - PID 1643 | 18:49:44 INFO - PID 1643 | ------- Summary: start ------- 18:49:44 INFO - PID 1643 | Number of tests: 6 18:49:44 INFO - PID 1643 | 18:49:44 INFO - PID 1643 | [#0] dojo.html Cycles:165 Average:6124.92 Median:6348.40 stddev:678.73 (10.7%) stddev-sans-first:650.29 18:49:44 INFO - PID 1643 | Values: 3559.3 3565.0 3565.2 3604.9 3609.1 6134.1 6157.6 6205.2 6209.8 6240.6 6099.7 6118.9 6133.8 6135.6 6180.8 5570.7 5649.8 5737.5 5761.0 5844.7 5617.2 5681.7 5812.2 5823.3 5840.6 5569.9 5724.7 5727.1 5814.4 5831.9 5604.1 5661.3 5689.2 5689.5 5756.5 5532.9 5573.1 5591.8 5624.9 5676.8 4580.6 5054.6 6108.2 6125.1 6142.6 5757.4 6491.5 6534.2 6620.5 6656.0 6689.8 6727.0 6748.6 6749.2 6766.5 5160.3 6221.5 6292.2 6300.3 6304.0 6628.4 6727.7 6794.0 6868.2 6868.3 5749.6 6149.9 6365.1 6494.6 6572.5 6422.1 6475.2 6490.6 6503.2 6632.3 6534.5 6586.7 6646.3 6646.4 6687.3 6624.3 6624.3 6636.9 6638.1 6651.7 5226.6 5290.6 5292.3 5319.4 5352.4 4731.3 4780.6 4817.0 4822.5 4840.3 6334.3 6355.6 6370.7 6373.7 6381.6 6438.1 6756.1 6758.5 6768.3 6770.3 5386.0 6041.2 6285.7 6323.2 6326.2 5951.7 5973.3 5978.9 5982.2 6004.5 6756.4 6828.7 6861.1 6867.2 6894.0 6412.6 6540.5 6639.2 6670.2 6755.3 6660.7 6705.9 6716.4 6726.7 6727.9 5478.8 6475.4 6490.7 6515.8 6517.7 6375.4 6459.3 6608.2 6649.8 6669.6 6482.6 6525.6 6532.2 6544.2 6560.9 6335.0 6406.6 6491.8 6511.1 6520.2 6121.2 6324.3 6373.7 6378.7 6393.2 6293.6 6348.4 6406.5 6481.4 6507.5 5678.0 6282.8 6340.5 6363.8 6396.7 18:49:44 INFO - PID 1643 | 18:49:44 INFO - PID 1643 | [#1] ext.html Cycles:180 Average:50445.53 Median:35742.55 stddev:97689.36 (273.3%) stddev-sans-first:97891.76 18:49:44 INFO - PID 1643 | Values: 615.6 801.6 824.6 1233.4 1283.3 36672.3 37793.6 37872.0 37875.4 38015.0 30640.7 37069.0 37569.9 37613.0 37686.0 8089.0 8248.4 8517.1 8723.6 8766.8 8691.6 8806.3 8806.6 8825.8 8831.3 8766.6 8803.4 8839.1 8850.4 8859.5 7642.8 8734.0 8759.9 8771.2 8917.8 7702.7 8686.0 8712.8 8716.0 8737.1 38648.4 38858.8 38883.4 38927.0 38975.4 2959.6 2964.6 2967.3 2990.8 3002.6 20620.8 27577.4 27685.9 28605.9 28643.1 2740.2 2788.2 2853.8 2887.6 2901.4 242973.0 247045.8 247910.8 342141.0 343146.3 46633.0 46692.5 49459.5 49498.8 49501.3 51673.5 51722.2 51755.0 51777.2 51869.0 13730.9 13828.2 13846.7 13874.2 13880.6 11203.2 11218.4 11294.8 11300.9 11306.3 13729.3 13736.9 13746.3 13752.2 13864.8 5921.9 5942.9 5987.7 6002.4 6009.8 972.6 977.7 978.5 979.3 1017.7 18574.9 24017.0 24085.0 24111.8 24179.4 1080.3 1090.1 1097.9 1126.3 1126.4 11330.5 11771.4 12229.2 12229.8 12335.0 435237.0 437683.0 437841.0 439977.0 441450.0 55312.4 55504.0 55526.0 55526.9 55534.7 1447.8 1489.5 1498.1 1499.5 1516.9 23614.5 23762.4 23811.5 23951.2 23957.5 38269.0 46709.0 48648.8 48676.5 48809.0 332421.0 334676.0 335390.0 402793.0 406509.0 25934.9 26209.7 31234.4 31879.2 32674.2 31573.7 40026.2 42231.8 42939.0 42991.0 29002.9 29145.6 29664.0 29705.9 30095.0 28803.6 29583.5 29745.7 29757.9 30092.8 34794.0 35455.8 35661.5 35823.6 36345.0 37455.9 37844.9 37871.0 38467.2 38672.2 28252.9 30083.2 30475.8 30694.8 30822.2 18:49:44 INFO - PID 1643 | 18:49:44 INFO - PID 1643 | [#2] jquery.html Cycles:180 Average:39630.09 Median:22354.22 stddev:120144.86 (537.5%) stddev-sans-first:120445.81 18:49:44 INFO - PID 1643 | Values: 415.9 428.6 434.4 442.8 459.1 21510.8 21556.9 22225.4 22483.0 22742.0 19905.2 20753.1 22509.3 22642.7 22879.0 4637.7 5644.9 6224.8 6485.3 6505.4 4976.9 6348.5 6382.1 6391.4 6393.4 5749.2 6205.0 6235.5 6340.3 6341.8 6258.6 6261.7 6284.6 6290.7 6296.5 5582.5 5620.3 5695.9 5706.8 5727.0 27147.0 27219.0 27359.0 27397.8 27506.2 2065.1 2069.5 2094.0 2095.4 2166.0 15576.5 15958.6 15997.9 16230.6 16560.8 1603.3 1640.4 1650.7 1651.9 1666.4 109189.0 133260.7 134385.7 134410.0 137373.0 11796.8 12226.6 12513.6 12601.2 12611.2 16033.8 16041.8 16058.8 16099.3 16106.7 8775.3 8986.3 9010.9 9053.3 9115.9 7169.4 8436.5 8504.9 8516.1 8673.3 7701.4 7982.2 8011.2 8022.6 8042.8 2191.3 2201.3 2201.3 2207.8 2212.4 950.3 952.5 954.6 991.8 992.3 19509.7 20662.6 20761.8 20801.4 21130.2 990.7 991.8 1020.0 1027.8 1030.9 9886.5 10412.3 10645.8 10781.1 10792.9 705748.0 716934.0 721935.4 736882.0 741081.0 109190.0 132853.0 133471.3 136665.0 137537.0 1186.2 1194.8 1204.4 1209.6 1209.9 17586.4 17837.0 17866.6 17920.5 17977.8 56462.4 56997.0 57779.1 58134.7 58443.0 79313.2 80135.0 80533.0 81565.4 81694.0 7424.7 7526.3 8115.5 8376.7 8618.6 11110.4 12991.6 13424.7 13539.6 13599.9 11221.7 11339.1 11427.0 11462.3 11581.2 10471.6 10713.8 10790.0 11046.7 11121.0 11601.0 11718.7 11808.8 11861.2 11913.3 8344.5 8597.4 8625.0 8794.7 8818.3 9452.7 11490.1 11726.5 11785.0 11788.0 18:49:44 INFO - PID 1643 | 18:49:44 INFO - PID 1643 | [#3] mootools.html Cycles:180 Average:6754.94 Median:2492.41 stddev:4161.16 (167.0%) stddev-sans-first:4152.02 18:49:44 INFO - PID 1643 | Values: 1217.5 1397.3 1410.6 1423.8 1447.7 10471.4 10826.6 10900.0 10909.5 11146.7 10901.9 10972.6 11087.7 11114.2 11135.6 2451.4 2491.8 2493.0 2495.3 2496.2 1823.8 2361.0 2416.1 2436.2 2461.7 2393.5 2417.9 2450.2 2474.6 2478.9 2375.9 2427.2 2440.8 2453.3 2482.4 2373.0 2460.0 2461.2 2472.1 2477.7 9353.4 11001.0 11084.3 11084.8 11096.6 6218.2 6441.6 6442.1 6444.5 6456.4 3394.0 4016.9 4073.0 4100.1 4143.9 1170.0 1172.6 1184.1 1213.1 1225.6 12581.7 12938.6 15426.9 15502.8 15523.2 4397.5 5245.2 5499.5 5500.0 5507.8 11531.7 11555.8 11572.0 11596.9 11644.0 6269.7 6338.3 6348.7 6356.2 6358.1 5763.1 5972.9 5980.3 5984.4 6014.9 2889.0 4252.6 4306.9 4330.5 4343.2 1276.2 1276.4 1278.0 1283.4 1292.6 701.3 741.1 747.2 758.0 808.1 5255.4 6117.6 6657.5 6765.1 6862.4 554.8 564.5 580.0 584.3 590.1 4014.4 4223.9 4332.4 4376.2 4409.0 13441.5 13662.9 14061.7 14087.0 14108.2 11440.9 14152.5 14162.6 14198.3 14211.7 10060.2 10289.6 10363.6 10399.7 10410.9 5651.4 6108.8 6292.7 6953.5 7000.6 12098.1 12352.9 12435.4 12460.2 12574.2 11226.6 12314.4 12557.0 12697.9 12960.7 8238.2 8396.5 8421.7 8422.6 8442.2 10093.4 10157.5 10256.7 10278.9 10348.9 8763.8 8845.0 8873.1 8990.4 9042.1 8544.3 8621.9 8661.4 8693.1 8708.5 6942.1 7886.6 7903.4 7988.0 7996.2 5866.9 5941.3 5953.7 6091.1 6101.1 5221.2 5938.2 6177.6 6196.3 6323.1 18:49:44 INFO - PID 1643 | 18:49:44 INFO - PID 1643 | [#4] prototype.html Cycles:180 Average:5352.47 Median:5992.63 stddev:1626.63 (27.1%) stddev-sans-first:1587.10 18:49:44 INFO - PID 1643 | Values: 340.9 376.2 406.6 409.0 410.3 5980.9 6111.4 6199.7 6279.6 6281.0 5925.8 6114.2 6176.6 6183.8 6211.4 4244.8 4367.4 4850.8 4855.5 4863.7 4557.2 4816.1 4832.7 4844.9 4868.8 4510.7 4589.7 4746.7 4770.9 4783.0 3771.8 4764.2 4767.0 4790.1 4794.2 4038.2 4079.8 4260.9 4267.0 4284.5 5726.7 5994.6 6005.0 6015.7 6025.4 4607.9 4608.9 5572.0 5585.1 5585.3 5440.3 6219.8 6496.9 6607.6 6684.4 4280.5 4309.5 4470.6 4722.1 4731.2 6951.6 7022.8 7023.9 7046.6 7059.4 3826.5 4837.0 4877.9 4880.1 4885.2 4874.0 4875.6 4914.7 4935.5 4966.1 4335.9 4565.7 5568.8 5580.3 5598.2 5427.7 5969.3 6514.5 6593.7 6631.6 1435.8 1655.0 1686.5 1691.4 1698.5 976.5 1164.1 1174.4 1175.0 1175.9 6202.8 6230.2 6233.8 6245.7 6294.3 6770.8 6780.7 6810.3 6822.9 6858.2 5796.2 5990.7 6077.5 6284.3 6366.4 2876.0 3295.0 3423.4 3560.6 3597.2 5525.6 6200.7 6584.7 7010.3 7065.9 5608.9 6920.0 7074.5 7078.4 7115.6 6699.8 6877.7 6986.2 7009.4 7051.7 5713.5 6657.8 6862.1 6874.0 6903.0 6183.3 6456.9 6631.9 6672.3 6786.6 5875.6 6204.9 6718.9 6984.3 6989.2 4430.4 4438.7 4451.5 4464.6 4468.1 6084.8 6437.2 6495.5 6731.4 6885.4 6235.5 6261.2 6596.1 6610.4 6653.4 5144.6 6554.4 6560.7 6565.6 6601.4 5348.9 5365.7 6414.2 6428.8 6444.8 5097.4 5453.6 6544.2 6564.7 6598.4 6164.8 6346.0 6357.4 6380.9 6409.8 18:49:44 INFO - PID 1643 | 18:49:44 INFO - PID 1643 | [#5] yui.html Cycles:180 Average:18380.59 Median:4159.09 stddev:25494.13 (613.0%) stddev-sans-first:25531.08 18:49:44 INFO - PID 1643 | Values: 699.0 727.4 729.3 730.7 730.8 14754.9 15617.0 15617.8 15629.0 15702.9 14350.5 14431.1 14546.4 14617.3 15105.6 907.7 916.3 917.8 927.0 931.3 942.5 950.6 962.0 963.2 964.3 975.8 989.9 994.3 995.1 997.2 983.1 984.8 987.8 991.2 995.2 733.1 735.5 736.3 737.1 741.7 16323.8 16632.3 16718.6 16763.8 16798.4 11337.6 13767.0 14516.1 15224.7 15692.3 10069.0 10483.5 11241.7 11352.8 11751.6 2674.4 2974.4 3035.0 3073.9 3118.8 88051.0 92292.5 93862.0 94701.0 110477.9 10767.5 11040.7 11422.8 11684.5 11777.2 23769.6 24231.4 24258.0 24263.4 24287.8 7019.5 7665.7 7808.4 7916.0 8211.4 3394.0 3412.4 3991.6 4177.1 4218.6 4056.5 4152.3 4152.8 4165.4 4166.2 709.8 835.7 841.7 856.3 857.4 535.9 545.7 561.1 561.6 562.9 9395.7 10086.7 10155.9 10193.0 10579.9 529.2 570.9 574.5 600.2 604.4 4351.3 4494.9 4551.2 4719.7 4751.7 92912.0 97421.5 97657.0 98125.0 99468.0 65824.7 72199.5 73735.0 74282.6 76143.6 59705.0 69839.7 73491.9 75481.0 77174.9 10845.1 10863.4 10905.8 11067.7 11098.7 20415.2 34882.5 35023.3 36870.3 37146.8 43567.6 44876.8 44947.9 45220.0 45565.8 13133.2 13306.3 13765.3 13894.0 14031.8 12564.7 14178.2 14180.7 14496.0 15348.4 13146.8 13426.5 13455.9 13526.8 13908.2 13249.5 13282.5 13309.7 13448.5 14003.2 12791.4 13139.9 13215.3 13766.7 13837.1 9586.7 9673.7 9742.6 10223.3 10709.2 9687.4 9728.1 10554.0 11255.3 11257.5 18:49:44 INFO - PID 1643 | -------- Summary: end -------- 18:49:44 INFO - PID 1643 | 18:49:44 INFO - TEST-INFO | 1643: exit 0 18:49:44 INFO - TEST-OK | dromaeo_css | took 1119061ms 18:49:44 INFO - TEST-START | kraken 18:49:44 INFO - operating with platform_type : mac_ 18:49:44 INFO - Initialising browser for kraken test... 18:49:44 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp8iN3Lw/profile http://localhost:49250/getInfo.html) 18:49:47 INFO - PID 1655 | 2017-03-27 18:49:47.586 plugin-container[1656:10251] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 18:49:47 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:49:47 INFO - PID 1655 | 2017-03-27 18:49:47.589 plugin-container[1656:10251] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 18:49:47 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:49:47 INFO - PID 1655 | 2017-03-27 18:49:47.589 plugin-container[1656:10251] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:49:47 INFO - PID 1655 | __metrics Screen width/height:1600/1200 18:49:47 INFO - PID 1655 | colorDepth:24 18:49:47 INFO - PID 1655 | Browser inner width/height: 1024/689 18:49:47 INFO - PID 1655 | __metrics 18:49:47 ERROR - PID 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 18:49:47 ERROR - PID 1655 | 1490665787783 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/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp8iN3Lw/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp8iN3Lw/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp8iN3Lw/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp8iN3Lw/profile/extensions/talos-powers@TalosPowersService.js:55:9 18:49:48 INFO - TEST-INFO | 1655: exit 0 18:49:48 INFO - Browser initialized. 18:49:48 INFO - Running cycle 1/1 for kraken test... 18:49:48 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp8iN3Lw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:49:51 INFO - PID 1657 | 2017-03-27 18:49:51.061 plugin-container[1658:10388] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 18:49:51 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:49:51 INFO - PID 1657 | 2017-03-27 18:49:51.062 plugin-container[1658:10388] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d27, name = 'com.apple.CFPasteboardClient' 18:49:51 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:49:51 INFO - PID 1657 | 2017-03-27 18:49:51.062 plugin-container[1658:10388] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:50:00 INFO - PID 1657 | RSS: Main: 227749888 18:50:00 INFO - PID 1657 | 18:50:03 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 18:50:04 INFO - PID 1657 | RSS: Main: 243105792 18:50:04 INFO - PID 1657 | 18:50:07 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 18:50:08 INFO - PID 1657 | RSS: Main: 251559936 18:50:08 INFO - PID 1657 | 18:50:11 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 18:50:12 INFO - PID 1657 | RSS: Main: 253968384 18:50:12 INFO - PID 1657 | 18:50:14 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 18:50:14 INFO - PID 1657 | RSS: Main: 256040960 18:50:14 INFO - PID 1657 | 18:50:17 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:50:17 INFO - PID 1657 | RSS: Main: 256319488 18:50:17 INFO - PID 1657 | 18:50:21 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 18:50:21 INFO - PID 1657 | RSS: Main: 262025216 18:50:21 INFO - PID 1657 | 18:50:24 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 18:50:25 INFO - PID 1657 | RSS: Main: 263221248 18:50:25 INFO - PID 1657 | 18:50:28 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 18:50:29 INFO - PID 1657 | RSS: Main: 263168000 18:50:29 INFO - PID 1657 | 18:50:30 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:50:31 INFO - PID 1657 | RSS: Main: 262569984 18:50:31 INFO - PID 1657 | 18:50:33 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:50:34 INFO - PID 1657 | RSS: Main: 262537216 18:50:34 INFO - PID 1657 | 18:50:36 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:50:37 INFO - PID 1657 | RSS: Main: 262631424 18:50:37 INFO - PID 1657 | 18:50:40 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:50:41 INFO - PID 1657 | RSS: Main: 263057408 18:50:41 INFO - PID 1657 | 18:50:44 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:50:45 INFO - PID 1657 | RSS: Main: 260063232 18:50:45 INFO - PID 1657 | 18:50:46 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 18:50:47 INFO - PID 1657 | RSS: Main: 263434240 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | __start_tp_report 18:50:47 INFO - PID 1657 | _x_x_mozilla_page_load 18:50:47 INFO - PID 1657 | _x_x_mozilla_page_load_details 18:50:47 INFO - PID 1657 | |i|pagename|runs| 18:50:47 INFO - PID 1657 | |0;ai-astar;109;122;143;124;125;123;122;116;170;145 18:50:47 INFO - PID 1657 | |1;audio-beat-detection;156;131;120;158;118;117;127;157;177;116 18:50:47 INFO - PID 1657 | |2;audio-dft;233;266;253;233;216;241;217;214;211;222 18:50:47 INFO - PID 1657 | |3;audio-fft;83;62;63;91;65;63;61;61;75;64 18:50:47 INFO - PID 1657 | |4;audio-oscillator;91;93;114;108;108;67;75;75;103;124 18:50:47 INFO - PID 1657 | |5;imaging-gaussian-blur;95;94;127;115;141;120;95;93;96;94 18:50:47 INFO - PID 1657 | |6;imaging-darkroom;101;101;108;135;101;100;102;101;101;101 18:50:47 INFO - PID 1657 | |7;imaging-desaturate;102;103;102;104;102;104;100;103;102;103 18:50:47 INFO - PID 1657 | |8;json-parse-financial;70;68;67;69;68;69;69;69;69;72 18:50:47 INFO - PID 1657 | |9;json-stringify-tinderbox;61;72;77;93;68;63;62;62;64;63 18:50:47 INFO - PID 1657 | |10;stanford-crypto-aes;91;86;89;88;87;89;88;84;104;102 18:50:47 INFO - PID 1657 | |11;stanford-crypto-ccm;176;157;149;153;154;185;156;149;211;170 18:50:47 INFO - PID 1657 | |12;stanford-crypto-pbkdf2;208;195;199;201;214;206;202;221;254;250 18:50:47 INFO - PID 1657 | |13;stanford-crypto-sha256-iterative;72;70;75;75;96;102;61;85;103;100 18:50:47 INFO - PID 1657 | __end_tp_report 18:50:47 INFO - PID 1657 | __start_cc_report 18:50:47 INFO - PID 1657 | _x_x_mozilla_cycle_collect,1016 18:50:47 INFO - PID 1657 | __end_cc_report 18:50:47 INFO - PID 1657 | __startTimestamp1490665847241__endTimestamp 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | ------- Summary: start ------- 18:50:47 INFO - PID 1657 | Number of tests: 14 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#0] ai-astar Cycles:10 Average:129.90 Median:124.50 stddev:17.85 (14.3%) stddev-sans-first:17.26 18:50:47 INFO - PID 1657 | Values: 109.0 122.0 143.0 124.0 125.0 123.0 122.0 116.0 170.0 145.0 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:143.50 stddev:22.17 (15.5%) stddev-sans-first:22.51 18:50:47 INFO - PID 1657 | Values: 156.0 131.0 120.0 158.0 118.0 117.0 127.0 157.0 177.0 116.0 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#2] audio-dft Cycles:10 Average:230.60 Median:233.00 stddev:18.28 (7.8%) stddev-sans-first:19.36 18:50:47 INFO - PID 1657 | Values: 233.0 266.0 253.0 233.0 216.0 241.0 217.0 214.0 211.0 222.0 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#3] audio-fft Cycles:10 Average:68.80 Median:64.50 stddev:10.57 (16.4%) stddev-sans-first:9.88 18:50:47 INFO - PID 1657 | Values: 83.0 62.0 63.0 91.0 65.0 63.0 61.0 61.0 75.0 64.0 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:71.00 stddev:18.86 (26.6%) stddev-sans-first:19.92 18:50:47 INFO - PID 1657 | Values: 91.0 93.0 114.0 108.0 108.0 67.0 75.0 75.0 103.0 124.0 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:107.00 Median:94.00 stddev:17.42 (18.5%) stddev-sans-first:17.93 18:50:47 INFO - PID 1657 | Values: 95.0 94.0 127.0 115.0 141.0 120.0 95.0 93.0 96.0 94.0 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#6] imaging-darkroom Cycles:10 Average:105.10 Median:101.00 stddev:10.74 (10.6%) stddev-sans-first:11.29 18:50:47 INFO - PID 1657 | Values: 101.0 101.0 108.0 135.0 101.0 100.0 102.0 101.0 101.0 101.0 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#7] imaging-desaturate Cycles:10 Average:102.50 Median:103.00 stddev:1.18 (1.1%) stddev-sans-first:1.24 18:50:47 INFO - PID 1657 | Values: 102.0 103.0 102.0 104.0 102.0 104.0 100.0 103.0 102.0 103.0 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#8] json-parse-financial Cycles:10 Average:69.00 Median:69.00 stddev:1.33 (1.9%) stddev-sans-first:1.36 18:50:47 INFO - PID 1657 | Values: 70.0 68.0 67.0 69.0 68.0 69.0 69.0 69.0 69.0 72.0 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:66.00 stddev:10.04 (15.2%) stddev-sans-first:10.27 18:50:47 INFO - PID 1657 | Values: 61.0 72.0 77.0 93.0 68.0 63.0 62.0 62.0 64.0 63.0 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:88.00 stddev:6.71 (7.6%) stddev-sans-first:7.12 18:50:47 INFO - PID 1657 | Values: 91.0 86.0 89.0 88.0 87.0 89.0 88.0 84.0 104.0 102.0 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:166.00 Median:163.50 stddev:19.93 (12.2%) stddev-sans-first:20.81 18:50:47 INFO - PID 1657 | Values: 176.0 157.0 149.0 153.0 154.0 185.0 156.0 149.0 211.0 170.0 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:215.00 Median:211.00 stddev:20.91 (9.9%) stddev-sans-first:22.02 18:50:47 INFO - PID 1657 | Values: 208.0 195.0 199.0 201.0 214.0 206.0 202.0 221.0 254.0 250.0 18:50:47 INFO - PID 1657 | 18:50:47 INFO - PID 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.90 Median:73.50 stddev:15.34 (20.9%) stddev-sans-first:15.65 18:50:47 INFO - PID 1657 | Values: 72.0 70.0 75.0 75.0 96.0 102.0 61.0 85.0 103.0 100.0 18:50:47 INFO - PID 1657 | -------- Summary: end -------- 18:50:47 INFO - PID 1657 | 18:50:48 INFO - TEST-INFO | 1657: exit 0 18:50:48 INFO - TEST-OK | kraken | took 63355ms 18:50:48 INFO - SUITE-END | took 1182s 18:50:48 INFO - Completed test suite (00:19:42) 18:50:48 INFO - javascript benchmark 18:50:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7648.3991103333365, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3559.252556963036, 3564.966159813654, 3565.2156525564374, 3604.855805767769, 3609.0616439725673, 6134.141220229171, 6157.568970172089, 6205.162303089087, 6209.782657606979, 6240.594361366508, 6099.703252314929, 6118.893302669174, 6133.763142530646, 6135.570510064297, 6180.845478863031, 5570.6657600544095, 5649.830505084854, 5737.454941780524, 5760.971195144019, 5844.707764611774, 5617.241672374238, 5681.744321505522, 5812.186293918855, 5823.272090988627, 5840.561957853157, 5569.944300556983, 5724.742386592572, 5727.11229759388, 5814.389489103647, 5831.941680583203, 5604.075327570946, 5661.34894487131, 5689.231953686241, 5689.516391106719, 5756.453136951983, 5532.889342213133, 5573.136163894603, 5591.832245032656, 5624.943750562524, 5676.772929082832, 4580.610648094916, 5054.570361519275, 6108.175396321484, 6125.111858780497, 6142.6314421134875, 5757.366689664133, 6491.545591808575, 6534.1505604271615, 6620.536562440631, 6655.966720166417, 6689.832754181136, 6727.0, 6748.6288254146, 6749.19009718832, 6766.5263431559315, 5160.251763494272, 6221.47117495018, 6292.182016337847, 6300.275468321156, 6304.0, 6628.436582890412, 6727.66361681914, 6794.014867844234, 6868.175818901764, 6868.347506986828, 5749.626274292157, 6149.86227547905, 6365.108884756138, 6494.64279464634, 6572.474202063823, 6422.068800024063, 6475.222973243147, 6490.643014634146, 6503.154589903283, 6632.336766323426, 6534.477241820643, 6586.70359833799, 6646.26891041985, 6646.401823835878, 6687.297833727486, 6624.337566243337, 6624.337566243434, 6636.867262654817, 6638.070670106189, 6651.700673469705, 5226.5818734501145, 5290.550303224192, 5292.285541451932, 5319.38827034896, 5352.357717073976, 4731.266653668687, 4780.593649539758, 4817.0365926814775, 4822.493638168012, 4840.273958906192, 6334.334894835976, 6355.586886852339, 6370.713317900706, 6373.71318290678, 6381.64900930444, 6438.098666186737, 6756.121704178426, 6758.526903116833, 6768.255491895888, 6770.289119642465, 5386.007177344408, 6041.154238406627, 6285.748570057238, 6323.241211054703, 6326.2092238470195, 5951.702414879239, 5973.313068997034, 5978.880422391528, 5982.192404025488, 6004.489618382399, 6756.391924726799, 6828.692708828015, 6861.073755043005, 6867.244603093556, 6894.0, 6412.551121421456, 6540.542162048611, 6639.170103737033, 6670.199576050905, 6755.290694477206, 6660.666966651745, 6705.899411508929, 6716.429103526255, 6726.6636668167375, 6727.8990815138795, 5478.753456094406, 6475.449586785176, 6490.707918143789, 6515.837104072247, 6517.7067031984625, 6375.394337537927, 6459.289478157493, 6608.240052394036, 6649.800505984828, 6669.633170175687, 6482.611043337415, 6525.608463492205, 6532.183477065367, 6544.247411547733, 6560.901586476302, 6335.0, 6406.647634380154, 6491.805245842632, 6511.055896894829, 6520.18497687789, 6121.239585875057, 6324.272708638566, 6373.681315934277, 6378.74485020594, 6393.200849893763, 6293.590916590407, 6348.396902294275, 6406.487481001602, 6481.384268494486, 6507.544471886922, 5678.0, 6282.842928926813, 6340.524460665561, 6363.809085727436, 6396.712147953446], "value": 6083.843967383599, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [615.5660259517041, 801.555136899021, 824.5877061469265, 1233.407964177195, 1283.2877752847166, 36672.266554668924, 37793.62206377939, 37872.0, 37875.43186852199, 38015.0, 30640.69359306406, 37069.0, 37569.87290381293, 37613.0, 37686.0, 8088.95955520223, 8248.422610417274, 8517.148285171495, 8723.607437665289, 8766.780830479258, 8691.56542172892, 8806.251468625174, 8806.603702833389, 8825.779355516132, 8831.337649676268, 8766.649334026662, 8803.383763136582, 8839.116088391174, 8850.424722393023, 8859.512726800023, 7642.770716878475, 8734.039255681902, 8759.948806143304, 8771.16673915977, 8917.777055573597, 7702.730404435874, 8685.956570217108, 8712.78218044554, 8715.997660269088, 8737.082606326372, 38648.42027369592, 38858.80570597157, 38883.416748748794, 38927.0, 38975.41536876949, 2959.5708622249654, 2964.6294213223346, 2967.317516971087, 2990.7756918231003, 3002.6096607441114, 20620.79379206204, 27577.44845103107, 27685.861570692217, 28605.856970715013, 28643.14070577886, 2740.174997765345, 2788.1774876411305, 2853.8287702737903, 2887.6101726266893, 2901.3907079513115, 242973.0, 247045.7647711768, 247910.76044619843, 342141.0, 343146.28426857956, 46633.0, 46692.533074669496, 49459.50540494549, 49498.75250623724, 49501.257481137814, 51673.48326516762, 51722.224166637534, 51754.96490070177, 51777.22334164991, 51869.0, 13730.862691373159, 13828.23944683032, 13846.723065538632, 13874.236916969663, 13880.583582492542, 11203.21577489568, 11218.382988935688, 11294.774104517863, 11300.94349528247, 11306.265092769108, 13729.313534323244, 13736.862631373759, 13746.312684365741, 13752.174869507662, 13864.792028119586, 5921.851953701192, 5942.930272550982, 5987.730552125077, 6002.429769171922, 6009.789657362059, 972.5963725054023, 977.7457860956202, 978.4618459847055, 979.2802290316612, 1017.7150397888603, 18574.907125464288, 24017.03931842707, 24085.0, 24111.7588824113, 24179.39551511191, 1080.2978064258139, 1090.0897750378365, 1097.91765617579, 1126.3298337489346, 1126.357975953715, 11330.490127944262, 11771.352575608253, 12229.205101668364, 12229.816552751716, 12335.013198944063, 435237.0, 437683.0, 437841.0, 439977.0, 441450.0, 55312.44687553153, 55504.0, 55526.0, 55526.88946221053, 55534.72232638811, 1447.7900704397803, 1489.5010171592637, 1498.1086253679043, 1499.4976682811161, 1516.8558986896228, 23614.52770944537, 23762.405939851644, 23811.523769524858, 23951.16170934009, 23957.52084958256, 38269.0, 46709.0, 48648.75675621741, 48676.51323486862, 48809.0, 332421.0, 334676.0, 335390.0, 402793.0, 406509.0, 25934.87032564825, 26209.73790262073, 31234.375312493168, 31879.203019924687, 32674.183145421553, 31573.73705051772, 40026.1988210537, 42231.7888410556, 42939.0, 42991.0, 29002.854985724935, 29145.562816557343, 29664.0, 29705.85147074251, 30095.0, 28803.61173814865, 29583.51652743973, 29745.702542974293, 29757.851210743807, 30092.849535752182, 34794.0, 35455.82272088726, 35661.46507802437, 35823.64176358307, 36345.0, 37455.876323710334, 37844.864654060424, 37871.0, 38467.2306553873, 38672.22655546817, 28252.85873570619, 30083.24791880133, 30475.847620761753, 30694.817045806114, 30822.229444264074], "value": 17733.211900635273, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.9126583417482, 428.6270944278478, 434.41825519905217, 442.7874620182312, 459.14369700508564, 21510.784892151092, 21556.89221553892, 22225.44436389089, 22483.0, 22742.0, 19905.203791848307, 20753.066112024957, 22509.324720258377, 22642.66036009461, 22879.0, 4637.744924029177, 5644.887102257952, 6224.8443788905415, 6485.254195767477, 6505.381988711065, 4976.850694479172, 6348.492120630362, 6382.106505089291, 6391.360863913618, 6393.360663933616, 5749.223854779614, 6205.0, 6235.469985051276, 6340.2708688500725, 6341.8097457076365, 6258.593191442542, 6261.686915654199, 6284.622922624657, 6290.685465726719, 6296.496280297611, 5582.525485333752, 5620.269364982526, 5695.943040569583, 5706.80026199081, 5727.0, 27147.0, 27219.0, 27359.0, 27397.767100480327, 27506.174814755588, 2065.101680768855, 2069.5343547701796, 2094.0472085201195, 2095.434232757147, 2166.014463419156, 15576.454824081104, 15958.60103497422, 15997.92001039999, 16230.594235144215, 16560.840741148124, 1603.2544866636954, 1640.4176517336293, 1650.745433470559, 1651.8926269792426, 1666.441742016426, 109189.0, 133260.6673933258, 134385.65614343926, 134410.0, 137372.999999999, 11796.823047654292, 12226.572069977508, 12513.624591262276, 12601.243925364508, 12611.180273282207, 16033.839661603235, 16041.839581604268, 16058.75911861323, 16099.275532601061, 16106.677866442606, 8775.297976161892, 8986.281097512183, 9010.86483702745, 9053.275737940949, 9115.908840911507, 7169.426445884316, 8436.45163064399, 8504.914950850536, 8516.148385161558, 8673.306135509145, 7701.422393320523, 7982.241687039722, 8011.198880111942, 8022.638981245858, 8042.758717238492, 2191.29878438898, 2201.2625770366785, 2201.306588424641, 2207.768184340653, 2212.391592312127, 950.2872845365976, 952.5284983932997, 954.5799848066829, 991.7718924647371, 992.2855544008303, 19509.707354389695, 20662.586748264952, 20761.792382075986, 20801.375958721263, 21130.154793808382, 990.7170214572146, 991.7768502086973, 1020.0, 1027.8406846938587, 1030.922680798928, 9886.505674716236, 10412.334026555714, 10645.78708425827, 10781.137508999262, 10792.946035269773, 705748.0, 716934.0, 721935.390323045, 736882.0, 741081.0, 109190.0, 132853.0, 133471.3326433381, 136664.95002574968, 137537.0, 1186.2467333243453, 1194.8148037054098, 1204.403820109034, 1209.6129238643725, 1209.9455524501418, 17586.38447654326, 17837.0, 17866.642667146843, 17920.462386128438, 17977.820221797614, 56462.43537564736, 56997.0, 57779.13331300118, 58134.7093264531, 58443.0, 79313.2068679329, 80135.0, 80533.0, 81565.368692627, 81694.0, 7424.665890034853, 7526.285002924714, 8115.472494287852, 8376.706815261437, 8618.569071546523, 11110.44447777624, 12991.610251692464, 13424.664383390493, 13539.59381218565, 13599.932000339935, 11221.71945701364, 11339.09287257034, 11427.0, 11462.254953428, 11581.189316747746, 10471.584881792644, 10713.785724285626, 10790.0, 11046.723831903944, 11120.99911008013, 11601.013913817082, 11718.707032323919, 11808.82286765682, 11861.169718119994, 11913.285202887855, 8344.499330040217, 8597.398182127441, 8624.956875215585, 8794.692185773467, 8818.294536437197, 9452.669156579486, 11490.138239631893, 11726.530938762355, 11785.0, 11788.0], "value": 10252.085633186342, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1217.4643157010917, 1397.2734178227322, 1410.5627255550773, 1423.7684402991408, 1447.7032208397277, 10471.424071676054, 10826.566937322517, 10900.0, 10909.509072091754, 11146.66560003199, 10901.890981090186, 10972.561097556109, 11087.667369978913, 11114.166437517199, 11135.554577816898, 2451.3503921460783, 2491.838030528019, 2492.987535062322, 2495.2763698527406, 2496.151308555091, 1823.772028496438, 2360.9881950590225, 2416.1060407649193, 2436.2204094689705, 2461.6676748638974, 2393.461471168982, 2417.9274621761374, 2450.1669432392982, 2474.591692370757, 2478.9008439662393, 2375.9406014849596, 2427.2232885476656, 2440.804735621146, 2453.33759884831, 2482.404222986488, 2373.0, 2460.0282888259217, 2461.249318957717, 2472.097684345219, 2477.7274499805008, 9353.438793672402, 11001.0, 11084.279521831137, 11084.833727494093, 11096.611618593392, 6218.22272215973, 6441.645709485976, 6442.098106265126, 6444.516661250425, 6456.386643268882, 3394.0, 4016.879493615196, 4073.0, 4100.097978444737, 4143.937840932388, 1169.9801192842942, 1172.6364826903687, 1184.1178322149967, 1213.1447329632588, 1225.6323103069044, 12581.74836503274, 12938.611841644766, 15426.922865385714, 15502.767458488132, 15523.223838808128, 4397.472303323621, 5245.160774276097, 5499.477549632778, 5500.009998200362, 5507.807226747044, 11531.711707207387, 11555.826662600068, 11572.0, 11596.942015290038, 11644.0, 6269.717862696099, 6338.271098823694, 6348.682565871688, 6356.173697419306, 6358.078078678565, 5763.135529670499, 5972.880542389128, 5980.252468441445, 5984.401559844068, 6014.879702405928, 2888.9711102889123, 4252.596003379674, 4306.935395969063, 4330.545292744217, 4343.19650864591, 1276.183242724657, 1276.3809552367095, 1277.961661150167, 1283.3583208395803, 1292.5863723608543, 701.2916953876519, 741.0514541387033, 747.1892996099184, 757.9583122928182, 808.0787901791962, 5255.369355677343, 6117.632942023492, 6657.500687448461, 6765.1205343305055, 6862.416694581017, 554.8169104195606, 564.5060571999501, 579.9647628982246, 584.3455330030393, 590.1442907783645, 4014.397840323975, 4223.873283801491, 4332.393464914914, 4376.212281789309, 4409.0, 13441.462341506425, 13662.863371366358, 14061.71876562463, 14087.013909026475, 14108.224047677477, 11440.885591144148, 14152.504662336769, 14162.575122746333, 14198.290085495684, 14211.715765684836, 10060.24548158891, 10289.639862604772, 10363.585456581803, 10399.688009359732, 10410.947945260225, 5651.350094738993, 6108.847278817887, 6292.653904035139, 6953.478489113236, 7000.614966176706, 12098.092643051983, 12352.949999249804, 12435.440405181607, 12460.190087644274, 12574.182678125893, 11226.607068752555, 12314.445849936596, 12557.0, 12697.93651031739, 12960.740785184433, 8238.176182381954, 8396.454230475, 8421.663133474838, 8422.578871056545, 8442.197991190827, 10093.444860532447, 10157.542910568895, 10256.692299231036, 10278.948605256926, 10348.948255258676, 8763.82986109376, 8845.02704702495, 8873.112688731075, 8990.370674052754, 9042.095790420904, 8544.273736732448, 8621.87067194005, 8661.437006594551, 8693.08722584145, 8708.521031343336, 6942.097527321416, 7886.566238856918, 7903.3677305816545, 7987.960060199662, 7996.20037996219, 5866.882662346644, 5941.31674857397, 5953.672548009901, 6091.116788065794, 6101.145839582373, 5221.242027258127, 5938.168656388023, 6177.567570270038, 6196.318404975359, 6323.114763933145], "value": 5105.1481084019815, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.85343302379977, 376.2062049076448, 406.5954375396479, 409.0101953273079, 410.345498929208, 5980.940190598093, 6111.419415155553, 6199.659018753967, 6279.591826531278, 6280.963640999235, 5925.822225333236, 6114.1745864308305, 6176.629402235869, 6183.75264989401, 6211.378862113797, 4244.832671015464, 4367.410399596046, 4850.8059677612855, 4855.465898751135, 4863.708177509343, 4557.154352487248, 4816.109019831325, 4832.71003739775, 4844.854654360375, 4868.756562171895, 4510.661700372469, 4589.65577581681, 4746.715197088186, 4770.880727981811, 4782.952170478286, 3771.7736935783805, 4764.237721964469, 4767.046590681877, 4790.114083634673, 4794.2329227323635, 4038.172174704179, 4079.7552146870985, 4260.893477663052, 4267.0, 4284.507281662618, 5726.713664316809, 5994.580379373446, 6005.039193728982, 6015.699215039274, 6025.427584379477, 4607.861764147081, 4608.9308660370125, 5571.9721401393135, 5585.106382978704, 5585.301837270341, 5440.347158340985, 6219.8182345354235, 6496.93503064968, 6607.603543787388, 6684.431823295025, 4280.486341638992, 4309.525952145262, 4470.575295346936, 4722.126406614787, 4731.172044892164, 6951.582905025664, 7022.754203602901, 7023.859522809566, 7046.612436316001, 7059.423310491685, 3826.521684789401, 4836.975815120902, 4877.853664390074, 4880.097182021337, 4885.218365061644, 4873.975630121827, 4875.634327425386, 4914.680545764514, 4935.531124543163, 4966.106100901872, 4335.913281734411, 4565.748883811356, 5568.832935011957, 5580.274564306615, 5598.188262701886, 5427.701476418835, 5969.313528944227, 6514.478841692653, 6593.6703164841565, 6631.617874883844, 1435.8205224346957, 1654.9586260343588, 1686.5362025442855, 1691.4418241980118, 1698.4819630012717, 976.5068640336584, 1164.1152723929874, 1174.448009435564, 1174.9718995878607, 1175.9176857620055, 6202.782902598477, 6230.158928544679, 6233.812985610348, 6245.718942647592, 6294.276158241769, 6770.83072923171, 6780.660966951633, 6810.31896810315, 6822.8635427292165, 6858.211305699809, 5796.21751063601, 5990.670513121733, 6077.511009802627, 6284.308726040132, 6366.389780190192, 2876.036527763181, 3295.044437113258, 3423.400904841643, 3560.590532088791, 3597.1726502903957, 5525.6408333459, 6200.689965501707, 6584.715980383779, 7010.334018268256, 7065.89401158983, 5608.859778505488, 6920.0, 7074.540154889915, 7078.362947334662, 7115.5730656160795, 6699.827530182199, 6877.65611719412, 6986.231514533499, 7009.404200642901, 7051.717931282691, 5713.457221563945, 6657.7669781557415, 6862.107925969592, 6874.0, 6903.0, 6183.288921773874, 6456.935430645822, 6631.900521492278, 6672.299408562225, 6786.59280443175, 5875.588708790344, 6204.859323533374, 6718.860072044988, 6984.253936515871, 6989.231184569591, 4430.411366792908, 4438.7114837535455, 4451.53258907823, 4464.642828573771, 4468.106378724203, 6084.756609735561, 6437.163168788027, 6495.480361571157, 6731.360520750521, 6885.449164066762, 6235.53696289972, 6261.186045814015, 6596.109525214035, 6610.4050635443045, 6653.401193892573, 5144.6109550421925, 6554.442872355903, 6560.671966401756, 6565.573237739532, 6601.438877695449, 5348.893022139613, 5365.678059316453, 6414.166158399378, 6428.8071357859335, 6444.806655800334, 5097.445279189883, 5453.64551304164, 6544.247411547733, 6564.737410503717, 6598.37315455031, 6164.815055548341, 6346.0, 6357.427831495188, 6380.93619063822, 6409.807705768834], "value": 4852.197783078161, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.9825254368643, 727.3780917315695, 729.2525161709248, 730.7077169132347, 730.7771129805409, 14754.852451475483, 15616.984895981754, 15617.84382156178, 15629.0, 15702.921485392571, 14350.497732579362, 14431.134131952063, 14546.418143274283, 14617.342219600117, 15105.62235944102, 907.7049958763412, 916.2990312411011, 917.8393781088316, 926.9582868770922, 931.2922179143866, 942.5334459442586, 950.6292545907102, 962.0091305954878, 963.1957315641413, 964.2575217082842, 975.7511834482198, 989.9059589339038, 994.3089552760835, 995.1392045880334, 997.1922742578498, 983.066087217143, 984.8375018122001, 987.8024395121005, 991.2367477042673, 995.1590905684685, 733.0580204437273, 735.4888352594949, 736.2710916192984, 737.0676094740159, 741.6551303643778, 16323.836761632469, 16632.33470661172, 16718.58203544909, 16763.83236167635, 16798.412055577995, 11337.603183888525, 13767.0, 14516.056456330305, 15224.695506089816, 15692.293846776922, 10069.0, 10483.47582620866, 11241.66275011751, 11352.829707554394, 11751.647450576496, 2674.424998625305, 2974.3902499987453, 3034.9848250758605, 3073.861676224575, 3118.8128712277107, 88051.0, 92292.53853730689, 93862.0, 94701.0, 110477.89522104837, 10767.461626918623, 11040.723981900355, 11422.828657569977, 11684.47419866108, 11777.23447975896, 23769.643455348185, 24231.39421514441, 24258.0, 24263.393415164763, 24287.757122428902, 7019.543729657556, 7665.7351536995775, 7808.375329973588, 7915.970923779987, 8211.425200236046, 3394.0, 3412.4028295048265, 3991.560928297885, 4177.143685544456, 4218.620324170778, 4056.492938382702, 4152.314868046798, 4152.750834949913, 4165.437665915123, 4166.229247589205, 709.8402859356706, 835.6698556253326, 841.6591280531395, 856.2550580994574, 857.4340934982881, 535.9035373632784, 545.73531837059, 561.102236421722, 561.5563704673344, 562.9268195134606, 9395.671151509665, 10086.688730465139, 10155.949220253851, 10193.0, 10579.94710026445, 529.1982646290872, 570.8525863015369, 574.5403677058337, 600.2406955201674, 604.380509977268, 4351.282038463682, 4494.910101798011, 4551.180787458289, 4719.716816990992, 4751.714897106184, 92912.0, 97421.5128924365, 97657.0, 98125.0, 99468.0, 65824.67087664531, 72199.53106799485, 73735.0, 74282.62858685672, 76143.61928190323, 59705.0, 69839.6508017477, 73491.89762153573, 75481.0, 77174.91300348035, 10845.078168355463, 10863.402512861869, 10905.781884362426, 11067.72330691739, 11098.667039988813, 20415.18339266472, 34882.47676284909, 35023.29953400867, 36870.26259474849, 37146.8142659285, 43567.56432435635, 44876.77561612301, 44947.876303091376, 45220.0, 45565.772171140256, 13133.212007279211, 13306.268155251553, 13765.311734413039, 13894.0, 14031.754919212877, 12564.748705026032, 14178.220197889215, 14180.716385672022, 14495.985281030227, 15348.386064557739, 13146.80279795823, 13426.530071447452, 13455.86544134546, 13526.79709804334, 13908.235047072509, 13249.470021199044, 13282.468701251843, 13309.66725831862, 13448.529301474402, 14003.22982235946, 12791.360431978177, 13139.934300328438, 13215.339233038501, 13766.65583360424, 13837.100588461717, 9586.66446674363, 9673.698664336538, 9742.610295588098, 10223.335483193569, 10709.196810239107, 9687.41875487473, 9728.070872397857, 10554.0, 11255.268407553483, 11257.549698012315], "value": 7305.789625999505, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1670.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 122.0, 143.0, 124.0, 125.0, 123.0, 122.0, 116.0, 170.0, 145.0], "value": 129.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 131.0, 120.0, 158.0, 118.0, 117.0, 127.0, 157.0, 177.0, 116.0], "value": 137.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [233.0, 266.0, 253.0, 233.0, 216.0, 241.0, 217.0, 214.0, 211.0, 222.0], "value": 230.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 62.0, 63.0, 91.0, 65.0, 63.0, 61.0, 61.0, 75.0, 64.0], "value": 68.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 114.0, 108.0, 108.0, 67.0, 75.0, 75.0, 103.0, 124.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 127.0, 115.0, 141.0, 120.0, 95.0, 93.0, 96.0, 94.0], "value": 107.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 108.0, 135.0, 101.0, 100.0, 102.0, 101.0, 101.0, 101.0], "value": 105.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 103.0, 102.0, 104.0, 102.0, 104.0, 100.0, 103.0, 102.0, 103.0], "value": 102.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 67.0, 69.0, 68.0, 69.0, 69.0, 69.0, 69.0, 72.0], "value": 69.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 72.0, 77.0, 93.0, 68.0, 63.0, 62.0, 62.0, 64.0, 63.0], "value": 68.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 86.0, 89.0, 88.0, 87.0, 89.0, 88.0, 84.0, 104.0, 102.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 157.0, 149.0, 153.0, 154.0, 185.0, 156.0, 149.0, 211.0, 170.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 195.0, 199.0, 201.0, 214.0, 206.0, 202.0, 221.0, 254.0, 250.0], "value": 215.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 75.0, 75.0, 96.0, 102.0, 61.0, 85.0, 103.0, 100.0], "value": 83.9, "unit": "score"}]}]} 18:50:48 INFO - Return code: 0 18:50:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:50:48 ERROR - # TBPL SUCCESS # 18:50:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:50:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:50:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:50:48 INFO - 2017-03-27 18:50:48.532 screenresolution[1662:10781] starting screenresolution argv=screenresolution get 18:50:48 INFO - 2017-03-27 18:50:48.535 screenresolution[1662:10781] Display 0: 1600x1200x32@60 18:50:48 INFO - 2017-03-27 18:50:48.541 screenresolution[1663:10784] starting screenresolution argv=screenresolution list 18:50:48 INFO - Available Modes on Display 0 18:50:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:50:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:50:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:50:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:50:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:50:48 INFO - Intel Iris: 18:50:48 INFO - Chipset Model: Intel Iris 18:50:48 INFO - Type: GPU 18:50:48 INFO - Bus: Built-In 18:50:48 INFO - VRAM (Dynamic, Max): 1536 MB 18:50:48 INFO - Vendor: Intel (0x8086) 18:50:48 INFO - Device ID: 0x0a2e 18:50:48 INFO - Revision ID: 0x0009 18:50:48 INFO - Displays: 18:50:48 INFO - Display: 18:50:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:50:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:50:48 INFO - Display Serial Number: 1600x1200 60 18:50:48 INFO - Main Display: Yes 18:50:48 INFO - Mirror: Off 18:50:48 INFO - Online: Yes 18:50:48 INFO - Rotation: Supported 18:50:48 INFO - Return code: 0 18:50:48 INFO - Running post-action listener: _resource_record_post_action 18:50:48 INFO - [mozharness: 2017-03-28 01:50:48.781639Z] Finished run-tests step (success) 18:50:48 INFO - Running post-run listener: _resource_record_post_run 18:50:48 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 40782336; Write bytes: 521024512; Read time: 395; Write time: 1896 18:50:48 INFO - TinderboxPrint: CPU usage
54.2% 18:50:48 INFO - TinderboxPrint: I/O read bytes / time
40,782,336 / 395 18:50:48 INFO - TinderboxPrint: I/O write bytes / time
521,024,512 / 1,896 18:50:48 INFO - TinderboxPrint: CPU idle
2,207.5 (45.8%) 18:50:48 INFO - TinderboxPrint: CPU system
966.2 (20.1%) 18:50:48 INFO - TinderboxPrint: CPU user
1,644.0 (34.1%) 18:50:48 INFO - TinderboxPrint: Swap in / out
251,981,824 / 0 18:50:48 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 211307008; Write bytes: 207759360; Read time: 16990; Write time: 617 18:50:48 INFO - run-tests - Wall time: 1184s; CPU: 54.0%; Read bytes: 35650048; Write bytes: 303049728; Read time: 357; Write time: 1244 18:50:49 INFO - Running post-run listener: _upload_blobber_files 18:50:49 INFO - Blob upload gear active. 18:50:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:50:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:50:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:50:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:50:49 INFO - (blobuploader) - INFO - Open directory for files ... 18:50:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:50:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:50:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:50 INFO - (blobuploader) - INFO - Done attempting. 18:50:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:50:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:50:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:51 INFO - (blobuploader) - INFO - Done attempting. 18:50:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:50:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:50:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:53 INFO - (blobuploader) - INFO - Done attempting. 18:50:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:50:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:50:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:55 INFO - (blobuploader) - INFO - Done attempting. 18:50:55 INFO - (blobuploader) - INFO - Iteration through files over. 18:50:55 INFO - Return code: 0 18:50:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:50:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:50:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e8866aed29e8982f0c7d9f39941470391017a67d3e972d71b212332bca0e9a02e8d8243e8a43b24fa20e5752c4535e672677d584190593addc71dbec1c93db9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c4f8b9f9eed208f423c369761a913e8c747b47be31e51817c41de4c6774b96275c2d6f8cdcab11336d4557f04b236d7b9811974e169314bd6c01d6858c350b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1affd67fd5e9ba2bc0f9ad04426668738415819d0005a05f5433b1ff467491f729b9129c948ec8a1494346bac54ac891b978682872a987a2adf60c3d249c5edd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/148fa2f134a1d141186c23c3796c6d05c8e5022bc43442f65ba0ce6e4f92f7148d5a9aeab7b1c49c5fbeaded1f6f5f9526a60581d5170ca4f71f6bbab659f462"} 18:50:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:50:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:50:55 INFO - Contents: 18:50:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e8866aed29e8982f0c7d9f39941470391017a67d3e972d71b212332bca0e9a02e8d8243e8a43b24fa20e5752c4535e672677d584190593addc71dbec1c93db9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c4f8b9f9eed208f423c369761a913e8c747b47be31e51817c41de4c6774b96275c2d6f8cdcab11336d4557f04b236d7b9811974e169314bd6c01d6858c350b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1affd67fd5e9ba2bc0f9ad04426668738415819d0005a05f5433b1ff467491f729b9129c948ec8a1494346bac54ac891b978682872a987a2adf60c3d249c5edd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/148fa2f134a1d141186c23c3796c6d05c8e5022bc43442f65ba0ce6e4f92f7148d5a9aeab7b1c49c5fbeaded1f6f5f9526a60581d5170ca4f71f6bbab659f462"} 18:50:55 INFO - Running post-run listener: copy_logs_to_upload_dir 18:50:55 INFO - Copying logs to upload dir... 18:50:55 INFO - mkdir: /builds/slave/test/build/upload/logs 18:50:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.367963 ========= master_lag: 0.17 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-27 18:50:55.288490) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 18:50:55.292117) ========= 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.stK1UBrrQp/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.CmEmREZ1YD/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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/0e8866aed29e8982f0c7d9f39941470391017a67d3e972d71b212332bca0e9a02e8d8243e8a43b24fa20e5752c4535e672677d584190593addc71dbec1c93db9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c4f8b9f9eed208f423c369761a913e8c747b47be31e51817c41de4c6774b96275c2d6f8cdcab11336d4557f04b236d7b9811974e169314bd6c01d6858c350b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1affd67fd5e9ba2bc0f9ad04426668738415819d0005a05f5433b1ff467491f729b9129c948ec8a1494346bac54ac891b978682872a987a2adf60c3d249c5edd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/148fa2f134a1d141186c23c3796c6d05c8e5022bc43442f65ba0ce6e4f92f7148d5a9aeab7b1c49c5fbeaded1f6f5f9526a60581d5170ca4f71f6bbab659f462"} build_url:https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011468 build_url: 'https://queue.taskcluster.net/v1/task/YVW1qUAERVO0twtRTo1NwQ/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/0e8866aed29e8982f0c7d9f39941470391017a67d3e972d71b212332bca0e9a02e8d8243e8a43b24fa20e5752c4535e672677d584190593addc71dbec1c93db9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c4f8b9f9eed208f423c369761a913e8c747b47be31e51817c41de4c6774b96275c2d6f8cdcab11336d4557f04b236d7b9811974e169314bd6c01d6858c350b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1affd67fd5e9ba2bc0f9ad04426668738415819d0005a05f5433b1ff467491f729b9129c948ec8a1494346bac54ac891b978682872a987a2adf60c3d249c5edd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/148fa2f134a1d141186c23c3796c6d05c8e5022bc43442f65ba0ce6e4f92f7148d5a9aeab7b1c49c5fbeaded1f6f5f9526a60581d5170ca4f71f6bbab659f462"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 18:50:55.329509) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:50:55.329849) ========= 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.stK1UBrrQp/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.CmEmREZ1YD/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.005350 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:50:55.382832) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 18:50:55.385189) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 18:50:55.385545) ========= ========= Total master_lag: 0.33 =========