builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0184 starttime: 1491530457.84 results: success (0) buildid: 20170406133233 builduid: 5e1ce408cfe846c98b01b3dc74d2d9a6 revision: b66522c95dc82eb812b70cd2612e9409ac6da799 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 19:00:57.842649) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 19:00:57.860687) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 19:00:57.861068) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 19:00:57.898821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-04-06 19:00:57.899182) ========= 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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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-04-06 19:00:57-- 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% 13.9M=0.001s 2017-04-06 19:00:58 (13.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133225 ========= master_lag: 3.41 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-04-06 19:01:01.444806) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 19:01:01.445434) ========= 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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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.036116 ========= master_lag: 0.28 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 19:01:01.761682) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 19:01:01.762295) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev b66522c95dc82eb812b70cd2612e9409ac6da799 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev b66522c95dc82eb812b70cd2612e9409ac6da799 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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-04-06 19:01:01,860 truncating revision to first 12 chars 2017-04-06 19:01:01,860 Setting DEBUG logging. 2017-04-06 19:01:01,860 attempt 1/10 2017-04-06 19:01:01,860 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/b66522c95dc8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 19:01:03,450 unpacking tar archive at: pine-b66522c95dc8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.846573 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 19:01:03.634376) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 19:01:03.634804) ========= script_repo_revision: b66522c95dc82eb812b70cd2612e9409ac6da799 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 19:01:03.635233) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 19:01:03.635518) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 19:01:03.658727) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-04-06 19:01:03.660469) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 19:01:03 INFO - MultiFileLogger online at 20170406 19:01:03 in /builds/slave/test 19:01:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 19:01:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:01:03 INFO - {'append_to_log': False, 19:01:03 INFO - 'base_work_dir': '/builds/slave/test', 19:01:03 INFO - 'blob_upload_branch': 'Pine', 19:01:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:01:03 INFO - 'branch': 'Pine', 19:01:03 INFO - 'buildbot_json_path': 'buildprops.json', 19:01:03 INFO - 'config_files': ('talos/mac_config.py',), 19:01:03 INFO - 'default_actions': ('clobber', 19:01:03 INFO - 'read-buildbot-config', 19:01:03 INFO - 'download-and-extract', 19:01:03 INFO - 'populate-webroot', 19:01:03 INFO - 'create-virtualenv', 19:01:03 INFO - 'install', 19:01:03 INFO - 'run-tests'), 19:01:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:01:03 INFO - 'download_minidump_stackwalk': True, 19:01:03 INFO - 'download_symbols': 'ondemand', 19:01:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:01:03 INFO - 'tooltool.py': '/tools/tooltool.py', 19:01:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:01:03 INFO - '/tools/misc-python/virtualenv.py')}, 19:01:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:01:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:01:03 INFO - 'gecko_profile': False, 19:01:03 INFO - 'gecko_profile_interval': 0, 19:01:03 INFO - 'installer_path': 'installer.exe', 19:01:03 INFO - 'log_level': 'info', 19:01:03 INFO - 'log_name': 'talos', 19:01:03 INFO - 'log_to_console': True, 19:01:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:01:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:01:03 INFO - 'opt_config_files': (), 19:01:03 INFO - 'pip_index': False, 19:01:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:01:03 INFO - 'cmd': ('bash', 19:01:03 INFO - '-c', 19:01:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:01:03 INFO - 'enabled': True, 19:01:03 INFO - 'halt_on_failure': False, 19:01:03 INFO - 'name': 'check_screen_resolution'},), 19:01:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:01:03 INFO - 'cmd': ('bash', 19:01:03 INFO - '-c', 19:01:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:01:03 INFO - 'enabled': True, 19:01:03 INFO - 'halt_on_failure': False, 19:01:03 INFO - 'name': 'check_screen_resolution'},), 19:01:03 INFO - 'run_cmd_checks_enabled': True, 19:01:03 INFO - 'suite': 'dromaeojs-e10s', 19:01:03 INFO - 'system_bits': '32', 19:01:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:01:03 INFO - 'title': 't-yosemite-r7-0184', 19:01:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:01:03 INFO - 'use_talos_json': True, 19:01:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:01:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:01:03 INFO - 'work_dir': 'build'} 19:01:03 INFO - [mozharness: 2017-04-07 02:01:03.876783Z] Running clobber step. 19:01:03 INFO - Running pre-action listener: _resource_record_pre_action 19:01:03 INFO - Running main action method: clobber 19:01:03 INFO - rmtree: /builds/slave/test/build 19:01:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:01:06 INFO - Running post-action listener: _resource_record_post_action 19:01:06 INFO - [mozharness: 2017-04-07 02:01:06.332748Z] Finished clobber step (success) 19:01:06 INFO - [mozharness: 2017-04-07 02:01:06.333001Z] Running read-buildbot-config step. 19:01:06 INFO - Running pre-action listener: _resource_record_pre_action 19:01:06 INFO - Running main action method: read_buildbot_config 19:01:06 INFO - Using buildbot properties: 19:01:06 INFO - { 19:01:06 INFO - "project": "", 19:01:06 INFO - "product": "firefox", 19:01:06 INFO - "script_repo_revision": "default", 19:01:06 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 19:01:06 INFO - "repository": "", 19:01:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 19:01:06 INFO - "buildid": "20170406133233", 19:01:06 INFO - "pgo_build": "False", 19:01:06 INFO - "basedir": "/builds/slave/test", 19:01:06 INFO - "buildnumber": 0, 19:01:06 INFO - "slavename": "t-yosemite-r7-0184", 19:01:06 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 19:01:06 INFO - "platform": "yosemite_r7", 19:01:06 INFO - "scheduler": "tests-pine-macosx64-talos", 19:01:06 INFO - "branch": "pine", 19:01:06 INFO - "repo_path": "projects/pine", 19:01:06 INFO - "revision": "b66522c95dc82eb812b70cd2612e9409ac6da799", 19:01:06 INFO - "stage_platform": "macosx64", 19:01:06 INFO - "builduid": "5e1ce408cfe846c98b01b3dc74d2d9a6", 19:01:06 INFO - "slavebuilddir": "test" 19:01:06 INFO - } 19:01:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 19:01:06 INFO - Running post-action listener: _resource_record_post_action 19:01:06 INFO - [mozharness: 2017-04-07 02:01:06.338760Z] Finished read-buildbot-config step (success) 19:01:06 INFO - [mozharness: 2017-04-07 02:01:06.338916Z] Running download-and-extract step. 19:01:06 INFO - Running pre-action listener: _resource_record_pre_action 19:01:06 INFO - Running main action method: download_and_extract 19:01:06 INFO - mkdir: /builds/slave/test/build/tests 19:01:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:06 INFO - trying https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 19:01:06 INFO - Downloading https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/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 19:01:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/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 19:01:07 INFO - Downloaded 1325 bytes. 19:01:07 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:01:07 INFO - Using the following test package requirements: 19:01:07 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:07 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 19:01:07 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:01:07 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:07 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 19:01:07 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:07 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 19:01:07 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:07 INFO - u'jsshell-mac.zip'], 19:01:07 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:07 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 19:01:07 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:01:07 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:07 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 19:01:07 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:07 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 19:01:07 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:07 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 19:01:07 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:01:07 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 19:01:07 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:01:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:01:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:01:07 INFO - Fetch https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:01:09 INFO - Expected file size: 52521830 19:01:09 INFO - Obtained file size: 52521830 19:01:25 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 19:01:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:01:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:01:25 INFO - Fetch https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:01:26 INFO - Expected file size: 52521830 19:01:26 INFO - Obtained file size: 52521830 19:01:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 19:01:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:01:42 INFO - Fetch https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 19:01:44 INFO - Expected file size: 14404138 19:01:44 INFO - Obtained file size: 14404138 19:01:44 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')]} 19:01:44 INFO - trying https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:01:44 INFO - Downloading https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 19:01:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/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 19:01:46 INFO - Downloaded 71108834 bytes. 19:01:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:01:46 INFO - mkdir: /builds/slave/test/properties 19:01:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:01:46 INFO - Writing to file /builds/slave/test/properties/build_url 19:01:46 INFO - Contents: 19:01:46 INFO - build_url:https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:01:47 INFO - Running post-action listener: _resource_record_post_action 19:01:47 INFO - Running post-action listener: set_extra_try_arguments 19:01:47 INFO - [mozharness: 2017-04-07 02:01:47.294507Z] Finished download-and-extract step (success) 19:01:47 INFO - [mozharness: 2017-04-07 02:01:47.294652Z] Running populate-webroot step. 19:01:47 INFO - Running pre-action listener: _resource_record_pre_action 19:01:47 INFO - Running main action method: populate_webroot 19:01:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:01:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:01:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:01:47 INFO - '--tppagecycles', 19:01:47 INFO - '7'], 19:01:47 INFO - 'tests': ['tsvgm']}}, 19:01:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:01:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:01:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:01:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:01:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:01:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:01:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:01:47 INFO - 'talos_options': ['--disable-e10s'], 19:01:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:01:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:01:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:01:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:01:47 INFO - 'talos_options': ['--disable-e10s'], 19:01:47 INFO - 'tests': ['damp', 'tps']}, 19:01:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:01:47 INFO - 'tests': ['damp', 'tps']}, 19:01:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:01:47 INFO - 'tests': ['dromaeo_dom']}, 19:01:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:01:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:01:47 INFO - 'tests': ['basic_compositor_video']}, 19:01:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:01:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:01:47 INFO - 'tests': ['a11yr', 19:01:47 INFO - 'ts_paint', 19:01:47 INFO - 'tpaint', 19:01:47 INFO - 'sessionrestore', 19:01:47 INFO - 'sessionrestore_no_auto_restore', 19:01:47 INFO - 'tabpaint']}, 19:01:47 INFO - 'other-e10s': {'tests': ['a11yr', 19:01:47 INFO - 'ts_paint', 19:01:47 INFO - 'tpaint', 19:01:47 INFO - 'sessionrestore', 19:01:47 INFO - 'sessionrestore_no_auto_restore', 19:01:47 INFO - 'tabpaint']}, 19:01:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:01:47 INFO - 'ts_paint', 19:01:47 INFO - 'tpaint', 19:01:47 INFO - 'sessionrestore', 19:01:47 INFO - 'sessionrestore_no_auto_restore', 19:01:47 INFO - 'tabpaint']}, 19:01:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:01:47 INFO - 'ts_paint', 19:01:47 INFO - 'tpaint', 19:01:47 INFO - 'sessionrestore', 19:01:47 INFO - 'sessionrestore_no_auto_restore', 19:01:47 INFO - 'tabpaint']}, 19:01:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:01:47 INFO - 'tests': ['a11yr', 19:01:47 INFO - 'ts_paint', 19:01:47 INFO - 'tpaint', 19:01:47 INFO - 'sessionrestore', 19:01:47 INFO - 'sessionrestore_no_auto_restore', 19:01:47 INFO - 'tabpaint']}, 19:01:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:01:47 INFO - 'tests': ['a11yr', 19:01:47 INFO - 'ts_paint', 19:01:47 INFO - 'tpaint', 19:01:47 INFO - 'sessionrestore', 19:01:47 INFO - 'sessionrestore_no_auto_restore', 19:01:47 INFO - 'tabpaint']}, 19:01:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:01:47 INFO - 'tests': ['tsvgx', 19:01:47 INFO - 'tsvgr_opacity', 19:01:47 INFO - 'tart', 19:01:47 INFO - 'tscrollx', 19:01:47 INFO - 'cart', 19:01:47 INFO - 'tsvg_static']}, 19:01:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:01:47 INFO - 'tsvgr_opacity', 19:01:47 INFO - 'tart', 19:01:47 INFO - 'tscrollx', 19:01:47 INFO - 'cart', 19:01:47 INFO - 'tsvg_static']}, 19:01:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:01:47 INFO - 'talos_options': ['--disable-e10s'], 19:01:47 INFO - 'tests': ['tp5o']}, 19:01:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:01:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:01:47 INFO - 'talos_options': ['--disable-e10s', 19:01:47 INFO - '--xperf_path', 19:01:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:01:47 INFO - 'tests': ['tp5n']}, 19:01:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:01:47 INFO - 'talos_options': ['--xperf_path', 19:01:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:01:47 INFO - 'tests': ['tp5n']}}, 19:01:47 INFO - 'talos.zip': {'path': '', 19:01:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:01:47 INFO - Running post-action listener: _resource_record_post_action 19:01:47 INFO - [mozharness: 2017-04-07 02:01:47.313021Z] Finished populate-webroot step (success) 19:01:47 INFO - [mozharness: 2017-04-07 02:01:47.313134Z] Running create-virtualenv step. 19:01:47 INFO - Running pre-action listener: _resource_record_pre_action 19:01:47 INFO - Running main action method: create_virtualenv 19:01:47 INFO - Creating virtualenv /builds/slave/test/build/venv 19:01:47 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 19:01:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:01:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:01:47 INFO - Using real prefix '/tools/python27' 19:01:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:01:48 INFO - Installing distribute.............................................................................................................................................................................................done. 19:01:51 INFO - Installing pip.................done. 19:01:51 INFO - Return code: 0 19:01:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:01:51 INFO - Reading from file tmpfile_stdout 19:01:51 INFO - Output received: 19:01:51 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) 19:01:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcead7b030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101737db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101805be8>, '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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 19:01:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzsZyE3XNx/Render', 19:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:51 INFO - 'HOME': '/Users/cltbld', 19:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:51 INFO - 'LOGNAME': 'cltbld', 19:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:51 INFO - 'MOZ_NO_REMOTE': '1', 19:01:51 INFO - 'NO_EM_RESTART': '1', 19:01:51 INFO - 'PAGER': '/bin/cat', 19:01:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:51 INFO - 'PWD': '/builds/slave/test', 19:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:51 INFO - 'SHELL': '/bin/bash', 19:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EeyqdUR0Ge/Listeners', 19:01:51 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 19:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:51 INFO - 'USER': 'cltbld', 19:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:51 INFO - 'XPC_FLAGS': '0x0', 19:01:51 INFO - 'XPC_SERVICE_NAME': '0', 19:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:51 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 19:01:51 INFO - Cleaning up... 19:01:51 INFO - Return code: 0 19:01:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101a32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcead7b030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101737db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101805be8>, '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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 19:01:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzsZyE3XNx/Render', 19:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:51 INFO - 'HOME': '/Users/cltbld', 19:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:51 INFO - 'LOGNAME': 'cltbld', 19:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:51 INFO - 'MOZ_NO_REMOTE': '1', 19:01:51 INFO - 'NO_EM_RESTART': '1', 19:01:51 INFO - 'PAGER': '/bin/cat', 19:01:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:51 INFO - 'PWD': '/builds/slave/test', 19:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:51 INFO - 'SHELL': '/bin/bash', 19:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EeyqdUR0Ge/Listeners', 19:01:51 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 19:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:51 INFO - 'USER': 'cltbld', 19:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:51 INFO - 'XPC_FLAGS': '0x0', 19:01:51 INFO - 'XPC_SERVICE_NAME': '0', 19:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:51 INFO - Downloading/unpacking psutil>=3.1.1 19:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:01:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:01:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:01:55 INFO - Installing collected packages: psutil 19:01:55 INFO - Running setup.py install for psutil 19:01:55 INFO - building 'psutil._psutil_osx' extension 19:01:55 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 19:01:56 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 19:01:56 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 19:01:56 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 19:01:56 INFO - building 'psutil._psutil_posix' extension 19:01:56 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 19:01:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:01:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:01:56 INFO - ^ 19:01:56 INFO - 1 warning generated. 19:01:56 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 19:01:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:01:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:01:56 INFO - Successfully installed psutil 19:01:56 INFO - Cleaning up... 19:01:56 INFO - Return code: 0 19:01:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:57 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 0x101a32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcead7b030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101737db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101805be8>, '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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 19:01:57 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 19:01:57 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 19:01:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzsZyE3XNx/Render', 19:01:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:57 INFO - 'HOME': '/Users/cltbld', 19:01:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:57 INFO - 'LOGNAME': 'cltbld', 19:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:57 INFO - 'MOZ_NO_REMOTE': '1', 19:01:57 INFO - 'NO_EM_RESTART': '1', 19:01:57 INFO - 'PAGER': '/bin/cat', 19:01:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:57 INFO - 'PWD': '/builds/slave/test', 19:01:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:57 INFO - 'SHELL': '/bin/bash', 19:01:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EeyqdUR0Ge/Listeners', 19:01:57 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 19:01:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:57 INFO - 'USER': 'cltbld', 19:01:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:57 INFO - 'XPC_FLAGS': '0x0', 19:01:57 INFO - 'XPC_SERVICE_NAME': '0', 19:01:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:01:57 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 19:01:57 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 19:01:57 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 19:01:57 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 19:01:57 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 19:01:57 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 19:02:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:02:00 INFO - Installing collected packages: mozsystemmonitor 19:02:00 INFO - Running setup.py install for mozsystemmonitor 19:02:00 INFO - Successfully installed mozsystemmonitor 19:02:00 INFO - Cleaning up... 19:02:01 INFO - Return code: 0 19:02:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcead7b030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101737db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101805be8>, '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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 19:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:02:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzsZyE3XNx/Render', 19:02:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:01 INFO - 'HOME': '/Users/cltbld', 19:02:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:01 INFO - 'LOGNAME': 'cltbld', 19:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:01 INFO - 'MOZ_NO_REMOTE': '1', 19:02:01 INFO - 'NO_EM_RESTART': '1', 19:02:01 INFO - 'PAGER': '/bin/cat', 19:02:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:01 INFO - 'PWD': '/builds/slave/test', 19:02:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:01 INFO - 'SHELL': '/bin/bash', 19:02:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EeyqdUR0Ge/Listeners', 19:02:01 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 19:02:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:01 INFO - 'USER': 'cltbld', 19:02:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:01 INFO - 'XPC_FLAGS': '0x0', 19:02:01 INFO - 'XPC_SERVICE_NAME': '0', 19:02:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:01 INFO - Downloading/unpacking jsonschema==2.5.1 19:02:01 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 19:02:01 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 19:02:01 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 19:02:01 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 19:02:01 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 19:02:01 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 19:02:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:02:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:02:03 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 19:02:03 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 19:02:03 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 19:02:03 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 19:02:03 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 19:02:03 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 19:02:04 INFO - Downloading functools32-3.2.3-2.tar.gz 19:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:02:04 INFO - warning: no files found matching '*.txt' 19:02:04 INFO - no previously-included directories found matching 'build' 19:02:04 INFO - no previously-included directories found matching 'dist' 19:02:04 INFO - no previously-included directories found matching '.git*' 19:02:04 INFO - Installing collected packages: jsonschema, functools32 19:02:04 INFO - Running setup.py install for functools32 19:02:04 INFO - warning: no files found matching '*.txt' 19:02:04 INFO - no previously-included directories found matching 'build' 19:02:04 INFO - no previously-included directories found matching 'dist' 19:02:04 INFO - no previously-included directories found matching '.git*' 19:02:04 INFO - Successfully installed jsonschema functools32 19:02:04 INFO - Cleaning up... 19:02:04 INFO - Return code: 0 19:02:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:02:04 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')]} 19:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:04 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')]} 19:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:04 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 0x101a32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcead7b030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101737db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101805be8>, '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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 19:02:04 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 19:02:04 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 19:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzsZyE3XNx/Render', 19:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:04 INFO - 'HOME': '/Users/cltbld', 19:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:04 INFO - 'LOGNAME': 'cltbld', 19:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:04 INFO - 'MOZ_NO_REMOTE': '1', 19:02:04 INFO - 'NO_EM_RESTART': '1', 19:02:04 INFO - 'PAGER': '/bin/cat', 19:02:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:04 INFO - 'PWD': '/builds/slave/test', 19:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:04 INFO - 'SHELL': '/bin/bash', 19:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EeyqdUR0Ge/Listeners', 19:02:04 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 19:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:04 INFO - 'USER': 'cltbld', 19:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:04 INFO - 'XPC_FLAGS': '0x0', 19:02:04 INFO - 'XPC_SERVICE_NAME': '0', 19:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:02:05 INFO - Cleaning up... 19:02:05 INFO - Return code: 0 19:02:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:02: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')]} 19:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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')]} 19:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:05 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 0x101a32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcead7b030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101737db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101805be8>, '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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 19:02:05 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 19:02:05 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 19:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzsZyE3XNx/Render', 19:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:05 INFO - 'HOME': '/Users/cltbld', 19:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:05 INFO - 'LOGNAME': 'cltbld', 19:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:05 INFO - 'MOZ_NO_REMOTE': '1', 19:02:05 INFO - 'NO_EM_RESTART': '1', 19:02:05 INFO - 'PAGER': '/bin/cat', 19:02:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:05 INFO - 'PWD': '/builds/slave/test', 19:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:05 INFO - 'SHELL': '/bin/bash', 19:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EeyqdUR0Ge/Listeners', 19:02:05 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 19:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:05 INFO - 'USER': 'cltbld', 19:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:05 INFO - 'XPC_FLAGS': '0x0', 19:02:05 INFO - 'XPC_SERVICE_NAME': '0', 19:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:05 INFO - Downloading/unpacking blobuploader==1.2.4 19:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:07 INFO - Downloading blobuploader-1.2.4.tar.gz 19:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:02:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:02:08 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 19:02:08 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 19:02:08 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 19:02:08 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 19:02:08 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 19:02:08 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 19:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:02:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:02:08 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 19:02:08 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 19:02:08 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 19:02:08 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 19:02:08 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 19:02:08 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 19:02:09 INFO - Downloading docopt-0.6.1.tar.gz 19:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:02:09 INFO - Installing collected packages: blobuploader, requests, docopt 19:02:09 INFO - Running setup.py install for blobuploader 19:02:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:02:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:02:09 INFO - Running setup.py install for requests 19:02:09 INFO - Running setup.py install for docopt 19:02:09 INFO - Successfully installed blobuploader requests docopt 19:02:09 INFO - Cleaning up... 19:02:10 INFO - Return code: 0 19:02:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:10 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 0x101a32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcead7b030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101737db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101805be8>, '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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 19:02:10 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 19:02:10 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 19:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzsZyE3XNx/Render', 19:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:10 INFO - 'HOME': '/Users/cltbld', 19:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:10 INFO - 'LOGNAME': 'cltbld', 19:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:10 INFO - 'MOZ_NO_REMOTE': '1', 19:02:10 INFO - 'NO_EM_RESTART': '1', 19:02:10 INFO - 'PAGER': '/bin/cat', 19:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:10 INFO - 'PWD': '/builds/slave/test', 19:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:10 INFO - 'SHELL': '/bin/bash', 19:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EeyqdUR0Ge/Listeners', 19:02:10 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 19:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:10 INFO - 'USER': 'cltbld', 19:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:10 INFO - 'XPC_FLAGS': '0x0', 19:02:10 INFO - 'XPC_SERVICE_NAME': '0', 19:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:02:10 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-ISUPwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:02:10 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-fenQ_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:02:10 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-nIEhrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:02:10 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-qtFL_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:02:10 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-5q8rnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:02:10 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-R0pwJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:02:10 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-Mkhq8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:02:11 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-q6xExt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:02:11 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-KRpDDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:02:11 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-x7o0q6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:02:11 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-HMBMKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:02:11 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-5_G4FZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:02:11 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-1dA8yp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:02:11 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-2ypq3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:11 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-YuBGtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:02:11 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-edigAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:02:11 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-Evfb4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:02:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:02:12 INFO - Running setup.py install for manifestparser 19:02:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:02:12 INFO - Running setup.py install for mozcrash 19:02:12 INFO - Running setup.py install for mozdebug 19:02:12 INFO - Running setup.py install for mozdevice 19:02:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:02:12 INFO - Running setup.py install for mozfile 19:02:12 INFO - Running setup.py install for mozhttpd 19:02:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:02:12 INFO - Running setup.py install for mozinfo 19:02:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:02:13 INFO - Running setup.py install for mozInstall 19:02:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:02:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:02:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:02:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:02:13 INFO - Running setup.py install for mozleak 19:02:13 INFO - Running setup.py install for mozlog 19:02:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:02:13 INFO - Running setup.py install for moznetwork 19:02:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:02:13 INFO - Running setup.py install for mozprocess 19:02:13 INFO - Running setup.py install for mozprofile 19:02:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:02:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:02:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:02:14 INFO - Running setup.py install for mozrunner 19:02:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:02:14 INFO - Running setup.py install for mozscreenshot 19:02:14 INFO - Running setup.py install for moztest 19:02:14 INFO - Running setup.py install for mozversion 19:02:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:02:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:02:14 INFO - Cleaning up... 19:02:14 INFO - Return code: 0 19:02:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02: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')]} 19:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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')]} 19:02:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:14 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 0x101a32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcead7b030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101737db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101805be8>, '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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 19:02:14 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 19:02:14 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 19:02:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzsZyE3XNx/Render', 19:02:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:14 INFO - 'HOME': '/Users/cltbld', 19:02:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:14 INFO - 'LOGNAME': 'cltbld', 19:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:14 INFO - 'MOZ_NO_REMOTE': '1', 19:02:14 INFO - 'NO_EM_RESTART': '1', 19:02:14 INFO - 'PAGER': '/bin/cat', 19:02:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:14 INFO - 'PWD': '/builds/slave/test', 19:02:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:14 INFO - 'SHELL': '/bin/bash', 19:02:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EeyqdUR0Ge/Listeners', 19:02:14 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 19:02:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:14 INFO - 'USER': 'cltbld', 19:02:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:14 INFO - 'XPC_FLAGS': '0x0', 19:02:14 INFO - 'XPC_SERVICE_NAME': '0', 19:02:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:02:14 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-kJIG0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:02:15 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)) 19:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:02:15 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-HHXrVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:02:15 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)) 19:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:02:15 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-zX6Xhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:02:15 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)) 19:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:02:15 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-91ol1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:02:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 19:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:02:15 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-2BoEU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:02:15 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)) 19:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:02:15 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-yzivQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:02:15 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)) 19:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:02:15 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-6lJU0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:02:15 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)) 19:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:02:15 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-XihnV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:02:15 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)) 19:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:02:15 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-8_GPdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:02:15 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)) 19:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:02:15 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-L__zaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:02:15 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)) 19:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:02:15 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-3bqncx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:02:16 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)) 19:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:02:16 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-FgyZvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:02:16 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)) 19:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:02:16 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-Dypn8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:02:16 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)) 19:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:02:16 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-Bg50Uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:02:16 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)) 19:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:16 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-6ABJMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:16 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)) 19:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:02:16 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-8E7aNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:02:16 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)) 19:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:02:16 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-4jksHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:02:16 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)) 19:02:16 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)) 19:02:16 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)) 19:02:16 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)) 19:02:16 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:02:16 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:02:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:02:16 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 19:02:16 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 19:02:16 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 19:02:16 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 19:02:16 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 19:02:16 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 19:02:19 INFO - Downloading blessings-1.6.tar.gz 19:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:02:19 INFO - Installing collected packages: blessings 19:02:19 INFO - Running setup.py install for blessings 19:02:19 INFO - Successfully installed blessings 19:02:19 INFO - Cleaning up... 19:02:19 INFO - Return code: 0 19:02:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:02:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:02:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:02:20 INFO - Reading from file tmpfile_stdout 19:02:20 INFO - Current package versions: 19:02:20 INFO - blessings == 1.6 19:02:20 INFO - blobuploader == 1.2.4 19:02:20 INFO - docopt == 0.6.1 19:02:20 INFO - functools32 == 3.2.3-2 19:02:20 INFO - jsonschema == 2.5.1 19:02:20 INFO - manifestparser == 1.1 19:02:20 INFO - mozInstall == 1.12 19:02:20 INFO - mozcrash == 1.0 19:02:20 INFO - mozdebug == 0.1 19:02:20 INFO - mozdevice == 0.50 19:02:20 INFO - mozfile == 1.2 19:02:20 INFO - mozhttpd == 0.7 19:02:20 INFO - mozinfo == 0.9 19:02:20 INFO - mozleak == 0.1 19:02:20 INFO - mozlog == 3.4 19:02:20 INFO - moznetwork == 0.27 19:02:20 INFO - mozprocess == 0.25 19:02:20 INFO - mozprofile == 0.28 19:02:20 INFO - mozrunner == 6.13 19:02:20 INFO - mozscreenshot == 0.1 19:02:20 INFO - mozsystemmonitor == 0.3 19:02:20 INFO - moztest == 0.8 19:02:20 INFO - mozversion == 1.4 19:02:20 INFO - psutil == 3.1.1 19:02:20 INFO - requests == 1.2.3 19:02:20 INFO - wsgiref == 0.1.2 19:02:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02: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')]} 19:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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')]} 19:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:20 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 0x101a32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcead7b030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101737db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101805be8>, '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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 19:02:20 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 19:02:20 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 19:02:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzsZyE3XNx/Render', 19:02:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:20 INFO - 'HOME': '/Users/cltbld', 19:02:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:20 INFO - 'LOGNAME': 'cltbld', 19:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:20 INFO - 'MOZ_NO_REMOTE': '1', 19:02:20 INFO - 'NO_EM_RESTART': '1', 19:02:20 INFO - 'PAGER': '/bin/cat', 19:02:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:20 INFO - 'PWD': '/builds/slave/test', 19:02:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:20 INFO - 'SHELL': '/bin/bash', 19:02:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EeyqdUR0Ge/Listeners', 19:02:20 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 19:02:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:20 INFO - 'USER': 'cltbld', 19:02:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:20 INFO - 'XPC_FLAGS': '0x0', 19:02:20 INFO - 'XPC_SERVICE_NAME': '0', 19:02:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:20 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)) 19:02:20 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)) 19:02:20 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)) 19:02:20 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)) 19:02:20 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)) 19:02:20 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)) 19:02:20 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)) 19:02:20 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)) 19:02:20 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)) 19:02:20 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)) 19:02:20 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)) 19:02:20 INFO - Cleaning up... 19:02:20 INFO - Return code: 0 19:02:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:02: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')]} 19:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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')]} 19:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcead7b030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101737db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101805be8>, '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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 19:02: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', 'jsonschema'] in /builds/slave/test/build 19:02: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 jsonschema 19:02:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzsZyE3XNx/Render', 19:02:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:20 INFO - 'HOME': '/Users/cltbld', 19:02:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:20 INFO - 'LOGNAME': 'cltbld', 19:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:20 INFO - 'MOZ_NO_REMOTE': '1', 19:02:20 INFO - 'NO_EM_RESTART': '1', 19:02:20 INFO - 'PAGER': '/bin/cat', 19:02:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:20 INFO - 'PWD': '/builds/slave/test', 19:02:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:20 INFO - 'SHELL': '/bin/bash', 19:02:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EeyqdUR0Ge/Listeners', 19:02:20 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 19:02:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:20 INFO - 'USER': 'cltbld', 19:02:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:20 INFO - 'XPC_FLAGS': '0x0', 19:02:20 INFO - 'XPC_SERVICE_NAME': '0', 19:02:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:02:20 INFO - Cleaning up... 19:02:20 INFO - Return code: 0 19:02:20 INFO - Running post-action listener: _resource_record_post_action 19:02:20 INFO - Running post-action listener: _start_resource_monitoring 19:02:20 INFO - Starting resource monitoring. 19:02:20 INFO - [mozharness: 2017-04-07 02:02:20.629412Z] Finished create-virtualenv step (success) 19:02:20 INFO - [mozharness: 2017-04-07 02:02:20.630431Z] Running install step. 19:02:20 INFO - Running pre-action listener: _resource_record_pre_action 19:02:20 INFO - Running main action method: install 19:02:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:02:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:02:20 INFO - Reading from file tmpfile_stdout 19:02:20 INFO - Detecting whether we're running mozinstall >=1.0... 19:02:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:02:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:02:20 INFO - Reading from file tmpfile_stdout 19:02:20 INFO - Output received: 19:02:20 INFO - Usage: mozinstall [options] installer 19:02:20 INFO - Options: 19:02:20 INFO - -h, --help show this help message and exit 19:02:20 INFO - -d DEST, --destination=DEST 19:02:20 INFO - Directory to install application into. [default: 19:02:20 INFO - "/builds/slave/test"] 19:02:20 INFO - --app=APP Application being installed. [default: firefox] 19:02:20 INFO - mkdir: /builds/slave/test/build/application 19:02:20 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'] 19:02:20 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 19:02:40 INFO - Reading from file tmpfile_stdout 19:02:40 INFO - Output received: 19:02:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:02:40 INFO - Running post-action listener: _resource_record_post_action 19:02:40 INFO - [mozharness: 2017-04-07 02:02:40.613657Z] Finished install step (success) 19:02:40 INFO - [mozharness: 2017-04-07 02:02:40.613797Z] Running run-tests step. 19:02:40 INFO - Running pre-action listener: _resource_record_pre_action 19:02:40 INFO - Running main action method: run_tests 19:02:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:02:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:02:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:02:40 INFO - 2017-04-06 19:02:40.628 screenresolution[1619:7428] starting screenresolution argv=screenresolution get 19:02:40 INFO - 2017-04-06 19:02:40.631 screenresolution[1619:7428] Display 0: 1600x1200x32@60 19:02:40 INFO - 2017-04-06 19:02:40.637 screenresolution[1620:7430] starting screenresolution argv=screenresolution list 19:02:40 INFO - Available Modes on Display 0 19:02:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:02:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:02:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:02:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:02:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:02:40 INFO - Intel Iris: 19:02:40 INFO - Chipset Model: Intel Iris 19:02:40 INFO - Type: GPU 19:02:40 INFO - Bus: Built-In 19:02:40 INFO - VRAM (Dynamic, Max): 1536 MB 19:02:40 INFO - Vendor: Intel (0x8086) 19:02:40 INFO - Device ID: 0x0a2e 19:02:40 INFO - Revision ID: 0x0009 19:02:40 INFO - Displays: 19:02:40 INFO - Display: 19:02:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:02:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:02:40 INFO - Display Serial Number: 1600x1200 60 19:02:40 INFO - Main Display: Yes 19:02:40 INFO - Mirror: Off 19:02:40 INFO - Online: Yes 19:02:40 INFO - Rotation: Supported 19:02:40 INFO - Return code: 0 19:02:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:02:40 INFO - Python 2.7.3 19:02:40 INFO - Return code: 0 19:02:40 INFO - grabbing minidump binary from tooltool 19:02:40 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')]} 19:02:40 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 0x101810d30>, '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 0x101737db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101805be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:02:40 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 19:02:40 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 19:02:40 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 19:02:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:02:40 INFO - Return code: 0 19:02:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:02:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:02:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:02:40 INFO - ENV: RUST_BACKTRACE is now 1 19:02:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:02:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:02:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0184', '--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 19:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0184 --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 19:02:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nzsZyE3XNx/Render', 19:02:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:41 INFO - 'HOME': '/Users/cltbld', 19:02:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:41 INFO - 'LOGNAME': 'cltbld', 19:02:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:02:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:41 INFO - 'MOZ_NO_REMOTE': '1', 19:02:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:02:41 INFO - 'NO_EM_RESTART': '1', 19:02:41 INFO - 'PAGER': '/bin/cat', 19:02:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:41 INFO - 'PWD': '/builds/slave/test', 19:02:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:02:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:41 INFO - 'RUST_BACKTRACE': '1', 19:02:41 INFO - 'SHELL': '/bin/bash', 19:02:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EeyqdUR0Ge/Listeners', 19:02:41 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 19:02:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:41 INFO - 'USER': 'cltbld', 19:02:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:41 INFO - 'XPC_FLAGS': '0x0', 19:02:41 INFO - 'XPC_SERVICE_NAME': '0', 19:02:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0184', '--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 19:02:41 INFO - mozversion application_buildid: 20170406133233 19:02:41 INFO - mozversion application_changeset: b66522c95dc82eb812b70cd2612e9409ac6da799 19:02:41 INFO - mozversion application_display_name: Nightly 19:02:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:02:41 INFO - mozversion application_name: Firefox 19:02:41 INFO - mozversion application_remotingname: firefox 19:02:41 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 19:02:41 INFO - mozversion application_vendor: Mozilla 19:02:41 INFO - mozversion application_version: 55.0a1 19:02:41 INFO - mozversion platform_buildid: 20170406133233 19:02:41 INFO - mozversion platform_changeset: b66522c95dc82eb812b70cd2612e9409ac6da799 19:02:41 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 19:02:41 INFO - mozversion platform_version: 55.0a1 19:02:41 INFO - using testdate: 1491530561 19:02:41 INFO - actual date: 1491530561 19:02:41 INFO - starting webserver on 'localhost:49241' 19:02:41 INFO - SUITE-START | Running 2 tests 19:02:41 INFO - TEST-START | dromaeo_css 19:02:41 INFO - operating with platform_type : mac_ 19:02:41 INFO - Initialising browser for dromaeo_css test... 19:02:41 INFO - TEST-INFO | started process 1630 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpZhs82d/profile http://localhost:49241/getInfo.html) 19:02:42 INFO - PID 1630 | 1491530562133 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 19:02:42 INFO - PID 1630 | 1491530562134 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 19:02:42 INFO - PID 1630 | 1491530562196 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 19:02:44 INFO - PID 1630 | 2017-04-06 19:02:44.690 plugin-container[1633:7612] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x493b, name = 'com.apple.tsm.portname' 19:02:44 INFO - PID 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:02:44 INFO - PID 1630 | 2017-04-06 19:02:44.693 plugin-container[1633:7612] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 19:02:44 INFO - PID 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:02:44 INFO - PID 1630 | 2017-04-06 19:02:44.693 plugin-container[1633:7612] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:02:44 INFO - PID 1630 | __metrics Screen width/height:1600/1200 19:02:44 INFO - PID 1630 | colorDepth:24 19:02:44 INFO - PID 1630 | Browser inner width/height: 1024/689 19:02:44 INFO - PID 1630 | __metrics 19:02:44 ERROR - PID 1630 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 19:02:44 INFO - PID 1630 | 1491530564868 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 19:02:45 INFO - TEST-INFO | 1630: exit 0 19:02:45 INFO - Browser initialized. 19:02:45 INFO - Running cycle 1/1 for dromaeo_css test... 19:02:45 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpZhs82d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:02:46 INFO - PID 1634 | 1491530566242 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 19:02:46 INFO - PID 1634 | 1491530566243 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 19:02:48 INFO - PID 1634 | 2017-04-06 19:02:48.364 plugin-container[1635:7749] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 19:02:48 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:02:48 INFO - PID 1634 | 2017-04-06 19:02:48.364 plugin-container[1635:7749] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 19:02:48 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:02:48 INFO - PID 1634 | 2017-04-06 19:02:48.364 plugin-container[1635:7749] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:02:58 INFO - PID 1634 | RSS: Main: 235376640 19:02:58 INFO - PID 1634 | 19:06:02 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 19:06:04 INFO - PID 1634 | RSS: Main: 253485056 19:06:04 INFO - PID 1634 | 19:07:46 INFO - PID 1634 | ************************* 19:07:46 INFO - PID 1634 | A coding exception was thrown and uncaught in a Task. 19:07:46 INFO - PID 1634 | 19:07:46 ERROR - PID 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:07:46 INFO - PID 1634 | Full stack: 19:07:46 INFO - PID 1634 | ************************* 19:07:46 INFO - PID 1634 | ************************* 19:07:46 INFO - PID 1634 | A coding exception was thrown and uncaught in a Task. 19:07:46 INFO - PID 1634 | 19:07:46 ERROR - PID 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:07:46 INFO - PID 1634 | Full stack: 19:07:46 INFO - PID 1634 | ************************* 19:07:46 INFO - PID 1634 | ************************* 19:07:46 INFO - PID 1634 | A coding exception was thrown and uncaught in a Task. 19:07:46 INFO - PID 1634 | 19:07:46 ERROR - PID 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:07:46 INFO - PID 1634 | Full stack: 19:07:46 INFO - PID 1634 | ************************* 19:07:46 INFO - PID 1634 | ************************* 19:07:46 INFO - PID 1634 | A coding exception was thrown and uncaught in a Task. 19:07:46 INFO - PID 1634 | 19:07:46 ERROR - PID 1634 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:07:46 INFO - PID 1634 | Full stack: 19:07:46 INFO - PID 1634 | ************************* 19:09:06 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 19:09:07 INFO - PID 1634 | RSS: Main: 261922816 19:09:07 INFO - PID 1634 | 19:12:08 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 19:12:09 INFO - PID 1634 | RSS: Main: 274038784 19:12:09 INFO - PID 1634 | 19:15:11 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 19:15:11 INFO - PID 1634 | RSS: Main: 269914112 19:15:11 INFO - PID 1634 | 19:18:14 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 19:18:15 INFO - PID 1634 | RSS: Main: 280297472 19:18:15 INFO - PID 1634 | 19:21:17 INFO - PID 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 19:21:18 INFO - PID 1634 | RSS: Main: 274030592 19:21:18 INFO - PID 1634 | 19:21:18 INFO - PID 1634 | __start_tp_report 19:21:18 INFO - PID 1634 | _x_x_mozilla_page_load 19:21:18 INFO - PID 1634 | _x_x_mozilla_page_load_details 19:21:18 INFO - PID 1634 | |i|pagename|runs| 19:21:18 INFO - PID 1634 | |0;dojo.html;3530.046887340418;3585.0320413488344;3602.8919132426036;3611.9638803611956;3612.981935090324;6120.111926550693;6228.595141315817;6240.2199725034425;6277.435030847223;6297.622142671443;6217.844553886144;6247.250329960411;6252.749890004406;6263.279722831876;6331.461825744817;5533.6403133796375;5537.750801213955;5560.193771903072;5588.636738611977;5656.095024796034;5473.397926228112;5567.331920169565;5670.262865827437;5681.380806470157;5688.744006519716;5403.054465468527;5492.615516913777;5572.498475137217;5578.609497335188;5638.661680299195;5507.146392309199;5517.668939863581;5521.1442226454565;5546.168074788774;5585.664860108406;5477.315335583052;5485.149801780731;5486.31421072366;5489.39616642169;5512.090505066659;6046.72789724459;6160.075988601746;6186.133941248228;6215.658138802364;6248.28144763349;6728.596284222962;6773.221079575863;6782.1861376635115;6787.592744435349;6793.626350550767;6834.1457317835275;6862.725490980355;6871.931280687229;6873.2783057778715;6877.931220687779;6245.937540624535;6261.624302541862;6339.904901426482;6344.429001389897;6364.872702545923;5845.766169353264;6915.550489218285;6918.446524278046;6963.1296087989;6979.651017449209;5639.689817060101;6117.174181485531;6129.970128114947;6586.571872828251;6663.5335526513645;5963.4334738199805;6708.2956289589865;6814.761483348059;6854.10896583441;6889.207741109736;6415.647139407284;6549.083128362033;6769.120014398097;6773.085633439521;6783.118194634764;6461.547691661539;6512.316206798313;6702.731890724414;6744.527883048139;6786.423154031864;5389.51494365509;5394.865128371822;5400.162974738922;5409.864753381197;5426.158945363475;3952.8418863245724;4817.7350245736825;4839.491853355417;4844.103840789465;4878.243872199744;5632.887342253132;5925.644461332334;6193.4425901669065;6277.937220627735;6301;6864.553804002724;6867.416269617039;6869.175698916162;6886;6900.654967251618;6227.044244264118;6342.809715708536;6351.872962540723;6370.55406121567;6390.233172019294;5763.164341170507;5804.071348584206;5825.592208545361;5837.036888913367;6040.516758659297;6875.965620171767;6987.441004719712;6997.440204783707;7024.964875175796;7028.262032486719;5169.974150129226;6034.24571928509;6044.486218671286;6246.938020536611;6886.311368863074;6645.169353830771;6703.463722902058;6823.897641535282;7022.332878376546;7026.789196324118;4801.831935882227;5532.889342213214;6637.70130344126;6678.131842860397;6693.866122677617;5455.972720136374;5706.42935706426;6806.115205023315;6830.965845170742;6838.931610684029;6393.520485963664;6420.1974753155855;6636.27001029896;6640.966795165993;6642.734290628514;6375.330590287953;6387.073874288109;6595.835104122435;6636.867262654817;6671.66641667905;5681.8863622726485;6454.128692626436;6483;6489.156409666713;6509.088727578048;6661.067450556829;6666.899996500153;6677.2655007948115;6682.331766823473;6683.164604424447;6262.34245404226;6467.450266727193;6503;6508.121403610452;6515.478761699147 19:21:18 INFO - PID 1634 | |1;ext.html;686.2108575138591;742.792018234894;833.2792134803395;1241.1002023532944;1281.0520215040867;37674.43488347674;38176.427353589715;38181.23637527248;38237.852864414046;38243.99999999996;37619;37950.62049379505;38047.239055218946;38260;38304;7965.721199757995;8115.594220288992;8489.23596876281;8679.566021698922;8762.430442021248;8561.101084386144;8609.95695021524;8654.22112009919;8671.653133874637;8679.522626255553;8601.225889669928;8622.784430389227;8667.69663061793;8694.39139260252;8699.478031318142;8568.914310856906;8618.65525378984;8653.524056176908;8660.8700869487;8663.220310172115;8452.535110568917;8457.703980360659;8505.74482765518;8510.74467765968;8521.36089793268;37533.49866005356;37605.81197093997;37653.24693506142;37660.81169594162;37680.43479347812;3063.2954420483193;3460.9480857787153;3461.6538346165435;3467.549218601591;3506.351325004857;30008.54987175194;30251.546226806615;30364.54453183204;30385.24036899073;30477.695223047707;3059.770517211196;3108.782385233035;3130.1392117167734;3138;3146.921326966844;230042;235423;255378;264481;265382;47676;47694.52305476901;47729.761351193025;47733.76133119312;47735;35535.466967995504;40904.181916362104;49447.25829112566;49641.751791241535;49843.750781245864;14441.49454769078;14547.636309092357;14680.486182983544;14680.632984175267;14688.926555367154;11967;11980.161388702882;11994.040476761838;12011.339376334385;12055.457504412323;12067.637970860887;12524.624261272176;13436.462541498426;13458.52895148665;13465.461381544825;5470.808521701801;5552.750631108049;5875.265591801025;5901.911471327933;5913.113033044991;889.6130183370254;983.9852402213972;991.2268430624125;1012.8632634594236;1025.876894772632;23592.882035590057;23641.85260277516;23716.288511344686;23718.644220336708;23807.523849522913;1082.3992684060422;1092.2136062035322;1094.9288296260718;1109.5506319940275;1140.1562843495753;10136.695899123039;12089.57686480969;12240.632781016584;12247.142700010914;12309.322987235788;302300;336877;356374.61752294947;357242;358002;51401.97196056058;51584.74207629013;51637.483625163266;51663.74168129135;51885;1491.9179445130621;1538.7153376625583;1550.2403822127076;1551.8525740054677;1551.8603325700742;20236.19055237774;23769.16807911715;23937.922793474685;23940;23967.400814979766;38026.619733802305;38107.61892381152;38265.426018609185;38321.80839095786;38362.80818595889;298656;299393;299852;300635;303013;32549.511757323184;32613.836930815192;33637.32725345431;33662.83168584141;33762.83118584391;34664.653353467154;42338.15323693571;43380.34929475997;43574.56425435705;43743.78128109339;27832;29632.259193520335;30076;30169.396612068074;30272.394552109276;29294.70705293005;29523;30070.548941765453;30264.394712105193;30332.983874994112;34520.61917523271;35436.46845297374;35604.46593301155;35806.283874322886;35839.46240806338;29540.96606618758;30047.647855846975;30176.69823301739;30466.543001854545;30517.694823051486;21820.12719491266;22171.778282216972;22506.670369672982;24240.1515946941;24468.26595202147 19:21:18 INFO - PID 1634 | |2;jquery.html;431.48653102807674;439.33002171688173;443.76258701594634;458.65371644408475;460.81337058491283;19833.60332793345;21447.785522144775;21957.01193446295;22159.224427145055;22234.555308893854;21694.89152554239;22003.449913752163;22701.886490567565;22859.885700571474;23107.768922310774;5532.585056120807;6162.137300777895;6232.84417889553;6412.61524308543;6478.416942475176;6210.347913469089;6366.968165159168;6371.362863713614;6378.202724659418;6400.7119679614525;6181.505479561624;6266.905996410058;6306.1171435998995;6328.746850125989;6335.4614857736915;6345.777897773579;6371.745130194787;6380.808575742735;6385.96807015962;6393.456556192729;4721.834735784266;5467.9453205467835;5616.129499927518;5656.4060773618585;5665.093585026376;23045.308640740805;27720.306992325153;27782.583261251097;28080.578791318145;28510.572341414896;1980.8019198080076;2169.4684802223396;2243.450354663118;2246.1127854497545;2246.2138251611805;17283.481495555152;17991.73012404815;18586.53533661656;18613.34853280142;18734.250629974784;2077.9376618701463;2149.4841238102745;2166.5991791518463;2173.739151301854;2174.695542624034;162499;166678;166747.16626416912;168602.31397685988;169044;10423.739406514745;10664.880187580347;11008.504617292241;12635.810462843065;12772.489100435878;16475.528670699492;16772.664546709;16787;16798.664026719398;16839.32642694303;9060.229880460234;9265.305102117369;9276.257899368034;9279.62881484733;9345.90654093464;8913.955430222937;8915.866262006075;8932.240759535513;9037.502937338379;9120.81758364829;7920.207979202149;8075.3539716822515;8085.393595480363;8089.9191008089165;8094.595270236465;2166.9133234670435;2172.511184983366;2174.826013918914;2176.640854259061;2317.0305240545404;900.2572877376191;908.404994728454;910.9544522773834;926.0184204742994;926.9351145419756;16754.916225418794;20411.285605003755;21259.149634014466;21326.253581124587;21515.677264840728;850.6724910909221;971.9222462203007;974.8732664753537;990.5393991793851;1003.0721582536125;10151.390916545031;10876.510557024953;10898.746644135867;10910.908909109026;10911.526943862636;682814;698570;703846;713735;720329;112159.87840121657;132227;153066.23466882817;155922.6611600827;158164;1013.2501948558162;1084.6800193942806;1137.8407023016784;1177.5701868817991;1181.9244487516319;17193.484195474157;17603.911980440014;18063.2774689011;18263.908680456512;18446.800957937692;62407;62696.059559105735;63114.42213944504;63251.68374158284;64028.03957940541;68390.65804670945;85054.57472712598;85243;88293.55853220692;95664.52167739117;12836.422360993964;13141.73716525645;13255;13763.655908602364;13968.02223844321;14312.06971546846;14385;14620.926895365455;14733.55799326022;14796.260186990392;11914.344711040976;12161.905428511476;12302.323372214614;12312.445939932904;12357;9374.343795934219;11350.94324528372;11573.652790416301;11737.295762254293;12020.33888136161;12032.75934481323;12110.576129835414;12342.568010119603;12455.626331210078;12492.687682808002;13207.867921321049;13216.801747973981;13250;13571.1857288563;13578.592642220749;11942.641720748392;12001.237549651563;12232.327222002876;12257.26456412618;12283.69290767738 19:21:18 INFO - PID 1634 | |3;mootools.html;1195.784758743427;1346.0981142634434;1408.9292137975135;1410.3441899516722;1441.084911081463;11283.548658053678;11605.3036817791;11714.941425292873;11735.295882247061;11742.354170520639;11538.423078846066;11674.35791031493;11727;11744.765104697923;11808.055355571556;2472.8021758259342;2521.4830959653227;2524.9747502524924;2527.064985955199;2531.4810463854956;2451.4851881104987;2472.863992480413;2490.1160088168713;2504.0359461607322;2504.2362079565723;2474.8886300116446;2481.330040888959;2501.662275592799;2503.8622875741826;2506.323292710967;2405.9759402405925;2451.9264422067367;2467.444824914389;2471.159805666064;2485.825992180548;2412.1075202175307;2459.803215742754;2463.187148241076;2496.9762397417153;2500.749925007503;11507.597234096851;11538.769224615544;11654;11672.883271167264;11710;5638.971805140948;6482.773102941374;6633.2703402625675;6648.833779155511;6721.0254513095815;4013.2976729072298;4249.915001699979;4293.549177336397;4323.092150648367;4343.261645520269;1198.9100817438639;1210.8304837322783;1260.6974326161746;1265.8354413926222;1267.4930027988787;14456.060356076927;14840.703185936329;15801.051936883823;16205.35178592855;16211.837881621268;5841.824745257649;5947.910781338283;6060.2424696912885;6137.478314343241;6140.140380346755;10103.84844227337;13642.249676267702;13643.522476713268;13648;13683.589492315246;5904.350521442638;6121.959266924545;6740.730370785212;6751.189857217166;6768.289329620319;5064.5948324134715;5549.5837812163445;5719.456651618089;6046.27444706638;6237.313895471494;3593.964060359363;4674.69614475058;4701.553352431513;4703.529647035269;4759.452662943806;1417.3126033873564;1457.3733294683177;1462.9853701463062;1472.8674419302315;1474.3733913086894;734.4931996922213;747.1221314955014;764.6024067484988;765.5368502056176;791.181127533012;4253.787310634456;5907.438793314628;6107.541934354941;6160.7535698571555;6223.96888015557;568.5962966293978;580.1066357809011;585.7656937225144;585.8184359341617;598.6497898729292;4005.178938317638;4240.215560121387;4377.3433984901885;4482.529334419904;4644.883877903079;14533.34599942984;14914.850851491563;14980.026298290577;14985.250737463084;14992.850071499146;12164.817527736914;14860.479883204036;14895.77656335156;14901.925490372696;14917.850821491864;9250.583723732449;10497.9475102624;10770.192235582363;10783.6225732099;10788.676339709822;5745.253117094751;5773.451522105393;5968.940310596839;6787.812132876628;7062.223155452793;12969.870301297244;13263.535776247785;13422.530211442552;13476.191428514321;13503.527376541773;13455.663608409868;13470.595882123553;13536.526221582197;13629.25039122818;13738.65653358674;9307.348485606213;9471.715848524555;9476.289278304017;9577.329586928849;9612.855807162758;10265.486725663837;10485.423301718478;10653.573857045942;10700.571977120828;10727.731806704895;8856;8972.147645973622;9011.864822027806;9159.633614655606;9284.35009549352;8565.828683426422;8637.827243455222;8748.78128046804;8799.780005499913;8965.820683586422;6194.535409844189;8008.719694810654;8035.196480351918;8110.0673422557165;8146.75559733209;5988.37122102173;6008.1588577598295;6127.938720612737;6177.166082578974;6206.968965155145;5953.493953014043;6118.724657390517;6260.280067792262;6298.212723409574;6368.554201205871 19:21:18 INFO - PID 1634 | |4;prototype.html;316.66749912591786;380.5585520795877;401.48810266909703;409.77536410762775;418.4936227165132;5620.437956204382;6153.046277826933;6326.683665816714;6523.641199734013;6531.738730450788;5207.557357624606;5581.413951535092;5637.408072152426;6235.844103897405;6433.806985790434;4587.128445595343;4797.136515427222;4802.759862006903;4809.879753006167;4820.638452116088;4808.855734327975;4814.350062741538;4845.733484658343;4853.199222128363;4877.7318546875695;4630.212863813143;4736.834210802623;4755.262934245197;4780.952190478086;4799.976000119995;4741.099191153669;4744.4069491313585;4758.048390321915;4763.833265835697;4776.020915712271;4223.5142958559545;4232.746035237896;4233.269541145944;4234.703570750049;4236.766977816219;5964.910526342108;6157.507399408081;6212.1303017577575;6232.594881332699;6261.7182226799905;4980.178270585349;5394.514493695587;5675.886482270332;5706.657600543939;5710.2862142232225;5218.5825133989565;6415.743370265184;6586.411099414771;6856.6228857412825;6864.704941628046;3840.7503512271614;4554.0891821635805;4793.9280910786365;4816.157172494834;4818.518148185189;6014.488768454687;6457.740740555593;6946.479014073965;7062.293770622948;7142.928570714278;3567.090391950088;4895.608351331956;4958.553730164231;4972.105021096165;4996.5003499650475;5061.746912654353;5071.717727022855;5073.568746656576;5078.263651770547;5104.132297509433;4775.6657034007985;5215.452377500308;5281.471852814688;5671.6597004179885;5728.885422291531;5304.124819404832;5689.573282003867;6001.549883758736;6868.141482314711;6905.240423553405;1325.337331334333;1354.5462270139417;1593.8804589655822;1593.944211952576;1597.6085858964627;995.348047029197;1000.9699709008742;1039.2153923787491;1052.3528030261334;1055.8310670292717;5136.30659860914;5396.946030539723;5657;5686.118651609007;6425.293217746138;5830.198952042744;7065.788026359218;7078.115235595551;7081.291870812981;7115.608641524663;5570.275864137636;6383.840403989938;6405.871882562323;6409.262934762562;6448.613083215022;2964.762818974477;3406.403879321109;3665.248624032067;3692.981535092361;3699;5766.261026012694;5907.4683278505145;6255;6853.965730171317;7267.091613548307;6138.324784273726;6490.545661803629;7291.380232680175;7302.160251571031;7322.414206863438;5774.855628609318;6883.070785443901;7021.332973367522;7107.999999999896;7171.92828071723;5592.552595792408;5639.746211420578;6788.25329213796;6984.3364880336285;7004.124484439445;5713.600287879184;6530.281669016499;6597.901031484627;6703.899441508283;6790.354916282945;6129.478994285536;6657.434118100015;6906.825839607179;7079.2566780487305;7256.7822965309015;3813.4089216171537;4474.149911516932;4533.977330113328;4542.545745425431;4553.289069408422;5380.139177731544;5820;6829.590224586541;6989.95150727395;7134;5972.58191926561;6582.275949645439;6629.303923088007;6831.351021652935;6844.691988860613;6337.7148028337915;6532.1786754749;6733.663316834237;6857.142857142857;6905.965470172617;5672.177534257592;5967.552433567413;5976.432238937468;6027.4876635484725;6681.732730690718;5732.512736417451;6662.5348621010535;6750.86498270022;6803.659817009031;6822.420094292041;5513.669179849222;6659.833504162435;6665.467841515439;6668.83327916806;6683.498737594797 19:21:18 INFO - PID 1634 | |5;yui.html;750.9737159199427;793.5873345860152;838.1032295443878;843.9240468357847;844.3456321350956;15557.29992150353;16013.919930400347;16069.035857848537;16541.255643496042;16885.24016419261;13699.452021919136;14862.777058344134;15334;15487.148206848648;15518.146501942389;949.6343907595557;959.5346257065336;977.1401166973089;978.2760757039811;978.5938835383307;1011.4942528735633;1023.9539220735047;1024.810410074135;1027.321967501449;1029.2949329709172;1042.3016578892161;1060.872695276564;1060.8992145746142;1066.9306495077833;1068.0547715271962;1034.4207243943415;1046.26238501856;1059.8251288537383;1060.0459586372288;1060.543966094579;786.9016372953381;788.5702292250737;789.2186735132235;789.6643926331344;791.5171745235402;13724.176549407068;16976.74534881978;17035.06307153106;17088.31646734129;17300.134993250285;14678.779818302733;14845.238095238095;14878.052429227559;15072.246387680572;15088.622784430478;13156.079074464791;13289.269090200036;13334.266615336153;13441.731165376637;13475.663108422368;3272.9509057364157;3274.918127046819;3284.720798732111;3307.6361600224195;3316.403047451458;115432;123285;125835;126032;126285.73714262832;10245.641402550875;11605.402394875959;11658.18392712519;11928.821067683992;11973.880261197277;23819.523609527714;24125.638115428283;24361.14735984232;24437.511249774903;24545.318202269802;7380.9261907381315;7817.921820781833;8142.674293028213;8270.2556769891;8409.915900840913;4116.300228961084;4116.732412393185;4125.731827431207;4192.119654872511;4207.97896010518;4156.168766246702;4234;4247.723897946686;4274.764887931194;4276.87169384919;933.3746389918771;938.0478814003792;951;953.1088432315669;953.5041778275258;503.38335538964475;523.4477626104497;542.3085565903426;555.5500044963593;604.3020311540179;8855.690050848189;9308.348415610977;9950.303478756563;10413.53139108742;10477.633282835064;559.6558116758165;588.9146073819272;606.6178307666142;618.8019833653187;658.4337469775988;4693.530646935347;4695.835645752382;4717.339572459859;4724.385829842167;4779.784909679072;103169.48415257876;106862;107165;107653;110654;92638;93823.53088234515;95813.08373832484;96306;97078.89434926336;61892;80972;81671.5916420426;81750;82894;9429.669961551313;9530.758812328448;9607.51962401891;9674.467904265333;9706.6117355305;42870.57129428666;43421.348679769195;43488.78255608702;43907.46323878649;44055;51628;56459.459324329684;57059.71470142623;57086.714566426905;57367;13452.26012569279;13477.932610336886;13642.658933526742;13684.52104176349;13785.310734463437;14596.85403145955;14640.853591463949;14799.260036997892;15086.849131508545;15088.24558772079;13659.726805463637;13911.791323129959;13936;14145.929270353583;14534;14056;14237.715245695235;14325;14483;14958.40166393332;13366.732665346834;13538.796918046433;13691;13692.794608081085;14581.781273280698;9950;10019.649312274036;10217.438040907822;10344.844827327746;10483.842985243236;11146.49840757184;11235;11392.373419462008;11397.886021140013;11444.71388215268 19:21:18 INFO - PID 1634 | __end_tp_report 19:21:18 INFO - PID 1634 | __start_cc_report 19:21:18 INFO - PID 1634 | _x_x_mozilla_cycle_collect,425 19:21:18 INFO - PID 1634 | __end_cc_report 19:21:18 INFO - PID 1634 | __startTimestamp1491531678221__endTimestamp 19:21:18 INFO - PID 1634 | 19:21:18 INFO - PID 1634 | ------- Summary: start ------- 19:21:18 INFO - PID 1634 | Number of tests: 6 19:21:18 INFO - PID 1634 | 19:21:18 INFO - PID 1634 | [#0] dojo.html Cycles:165 Average:6148.16 Median:6331.46 stddev:738.67 (11.7%) stddev-sans-first:711.81 19:21:18 INFO - PID 1634 | Values: 3530.0 3585.0 3602.9 3612.0 3613.0 6120.1 6228.6 6240.2 6277.4 6297.6 6217.8 6247.3 6252.7 6263.3 6331.5 5533.6 5537.8 5560.2 5588.6 5656.1 5473.4 5567.3 5670.3 5681.4 5688.7 5403.1 5492.6 5572.5 5578.6 5638.7 5507.1 5517.7 5521.1 5546.2 5585.7 5477.3 5485.1 5486.3 5489.4 5512.1 6046.7 6160.1 6186.1 6215.7 6248.3 6728.6 6773.2 6782.2 6787.6 6793.6 6834.1 6862.7 6871.9 6873.3 6877.9 6245.9 6261.6 6339.9 6344.4 6364.9 5845.8 6915.6 6918.4 6963.1 6979.7 5639.7 6117.2 6130.0 6586.6 6663.5 5963.4 6708.3 6814.8 6854.1 6889.2 6415.6 6549.1 6769.1 6773.1 6783.1 6461.5 6512.3 6702.7 6744.5 6786.4 5389.5 5394.9 5400.2 5409.9 5426.2 3952.8 4817.7 4839.5 4844.1 4878.2 5632.9 5925.6 6193.4 6277.9 6301.0 6864.6 6867.4 6869.2 6886.0 6900.7 6227.0 6342.8 6351.9 6370.6 6390.2 5763.2 5804.1 5825.6 5837.0 6040.5 6876.0 6987.4 6997.4 7025.0 7028.3 5170.0 6034.2 6044.5 6246.9 6886.3 6645.2 6703.5 6823.9 7022.3 7026.8 4801.8 5532.9 6637.7 6678.1 6693.9 5456.0 5706.4 6806.1 6831.0 6838.9 6393.5 6420.2 6636.3 6641.0 6642.7 6375.3 6387.1 6595.8 6636.9 6671.7 5681.9 6454.1 6483.0 6489.2 6509.1 6661.1 6666.9 6677.3 6682.3 6683.2 6262.3 6467.5 6503.0 6508.1 6515.5 19:21:18 INFO - PID 1634 | 19:21:18 INFO - PID 1634 | [#1] ext.html Cycles:180 Average:44448.41 Median:185319.92 stddev:78852.00 (42.5%) stddev-sans-first:79004.74 19:21:18 INFO - PID 1634 | Values: 686.2 742.8 833.3 1241.1 1281.1 37674.4 38176.4 38181.2 38237.9 38244.0 37619.0 37950.6 38047.2 38260.0 38304.0 7965.7 8115.6 8489.2 8679.6 8762.4 8561.1 8610.0 8654.2 8671.7 8679.5 8601.2 8622.8 8667.7 8694.4 8699.5 8568.9 8618.7 8653.5 8660.9 8663.2 8452.5 8457.7 8505.7 8510.7 8521.4 37533.5 37605.8 37653.2 37660.8 37680.4 3063.3 3460.9 3461.7 3467.5 3506.4 30008.5 30251.5 30364.5 30385.2 30477.7 3059.8 3108.8 3130.1 3138.0 3146.9 230042.0 235423.0 255378.0 264481.0 265382.0 47676.0 47694.5 47729.8 47733.8 47735.0 35535.5 40904.2 49447.3 49641.8 49843.8 14441.5 14547.6 14680.5 14680.6 14688.9 11967.0 11980.2 11994.0 12011.3 12055.5 12067.6 12524.6 13436.5 13458.5 13465.5 5470.8 5552.8 5875.3 5901.9 5913.1 889.6 984.0 991.2 1012.9 1025.9 23592.9 23641.9 23716.3 23718.6 23807.5 1082.4 1092.2 1094.9 1109.6 1140.2 10136.7 12089.6 12240.6 12247.1 12309.3 302300.0 336877.0 356374.6 357242.0 358002.0 51402.0 51584.7 51637.5 51663.7 51885.0 1491.9 1538.7 1550.2 1551.9 1551.9 20236.2 23769.2 23937.9 23940.0 23967.4 38026.6 38107.6 38265.4 38321.8 38362.8 298656.0 299393.0 299852.0 300635.0 303013.0 32549.5 32613.8 33637.3 33662.8 33762.8 34664.7 42338.2 43380.3 43574.6 43743.8 27832.0 29632.3 30076.0 30169.4 30272.4 29294.7 29523.0 30070.5 30264.4 30333.0 34520.6 35436.5 35604.5 35806.3 35839.5 29541.0 30047.6 30176.7 30466.5 30517.7 21820.1 22171.8 22506.7 24240.2 24468.3 19:21:18 INFO - PID 1634 | 19:21:18 INFO - PID 1634 | [#2] jquery.html Cycles:180 Average:41193.16 Median:2174.22 stddev:118055.66 (5429.8%) stddev-sans-first:118347.16 19:21:18 INFO - PID 1634 | Values: 431.5 439.3 443.8 458.7 460.8 19833.6 21447.8 21957.0 22159.2 22234.6 21694.9 22003.4 22701.9 22859.9 23107.8 5532.6 6162.1 6232.8 6412.6 6478.4 6210.3 6367.0 6371.4 6378.2 6400.7 6181.5 6266.9 6306.1 6328.7 6335.5 6345.8 6371.7 6380.8 6386.0 6393.5 4721.8 5467.9 5616.1 5656.4 5665.1 23045.3 27720.3 27782.6 28080.6 28510.6 1980.8 2169.5 2243.5 2246.1 2246.2 17283.5 17991.7 18586.5 18613.3 18734.3 2077.9 2149.5 2166.6 2173.7 2174.7 162499.0 166678.0 166747.2 168602.3 169044.0 10423.7 10664.9 11008.5 12635.8 12772.5 16475.5 16772.7 16787.0 16798.7 16839.3 9060.2 9265.3 9276.3 9279.6 9345.9 8914.0 8915.9 8932.2 9037.5 9120.8 7920.2 8075.4 8085.4 8089.9 8094.6 2166.9 2172.5 2174.8 2176.6 2317.0 900.3 908.4 911.0 926.0 926.9 16754.9 20411.3 21259.1 21326.3 21515.7 850.7 971.9 974.9 990.5 1003.1 10151.4 10876.5 10898.7 10910.9 10911.5 682814.0 698570.0 703846.0 713735.0 720329.0 112159.9 132227.0 153066.2 155922.7 158164.0 1013.3 1084.7 1137.8 1177.6 1181.9 17193.5 17603.9 18063.3 18263.9 18446.8 62407.0 62696.1 63114.4 63251.7 64028.0 68390.7 85054.6 85243.0 88293.6 95664.5 12836.4 13141.7 13255.0 13763.7 13968.0 14312.1 14385.0 14620.9 14733.6 14796.3 11914.3 12161.9 12302.3 12312.4 12357.0 9374.3 11350.9 11573.7 11737.3 12020.3 12032.8 12110.6 12342.6 12455.6 12492.7 13207.9 13216.8 13250.0 13571.2 13578.6 11942.6 12001.2 12232.3 12257.3 12283.7 19:21:18 INFO - PID 1634 | 19:21:18 INFO - PID 1634 | [#3] mootools.html Cycles:180 Average:7091.84 Median:2523.23 stddev:4489.83 (177.9%) stddev-sans-first:4480.56 19:21:18 INFO - PID 1634 | Values: 1195.8 1346.1 1408.9 1410.3 1441.1 11283.5 11605.3 11714.9 11735.3 11742.4 11538.4 11674.4 11727.0 11744.8 11808.1 2472.8 2521.5 2525.0 2527.1 2531.5 2451.5 2472.9 2490.1 2504.0 2504.2 2474.9 2481.3 2501.7 2503.9 2506.3 2406.0 2451.9 2467.4 2471.2 2485.8 2412.1 2459.8 2463.2 2497.0 2500.7 11507.6 11538.8 11654.0 11672.9 11710.0 5639.0 6482.8 6633.3 6648.8 6721.0 4013.3 4249.9 4293.5 4323.1 4343.3 1198.9 1210.8 1260.7 1265.8 1267.5 14456.1 14840.7 15801.1 16205.4 16211.8 5841.8 5947.9 6060.2 6137.5 6140.1 10103.8 13642.2 13643.5 13648.0 13683.6 5904.4 6122.0 6740.7 6751.2 6768.3 5064.6 5549.6 5719.5 6046.3 6237.3 3594.0 4674.7 4701.6 4703.5 4759.5 1417.3 1457.4 1463.0 1472.9 1474.4 734.5 747.1 764.6 765.5 791.2 4253.8 5907.4 6107.5 6160.8 6224.0 568.6 580.1 585.8 585.8 598.6 4005.2 4240.2 4377.3 4482.5 4644.9 14533.3 14914.9 14980.0 14985.3 14992.9 12164.8 14860.5 14895.8 14901.9 14917.9 9250.6 10497.9 10770.2 10783.6 10788.7 5745.3 5773.5 5968.9 6787.8 7062.2 12969.9 13263.5 13422.5 13476.2 13503.5 13455.7 13470.6 13536.5 13629.3 13738.7 9307.3 9471.7 9476.3 9577.3 9612.9 10265.5 10485.4 10653.6 10700.6 10727.7 8856.0 8972.1 9011.9 9159.6 9284.4 8565.8 8637.8 8748.8 8799.8 8965.8 6194.5 8008.7 8035.2 8110.1 8146.8 5988.4 6008.2 6127.9 6177.2 6207.0 5953.5 6118.7 6260.3 6298.2 6368.6 19:21:18 INFO - PID 1634 | 19:21:18 INFO - PID 1634 | [#4] prototype.html Cycles:180 Average:5388.85 Median:5721.24 stddev:1652.03 (28.9%) stddev-sans-first:1612.20 19:21:18 INFO - PID 1634 | Values: 316.7 380.6 401.5 409.8 418.5 5620.4 6153.0 6326.7 6523.6 6531.7 5207.6 5581.4 5637.4 6235.8 6433.8 4587.1 4797.1 4802.8 4809.9 4820.6 4808.9 4814.4 4845.7 4853.2 4877.7 4630.2 4736.8 4755.3 4781.0 4800.0 4741.1 4744.4 4758.0 4763.8 4776.0 4223.5 4232.7 4233.3 4234.7 4236.8 5964.9 6157.5 6212.1 6232.6 6261.7 4980.2 5394.5 5675.9 5706.7 5710.3 5218.6 6415.7 6586.4 6856.6 6864.7 3840.8 4554.1 4793.9 4816.2 4818.5 6014.5 6457.7 6946.5 7062.3 7142.9 3567.1 4895.6 4958.6 4972.1 4996.5 5061.7 5071.7 5073.6 5078.3 5104.1 4775.7 5215.5 5281.5 5671.7 5728.9 5304.1 5689.6 6001.5 6868.1 6905.2 1325.3 1354.5 1593.9 1593.9 1597.6 995.3 1001.0 1039.2 1052.4 1055.8 5136.3 5396.9 5657.0 5686.1 6425.3 5830.2 7065.8 7078.1 7081.3 7115.6 5570.3 6383.8 6405.9 6409.3 6448.6 2964.8 3406.4 3665.2 3693.0 3699.0 5766.3 5907.5 6255.0 6854.0 7267.1 6138.3 6490.5 7291.4 7302.2 7322.4 5774.9 6883.1 7021.3 7108.0 7171.9 5592.6 5639.7 6788.3 6984.3 7004.1 5713.6 6530.3 6597.9 6703.9 6790.4 6129.5 6657.4 6906.8 7079.3 7256.8 3813.4 4474.1 4534.0 4542.5 4553.3 5380.1 5820.0 6829.6 6990.0 7134.0 5972.6 6582.3 6629.3 6831.4 6844.7 6337.7 6532.2 6733.7 6857.1 6906.0 5672.2 5967.6 5976.4 6027.5 6681.7 5732.5 6662.5 6750.9 6803.7 6822.4 5513.7 6659.8 6665.5 6668.8 6683.5 19:21:18 INFO - PID 1634 | 19:21:18 INFO - PID 1634 | [#5] yui.html Cycles:180 Average:21064.64 Median:20559.83 stddev:30991.75 (150.7%) stddev-sans-first:31041.16 19:21:18 INFO - PID 1634 | Values: 751.0 793.6 838.1 843.9 844.3 15557.3 16013.9 16069.0 16541.3 16885.2 13699.5 14862.8 15334.0 15487.1 15518.1 949.6 959.5 977.1 978.3 978.6 1011.5 1024.0 1024.8 1027.3 1029.3 1042.3 1060.9 1060.9 1066.9 1068.1 1034.4 1046.3 1059.8 1060.0 1060.5 786.9 788.6 789.2 789.7 791.5 13724.2 16976.7 17035.1 17088.3 17300.1 14678.8 14845.2 14878.1 15072.2 15088.6 13156.1 13289.3 13334.3 13441.7 13475.7 3273.0 3274.9 3284.7 3307.6 3316.4 115432.0 123285.0 125835.0 126032.0 126285.7 10245.6 11605.4 11658.2 11928.8 11973.9 23819.5 24125.6 24361.1 24437.5 24545.3 7380.9 7817.9 8142.7 8270.3 8409.9 4116.3 4116.7 4125.7 4192.1 4208.0 4156.2 4234.0 4247.7 4274.8 4276.9 933.4 938.0 951.0 953.1 953.5 503.4 523.4 542.3 555.6 604.3 8855.7 9308.3 9950.3 10413.5 10477.6 559.7 588.9 606.6 618.8 658.4 4693.5 4695.8 4717.3 4724.4 4779.8 103169.5 106862.0 107165.0 107653.0 110654.0 92638.0 93823.5 95813.1 96306.0 97078.9 61892.0 80972.0 81671.6 81750.0 82894.0 9429.7 9530.8 9607.5 9674.5 9706.6 42870.6 43421.3 43488.8 43907.5 44055.0 51628.0 56459.5 57059.7 57086.7 57367.0 13452.3 13477.9 13642.7 13684.5 13785.3 14596.9 14640.9 14799.3 15086.8 15088.2 13659.7 13911.8 13936.0 14145.9 14534.0 14056.0 14237.7 14325.0 14483.0 14958.4 13366.7 13538.8 13691.0 13692.8 14581.8 9950.0 10019.6 10217.4 10344.8 10483.8 11146.5 11235.0 11392.4 11397.9 11444.7 19:21:18 INFO - PID 1634 | -------- Summary: end -------- 19:21:18 INFO - PID 1634 | 19:21:18 INFO - PID 1634 | 1491531678250 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 19:21:18 INFO - TEST-INFO | 1634: exit 0 19:21:18 INFO - TEST-OK | dromaeo_css | took 1117692ms 19:21:18 INFO - TEST-START | kraken 19:21:18 INFO - operating with platform_type : mac_ 19:21:18 INFO - Initialising browser for kraken test... 19:21:18 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmp84K_mz/profile http://localhost:49241/getInfo.html) 19:21:19 INFO - PID 1645 | 1491531679530 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 19:21:19 INFO - PID 1645 | 1491531679531 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 19:21:19 INFO - PID 1645 | 1491531679597 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 19:21:21 INFO - PID 1645 | 2017-04-06 19:21:21.792 plugin-container[1646:10368] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 19:21:21 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:21:21 INFO - PID 1645 | 2017-04-06 19:21:21.795 plugin-container[1646:10368] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 19:21:21 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:21:21 INFO - PID 1645 | 2017-04-06 19:21:21.795 plugin-container[1646:10368] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:21:21 INFO - PID 1645 | __metrics Screen width/height:1600/1200 19:21:21 INFO - PID 1645 | colorDepth:24 19:21:21 INFO - PID 1645 | Browser inner width/height: 1024/689 19:21:21 INFO - PID 1645 | __metrics 19:21:21 ERROR - PID 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 19:21:21 INFO - PID 1645 | 1491531681965 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 19:21:22 INFO - TEST-INFO | 1645: exit 0 19:21:22 INFO - Browser initialized. 19:21:22 INFO - Running cycle 1/1 for kraken test... 19:21:22 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmp84K_mz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:21:23 INFO - PID 1647 | 1491531683344 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 19:21:23 INFO - PID 1647 | 1491531683345 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 19:21:25 INFO - PID 1647 | 2017-04-06 19:21:25.577 plugin-container[1648:10496] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 19:21:25 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:21:25 INFO - PID 1647 | 2017-04-06 19:21:25.578 plugin-container[1648:10496] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 19:21:25 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:21:25 INFO - PID 1647 | 2017-04-06 19:21:25.578 plugin-container[1648:10496] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:21:35 INFO - PID 1647 | RSS: Main: 235433984 19:21:35 INFO - PID 1647 | 19:21:37 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 19:21:38 INFO - PID 1647 | RSS: Main: 250548224 19:21:38 INFO - PID 1647 | 19:21:41 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 19:21:42 INFO - PID 1647 | RSS: Main: 258981888 19:21:42 INFO - PID 1647 | 19:21:45 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 19:21:46 INFO - PID 1647 | RSS: Main: 260956160 19:21:46 INFO - PID 1647 | 19:21:48 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 19:21:49 INFO - PID 1647 | RSS: Main: 262053888 19:21:49 INFO - PID 1647 | 19:21:51 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:21:51 INFO - PID 1647 | RSS: Main: 263733248 19:21:51 INFO - PID 1647 | 19:21:55 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 19:21:55 INFO - PID 1647 | RSS: Main: 268935168 19:21:55 INFO - PID 1647 | 19:21:59 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 19:21:59 INFO - PID 1647 | RSS: Main: 269426688 19:21:59 INFO - PID 1647 | 19:22:02 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 19:22:03 INFO - PID 1647 | RSS: Main: 269537280 19:22:03 INFO - PID 1647 | 19:22:05 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:22:05 INFO - PID 1647 | RSS: Main: 269115392 19:22:05 INFO - PID 1647 | 19:22:07 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:22:08 INFO - PID 1647 | RSS: Main: 269148160 19:22:08 INFO - PID 1647 | 19:22:10 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:22:11 INFO - PID 1647 | RSS: Main: 269176832 19:22:11 INFO - PID 1647 | 19:22:14 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:22:15 INFO - PID 1647 | RSS: Main: 269635584 19:22:15 INFO - PID 1647 | 19:22:18 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:22:19 INFO - PID 1647 | RSS: Main: 266698752 19:22:19 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 19:22:21 INFO - PID 1647 | RSS: Main: 269737984 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | __start_tp_report 19:22:21 INFO - PID 1647 | _x_x_mozilla_page_load 19:22:21 INFO - PID 1647 | _x_x_mozilla_page_load_details 19:22:21 INFO - PID 1647 | |i|pagename|runs| 19:22:21 INFO - PID 1647 | |0;ai-astar;111;122;121;121;123;120;123;123;121;122 19:22:21 INFO - PID 1647 | |1;audio-beat-detection;153;116;119;156;121;115;133;138;120;155 19:22:21 INFO - PID 1647 | |2;audio-dft;213;221;224;231;220;234;216;316;216;223 19:22:21 INFO - PID 1647 | |3;audio-fft;82;61;59;88;61;57;58;62;97;92 19:22:21 INFO - PID 1647 | |4;audio-oscillator;90;73;87;73;73;80;124;108;109;70 19:22:21 INFO - PID 1647 | |5;imaging-gaussian-blur;90;91;142;106;105;145;119;115;81;142 19:22:21 INFO - PID 1647 | |6;imaging-darkroom;100;101;127;160;131;95;96;160;118;111 19:22:21 INFO - PID 1647 | |7;imaging-desaturate;95;102;99;99;102;101;101;118;101;103 19:22:21 INFO - PID 1647 | |8;json-parse-financial;54;52;53;54;56;73;82;80;58;54 19:22:21 INFO - PID 1647 | |9;json-stringify-tinderbox;61;62;61;72;87;60;66;78;90;89 19:22:21 INFO - PID 1647 | |10;stanford-crypto-aes;89;87;91;80;113;89;103;78;104;113 19:22:21 INFO - PID 1647 | |11;stanford-crypto-ccm;168;167;145;147;156;166;181;173;209;173 19:22:21 INFO - PID 1647 | |12;stanford-crypto-pbkdf2;197;199;196;183;194;195;192;202;231;262 19:22:21 INFO - PID 1647 | |13;stanford-crypto-sha256-iterative;70;71;71;72;75;74;71;69;70;73 19:22:21 INFO - PID 1647 | __end_tp_report 19:22:21 INFO - PID 1647 | __start_cc_report 19:22:21 INFO - PID 1647 | _x_x_mozilla_cycle_collect,1017 19:22:21 INFO - PID 1647 | __end_cc_report 19:22:21 INFO - PID 1647 | __startTimestamp1491531741355__endTimestamp 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | ------- Summary: start ------- 19:22:21 INFO - PID 1647 | Number of tests: 14 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#0] ai-astar Cycles:10 Average:120.70 Median:122.00 stddev:3.56 (2.9%) stddev-sans-first:1.09 19:22:21 INFO - PID 1647 | Values: 111.0 122.0 121.0 121.0 123.0 120.0 123.0 123.0 121.0 122.0 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:135.50 stddev:16.86 (12.4%) stddev-sans-first:16.19 19:22:21 INFO - PID 1647 | Values: 153.0 116.0 119.0 156.0 121.0 115.0 133.0 138.0 120.0 155.0 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#2] audio-dft Cycles:10 Average:231.40 Median:223.50 stddev:30.44 (13.6%) stddev-sans-first:31.55 19:22:21 INFO - PID 1647 | Values: 213.0 221.0 224.0 231.0 220.0 234.0 216.0 316.0 216.0 223.0 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#3] audio-fft Cycles:10 Average:71.70 Median:72.00 stddev:16.03 (22.3%) stddev-sans-first:16.56 19:22:21 INFO - PID 1647 | Values: 82.0 61.0 59.0 88.0 61.0 57.0 58.0 62.0 97.0 92.0 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:73.00 stddev:18.86 (25.8%) stddev-sans-first:19.99 19:22:21 INFO - PID 1647 | Values: 90.0 73.0 87.0 73.0 73.0 80.0 124.0 108.0 109.0 70.0 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:143.50 stddev:23.32 (16.2%) stddev-sans-first:23.11 19:22:21 INFO - PID 1647 | Values: 90.0 91.0 142.0 106.0 105.0 145.0 119.0 115.0 81.0 142.0 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#6] imaging-darkroom Cycles:10 Average:119.90 Median:145.50 stddev:24.53 (16.9%) stddev-sans-first:24.94 19:22:21 INFO - PID 1647 | Values: 100.0 101.0 127.0 160.0 131.0 95.0 96.0 160.0 118.0 111.0 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#7] imaging-desaturate Cycles:10 Average:102.10 Median:110.50 stddev:6.03 (5.5%) stddev-sans-first:5.82 19:22:21 INFO - PID 1647 | Values: 95.0 102.0 99.0 99.0 102.0 101.0 101.0 118.0 101.0 103.0 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#8] json-parse-financial Cycles:10 Average:61.60 Median:57.00 stddev:11.87 (20.8%) stddev-sans-first:12.27 19:22:21 INFO - PID 1647 | Values: 54.0 52.0 53.0 54.0 56.0 73.0 82.0 80.0 58.0 54.0 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:75.00 stddev:12.44 (16.6%) stddev-sans-first:12.46 19:22:21 INFO - PID 1647 | Values: 61.0 62.0 61.0 72.0 87.0 60.0 66.0 78.0 90.0 89.0 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#10] stanford-crypto-aes Cycles:10 Average:94.70 Median:83.50 stddev:12.73 (15.2%) stddev-sans-first:13.33 19:22:21 INFO - PID 1647 | Values: 89.0 87.0 91.0 80.0 113.0 89.0 103.0 78.0 104.0 113.0 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:168.50 Median:170.50 stddev:18.31 (10.7%) stddev-sans-first:19.42 19:22:21 INFO - PID 1647 | Values: 168.0 167.0 145.0 147.0 156.0 166.0 181.0 173.0 209.0 173.0 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.10 Median:198.00 stddev:23.54 (11.9%) stddev-sans-first:24.79 19:22:21 INFO - PID 1647 | Values: 197.0 199.0 196.0 183.0 194.0 195.0 192.0 202.0 231.0 262.0 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:71.50 stddev:1.90 (2.7%) stddev-sans-first:1.92 19:22:21 INFO - PID 1647 | Values: 70.0 71.0 71.0 72.0 75.0 74.0 71.0 69.0 70.0 73.0 19:22:21 INFO - PID 1647 | -------- Summary: end -------- 19:22:21 INFO - PID 1647 | 19:22:21 INFO - PID 1647 | 1491531741381 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 19:22:22 INFO - TEST-INFO | 1647: exit 0 19:22:22 INFO - TEST-OK | kraken | took 63533ms 19:22:22 INFO - SUITE-END | took 1181s 19:22:22 INFO - Completed test suite (00:19:41) 19:22:22 INFO - javascript benchmark 19:22:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7844.677197960507, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3530.046887340418, 3585.0320413488344, 3602.8919132426036, 3611.9638803611956, 3612.981935090324, 6120.111926550693, 6228.595141315817, 6240.2199725034425, 6277.435030847223, 6297.622142671443, 6217.844553886144, 6247.250329960411, 6252.749890004406, 6263.279722831876, 6331.461825744817, 5533.6403133796375, 5537.750801213955, 5560.193771903072, 5588.636738611977, 5656.095024796034, 5473.397926228112, 5567.331920169565, 5670.262865827437, 5681.380806470157, 5688.744006519716, 5403.054465468527, 5492.615516913777, 5572.498475137217, 5578.609497335188, 5638.661680299195, 5507.146392309199, 5517.668939863581, 5521.1442226454565, 5546.168074788774, 5585.664860108406, 5477.315335583052, 5485.149801780731, 5486.31421072366, 5489.39616642169, 5512.090505066659, 6046.72789724459, 6160.075988601746, 6186.133941248228, 6215.658138802364, 6248.28144763349, 6728.596284222962, 6773.221079575863, 6782.1861376635115, 6787.592744435349, 6793.626350550767, 6834.1457317835275, 6862.725490980355, 6871.931280687229, 6873.2783057778715, 6877.931220687779, 6245.937540624535, 6261.624302541862, 6339.904901426482, 6344.429001389897, 6364.872702545923, 5845.766169353264, 6915.550489218285, 6918.446524278046, 6963.1296087989, 6979.651017449209, 5639.689817060101, 6117.174181485531, 6129.970128114947, 6586.571872828251, 6663.5335526513645, 5963.4334738199805, 6708.2956289589865, 6814.761483348059, 6854.10896583441, 6889.207741109736, 6415.647139407284, 6549.083128362033, 6769.120014398097, 6773.085633439521, 6783.118194634764, 6461.547691661539, 6512.316206798313, 6702.731890724414, 6744.527883048139, 6786.423154031864, 5389.51494365509, 5394.865128371822, 5400.162974738922, 5409.864753381197, 5426.158945363475, 3952.8418863245724, 4817.7350245736825, 4839.491853355417, 4844.103840789465, 4878.243872199744, 5632.887342253132, 5925.644461332334, 6193.4425901669065, 6277.937220627735, 6301.0, 6864.553804002724, 6867.416269617039, 6869.175698916162, 6886.0, 6900.654967251618, 6227.044244264118, 6342.809715708536, 6351.872962540723, 6370.55406121567, 6390.233172019294, 5763.164341170507, 5804.071348584206, 5825.592208545361, 5837.036888913367, 6040.516758659297, 6875.965620171767, 6987.441004719712, 6997.440204783707, 7024.964875175796, 7028.262032486719, 5169.974150129226, 6034.24571928509, 6044.486218671286, 6246.938020536611, 6886.311368863074, 6645.169353830771, 6703.463722902058, 6823.897641535282, 7022.332878376546, 7026.789196324118, 4801.831935882227, 5532.889342213214, 6637.70130344126, 6678.131842860397, 6693.866122677617, 5455.972720136374, 5706.42935706426, 6806.115205023315, 6830.965845170742, 6838.931610684029, 6393.520485963664, 6420.1974753155855, 6636.27001029896, 6640.966795165993, 6642.734290628514, 6375.330590287953, 6387.073874288109, 6595.835104122435, 6636.867262654817, 6671.66641667905, 5681.8863622726485, 6454.128692626436, 6483.0, 6489.156409666713, 6509.088727578048, 6661.067450556829, 6666.899996500153, 6677.2655007948115, 6682.331766823473, 6683.164604424447, 6262.34245404226, 6467.450266727193, 6503.0, 6508.121403610452, 6515.478761699147], "value": 6102.17633600705, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.2108575138591, 742.792018234894, 833.2792134803395, 1241.1002023532944, 1281.0520215040867, 37674.43488347674, 38176.427353589715, 38181.23637527248, 38237.852864414046, 38243.99999999996, 37619.0, 37950.62049379505, 38047.239055218946, 38260.0, 38304.0, 7965.721199757995, 8115.594220288992, 8489.23596876281, 8679.566021698922, 8762.430442021248, 8561.101084386144, 8609.95695021524, 8654.22112009919, 8671.653133874637, 8679.522626255553, 8601.225889669928, 8622.784430389227, 8667.69663061793, 8694.39139260252, 8699.478031318142, 8568.914310856906, 8618.65525378984, 8653.524056176908, 8660.8700869487, 8663.220310172115, 8452.535110568917, 8457.703980360659, 8505.74482765518, 8510.74467765968, 8521.36089793268, 37533.49866005356, 37605.81197093997, 37653.24693506142, 37660.81169594162, 37680.43479347812, 3063.2954420483193, 3460.9480857787153, 3461.6538346165435, 3467.549218601591, 3506.351325004857, 30008.54987175194, 30251.546226806615, 30364.54453183204, 30385.24036899073, 30477.695223047707, 3059.770517211196, 3108.782385233035, 3130.1392117167734, 3138.0, 3146.921326966844, 230042.0, 235423.0, 255378.0, 264481.0, 265382.0, 47676.0, 47694.52305476901, 47729.761351193025, 47733.76133119312, 47735.0, 35535.466967995504, 40904.181916362104, 49447.25829112566, 49641.751791241535, 49843.750781245864, 14441.49454769078, 14547.636309092357, 14680.486182983544, 14680.632984175267, 14688.926555367154, 11967.0, 11980.161388702882, 11994.040476761838, 12011.339376334385, 12055.457504412323, 12067.637970860887, 12524.624261272176, 13436.462541498426, 13458.52895148665, 13465.461381544825, 5470.808521701801, 5552.750631108049, 5875.265591801025, 5901.911471327933, 5913.113033044991, 889.6130183370254, 983.9852402213972, 991.2268430624125, 1012.8632634594236, 1025.876894772632, 23592.882035590057, 23641.85260277516, 23716.288511344686, 23718.644220336708, 23807.523849522913, 1082.3992684060422, 1092.2136062035322, 1094.9288296260718, 1109.5506319940275, 1140.1562843495753, 10136.695899123039, 12089.57686480969, 12240.632781016584, 12247.142700010914, 12309.322987235788, 302300.0, 336877.0, 356374.61752294947, 357242.0, 358002.0, 51401.97196056058, 51584.74207629013, 51637.483625163266, 51663.74168129135, 51885.0, 1491.9179445130621, 1538.7153376625583, 1550.2403822127076, 1551.8525740054677, 1551.8603325700742, 20236.19055237774, 23769.16807911715, 23937.922793474685, 23940.0, 23967.400814979766, 38026.619733802305, 38107.61892381152, 38265.426018609185, 38321.80839095786, 38362.80818595889, 298656.0, 299393.0, 299852.0, 300635.0, 303013.0, 32549.511757323184, 32613.836930815192, 33637.32725345431, 33662.83168584141, 33762.83118584391, 34664.653353467154, 42338.15323693571, 43380.34929475997, 43574.56425435705, 43743.78128109339, 27832.0, 29632.259193520335, 30076.0, 30169.396612068074, 30272.394552109276, 29294.70705293005, 29523.0, 30070.548941765453, 30264.394712105193, 30332.983874994112, 34520.61917523271, 35436.46845297374, 35604.46593301155, 35806.283874322886, 35839.46240806338, 29540.96606618758, 30047.647855846975, 30176.69823301739, 30466.543001854545, 30517.694823051486, 21820.12719491266, 22171.778282216972, 22506.670369672982, 24240.1515946941, 24468.26595202147], "value": 17281.85847256844, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.48653102807674, 439.33002171688173, 443.76258701594634, 458.65371644408475, 460.81337058491283, 19833.60332793345, 21447.785522144775, 21957.01193446295, 22159.224427145055, 22234.555308893854, 21694.89152554239, 22003.449913752163, 22701.886490567565, 22859.885700571474, 23107.768922310774, 5532.585056120807, 6162.137300777895, 6232.84417889553, 6412.61524308543, 6478.416942475176, 6210.347913469089, 6366.968165159168, 6371.362863713614, 6378.202724659418, 6400.7119679614525, 6181.505479561624, 6266.905996410058, 6306.1171435998995, 6328.746850125989, 6335.4614857736915, 6345.777897773579, 6371.745130194787, 6380.808575742735, 6385.96807015962, 6393.456556192729, 4721.834735784266, 5467.9453205467835, 5616.129499927518, 5656.4060773618585, 5665.093585026376, 23045.308640740805, 27720.306992325153, 27782.583261251097, 28080.578791318145, 28510.572341414896, 1980.8019198080076, 2169.4684802223396, 2243.450354663118, 2246.1127854497545, 2246.2138251611805, 17283.481495555152, 17991.73012404815, 18586.53533661656, 18613.34853280142, 18734.250629974784, 2077.9376618701463, 2149.4841238102745, 2166.5991791518463, 2173.739151301854, 2174.695542624034, 162499.0, 166678.0, 166747.16626416912, 168602.31397685988, 169044.0, 10423.739406514745, 10664.880187580347, 11008.504617292241, 12635.810462843065, 12772.489100435878, 16475.528670699492, 16772.664546709, 16787.0, 16798.664026719398, 16839.32642694303, 9060.229880460234, 9265.305102117369, 9276.257899368034, 9279.62881484733, 9345.90654093464, 8913.955430222937, 8915.866262006075, 8932.240759535513, 9037.502937338379, 9120.81758364829, 7920.207979202149, 8075.3539716822515, 8085.393595480363, 8089.9191008089165, 8094.595270236465, 2166.9133234670435, 2172.511184983366, 2174.826013918914, 2176.640854259061, 2317.0305240545404, 900.2572877376191, 908.404994728454, 910.9544522773834, 926.0184204742994, 926.9351145419756, 16754.916225418794, 20411.285605003755, 21259.149634014466, 21326.253581124587, 21515.677264840728, 850.6724910909221, 971.9222462203007, 974.8732664753537, 990.5393991793851, 1003.0721582536125, 10151.390916545031, 10876.510557024953, 10898.746644135867, 10910.908909109026, 10911.526943862636, 682814.0, 698570.0, 703846.0, 713735.0, 720329.0, 112159.87840121657, 132227.0, 153066.23466882817, 155922.6611600827, 158164.0, 1013.2501948558162, 1084.6800193942806, 1137.8407023016784, 1177.5701868817991, 1181.9244487516319, 17193.484195474157, 17603.911980440014, 18063.2774689011, 18263.908680456512, 18446.800957937692, 62407.0, 62696.059559105735, 63114.42213944504, 63251.68374158284, 64028.03957940541, 68390.65804670945, 85054.57472712598, 85243.0, 88293.55853220692, 95664.52167739117, 12836.422360993964, 13141.73716525645, 13255.0, 13763.655908602364, 13968.02223844321, 14312.06971546846, 14385.0, 14620.926895365455, 14733.55799326022, 14796.260186990392, 11914.344711040976, 12161.905428511476, 12302.323372214614, 12312.445939932904, 12357.0, 9374.343795934219, 11350.94324528372, 11573.652790416301, 11737.295762254293, 12020.33888136161, 12032.75934481323, 12110.576129835414, 12342.568010119603, 12455.626331210078, 12492.687682808002, 13207.867921321049, 13216.801747973981, 13250.0, 13571.1857288563, 13578.592642220749, 11942.641720748392, 12001.237549651563, 12232.327222002876, 12257.26456412618, 12283.69290767738], "value": 10895.443691447745, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1195.784758743427, 1346.0981142634434, 1408.9292137975135, 1410.3441899516722, 1441.084911081463, 11283.548658053678, 11605.3036817791, 11714.941425292873, 11735.295882247061, 11742.354170520639, 11538.423078846066, 11674.35791031493, 11727.0, 11744.765104697923, 11808.055355571556, 2472.8021758259342, 2521.4830959653227, 2524.9747502524924, 2527.064985955199, 2531.4810463854956, 2451.4851881104987, 2472.863992480413, 2490.1160088168713, 2504.0359461607322, 2504.2362079565723, 2474.8886300116446, 2481.330040888959, 2501.662275592799, 2503.8622875741826, 2506.323292710967, 2405.9759402405925, 2451.9264422067367, 2467.444824914389, 2471.159805666064, 2485.825992180548, 2412.1075202175307, 2459.803215742754, 2463.187148241076, 2496.9762397417153, 2500.749925007503, 11507.597234096851, 11538.769224615544, 11654.0, 11672.883271167264, 11710.0, 5638.971805140948, 6482.773102941374, 6633.2703402625675, 6648.833779155511, 6721.0254513095815, 4013.2976729072298, 4249.915001699979, 4293.549177336397, 4323.092150648367, 4343.261645520269, 1198.9100817438639, 1210.8304837322783, 1260.6974326161746, 1265.8354413926222, 1267.4930027988787, 14456.060356076927, 14840.703185936329, 15801.051936883823, 16205.35178592855, 16211.837881621268, 5841.824745257649, 5947.910781338283, 6060.2424696912885, 6137.478314343241, 6140.140380346755, 10103.84844227337, 13642.249676267702, 13643.522476713268, 13648.0, 13683.589492315246, 5904.350521442638, 6121.959266924545, 6740.730370785212, 6751.189857217166, 6768.289329620319, 5064.5948324134715, 5549.5837812163445, 5719.456651618089, 6046.27444706638, 6237.313895471494, 3593.964060359363, 4674.69614475058, 4701.553352431513, 4703.529647035269, 4759.452662943806, 1417.3126033873564, 1457.3733294683177, 1462.9853701463062, 1472.8674419302315, 1474.3733913086894, 734.4931996922213, 747.1221314955014, 764.6024067484988, 765.5368502056176, 791.181127533012, 4253.787310634456, 5907.438793314628, 6107.541934354941, 6160.7535698571555, 6223.96888015557, 568.5962966293978, 580.1066357809011, 585.7656937225144, 585.8184359341617, 598.6497898729292, 4005.178938317638, 4240.215560121387, 4377.3433984901885, 4482.529334419904, 4644.883877903079, 14533.34599942984, 14914.850851491563, 14980.026298290577, 14985.250737463084, 14992.850071499146, 12164.817527736914, 14860.479883204036, 14895.77656335156, 14901.925490372696, 14917.850821491864, 9250.583723732449, 10497.9475102624, 10770.192235582363, 10783.6225732099, 10788.676339709822, 5745.253117094751, 5773.451522105393, 5968.940310596839, 6787.812132876628, 7062.223155452793, 12969.870301297244, 13263.535776247785, 13422.530211442552, 13476.191428514321, 13503.527376541773, 13455.663608409868, 13470.595882123553, 13536.526221582197, 13629.25039122818, 13738.65653358674, 9307.348485606213, 9471.715848524555, 9476.289278304017, 9577.329586928849, 9612.855807162758, 10265.486725663837, 10485.423301718478, 10653.573857045942, 10700.571977120828, 10727.731806704895, 8856.0, 8972.147645973622, 9011.864822027806, 9159.633614655606, 9284.35009549352, 8565.828683426422, 8637.827243455222, 8748.78128046804, 8799.780005499913, 8965.820683586422, 6194.535409844189, 8008.719694810654, 8035.196480351918, 8110.0673422557165, 8146.75559733209, 5988.37122102173, 6008.1588577598295, 6127.938720612737, 6177.166082578974, 6206.968965155145, 5953.493953014043, 6118.724657390517, 6260.280067792262, 6298.212723409574, 6368.554201205871], "value": 5313.403923786169, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.66749912591786, 380.5585520795877, 401.48810266909703, 409.77536410762775, 418.4936227165132, 5620.437956204382, 6153.046277826933, 6326.683665816714, 6523.641199734013, 6531.738730450788, 5207.557357624606, 5581.413951535092, 5637.408072152426, 6235.844103897405, 6433.806985790434, 4587.128445595343, 4797.136515427222, 4802.759862006903, 4809.879753006167, 4820.638452116088, 4808.855734327975, 4814.350062741538, 4845.733484658343, 4853.199222128363, 4877.7318546875695, 4630.212863813143, 4736.834210802623, 4755.262934245197, 4780.952190478086, 4799.976000119995, 4741.099191153669, 4744.4069491313585, 4758.048390321915, 4763.833265835697, 4776.020915712271, 4223.5142958559545, 4232.746035237896, 4233.269541145944, 4234.703570750049, 4236.766977816219, 5964.910526342108, 6157.507399408081, 6212.1303017577575, 6232.594881332699, 6261.7182226799905, 4980.178270585349, 5394.514493695587, 5675.886482270332, 5706.657600543939, 5710.2862142232225, 5218.5825133989565, 6415.743370265184, 6586.411099414771, 6856.6228857412825, 6864.704941628046, 3840.7503512271614, 4554.0891821635805, 4793.9280910786365, 4816.157172494834, 4818.518148185189, 6014.488768454687, 6457.740740555593, 6946.479014073965, 7062.293770622948, 7142.928570714278, 3567.090391950088, 4895.608351331956, 4958.553730164231, 4972.105021096165, 4996.5003499650475, 5061.746912654353, 5071.717727022855, 5073.568746656576, 5078.263651770547, 5104.132297509433, 4775.6657034007985, 5215.452377500308, 5281.471852814688, 5671.6597004179885, 5728.885422291531, 5304.124819404832, 5689.573282003867, 6001.549883758736, 6868.141482314711, 6905.240423553405, 1325.337331334333, 1354.5462270139417, 1593.8804589655822, 1593.944211952576, 1597.6085858964627, 995.348047029197, 1000.9699709008742, 1039.2153923787491, 1052.3528030261334, 1055.8310670292717, 5136.30659860914, 5396.946030539723, 5657.0, 5686.118651609007, 6425.293217746138, 5830.198952042744, 7065.788026359218, 7078.115235595551, 7081.291870812981, 7115.608641524663, 5570.275864137636, 6383.840403989938, 6405.871882562323, 6409.262934762562, 6448.613083215022, 2964.762818974477, 3406.403879321109, 3665.248624032067, 3692.981535092361, 3699.0, 5766.261026012694, 5907.4683278505145, 6255.0, 6853.965730171317, 7267.091613548307, 6138.324784273726, 6490.545661803629, 7291.380232680175, 7302.160251571031, 7322.414206863438, 5774.855628609318, 6883.070785443901, 7021.332973367522, 7107.999999999896, 7171.92828071723, 5592.552595792408, 5639.746211420578, 6788.25329213796, 6984.3364880336285, 7004.124484439445, 5713.600287879184, 6530.281669016499, 6597.901031484627, 6703.899441508283, 6790.354916282945, 6129.478994285536, 6657.434118100015, 6906.825839607179, 7079.2566780487305, 7256.7822965309015, 3813.4089216171537, 4474.149911516932, 4533.977330113328, 4542.545745425431, 4553.289069408422, 5380.139177731544, 5820.0, 6829.590224586541, 6989.95150727395, 7134.0, 5972.58191926561, 6582.275949645439, 6629.303923088007, 6831.351021652935, 6844.691988860613, 6337.7148028337915, 6532.1786754749, 6733.663316834237, 6857.142857142857, 6905.965470172617, 5672.177534257592, 5967.552433567413, 5976.432238937468, 6027.4876635484725, 6681.732730690718, 5732.512736417451, 6662.5348621010535, 6750.86498270022, 6803.659817009031, 6822.420094292041, 5513.669179849222, 6659.833504162435, 6665.467841515439, 6668.83327916806, 6683.498737594797], "value": 4867.605018298403, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.9737159199427, 793.5873345860152, 838.1032295443878, 843.9240468357847, 844.3456321350956, 15557.29992150353, 16013.919930400347, 16069.035857848537, 16541.255643496042, 16885.24016419261, 13699.452021919136, 14862.777058344134, 15334.0, 15487.148206848648, 15518.146501942389, 949.6343907595557, 959.5346257065336, 977.1401166973089, 978.2760757039811, 978.5938835383307, 1011.4942528735633, 1023.9539220735047, 1024.810410074135, 1027.321967501449, 1029.2949329709172, 1042.3016578892161, 1060.872695276564, 1060.8992145746142, 1066.9306495077833, 1068.0547715271962, 1034.4207243943415, 1046.26238501856, 1059.8251288537383, 1060.0459586372288, 1060.543966094579, 786.9016372953381, 788.5702292250737, 789.2186735132235, 789.6643926331344, 791.5171745235402, 13724.176549407068, 16976.74534881978, 17035.06307153106, 17088.31646734129, 17300.134993250285, 14678.779818302733, 14845.238095238095, 14878.052429227559, 15072.246387680572, 15088.622784430478, 13156.079074464791, 13289.269090200036, 13334.266615336153, 13441.731165376637, 13475.663108422368, 3272.9509057364157, 3274.918127046819, 3284.720798732111, 3307.6361600224195, 3316.403047451458, 115432.0, 123285.0, 125835.0, 126032.0, 126285.73714262832, 10245.641402550875, 11605.402394875959, 11658.18392712519, 11928.821067683992, 11973.880261197277, 23819.523609527714, 24125.638115428283, 24361.14735984232, 24437.511249774903, 24545.318202269802, 7380.9261907381315, 7817.921820781833, 8142.674293028213, 8270.2556769891, 8409.915900840913, 4116.300228961084, 4116.732412393185, 4125.731827431207, 4192.119654872511, 4207.97896010518, 4156.168766246702, 4234.0, 4247.723897946686, 4274.764887931194, 4276.87169384919, 933.3746389918771, 938.0478814003792, 951.0, 953.1088432315669, 953.5041778275258, 503.38335538964475, 523.4477626104497, 542.3085565903426, 555.5500044963593, 604.3020311540179, 8855.690050848189, 9308.348415610977, 9950.303478756563, 10413.53139108742, 10477.633282835064, 559.6558116758165, 588.9146073819272, 606.6178307666142, 618.8019833653187, 658.4337469775988, 4693.530646935347, 4695.835645752382, 4717.339572459859, 4724.385829842167, 4779.784909679072, 103169.48415257876, 106862.0, 107165.0, 107653.0, 110654.0, 92638.0, 93823.53088234515, 95813.08373832484, 96306.0, 97078.89434926336, 61892.0, 80972.0, 81671.5916420426, 81750.0, 82894.0, 9429.669961551313, 9530.758812328448, 9607.51962401891, 9674.467904265333, 9706.6117355305, 42870.57129428666, 43421.348679769195, 43488.78255608702, 43907.46323878649, 44055.0, 51628.0, 56459.459324329684, 57059.71470142623, 57086.714566426905, 57367.0, 13452.26012569279, 13477.932610336886, 13642.658933526742, 13684.52104176349, 13785.310734463437, 14596.85403145955, 14640.853591463949, 14799.260036997892, 15086.849131508545, 15088.24558772079, 13659.726805463637, 13911.791323129959, 13936.0, 14145.929270353583, 14534.0, 14056.0, 14237.715245695235, 14325.0, 14483.0, 14958.40166393332, 13366.732665346834, 13538.796918046433, 13691.0, 13692.794608081085, 14581.781273280698, 9950.0, 10019.649312274036, 10217.438040907822, 10344.844827327746, 10483.842985243236, 11146.49840757184, 11235.0, 11392.373419462008, 11397.886021140013, 11444.71388215268], "value": 7841.729577073486, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1654.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 122.0, 121.0, 121.0, 123.0, 120.0, 123.0, 123.0, 121.0, 122.0], "value": 120.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 116.0, 119.0, 156.0, 121.0, 115.0, 133.0, 138.0, 120.0, 155.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [213.0, 221.0, 224.0, 231.0, 220.0, 234.0, 216.0, 316.0, 216.0, 223.0], "value": 231.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 59.0, 88.0, 61.0, 57.0, 58.0, 62.0, 97.0, 92.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 73.0, 87.0, 73.0, 73.0, 80.0, 124.0, 108.0, 109.0, 70.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 142.0, 106.0, 105.0, 145.0, 119.0, 115.0, 81.0, 142.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 101.0, 127.0, 160.0, 131.0, 95.0, 96.0, 160.0, 118.0, 111.0], "value": 119.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 102.0, 99.0, 99.0, 102.0, 101.0, 101.0, 118.0, 101.0, 103.0], "value": 102.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 54.0, 56.0, 73.0, 82.0, 80.0, 58.0, 54.0], "value": 61.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 72.0, 87.0, 60.0, 66.0, 78.0, 90.0, 89.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 91.0, 80.0, 113.0, 89.0, 103.0, 78.0, 104.0, 113.0], "value": 94.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 145.0, 147.0, 156.0, 166.0, 181.0, 173.0, 209.0, 173.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 199.0, 196.0, 183.0, 194.0, 195.0, 192.0, 202.0, 231.0, 262.0], "value": 205.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 71.0, 72.0, 75.0, 74.0, 71.0, 69.0, 70.0, 73.0], "value": 71.6, "unit": "score"}]}]} 19:22:22 INFO - Return code: 0 19:22:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:22:22 ERROR - # TBPL SUCCESS # 19:22:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:22:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:22:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:22:22 INFO - 2017-04-06 19:22:22.701 screenresolution[1652:10886] starting screenresolution argv=screenresolution get 19:22:22 INFO - 2017-04-06 19:22:22.704 screenresolution[1652:10886] Display 0: 1600x1200x32@60 19:22:22 INFO - 2017-04-06 19:22:22.710 screenresolution[1653:10889] starting screenresolution argv=screenresolution list 19:22:22 INFO - Available Modes on Display 0 19:22:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:22:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:22:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:22:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:22:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:22:22 INFO - Intel Iris: 19:22:22 INFO - Chipset Model: Intel Iris 19:22:22 INFO - Type: GPU 19:22:22 INFO - Bus: Built-In 19:22:22 INFO - VRAM (Dynamic, Max): 1536 MB 19:22:22 INFO - Vendor: Intel (0x8086) 19:22:22 INFO - Device ID: 0x0a2e 19:22:22 INFO - Revision ID: 0x0009 19:22:22 INFO - Displays: 19:22:22 INFO - Display: 19:22:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:22:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:22:22 INFO - Display Serial Number: 1600x1200 60 19:22:22 INFO - Main Display: Yes 19:22:22 INFO - Mirror: Off 19:22:22 INFO - Online: Yes 19:22:22 INFO - Rotation: Supported 19:22:22 INFO - Return code: 0 19:22:22 INFO - Running post-action listener: _resource_record_post_action 19:22:22 INFO - [mozharness: 2017-04-07 02:22:22.956198Z] Finished run-tests step (success) 19:22:22 INFO - Running post-run listener: _resource_record_post_run 19:22:23 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 48000000; Write bytes: 524230656; Read time: 471; Write time: 2533 19:22:23 INFO - TinderboxPrint: CPU usage
54.3% 19:22:23 INFO - TinderboxPrint: I/O read bytes / time
48,000,000 / 471 19:22:23 INFO - TinderboxPrint: I/O write bytes / time
524,230,656 / 2,533 19:22:23 INFO - TinderboxPrint: CPU idle
2,197.2 (45.7%) 19:22:23 INFO - TinderboxPrint: CPU system
974.4 (20.3%) 19:22:23 INFO - TinderboxPrint: CPU user
1,634.1 (34.0%) 19:22:23 INFO - TinderboxPrint: Swap in / out
261,943,296 / 0 19:22:23 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 193196544; Write bytes: 194418176; Read time: 14868; Write time: 388 19:22:23 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 46951424; Write bytes: 312969728; Read time: 453; Write time: 2096 19:22:23 INFO - Running post-run listener: _upload_blobber_files 19:22:23 INFO - Blob upload gear active. 19:22:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:22:23 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 19:22:23 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:22:23 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:22:23 INFO - (blobuploader) - INFO - Open directory for files ... 19:22:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:22:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:22:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:24 INFO - (blobuploader) - INFO - Done attempting. 19:22:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:22:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:22:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:25 INFO - (blobuploader) - INFO - Done attempting. 19:22:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:22:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:22:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:26 INFO - (blobuploader) - INFO - Done attempting. 19:22:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:22:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:22:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:28 INFO - (blobuploader) - INFO - Done attempting. 19:22:28 INFO - (blobuploader) - INFO - Iteration through files over. 19:22:28 INFO - Return code: 0 19:22:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:22:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:22:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/40d15ec574eb7fe698267a02e2f247c798807bfa546088402797855adbe2d3f1f94054e35ec119bf26f5cc772d34e6cf764e2fa33825d1a6d2a7e6f2420f7fb8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/bdc07f8eec52ce4012fe3fa7f973acb6b4911472b36218391b98f56550ec61110179400b3389c893164204f9a29d6555e4bdcb40009a51b00ba1fdef1d373608", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6ceaad6ccfc4d9456a0a17a5429fb8d6421b956beaddcbea945cf5a4967adecca1dbd43361d2788bd5b761b65ad484747f8adaf037213cd154766f2cbbd34dea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5f8f5eb5738138f854ce1e33f9a15b18e94eaaddae62e3e470743aeb258712a2f60b0a5694a54872232dd9e277078a41a11e0746f17e313c5f1acfff1a3a0fa0"} 19:22:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:22:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:22:28 INFO - Contents: 19:22:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/40d15ec574eb7fe698267a02e2f247c798807bfa546088402797855adbe2d3f1f94054e35ec119bf26f5cc772d34e6cf764e2fa33825d1a6d2a7e6f2420f7fb8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/bdc07f8eec52ce4012fe3fa7f973acb6b4911472b36218391b98f56550ec61110179400b3389c893164204f9a29d6555e4bdcb40009a51b00ba1fdef1d373608", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6ceaad6ccfc4d9456a0a17a5429fb8d6421b956beaddcbea945cf5a4967adecca1dbd43361d2788bd5b761b65ad484747f8adaf037213cd154766f2cbbd34dea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5f8f5eb5738138f854ce1e33f9a15b18e94eaaddae62e3e470743aeb258712a2f60b0a5694a54872232dd9e277078a41a11e0746f17e313c5f1acfff1a3a0fa0"} 19:22:28 INFO - Running post-run listener: copy_logs_to_upload_dir 19:22:28 INFO - Copying logs to upload dir... 19:22:28 INFO - mkdir: /builds/slave/test/build/upload/logs 19:22:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.339154 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-04-06 19:22:29.039468) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:29.044292) ========= 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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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/Pine/sha512/40d15ec574eb7fe698267a02e2f247c798807bfa546088402797855adbe2d3f1f94054e35ec119bf26f5cc772d34e6cf764e2fa33825d1a6d2a7e6f2420f7fb8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/bdc07f8eec52ce4012fe3fa7f973acb6b4911472b36218391b98f56550ec61110179400b3389c893164204f9a29d6555e4bdcb40009a51b00ba1fdef1d373608", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6ceaad6ccfc4d9456a0a17a5429fb8d6421b956beaddcbea945cf5a4967adecca1dbd43361d2788bd5b761b65ad484747f8adaf037213cd154766f2cbbd34dea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5f8f5eb5738138f854ce1e33f9a15b18e94eaaddae62e3e470743aeb258712a2f60b0a5694a54872232dd9e277078a41a11e0746f17e313c5f1acfff1a3a0fa0"} build_url:https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011190 build_url: 'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/40d15ec574eb7fe698267a02e2f247c798807bfa546088402797855adbe2d3f1f94054e35ec119bf26f5cc772d34e6cf764e2fa33825d1a6d2a7e6f2420f7fb8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/bdc07f8eec52ce4012fe3fa7f973acb6b4911472b36218391b98f56550ec61110179400b3389c893164204f9a29d6555e4bdcb40009a51b00ba1fdef1d373608", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6ceaad6ccfc4d9456a0a17a5429fb8d6421b956beaddcbea945cf5a4967adecca1dbd43361d2788bd5b761b65ad484747f8adaf037213cd154766f2cbbd34dea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/5f8f5eb5738138f854ce1e33f9a15b18e94eaaddae62e3e470743aeb258712a2f60b0a5694a54872232dd9e277078a41a11e0746f17e313c5f1acfff1a3a0fa0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:29.078981) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:29.079277) ========= 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.nzsZyE3XNx/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.EeyqdUR0Ge/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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.005157 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:29.127398) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 19:22:29.127687) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 19:22:29.127977) ========= ========= Total master_lag: 3.82 =========